builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0063 starttime: 1473558836.0 results: success (0) buildid: 20160910153615 builduid: 3e0eb92cc70e47b7ae23f8b38e0e4c28 revision: 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:55.998310) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:55.998756) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:55.999070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:56.026128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:56.026423) ========= 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.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-10 18:53:56-- 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% 9.55M=0.001s 2016-09-10 18:53:56 (9.55 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119480 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:56.219434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:56.219776) ========= 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.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.029038 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:56.294825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 18:53:56.295200) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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-10 18:53:56,425 truncating revision to first 12 chars 2016-09-10 18:53:56,426 Setting DEBUG logging. 2016-09-10 18:53:56,426 attempt 1/10 2016-09-10 18:53:56,426 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2a9bff279307?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 18:53:57,197 unpacking tar archive at: fx-team-2a9bff279307/testing/mozharness/ program finished with exit code 0 elapsedTime=1.036153 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-10 18:53:57.407262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:57.407628) ========= script_repo_revision: 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:57.408085) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:57.408429) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 18:53:57.427229) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 18:53:57.427542) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:53:57 INFO - MultiFileLogger online at 20160910 18:53:57 in /builds/slave/test 18:53:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:53:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:53:57 INFO - {'append_to_log': False, 18:53:57 INFO - 'base_work_dir': '/builds/slave/test', 18:53:57 INFO - 'blob_upload_branch': 'Fx-Team', 18:53:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:53:57 INFO - 'branch': 'Fx-Team', 18:53:57 INFO - 'buildbot_json_path': 'buildprops.json', 18:53:57 INFO - 'config_files': ('talos/mac_config.py',), 18:53:57 INFO - 'default_actions': ('clobber', 18:53:57 INFO - 'read-buildbot-config', 18:53:57 INFO - 'download-and-extract', 18:53:57 INFO - 'populate-webroot', 18:53:57 INFO - 'create-virtualenv', 18:53:57 INFO - 'install', 18:53:57 INFO - 'run-tests'), 18:53:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:53:57 INFO - 'download_minidump_stackwalk': True, 18:53:57 INFO - 'download_symbols': 'ondemand', 18:53:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:53:57 INFO - 'tooltool.py': '/tools/tooltool.py', 18:53:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:53:57 INFO - '/tools/misc-python/virtualenv.py')}, 18:53:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:53:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:53:57 INFO - 'installer_path': 'installer.exe', 18:53:57 INFO - 'log_level': 'info', 18:53:57 INFO - 'log_name': 'talos', 18:53:57 INFO - 'log_to_console': True, 18:53:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:53:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:53:57 INFO - 'opt_config_files': (), 18:53:57 INFO - 'pip_index': False, 18:53:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:57 INFO - 'cmd': ('bash', 18:53:57 INFO - '-c', 18:53:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:57 INFO - 'enabled': True, 18:53:57 INFO - 'halt_on_failure': False, 18:53:57 INFO - 'name': 'check_screen_resolution'},), 18:53:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:53:57 INFO - 'cmd': ('bash', 18:53:57 INFO - '-c', 18:53:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:53:57 INFO - 'enabled': True, 18:53:57 INFO - 'halt_on_failure': False, 18:53:57 INFO - 'name': 'check_screen_resolution'},), 18:53:57 INFO - 'run_cmd_checks_enabled': True, 18:53:57 INFO - 'sps_profile': False, 18:53:57 INFO - 'sps_profile_interval': 0, 18:53:57 INFO - 'suite': 'dromaeojs-e10s', 18:53:57 INFO - 'system_bits': '32', 18:53:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:53:57 INFO - 'title': 't-yosemite-r7-0063', 18:53:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:53:57 INFO - 'use_talos_json': True, 18:53:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:53:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:53:57 INFO - 'work_dir': 'build'} 18:53:57 INFO - [mozharness: 2016-09-11 01:53:57.625559Z] Running clobber step. 18:53:57 INFO - Running pre-action listener: _resource_record_pre_action 18:53:57 INFO - Running main action method: clobber 18:53:57 INFO - rmtree: /builds/slave/test/build 18:53:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:53:59 INFO - Running post-action listener: _resource_record_post_action 18:53:59 INFO - [mozharness: 2016-09-11 01:53:59.735002Z] Finished clobber step (success) 18:53:59 INFO - [mozharness: 2016-09-11 01:53:59.735282Z] Running read-buildbot-config step. 18:53:59 INFO - Running pre-action listener: _resource_record_pre_action 18:53:59 INFO - Running main action method: read_buildbot_config 18:53:59 INFO - Using buildbot properties: 18:53:59 INFO - { 18:53:59 INFO - "project": "", 18:53:59 INFO - "product": "firefox", 18:53:59 INFO - "script_repo_revision": "production", 18:53:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:53:59 INFO - "repository": "", 18:53:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:53:59 INFO - "buildid": "20160910153615", 18:53:59 INFO - "pgo_build": "False", 18:53:59 INFO - "basedir": "/builds/slave/test", 18:53:59 INFO - "buildnumber": 111, 18:53:59 INFO - "slavename": "t-yosemite-r7-0063", 18:53:59 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:53:59 INFO - "platform": "yosemite_r7", 18:53:59 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 18:53:59 INFO - "branch": "fx-team", 18:53:59 INFO - "repo_path": "integration/fx-team", 18:53:59 INFO - "revision": "2a9bff2793079f0581b7c3e68b9b940b3e9c20bd", 18:53:59 INFO - "stage_platform": "macosx64", 18:53:59 INFO - "builduid": "3e0eb92cc70e47b7ae23f8b38e0e4c28", 18:53:59 INFO - "slavebuilddir": "test" 18:53:59 INFO - } 18:53:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:53:59 INFO - Running post-action listener: _resource_record_post_action 18:53:59 INFO - [mozharness: 2016-09-11 01:53:59.740993Z] Finished read-buildbot-config step (success) 18:53:59 INFO - [mozharness: 2016-09-11 01:53:59.741206Z] Running download-and-extract step. 18:53:59 INFO - Running pre-action listener: _resource_record_pre_action 18:53:59 INFO - Running main action method: download_and_extract 18:53:59 INFO - mkdir: /builds/slave/test/build/tests 18:53:59 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')]} 18:53:59 INFO - trying https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:53:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/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 18:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/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 18:54:01 INFO - Downloaded 1199 bytes. 18:54:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:54:01 INFO - Using the following test package requirements: 18:54:01 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:54:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:54:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:54:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'jsshell-mac.zip'], 18:54:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:54:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:54:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:54:01 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:54:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:54:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:54:01 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:54:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:54:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:54:01 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:54:08 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 18:54:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:54:09 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:54:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:54:16 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:54:16 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')]} 18:54:16 INFO - trying https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:54:16 INFO - Downloading https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:54:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/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 18:54:19 INFO - Downloaded 111644201 bytes. 18:54:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:54:19 INFO - mkdir: /builds/slave/test/properties 18:54:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:54:19 INFO - Writing to file /builds/slave/test/properties/build_url 18:54:19 INFO - Contents: 18:54:19 INFO - build_url:https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:54:20 INFO - Running post-action listener: _resource_record_post_action 18:54:20 INFO - Running post-action listener: set_extra_try_arguments 18:54:20 INFO - [mozharness: 2016-09-11 01:54:20.199635Z] Finished download-and-extract step (success) 18:54:20 INFO - [mozharness: 2016-09-11 01:54:20.199785Z] Running populate-webroot step. 18:54:20 INFO - Running pre-action listener: _resource_record_pre_action 18:54:20 INFO - Running main action method: populate_webroot 18:54:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:54:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:54:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:54:20 INFO - '--tppagecycles', 18:54:20 INFO - '7'], 18:54:20 INFO - 'tests': ['tsvgm']}}, 18:54:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:54:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:54:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:54:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:54:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:54:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['damp', 'tps']}, 18:54:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'tests': ['damp', 'tps']}, 18:54:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['dromaeo_dom']}, 18:54:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:54:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['basic_compositor_video']}, 18:54:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:54:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'other-e10s': {'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['a11yr', 18:54:20 INFO - 'ts_paint', 18:54:20 INFO - 'tpaint', 18:54:20 INFO - 'sessionrestore', 18:54:20 INFO - 'sessionrestore_no_auto_restore', 18:54:20 INFO - 'tabpaint']}, 18:54:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['tsvgx', 18:54:20 INFO - 'tsvgr_opacity', 18:54:20 INFO - 'tart', 18:54:20 INFO - 'tscrollx', 18:54:20 INFO - 'cart']}, 18:54:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:54:20 INFO - 'tsvgr_opacity', 18:54:20 INFO - 'tart', 18:54:20 INFO - 'tscrollx', 18:54:20 INFO - 'cart']}, 18:54:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'talos_options': ['--disable-e10s'], 18:54:20 INFO - 'tests': ['tp5o']}, 18:54:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'tests': ['tp5o']}, 18:54:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'talos_options': ['--disable-e10s', 18:54:20 INFO - '--xperf_path', 18:54:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:20 INFO - 'tests': ['tp5n']}, 18:54:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:54:20 INFO - 'talos_options': ['--xperf_path', 18:54:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:54:20 INFO - 'tests': ['tp5n']}}, 18:54:20 INFO - 'talos.zip': {'path': '', 18:54:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:54:20 INFO - Running post-action listener: _resource_record_post_action 18:54:20 INFO - [mozharness: 2016-09-11 01:54:20.218712Z] Finished populate-webroot step (success) 18:54:20 INFO - [mozharness: 2016-09-11 01:54:20.218849Z] Running create-virtualenv step. 18:54:20 INFO - Running pre-action listener: _resource_record_pre_action 18:54:20 INFO - Running main action method: create_virtualenv 18:54:20 INFO - Creating virtualenv /builds/slave/test/build/venv 18:54:20 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 18:54:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:54:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:54:20 INFO - Using real prefix '/tools/python27' 18:54:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:54:21 INFO - Installing distribute.............................................................................................................................................................................................done. 18:54:23 INFO - Installing pip.................done. 18:54:23 INFO - Return code: 0 18:54:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:23 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:23 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 18:54:23 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 18:54:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:23 INFO - 'HOME': '/Users/cltbld', 18:54:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:23 INFO - 'LOGNAME': 'cltbld', 18:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:23 INFO - 'MOZ_NO_REMOTE': '1', 18:54:23 INFO - 'NO_EM_RESTART': '1', 18:54:23 INFO - 'PAGER': '/bin/cat', 18:54:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:23 INFO - 'PWD': '/builds/slave/test', 18:54:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:23 INFO - 'SHELL': '/bin/bash', 18:54:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:23 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:23 INFO - 'USER': 'cltbld', 18:54:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:23 INFO - 'XPC_FLAGS': '0x0', 18:54:23 INFO - 'XPC_SERVICE_NAME': '0', 18:54:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:24 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 18:54:24 INFO - Cleaning up... 18:54:24 INFO - Return code: 0 18:54:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:24 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:24 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 18:54:24 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 18:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:24 INFO - 'HOME': '/Users/cltbld', 18:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:24 INFO - 'LOGNAME': 'cltbld', 18:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:24 INFO - 'MOZ_NO_REMOTE': '1', 18:54:24 INFO - 'NO_EM_RESTART': '1', 18:54:24 INFO - 'PAGER': '/bin/cat', 18:54:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:24 INFO - 'PWD': '/builds/slave/test', 18:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:24 INFO - 'SHELL': '/bin/bash', 18:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:24 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:24 INFO - 'USER': 'cltbld', 18:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:24 INFO - 'XPC_FLAGS': '0x0', 18:54:24 INFO - 'XPC_SERVICE_NAME': '0', 18:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:24 INFO - Downloading/unpacking psutil>=3.1.1 18:54:24 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 18:54:24 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 18:54:24 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 18:54:24 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 18:54:24 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 18:54:24 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 18:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:54:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:27 INFO - Installing collected packages: psutil 18:54:27 INFO - Running setup.py install for psutil 18:54:27 INFO - building 'psutil._psutil_osx' extension 18:54:27 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 18:54:28 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 18:54:28 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 18:54:28 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 18:54:28 INFO - building 'psutil._psutil_posix' extension 18:54:28 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 18:54:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:54:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:54:28 INFO - ^ 18:54:28 INFO - 1 warning generated. 18:54:28 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 18:54:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:54:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:54:28 INFO - Successfully installed psutil 18:54:28 INFO - Cleaning up... 18:54:29 INFO - Return code: 0 18:54:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:29 INFO - 'HOME': '/Users/cltbld', 18:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:29 INFO - 'LOGNAME': 'cltbld', 18:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:29 INFO - 'MOZ_NO_REMOTE': '1', 18:54:29 INFO - 'NO_EM_RESTART': '1', 18:54:29 INFO - 'PAGER': '/bin/cat', 18:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:29 INFO - 'PWD': '/builds/slave/test', 18:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:29 INFO - 'SHELL': '/bin/bash', 18:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:29 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:29 INFO - 'USER': 'cltbld', 18:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:29 INFO - 'XPC_FLAGS': '0x0', 18:54:29 INFO - 'XPC_SERVICE_NAME': '0', 18:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:54: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 18:54: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 18:54: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 18:54: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 18:54: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 18:54: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 18:54:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:54:31 INFO - Installing collected packages: mozsystemmonitor 18:54:31 INFO - Running setup.py install for mozsystemmonitor 18:54:32 INFO - Successfully installed mozsystemmonitor 18:54:32 INFO - Cleaning up... 18:54:32 INFO - Return code: 0 18:54:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:54:32 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')]} 18:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:32 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')]} 18:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:32 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:32 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 18:54:32 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 18:54:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:32 INFO - 'HOME': '/Users/cltbld', 18:54:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:32 INFO - 'LOGNAME': 'cltbld', 18:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:32 INFO - 'MOZ_NO_REMOTE': '1', 18:54:32 INFO - 'NO_EM_RESTART': '1', 18:54:32 INFO - 'PAGER': '/bin/cat', 18:54:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:32 INFO - 'PWD': '/builds/slave/test', 18:54:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:32 INFO - 'SHELL': '/bin/bash', 18:54:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:32 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:32 INFO - 'USER': 'cltbld', 18:54:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:32 INFO - 'XPC_FLAGS': '0x0', 18:54:32 INFO - 'XPC_SERVICE_NAME': '0', 18:54:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:32 INFO - Downloading/unpacking jsonschema==2.5.1 18:54:32 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 18:54:32 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 18:54:32 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 18:54:32 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 18:54:32 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 18:54:32 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 18:54:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:54:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:54:34 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 18:54:34 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 18:54:34 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 18:54:34 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 18:54:34 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 18:54:34 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 18:54:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:54:35 INFO - warning: no files found matching '*.txt' 18:54:35 INFO - no previously-included directories found matching 'build' 18:54:35 INFO - no previously-included directories found matching 'dist' 18:54:35 INFO - no previously-included directories found matching '.git*' 18:54:35 INFO - Installing collected packages: jsonschema, functools32 18:54:35 INFO - Running setup.py install for functools32 18:54:35 INFO - warning: no files found matching '*.txt' 18:54:35 INFO - no previously-included directories found matching 'build' 18:54:35 INFO - no previously-included directories found matching 'dist' 18:54:35 INFO - no previously-included directories found matching '.git*' 18:54:35 INFO - Successfully installed jsonschema functools32 18:54:35 INFO - Cleaning up... 18:54:35 INFO - Return code: 0 18:54:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:54: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')]} 18:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:35 INFO - 'HOME': '/Users/cltbld', 18:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:35 INFO - 'LOGNAME': 'cltbld', 18:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:35 INFO - 'MOZ_NO_REMOTE': '1', 18:54:35 INFO - 'NO_EM_RESTART': '1', 18:54:35 INFO - 'PAGER': '/bin/cat', 18:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:35 INFO - 'PWD': '/builds/slave/test', 18:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:35 INFO - 'SHELL': '/bin/bash', 18:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:35 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:35 INFO - 'USER': 'cltbld', 18:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:35 INFO - 'XPC_FLAGS': '0x0', 18:54:35 INFO - 'XPC_SERVICE_NAME': '0', 18:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:54:35 INFO - Cleaning up... 18:54:35 INFO - Return code: 0 18:54:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:54: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')]} 18:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54: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')]} 18:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:35 INFO - 'HOME': '/Users/cltbld', 18:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:35 INFO - 'LOGNAME': 'cltbld', 18:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:35 INFO - 'MOZ_NO_REMOTE': '1', 18:54:35 INFO - 'NO_EM_RESTART': '1', 18:54:35 INFO - 'PAGER': '/bin/cat', 18:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:35 INFO - 'PWD': '/builds/slave/test', 18:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:35 INFO - 'SHELL': '/bin/bash', 18:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:35 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:35 INFO - 'USER': 'cltbld', 18:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:35 INFO - 'XPC_FLAGS': '0x0', 18:54:35 INFO - 'XPC_SERVICE_NAME': '0', 18:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:36 INFO - Downloading/unpacking blobuploader==1.2.4 18:54:36 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 18:54:36 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 18:54:36 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 18:54:36 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 18:54:36 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 18:54:36 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 18:54:38 INFO - Downloading blobuploader-1.2.4.tar.gz 18:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:54:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:54:38 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 18:54:38 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 18:54:38 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 18:54:38 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 18:54:38 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 18:54:38 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 18:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:54:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:39 INFO - Downloading docopt-0.6.1.tar.gz 18:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:54:39 INFO - Installing collected packages: blobuploader, requests, docopt 18:54:39 INFO - Running setup.py install for blobuploader 18:54:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:54:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:54:40 INFO - Running setup.py install for requests 18:54:40 INFO - Running setup.py install for docopt 18:54:40 INFO - Successfully installed blobuploader requests docopt 18:54:40 INFO - Cleaning up... 18:54:40 INFO - Return code: 0 18:54:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:40 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:40 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 18:54:40 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 18:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:40 INFO - 'HOME': '/Users/cltbld', 18:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:40 INFO - 'LOGNAME': 'cltbld', 18:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:40 INFO - 'MOZ_NO_REMOTE': '1', 18:54:40 INFO - 'NO_EM_RESTART': '1', 18:54:40 INFO - 'PAGER': '/bin/cat', 18:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:40 INFO - 'PWD': '/builds/slave/test', 18:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:40 INFO - 'SHELL': '/bin/bash', 18:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:40 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:40 INFO - 'USER': 'cltbld', 18:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:40 INFO - 'XPC_FLAGS': '0x0', 18:54:40 INFO - 'XPC_SERVICE_NAME': '0', 18:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-S8C7Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-mDJgaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-4JAmdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-0hPWuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-3tJYTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-gIoGUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-jXXhnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-sutAgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-3R1JYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:54:41 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-6O05DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-oHD2Vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-UCDlVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-8PxsJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-hFVh8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-mkeqA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-4dJ7Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:54:42 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-M3ppBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:54:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:54:42 INFO - Running setup.py install for manifestparser 18:54:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:54:42 INFO - Running setup.py install for mozcrash 18:54:43 INFO - Running setup.py install for mozdebug 18:54:43 INFO - Running setup.py install for mozdevice 18:54:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Running setup.py install for mozfile 18:54:43 INFO - Running setup.py install for mozhttpd 18:54:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Running setup.py install for mozinfo 18:54:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Running setup.py install for mozInstall 18:54:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:54:43 INFO - Running setup.py install for mozleak 18:54:44 INFO - Running setup.py install for mozlog 18:54:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Running setup.py install for moznetwork 18:54:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Running setup.py install for mozprocess 18:54:44 INFO - Running setup.py install for mozprofile 18:54:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Running setup.py install for mozrunner 18:54:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:54:44 INFO - Running setup.py install for mozscreenshot 18:54:45 INFO - Running setup.py install for moztest 18:54:45 INFO - Running setup.py install for mozversion 18:54:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:54:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:54:45 INFO - Cleaning up... 18:54:45 INFO - Return code: 0 18:54:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54:45 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')]} 18:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:45 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')]} 18:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:45 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:45 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 18:54:45 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 18:54:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:45 INFO - 'HOME': '/Users/cltbld', 18:54:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:45 INFO - 'LOGNAME': 'cltbld', 18:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:45 INFO - 'MOZ_NO_REMOTE': '1', 18:54:45 INFO - 'NO_EM_RESTART': '1', 18:54:45 INFO - 'PAGER': '/bin/cat', 18:54:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:45 INFO - 'PWD': '/builds/slave/test', 18:54:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:45 INFO - 'SHELL': '/bin/bash', 18:54:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:45 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:45 INFO - 'USER': 'cltbld', 18:54:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:45 INFO - 'XPC_FLAGS': '0x0', 18:54:45 INFO - 'XPC_SERVICE_NAME': '0', 18:54:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:54:45 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-3gEY0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:54:45 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)) 18:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:54:45 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-L98V7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:54:45 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)) 18:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:54:45 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-BBjysL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:54:45 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)) 18:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:54:45 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-8FEl1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-OXVOgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-fWRaYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-34vhBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-vcsEB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zkMoAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-zL9ObT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-jyt24o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-BSW6dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:54:46 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)) 18:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:54:46 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-hGvOiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:54:47 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)) 18:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:54:47 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-rdJ_LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:54:47 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)) 18:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:47 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-KHYZvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:54:47 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)) 18:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:54:47 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-0IeCTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:54:47 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)) 18:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:54:47 INFO - Running setup.py (path:/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/pip-VFWNy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:54:47 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)) 18:54:47 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)) 18:54:47 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)) 18:54:47 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)) 18:54:47 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)) 18:54:47 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)) 18:54:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:54:49 INFO - Downloading blessings-1.6.tar.gz 18:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:54:50 INFO - Installing collected packages: blessings 18:54:50 INFO - Running setup.py install for blessings 18:54:50 INFO - Successfully installed blessings 18:54:50 INFO - Cleaning up... 18:54:50 INFO - Return code: 0 18:54:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:54:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:54:50 INFO - Reading from file tmpfile_stdout 18:54:50 INFO - Current package versions: 18:54:50 INFO - blessings == 1.6 18:54:50 INFO - blobuploader == 1.2.4 18:54:50 INFO - docopt == 0.6.1 18:54:50 INFO - functools32 == 3.2.3-2 18:54:50 INFO - jsonschema == 2.5.1 18:54:50 INFO - manifestparser == 1.1 18:54:50 INFO - mozInstall == 1.12 18:54:50 INFO - mozcrash == 0.17 18:54:50 INFO - mozdebug == 0.1 18:54:50 INFO - mozdevice == 0.48 18:54:50 INFO - mozfile == 1.2 18:54:50 INFO - mozhttpd == 0.7 18:54:50 INFO - mozinfo == 0.9 18:54:50 INFO - mozleak == 0.1 18:54:50 INFO - mozlog == 3.3 18:54:50 INFO - moznetwork == 0.27 18:54:50 INFO - mozprocess == 0.23 18:54:50 INFO - mozprofile == 0.28 18:54:50 INFO - mozrunner == 6.12 18:54:50 INFO - mozscreenshot == 0.1 18:54:50 INFO - mozsystemmonitor == 0.3 18:54:50 INFO - moztest == 0.7 18:54:50 INFO - mozversion == 1.4 18:54:50 INFO - psutil == 3.1.1 18:54:50 INFO - requests == 1.2.3 18:54:50 INFO - wsgiref == 0.1.2 18:54:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:54:50 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')]} 18:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:50 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')]} 18:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:50 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:50 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 18:54:50 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 18:54:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:50 INFO - 'HOME': '/Users/cltbld', 18:54:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:50 INFO - 'LOGNAME': 'cltbld', 18:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:50 INFO - 'MOZ_NO_REMOTE': '1', 18:54:50 INFO - 'NO_EM_RESTART': '1', 18:54:50 INFO - 'PAGER': '/bin/cat', 18:54:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:50 INFO - 'PWD': '/builds/slave/test', 18:54:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:50 INFO - 'SHELL': '/bin/bash', 18:54:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:50 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:50 INFO - 'USER': 'cltbld', 18:54:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:50 INFO - 'XPC_FLAGS': '0x0', 18:54:50 INFO - 'XPC_SERVICE_NAME': '0', 18:54:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 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)) 18:54:50 INFO - Cleaning up... 18:54:50 INFO - Return code: 0 18:54:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:54:50 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')]} 18:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:50 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')]} 18:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:54:50 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 0x103332490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39bc86580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, '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.P6E7PmAie7/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.JCvgNk92DH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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 18:54:50 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 18:54:50 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 18:54:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:54:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:54:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:54:50 INFO - 'HOME': '/Users/cltbld', 18:54:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:54:50 INFO - 'LOGNAME': 'cltbld', 18:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:54:50 INFO - 'MOZ_NO_REMOTE': '1', 18:54:50 INFO - 'NO_EM_RESTART': '1', 18:54:50 INFO - 'PAGER': '/bin/cat', 18:54:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:54:50 INFO - 'PWD': '/builds/slave/test', 18:54:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:54:50 INFO - 'SHELL': '/bin/bash', 18:54:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:54:50 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:54:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:54:50 INFO - 'USER': 'cltbld', 18:54:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:54:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:54:50 INFO - 'XPC_FLAGS': '0x0', 18:54:50 INFO - 'XPC_SERVICE_NAME': '0', 18:54:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:54:51 INFO - Cleaning up... 18:54:51 INFO - Return code: 0 18:54:51 INFO - Running post-action listener: _resource_record_post_action 18:54:51 INFO - Running post-action listener: _start_resource_monitoring 18:54:51 INFO - Starting resource monitoring. 18:54:51 INFO - [mozharness: 2016-09-11 01:54:51.127482Z] Finished create-virtualenv step (success) 18:54:51 INFO - [mozharness: 2016-09-11 01:54:51.128503Z] Running install step. 18:54:51 INFO - Running pre-action listener: _resource_record_pre_action 18:54:51 INFO - Running main action method: install 18:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:54:51 INFO - Reading from file tmpfile_stdout 18:54:51 INFO - Detecting whether we're running mozinstall >=1.0... 18:54:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:54:51 INFO - Reading from file tmpfile_stdout 18:54:51 INFO - Output received: 18:54:51 INFO - Usage: mozinstall [options] installer 18:54:51 INFO - Options: 18:54:51 INFO - -h, --help show this help message and exit 18:54:51 INFO - -d DEST, --destination=DEST 18:54:51 INFO - Directory to install application into. [default: 18:54:51 INFO - "/builds/slave/test"] 18:54:51 INFO - --app=APP Application being installed. [default: firefox] 18:54:51 INFO - mkdir: /builds/slave/test/build/application 18:54:51 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'] 18:54:51 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 18:55:25 INFO - Reading from file tmpfile_stdout 18:55:25 INFO - Output received: 18:55:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:55:25 INFO - Running post-action listener: _resource_record_post_action 18:55:25 INFO - [mozharness: 2016-09-11 01:55:25.553409Z] Finished install step (success) 18:55:25 INFO - [mozharness: 2016-09-11 01:55:25.553557Z] Running run-tests step. 18:55:25 INFO - Running pre-action listener: _resource_record_pre_action 18:55:25 INFO - Running main action method: run_tests 18:55:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:55:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:55:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:55:25 INFO - 2016-09-10 18:55:25.567 screenresolution[1758:8603] starting screenresolution argv=screenresolution get 18:55:25 INFO - 2016-09-10 18:55:25.570 screenresolution[1758:8603] Display 0: 1600x1200x32@60 18:55:25 INFO - 2016-09-10 18:55:25.576 screenresolution[1759:8605] starting screenresolution argv=screenresolution list 18:55:25 INFO - Available Modes on Display 0 18:55:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:55:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:55:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:55:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:55:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:55:25 INFO - Intel Iris: 18:55:25 INFO - Chipset Model: Intel Iris 18:55:25 INFO - Type: GPU 18:55:25 INFO - Bus: Built-In 18:55:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:55:25 INFO - Vendor: Intel (0x8086) 18:55:25 INFO - Device ID: 0x0a2e 18:55:25 INFO - Revision ID: 0x0009 18:55:25 INFO - Displays: 18:55:25 INFO - Display: 18:55:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:55:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:55:25 INFO - Display Serial Number: 1600x1200 60 18:55:25 INFO - Main Display: Yes 18:55:25 INFO - Mirror: Off 18:55:25 INFO - Online: Yes 18:55:25 INFO - Rotation: Supported 18:55:25 INFO - Return code: 0 18:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:55:25 INFO - Python 2.7.3 18:55:25 INFO - Return code: 0 18:55:25 INFO - grabbing minidump binary from tooltool 18:55:25 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')]} 18:55:25 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 0x103146d30>, '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 0x102f6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10313eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:55:25 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 18:55:25 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 18:55:25 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 18:55:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:55:25 INFO - Return code: 0 18:55:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:55:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:55:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:55:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:55:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 18:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0063 --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 18:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P6E7PmAie7/Render', 18:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:25 INFO - 'HOME': '/Users/cltbld', 18:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:25 INFO - 'LOGNAME': 'cltbld', 18:55:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:55:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:25 INFO - 'MOZ_NO_REMOTE': '1', 18:55:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:55:25 INFO - 'NO_EM_RESTART': '1', 18:55:25 INFO - 'PAGER': '/bin/cat', 18:55:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:25 INFO - 'PWD': '/builds/slave/test', 18:55:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:25 INFO - 'SHELL': '/bin/bash', 18:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JCvgNk92DH/Listeners', 18:55:25 INFO - 'TMPDIR': '/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/', 18:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:25 INFO - 'USER': 'cltbld', 18:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:25 INFO - 'XPC_FLAGS': '0x0', 18:55:25 INFO - 'XPC_SERVICE_NAME': '0', 18:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0063', '--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 18:55:26 INFO - mozversion application_buildid: 20160910153615 18:55:26 INFO - mozversion application_changeset: 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd 18:55:26 INFO - mozversion application_display_name: Nightly 18:55:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:55:26 INFO - mozversion application_name: Firefox 18:55:26 INFO - mozversion application_remotingname: firefox 18:55:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:55:26 INFO - mozversion application_vendor: Mozilla 18:55:26 INFO - mozversion application_version: 51.0a1 18:55:26 INFO - mozversion platform_buildid: 20160910153615 18:55:26 INFO - mozversion platform_changeset: 2a9bff2793079f0581b7c3e68b9b940b3e9c20bd 18:55:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:55:26 INFO - mozversion platform_version: 51.0a1 18:55:26 INFO - using testdate: 1473558926 18:55:26 INFO - actual date: 1473558926 18:55:26 INFO - starting webserver on 'localhost:49275' 18:55:26 INFO - SUITE-START | Running 2 tests 18:55:26 INFO - TEST-START | dromaeo_css 18:55:26 INFO - operating with platform_type : mac_ 18:55:26 INFO - Initialising browser for dromaeo_css test... 18:55:26 INFO - TEST-INFO | started process 1769 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpNtFHy6/profile http://localhost:49275/getInfo.html) 18:55:28 INFO - PROCESS | 1769 | __metrics Screen width/height:1600/1200 18:55:28 INFO - PROCESS | 1769 | colorDepth:24 18:55:28 INFO - PROCESS | 1769 | Browser inner width/height: 1024/689 18:55:28 INFO - PROCESS | 1769 | __metrics 18:55:29 ERROR - PROCESS | 1769 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 18:55:29 INFO - TEST-INFO | 1769: exit 0 18:55:29 INFO - Browser initialized. 18:55:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:55:29 INFO - TEST-INFO | started process 1774 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmpNtFHy6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:55:41 INFO - PROCESS | 1774 | RSS: Main: 244555776 18:55:41 INFO - PROCESS | 1774 | 18:56:31 INFO - PROCESS | 1774 | 1473558991370 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] 18:58:43 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 18:58:44 INFO - PROCESS | 1774 | RSS: Main: 261787648 18:58:44 INFO - PROCESS | 1774 | 19:01:46 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 19:01:47 INFO - PROCESS | 1774 | RSS: Main: 266162176 19:01:47 INFO - PROCESS | 1774 | 19:04:49 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 19:04:50 INFO - PROCESS | 1774 | RSS: Main: 271417344 19:04:50 INFO - PROCESS | 1774 | 19:07:52 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 19:07:52 INFO - PROCESS | 1774 | RSS: Main: 268836864 19:07:52 INFO - PROCESS | 1774 | 19:10:55 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 19:10:56 INFO - PROCESS | 1774 | RSS: Main: 274341888 19:10:56 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 19:13:59 INFO - PROCESS | 1774 | RSS: Main: 276873216 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | __start_tp_report 19:13:59 INFO - PROCESS | 1774 | _x_x_mozilla_page_load 19:13:59 INFO - PROCESS | 1774 | _x_x_mozilla_page_load_details 19:13:59 INFO - PROCESS | 1774 | |i|pagename|runs| 19:13:59 INFO - PROCESS | 1774 | |0;dojo.html;3110.7511399088075;3354.077628652122;3355.932881342373;3390.1355154435614;3416.9487457688138;4844.636652251078;5193.714345710984;5615.101583746601;5662.575306851987;5665.801696940608;4985;5153.201253805666;5447.828716825881;5644.971775141129;5768.538516918637;4063.3092374296293;4598.632109431237;4707.788149533262;5144.8713782155555;5303.045451818671;5109;5296.894062118756;5314.548263397596;5333.519983201511;5340.786368545273;5182.378114626231;5236.554892834114;5237.869053273661;5250.501202385767;5260.947390526103;5263.3947096083675;5288.180332048539;5298.576113910916;5308.389535203424;5329.387120481156;5163.199704045879;5208.713520756357;5236.7119808410525;5263.210518422229;5293.655912365684;4151.564085771011;5653.971730141323;5675.233843431125;5678.091505359164;5737.168110623936;6168.414000669929;6191.102290167945;6238.532110091716;6242.15730876335;6245.46913512352;6248.75004999804;6248.968755156195;6255.280642726054;6256.436920677161;6277.403646653561;5721.971390143064;5768.538516918637;5810.041343178413;5888.470037696585;5923.348431672513;5905.7932972346625;5916.875793599203;6197;6250.249970003629;6299.992001279773;6040.486558642477;6185.381461853779;6189.84525386869;6201.937980620226;6225.972714502056;4374.868753937387;6061.787837425669;6241.063840423973;6270.3416141305415;6291.5281353898645;6113.938860611337;6174.5060395168275;6210.844728881814;6256.155419018465;6274.403931626488;6216.067589861466;6217.937820621826;6218.31598524162;6269.435750782361;6288.811335659938;4900.019996000814;4955.008998200375;5022.497750225021;5023.874403139951;5029.5221953914315;4440.2007638625355;4466.285394336891;4466.330050492452;4527.2077386457895;4628.95371046292;5282.181261904334;5391.703456309862;5407.83776486706;5422.8373148805595;5446.863828404243;6126.111713801474;6205.565610407228;6209.472194863487;6243.875122497525;6262.2172228471445;4468.4637843458995;5328.413874473805;5344.893102137936;5397.082495975694;5463.69949652765;4876.341693871353;5283.603729720209;5300.257963885059;5317.096093664087;5319.973400133052;6372.139761132281;6395.20059992501;6409.807705768834;6410.391012853772;6411.4870810336;6104.359042300672;6107.219158344477;6124.899720462962;6145.678679084019;6160.599561028519;6355.618662880242;6383.425491705769;6408.93591064102;6415.294317624965;6433.420992110732;4746.92879606813;5186.559142472932;5911.349751527415;6042.244719410074;6230.190075290183;5605.850800585788;5750.712464376848;5918.2898052234;6216.564840461305;6285.528585356208;6119.60918786207;6173;6178.289496707756;6181.412765787243;6202.534809889366;5994.610350327215;6127.601705889103;6136.754529818936;6168.475679567287;6193.907091393543;5162.406323272721;5849.94150058494;5935.376785437639;5990.700464976647;6100.8779824404155;6082.087686847009;6088.299845517822;6131.54013449002;6143.047827586553;6153.261608606996;6003.459688628044;6008.789692360747;6045.6070355426755;6047.9395206047375;6048.213732214784 19:13:59 INFO - PROCESS | 1774 | |1;ext.html;448.27379644975144;459.5060310166572;484.66073748376135;518.5747686896747;555.5527849950878;40547.175377108026;42257.57742422575;42336;42367.78816105919;42466.787666061704;42718.78640606793;43062.78468607653;43142.568574314246;43201.56798432015;43339.34990975138;9779.853302200472;9863.358881672668;9900;9977.351472154323;9996.400215987027;9717.31978761487;9750.46372449515;9767.706968790948;9880.95059524705;9894.06006429388;9779.951100244525;9851.901480985205;9891.802163956716;9916.504174791267;9919.20646348294;9770.560324785401;9818.656347027856;9871.60513579456;9881.851772223386;9887.159591434736;9713.174380177694;9788.804223915553;9796.755081122958;9804.166645835112;9829.655962041294;41023.58976410257;41192.176156477006;41324;41369.1726165475;41412.792936035126;3108.8290144042066;3305.983470082634;3354.5407747629615;3366.427707289742;4043.939340909889;30875.22811929697;31118;31279.06162815119;31539.84230078858;32612.021639350856;2906.1717410537894;3256.8697252109887;3287.7534184935985;3333.8999830005137;3363.6468170841954;482011;485448;488971;490222;490335.5483222597;61142.69428652828;61637.07544386838;61746.765064698455;61865.762684746056;62116;33896.830515847265;35078;35198.64801352005;64953.42756148363;65102.67448662821;16266.51200463988;16371.017738935701;16434.75347869783;16458.341666333214;16482.587935301475;12583.11918165738;12736.299503527394;12749.490020399266;12756.61730148097;12761.234325940473;18215.544611384823;19101.522461938563;19117.90441047786;19144.617107658047;19222.519437014187;8071.273585377344;8161.428699990944;8172.550509722022;8218.301444377175;8250.216229458312;837.2548431895618;931.0456781798575;973.3673112476805;986.4328011393477;990.4948476277151;26404.60393094105;29962;29985.40029199404;30485;30661.386772264428;993.3989936088562;1036.4247842447514;1043.4209013997154;1049.5854137615752;1109.589451902785;15449.613759656098;15510.30203640839;15573.14347710864;15595.844041559667;15889.761653575206;537679;671847;675669;677671;679896;57667.84664306774;57719.71140144272;58304.54238643983;58308;58693;1322.927239001864;1331.5938638715286;1364.126958746403;1366.8769810717083;1367.0840536840142;28622;28803.711962880105;29716.55425168581;29791;30489.390212195187;51474.74262628663;51737.48262517327;51775.74112129415;51815.74092129515;51889.48110518847;584379;586035;586580.0670996618;587326;588852.0557397186;26354.86822565875;26637.60043599309;26866.731332686424;26976;27110;46445.6066318011;47386.52613473821;47402.26324598326;48680.26979595238;48765.78085547889;30982.22544436407;32308.030759077265;36391.3623886931;37525.43711844379;39057.8047109774;36566.817165914;38787.41818872776;39033;39111.6088839108;39339.63581244146;32323;34623.65376346305;38578.80710596429;38805;39049.82850514489;32633.673663264013;34461;34881.8255908729;35128;35173.12067198341;22222.111115555195;24117.15589954343;25834.741652583234;25852.741472585032;26193.73806261913 19:13:59 INFO - PROCESS | 1774 | |2;jquery.html;367.36629314432605;368.11652035115736;374.5243540703307;381.44118865861543;390.06968380412707;19830.99999999998;20900.37298881036;21170.25904093355;21215.363539093833;21420.143194272212;16381.672366552692;20427.387178384633;20917.47706307343;21200.363989080313;21254.89372553139;5796.82609521715;5859.44335288147;6072.969635151818;6094.146819445281;6141.324454310023;3884.7086468514835;4078.796060196993;6121.816345509619;6162.969185154068;6193.1948846649875;5822.825315240529;6096.4513193812545;6118.724657390406;6126.23422072241;6126.632402055869;6007.879842403149;6110.572259941806;6623.53635245533;6677.198736151693;6782.694778734969;5813.854653633651;5848.67832269225;5903.232579764646;5909.7636094556165;5924.911126333065;12912.483500659962;13476.191428514321;23053;23574.29277121689;24672.383190420205;1950.6150633050552;2102.2852230241792;2179.553191595719;2305.5965206089036;2577.917274744593;9520.90479095214;9983.400995940267;10088.89911100887;10284.640037598649;10540.104091152261;1864.3015080370647;1951.5804102118113;1967.4687834284807;1970.4778233768063;2011.0347033424039;107953;122952.38523807413;122976;123568;126535;9268.72193834186;11433.256838305484;11508.769824603462;11589.536418543332;11811.704707382385;14525.491607793676;14531.491397801026;14546.927265363816;14574.344154513072;14593.708125837424;6860.382565569123;6952.965235173791;7449.813754656177;8357.874631880526;8589.914100858912;8301.2943899768;8346.374021948379;8452.366072544584;8603.913960860436;8621.094785047606;4124.670026397881;4455.955440445554;4471;4471.821127154877;4590.104929538707;1468.9130043767748;1867.6451474219855;1924.2976313645418;1925.6918892977055;1933.806619338055;712.6864179760888;755.920628334028;844.7212419901418;875.9781005474914;1045.5242864496636;15855;17232.224549895283;20765.58468830645;21300.36098917035;21524;781.1095351299523;933.1741408853078;941.5527624378366;965.792354643755;999.5252255178877;9480.715578532656;10886.149354609735;11319.943400282946;11336;11339.206255562032;495563;500411;506011;568957;583524;109429.45285273522;125000;133496;136997;139337;994.2145704893054;1014.4167103915278;1045.4145678419957;1046.2571574182416;1051.1853313681959;19026.619467610846;19063.332783352515;19404.320848770225;19732.210711571377;19990.400287991382;53499;57179.71410142923;57208;57499;57762.84474310574;78992.60503697445;79291.60354198192;80411.59794200992;80835;81345.59327203326;5485.945140548543;7194.352508274281;7220.422366210585;7281.5631062136445;7525.096988361432;9055.604148444334;10485.842712359168;12689.936550317188;12703.745925081632;13103.017273704701;9300.790897183322;9934;10723.463826808473;10952.45237738094;11345;9817.852732209165;9946.254030947563;10279.612252346136;10390.844137337795;10797.406142662228;11294.49174787153;11576.710582235512;11658.591949281734;11724.237924534878;11809.645710628694;7206.819829504304;7222.591594638995;7300.598467084362;7454.25457454272;7538.924610753822;8556.828863422572;9939.105480506789;11103.612048493938;11283.887161128612;11387.54449822031 19:13:59 INFO - PROCESS | 1774 | |3;mootools.html;1335.7595632786097;1413.7949997250396;1591.9413589962676;1830.954226144347;1891.7067854482552;8037.517748935068;8301.792455188623;8540.231379175873;9124.361294709364;9140.040295768948;8602;8888.822223555526;8917.598708058136;8984.595693193805;8985.730428087152;2377.4175327044854;2387.319613910036;2411.4574220800355;2411.8191135664897;2420.9394765130924;2338.205010296499;2346.155384061736;2382.988085059581;2383.1778036577352;2391.892364843586;2363.9054437822465;2372.252740386781;2378.63131214662;2387.271882075966;2401.4476670365825;2334.6498025296173;2339.953200935972;2340.754220806816;2341.508283260517;2343.261872510154;1882.7458293130526;2161.740591129059;2350.0129945422964;2356.446235134742;2357.834951553392;8831.955840220822;8863.867041994376;8949.418287811272;8952.820943581157;8959.776005599848;5231.686098834082;5246.65896716716;5386.949544838758;5391.056565101092;5423.403425623178;3508.701760350373;3611.4221724524214;3688.483612294281;3702.8148592570265;3708.9258214835554;954.6992697300328;975.0201047946769;1069.2301542889106;1087.0596933652412;1090.7709381029993;11611.303321800635;11634.47644855975;11742;11752.412379381083;11758.941205293919;4578.267477203632;4690.179218636725;4781.2350023996;4790.904181916342;4817.542333478314;9958.053984871425;10026.799464010679;10044.899551004397;10051.346662466916;10064.39613623185;5316.388615309212;5362.061639213122;5368.060589396918;5392.218128371443;5405;4275.422817919603;4388.363687265329;4596.37948876904;4729.95270047302;4853.9029219415415;3839.0594304395045;3913.373860182357;3966.5240171179066;4002.3196056670436;4002.9799851000557;1257.591282833083;1270.0855384123417;1280.8847203751707;1289.9097063205672;1291.606060151644;799.8760192170223;828.2214718165026;892.0588778838259;900.293269783217;942.123824842889;5904.7342869570975;5927.347991720907;6026.276846778414;6167.013277875519;6389.744410223539;886.1094599927033;898.4609234459402;906.2297047509564;924.3206243411088;935.9438433694;2953.128826995998;3370.241695618466;3896.279188350164;3982.26328129297;4106.897327566835;10631.255812093234;10731.409772462433;10810.513526891309;10935.945320273348;10976.670699879016;10701.143908487302;10787.190960677824;10890.564377424973;10901.454927253606;10912.672619821418;7992.16082311349;8116.837663246702;8120.878186827203;8122.106568277485;8129.837403251902;5674.886502270014;5966;5977.193078934288;5995.760169593167;6070.878582428415;9713.465759383302;10030.64892728751;10084.991500849856;10181.847272290774;10191.898081019095;8187.181281871765;10341.586336546754;10365.378077315385;10376.325538840254;10377.896221037692;6567.507436942344;6769.221539523018;6862.897056544056;7044.718211271696;7130.500864939404;7335.716249656182;8258.628361723617;8353.289970352345;8561.957190214009;8570.657173712982;6665.300143484864;7295.124585049828;7545.6981720730555;7556.395488361028;7562.659680314509;6033.849153771191;7374.225706300976;7417.221191774786;7427.034485516848;7488.775336739906;6936.306369363023;6965.79102626922;7048.330408611174;7065.1521817382245;7068.434525238072;5006.07387633289;5059.645824792229;5078.111330517071;5090.643654944119;5156.97421512905;3921.627445392683;5013.122703526796;5124.641275110706;5162.6902385856965;5204.973975130251 19:13:59 INFO - PROCESS | 1774 | |4;prototype.html;306.93861227754445;308.68514115602073;319.12241336325104;319.29595242490313;320.2394313505425;4906;4959.941624225947;5164.328637277158;5408.675479471234;5631.324241091075;5374.811881584145;5455.918161227575;5533.750981205845;5589.636673616212;5630.802921897734;3736.0099573612947;4036.636702696757;4066.8373265069363;4261.849300688812;4269.743815371072;3497.915646149689;3869.187470631157;4069.613386728256;4121.096372778672;4217.460626871185;4202.768847713374;4213;4235.703500754948;4237.7457352558795;4270.252705776476;4188.623023927846;4224.598663126998;4232.343986682069;4257.936130958038;4270.316749320125;3680.8527658893613;3688.889333320005;3703.814809259526;3711.46183803347;3711.4989476420606;4497.403421785252;4784.856454306377;5063.873403164914;5157.329547158883;5400.2198461077405;4029.6171863672903;4056.371262454324;4883.975580122076;4893.779779909877;4906.656534042619;5402.000629883484;5904.789518148769;5966.612170208922;5966.955782738003;5997.940020599782;3071;3405.8126803025825;3457.1184347991352;4046.6560342370935;4061.8375264989363;5332.440093790135;5575.665460072368;6054.576179667425;6090.421409966046;6158.538109641795;3438.432658611351;4153.356229784388;4153.982274342806;4170.624643782075;4173.812178451916;3403.370376480359;3562.8420763252047;3855.6890657176696;4169.937450938239;4190.009745370985;3784.67536362271;4241.87274381769;4251.978740106341;4621.376114224537;4638.489766125724;5498.395176530578;5940.55445841555;5954.702264886739;5997.190379298826;6056.576039677268;1348.548236340817;1379.3861731529373;1387.8126452928927;1443.6463066548763;1452.7724073158165;795.4511387142853;957.8132264208551;970.8107568229004;982.1259079419323;991.4101109839634;3594.802285874302;4032.737872038308;4133.561198892668;4299.935500967488;4502.594766470967;4284.207421627009;4387.371323253934;4444.955320552517;4741.581793285449;4973.701577905337;4513.833359987225;4542.341360502709;4543.909801491351;4561.794719237642;4572.634189264851;2402.0752010475744;2745.531140839681;2788.930276743057;3056.449839028952;3187.856546455415;5859.853503662443;6240.1031025680295;6430.421262086435;6450.387213214737;6450.41946224842;6145.081086483974;6197.752089916443;6337.429631333202;6398.5521013528605;6432.51756118293;6010.909836352458;6079.027355623168;6354.809355719336;6370.4073981504625;6408.148373973537;5125.129327795403;5544.421843842632;5807.912881306868;6126.969365153146;6154.261488621394;5447.206458316656;5540.69526176048;5891.528677705859;6038.7282572283475;6081.6351018939;6124.693765311806;6388.2972872984865;6397.616143031433;6422.518311126591;6451.741930322923;3818.1953138531258;3998.8200530976756;4008.7394319369146;4032.173404452139;4051.5340735815757;5084.796608135781;5738.368779434342;5843.590948633555;5871.911921321269;6061.515078793598;4697.342372067981;5003.799848006039;5240.292560504283;5635.323761148688;5781;5962.970185149046;5978.282606087298;6000.609960352737;6021.608595441282;6024.90962635552;4415.867523974286;5701.173329867229;5779.488017591145;5843.970780146072;5919.023361145363;5540.501354878081;5861.472467477947;5887.704704964901;5966.910496342472;5982.722353517256;5529.585281104014;5813.6221145625395;5828.173335931985;5830.708464576839;5873.765049398147 19:13:59 INFO - PROCESS | 1774 | |5;yui.html;669.4577392312227;732.9413646908245;746.2872956326709;776.7475570439609;821.7740121466597;15143.24283785809;15289.694206115872;15681.52955411338;15816;15829.84170158298;13784.793228101562;14018.299085045757;14019;14082.014259001873;14098.295085245722;829.4981536170635;847.7075408983891;851.2551517422255;851.6082602003055;852.8081181734091;675.3853992866492;693.4106009891602;829.6100832608664;847.631280393028;860.7632900952226;899.4783025844994;907.2061945797427;908.9863652045225;909.0137201136741;913.8720579118929;856.6102423397339;862.5169904853302;900.2348004196446;905.063259526387;906.0893801729286;633.5691729623854;650.4633677216316;679.3410391919879;682.011083928306;684.0525871667776;15072.773908391382;15078.095314281069;15153.393864245418;15248.847511524853;15261.847381526153;14312.355943982442;14488.927555362261;14538.345774440175;14889.851101488955;14930.730887874544;11907.523699052113;11983.520659173622;12035.27788332694;12267.325297108655;12361.011119110539;3794.6584807367194;3845.1540661054523;3862.1117143057254;3974.284628766821;3976.5426975897562;109731;110190.89809101887;112877;113356.43321783339;113576.43211783971;11349.205555611028;11369.715757106138;11397.658070257905;11530;11737.706557336134;22949.426264343525;23023.654645180002;23170.189043383736;23212.535749284918;23283.650745238836;6984.3364880336285;7722.53664780115;8037.1514551653445;8054.798630034296;8232.888560044317;3378.6621337866018;4378.978105109454;4422.469303683579;4449.510553839075;4605.6085232755595;3582.1402863313137;3681.9815900920325;3731.869384571581;3732.906677333034;3738.345789486829;664.7473959895306;670.956387834799;676.090658064902;678;694.944404447643;601.6360102138143;611.8894207014255;681.5842336174929;686.7836631461074;691.68182635987;9186.5760807075;9358.57886395114;9445.952770236105;9544;9891.208703303719;569.956978728925;661.1999480628493;689.9344562266677;700.7722490190708;702.2304979992873;4502.639788816887;4654.97672511642;4664.976675116671;4720.52536647106;4722.952770472251;96244.51877740567;144452;159102;161233;162673;136706;138580;139628.30185849007;140963;142858.1428371427;118959;120121;120602.99999999824;120961.79038209506;121323;8946.821063578822;9135.634574617206;9160.450372977377;9222.907770922206;9306.976232614543;65641.34358656353;65753.67123164353;66098.66950665216;66910.66178676301;67874.3212567868;61991.07013394713;62436.37563624488;73775.63112184619;86905;91038.54480727554;13345.86654133485;13361;13382.197068175941;13520.256385898476;14106.506272280421;14803.481878134213;14861.702765944838;14916.328765205375;15017.549473515812;15234.3906243749;13729.382177802223;13883;14065.789013164605;14134;14178.858211417753;13649.454021839016;13702.314884255547;13774.586762397144;13912.234827084607;14046.367913444124;12431.751364972832;12453.626391208278;12784.296863672587;13163.47346106145;13800.792988104986;10962.945185274342;11075.280106793032;11358.943205284251;11501;11531.884681153417;8792.868106978272;9699.854502182614;11485;11618.70953226176;11769.764604708029 19:13:59 INFO - PROCESS | 1774 | __end_tp_report 19:13:59 INFO - PROCESS | 1774 | __start_cc_report 19:13:59 INFO - PROCESS | 1774 | _x_x_mozilla_cycle_collect,393 19:13:59 INFO - PROCESS | 1774 | __end_cc_report 19:13:59 INFO - PROCESS | 1774 | __startTimestamp1473560039430__endTimestamp 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | ------- Summary: start ------- 19:13:59 INFO - PROCESS | 1774 | Number of tests: 6 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#0] dojo.html Cycles:165 Average:5650.16 Median:5911.35 stddev:694.36 (11.7%) stddev-sans-first:667.30 19:13:59 INFO - PROCESS | 1774 | Values: 3110.8 3354.1 3355.9 3390.1 3416.9 4844.6 5193.7 5615.1 5662.6 5665.8 4985.0 5153.2 5447.8 5645.0 5768.5 4063.3 4598.6 4707.8 5144.9 5303.0 5109.0 5296.9 5314.5 5333.5 5340.8 5182.4 5236.6 5237.9 5250.5 5260.9 5263.4 5288.2 5298.6 5308.4 5329.4 5163.2 5208.7 5236.7 5263.2 5293.7 4151.6 5654.0 5675.2 5678.1 5737.2 6168.4 6191.1 6238.5 6242.2 6245.5 6248.8 6249.0 6255.3 6256.4 6277.4 5722.0 5768.5 5810.0 5888.5 5923.3 5905.8 5916.9 6197.0 6250.2 6300.0 6040.5 6185.4 6189.8 6201.9 6226.0 4374.9 6061.8 6241.1 6270.3 6291.5 6113.9 6174.5 6210.8 6256.2 6274.4 6216.1 6217.9 6218.3 6269.4 6288.8 4900.0 4955.0 5022.5 5023.9 5029.5 4440.2 4466.3 4466.3 4527.2 4629.0 5282.2 5391.7 5407.8 5422.8 5446.9 6126.1 6205.6 6209.5 6243.9 6262.2 4468.5 5328.4 5344.9 5397.1 5463.7 4876.3 5283.6 5300.3 5317.1 5320.0 6372.1 6395.2 6409.8 6410.4 6411.5 6104.4 6107.2 6124.9 6145.7 6160.6 6355.6 6383.4 6408.9 6415.3 6433.4 4746.9 5186.6 5911.3 6042.2 6230.2 5605.9 5750.7 5918.3 6216.6 6285.5 6119.6 6173.0 6178.3 6181.4 6202.5 5994.6 6127.6 6136.8 6168.5 6193.9 5162.4 5849.9 5935.4 5990.7 6100.9 6082.1 6088.3 6131.5 6143.0 6153.3 6003.5 6008.8 6045.6 6047.9 6048.2 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#1] ext.html Cycles:180 Average:70381.13 Median:41346.59 stddev:154804.84 (374.4%) stddev-sans-first:155150.06 19:13:59 INFO - PROCESS | 1774 | Values: 448.3 459.5 484.7 518.6 555.6 40547.2 42257.6 42336.0 42367.8 42466.8 42718.8 43062.8 43142.6 43201.6 43339.3 9779.9 9863.4 9900.0 9977.4 9996.4 9717.3 9750.5 9767.7 9881.0 9894.1 9780.0 9851.9 9891.8 9916.5 9919.2 9770.6 9818.7 9871.6 9881.9 9887.2 9713.2 9788.8 9796.8 9804.2 9829.7 41023.6 41192.2 41324.0 41369.2 41412.8 3108.8 3306.0 3354.5 3366.4 4043.9 30875.2 31118.0 31279.1 31539.8 32612.0 2906.2 3256.9 3287.8 3333.9 3363.6 482011.0 485448.0 488971.0 490222.0 490335.5 61142.7 61637.1 61746.8 61865.8 62116.0 33896.8 35078.0 35198.6 64953.4 65102.7 16266.5 16371.0 16434.8 16458.3 16482.6 12583.1 12736.3 12749.5 12756.6 12761.2 18215.5 19101.5 19117.9 19144.6 19222.5 8071.3 8161.4 8172.6 8218.3 8250.2 837.3 931.0 973.4 986.4 990.5 26404.6 29962.0 29985.4 30485.0 30661.4 993.4 1036.4 1043.4 1049.6 1109.6 15449.6 15510.3 15573.1 15595.8 15889.8 537679.0 671847.0 675669.0 677671.0 679896.0 57667.8 57719.7 58304.5 58308.0 58693.0 1322.9 1331.6 1364.1 1366.9 1367.1 28622.0 28803.7 29716.6 29791.0 30489.4 51474.7 51737.5 51775.7 51815.7 51889.5 584379.0 586035.0 586580.1 587326.0 588852.1 26354.9 26637.6 26866.7 26976.0 27110.0 46445.6 47386.5 47402.3 48680.3 48765.8 30982.2 32308.0 36391.4 37525.4 39057.8 36566.8 38787.4 39033.0 39111.6 39339.6 32323.0 34623.7 38578.8 38805.0 39049.8 32633.7 34461.0 34881.8 35128.0 35173.1 22222.1 24117.2 25834.7 25852.7 26193.7 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#2] jquery.html Cycles:180 Average:33090.72 Median:371.32 stddev:89759.69 (24173.1%) stddev-sans-first:89977.87 19:13:59 INFO - PROCESS | 1774 | Values: 367.4 368.1 374.5 381.4 390.1 19831.0 20900.4 21170.3 21215.4 21420.1 16381.7 20427.4 20917.5 21200.4 21254.9 5796.8 5859.4 6073.0 6094.1 6141.3 3884.7 4078.8 6121.8 6163.0 6193.2 5822.8 6096.5 6118.7 6126.2 6126.6 6007.9 6110.6 6623.5 6677.2 6782.7 5813.9 5848.7 5903.2 5909.8 5924.9 12912.5 13476.2 23053.0 23574.3 24672.4 1950.6 2102.3 2179.6 2305.6 2577.9 9520.9 9983.4 10088.9 10284.6 10540.1 1864.3 1951.6 1967.5 1970.5 2011.0 107953.0 122952.4 122976.0 123568.0 126535.0 9268.7 11433.3 11508.8 11589.5 11811.7 14525.5 14531.5 14546.9 14574.3 14593.7 6860.4 6953.0 7449.8 8357.9 8589.9 8301.3 8346.4 8452.4 8603.9 8621.1 4124.7 4456.0 4471.0 4471.8 4590.1 1468.9 1867.6 1924.3 1925.7 1933.8 712.7 755.9 844.7 876.0 1045.5 15855.0 17232.2 20765.6 21300.4 21524.0 781.1 933.2 941.6 965.8 999.5 9480.7 10886.1 11319.9 11336.0 11339.2 495563.0 500411.0 506011.0 568957.0 583524.0 109429.5 125000.0 133496.0 136997.0 139337.0 994.2 1014.4 1045.4 1046.3 1051.2 19026.6 19063.3 19404.3 19732.2 19990.4 53499.0 57179.7 57208.0 57499.0 57762.8 78992.6 79291.6 80411.6 80835.0 81345.6 5485.9 7194.4 7220.4 7281.6 7525.1 9055.6 10485.8 12689.9 12703.7 13103.0 9300.8 9934.0 10723.5 10952.5 11345.0 9817.9 9946.3 10279.6 10390.8 10797.4 11294.5 11576.7 11658.6 11724.2 11809.6 7206.8 7222.6 7300.6 7454.3 7538.9 8556.8 9939.1 11103.6 11283.9 11387.5 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#3] mootools.html Cycles:180 Average:5697.49 Median:4929.99 stddev:3219.80 (65.3%) stddev-sans-first:3212.15 19:13:59 INFO - PROCESS | 1774 | Values: 1335.8 1413.8 1591.9 1831.0 1891.7 8037.5 8301.8 8540.2 9124.4 9140.0 8602.0 8888.8 8917.6 8984.6 8985.7 2377.4 2387.3 2411.5 2411.8 2420.9 2338.2 2346.2 2383.0 2383.2 2391.9 2363.9 2372.3 2378.6 2387.3 2401.4 2334.6 2340.0 2340.8 2341.5 2343.3 1882.7 2161.7 2350.0 2356.4 2357.8 8832.0 8863.9 8949.4 8952.8 8959.8 5231.7 5246.7 5386.9 5391.1 5423.4 3508.7 3611.4 3688.5 3702.8 3708.9 954.7 975.0 1069.2 1087.1 1090.8 11611.3 11634.5 11742.0 11752.4 11758.9 4578.3 4690.2 4781.2 4790.9 4817.5 9958.1 10026.8 10044.9 10051.3 10064.4 5316.4 5362.1 5368.1 5392.2 5405.0 4275.4 4388.4 4596.4 4730.0 4853.9 3839.1 3913.4 3966.5 4002.3 4003.0 1257.6 1270.1 1280.9 1289.9 1291.6 799.9 828.2 892.1 900.3 942.1 5904.7 5927.3 6026.3 6167.0 6389.7 886.1 898.5 906.2 924.3 935.9 2953.1 3370.2 3896.3 3982.3 4106.9 10631.3 10731.4 10810.5 10935.9 10976.7 10701.1 10787.2 10890.6 10901.5 10912.7 7992.2 8116.8 8120.9 8122.1 8129.8 5674.9 5966.0 5977.2 5995.8 6070.9 9713.5 10030.6 10085.0 10181.8 10191.9 8187.2 10341.6 10365.4 10376.3 10377.9 6567.5 6769.2 6862.9 7044.7 7130.5 7335.7 8258.6 8353.3 8562.0 8570.7 6665.3 7295.1 7545.7 7556.4 7562.7 6033.8 7374.2 7417.2 7427.0 7488.8 6936.3 6965.8 7048.3 7065.2 7068.4 5006.1 5059.6 5078.1 5090.6 5157.0 3921.6 5013.1 5124.6 5162.7 5205.0 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#4] prototype.html Cycles:180 Average:4649.95 Median:4988.75 stddev:1490.86 (29.9%) stddev-sans-first:1458.97 19:13:59 INFO - PROCESS | 1774 | Values: 306.9 308.7 319.1 319.3 320.2 4906.0 4959.9 5164.3 5408.7 5631.3 5374.8 5455.9 5533.8 5589.6 5630.8 3736.0 4036.6 4066.8 4261.8 4269.7 3497.9 3869.2 4069.6 4121.1 4217.5 4202.8 4213.0 4235.7 4237.7 4270.3 4188.6 4224.6 4232.3 4257.9 4270.3 3680.9 3688.9 3703.8 3711.5 3711.5 4497.4 4784.9 5063.9 5157.3 5400.2 4029.6 4056.4 4884.0 4893.8 4906.7 5402.0 5904.8 5966.6 5967.0 5997.9 3071.0 3405.8 3457.1 4046.7 4061.8 5332.4 5575.7 6054.6 6090.4 6158.5 3438.4 4153.4 4154.0 4170.6 4173.8 3403.4 3562.8 3855.7 4169.9 4190.0 3784.7 4241.9 4252.0 4621.4 4638.5 5498.4 5940.6 5954.7 5997.2 6056.6 1348.5 1379.4 1387.8 1443.6 1452.8 795.5 957.8 970.8 982.1 991.4 3594.8 4032.7 4133.6 4299.9 4502.6 4284.2 4387.4 4445.0 4741.6 4973.7 4513.8 4542.3 4543.9 4561.8 4572.6 2402.1 2745.5 2788.9 3056.4 3187.9 5859.9 6240.1 6430.4 6450.4 6450.4 6145.1 6197.8 6337.4 6398.6 6432.5 6010.9 6079.0 6354.8 6370.4 6408.1 5125.1 5544.4 5807.9 6127.0 6154.3 5447.2 5540.7 5891.5 6038.7 6081.6 6124.7 6388.3 6397.6 6422.5 6451.7 3818.2 3998.8 4008.7 4032.2 4051.5 5084.8 5738.4 5843.6 5871.9 6061.5 4697.3 5003.8 5240.3 5635.3 5781.0 5963.0 5978.3 6000.6 6021.6 6024.9 4415.9 5701.2 5779.5 5844.0 5919.0 5540.5 5861.5 5887.7 5966.9 5982.7 5529.6 5813.6 5828.2 5830.7 5873.8 19:13:59 INFO - PROCESS | 1774 | 19:13:59 INFO - PROCESS | 1774 | [#5] yui.html Cycles:180 Average:25154.20 Median:3732.39 stddev:40525.99 (1085.8%) stddev-sans-first:40597.98 19:13:59 INFO - PROCESS | 1774 | Values: 669.5 732.9 746.3 776.7 821.8 15143.2 15289.7 15681.5 15816.0 15829.8 13784.8 14018.3 14019.0 14082.0 14098.3 829.5 847.7 851.3 851.6 852.8 675.4 693.4 829.6 847.6 860.8 899.5 907.2 909.0 909.0 913.9 856.6 862.5 900.2 905.1 906.1 633.6 650.5 679.3 682.0 684.1 15072.8 15078.1 15153.4 15248.8 15261.8 14312.4 14488.9 14538.3 14889.9 14930.7 11907.5 11983.5 12035.3 12267.3 12361.0 3794.7 3845.2 3862.1 3974.3 3976.5 109731.0 110190.9 112877.0 113356.4 113576.4 11349.2 11369.7 11397.7 11530.0 11737.7 22949.4 23023.7 23170.2 23212.5 23283.7 6984.3 7722.5 8037.2 8054.8 8232.9 3378.7 4379.0 4422.5 4449.5 4605.6 3582.1 3682.0 3731.9 3732.9 3738.3 664.7 671.0 676.1 678.0 694.9 601.6 611.9 681.6 686.8 691.7 9186.6 9358.6 9446.0 9544.0 9891.2 570.0 661.2 689.9 700.8 702.2 4502.6 4655.0 4665.0 4720.5 4723.0 96244.5 144452.0 159102.0 161233.0 162673.0 136706.0 138580.0 139628.3 140963.0 142858.1 118959.0 120121.0 120603.0 120961.8 121323.0 8946.8 9135.6 9160.5 9222.9 9307.0 65641.3 65753.7 66098.7 66910.7 67874.3 61991.1 62436.4 73775.6 86905.0 91038.5 13345.9 13361.0 13382.2 13520.3 14106.5 14803.5 14861.7 14916.3 15017.5 15234.4 13729.4 13883.0 14065.8 14134.0 14178.9 13649.5 13702.3 13774.6 13912.2 14046.4 12431.8 12453.6 12784.3 13163.5 13800.8 10962.9 11075.3 11358.9 11501.0 11531.9 8792.9 9699.9 11485.0 11618.7 11769.8 19:13:59 INFO - PROCESS | 1774 | -------- Summary: end -------- 19:13:59 INFO - PROCESS | 1774 | 19:14:00 INFO - TEST-INFO | 1774: exit 0 19:14:00 INFO - TEST-OK | dromaeo_css | took 1113991ms 19:14:00 INFO - TEST-START | kraken 19:14:00 INFO - operating with platform_type : mac_ 19:14:00 INFO - Initialising browser for kraken test... 19:14:00 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmphiOYIO/profile http://localhost:49275/getInfo.html) 19:14:02 INFO - PROCESS | 1893 | __metrics Screen width/height:1600/1200 19:14:02 INFO - PROCESS | 1893 | colorDepth:24 19:14:02 INFO - PROCESS | 1893 | Browser inner width/height: 1024/689 19:14:02 INFO - PROCESS | 1893 | __metrics 19:14:03 ERROR - PROCESS | 1893 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 19:14:03 INFO - TEST-INFO | 1893: exit 0 19:14:03 INFO - Browser initialized. 19:14:03 INFO - Running cycle 1/1 for kraken test... 19:14:03 INFO - TEST-INFO | started process 1895 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/tmphiOYIO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:14:15 INFO - PROCESS | 1895 | RSS: Main: 244371456 19:14:15 INFO - PROCESS | 1895 | 19:14:18 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 19:14:19 INFO - PROCESS | 1895 | RSS: Main: 257040384 19:14:19 INFO - PROCESS | 1895 | 19:14:22 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 19:14:22 INFO - PROCESS | 1895 | RSS: Main: 265674752 19:14:22 INFO - PROCESS | 1895 | 19:14:26 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 19:14:26 INFO - PROCESS | 1895 | RSS: Main: 264437760 19:14:26 INFO - PROCESS | 1895 | 19:14:28 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 19:14:29 INFO - PROCESS | 1895 | RSS: Main: 265785344 19:14:29 INFO - PROCESS | 1895 | 19:14:31 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:14:32 INFO - PROCESS | 1895 | RSS: Main: 266149888 19:14:32 INFO - PROCESS | 1895 | 19:14:35 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 19:14:36 INFO - PROCESS | 1895 | RSS: Main: 268292096 19:14:36 INFO - PROCESS | 1895 | 19:14:39 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 19:14:39 INFO - PROCESS | 1895 | RSS: Main: 268513280 19:14:39 INFO - PROCESS | 1895 | 19:14:42 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 19:14:43 INFO - PROCESS | 1895 | RSS: Main: 268636160 19:14:43 INFO - PROCESS | 1895 | 19:14:45 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:14:45 INFO - PROCESS | 1895 | RSS: Main: 267915264 19:14:45 INFO - PROCESS | 1895 | 19:14:47 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:14:48 INFO - PROCESS | 1895 | RSS: Main: 268066816 19:14:48 INFO - PROCESS | 1895 | 19:14:50 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:14:51 INFO - PROCESS | 1895 | RSS: Main: 268419072 19:14:51 INFO - PROCESS | 1895 | 19:14:54 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:14:54 INFO - PROCESS | 1895 | RSS: Main: 268730368 19:14:54 INFO - PROCESS | 1895 | 19:14:57 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:14:58 INFO - PROCESS | 1895 | RSS: Main: 268988416 19:14:58 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 19:15:00 INFO - PROCESS | 1895 | RSS: Main: 271126528 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | __start_tp_report 19:15:00 INFO - PROCESS | 1895 | _x_x_mozilla_page_load 19:15:00 INFO - PROCESS | 1895 | _x_x_mozilla_page_load_details 19:15:00 INFO - PROCESS | 1895 | |i|pagename|runs| 19:15:00 INFO - PROCESS | 1895 | |0;ai-astar;108;116;117;115;117;116;118;117;116;133 19:15:00 INFO - PROCESS | 1895 | |1;audio-beat-detection;163;144;173;165;127;135;130;100;167;121 19:15:00 INFO - PROCESS | 1895 | |2;audio-dft;197;204;211;275;204;282;276;203;191;194 19:15:00 INFO - PROCESS | 1895 | |3;audio-fft;69;83;78;148;59;58;61;60;136;78 19:15:00 INFO - PROCESS | 1895 | |4;audio-oscillator;69;77;130;71;72;86;107;70;73;113 19:15:00 INFO - PROCESS | 1895 | |5;imaging-gaussian-blur;135;131;92;97;137;86;107;101;109;132 19:15:00 INFO - PROCESS | 1895 | |6;imaging-darkroom;95;96;119;92;153;153;97;94;94;97 19:15:00 INFO - PROCESS | 1895 | |7;imaging-desaturate;140;99;108;145;78;146;152;93;95;91 19:15:00 INFO - PROCESS | 1895 | |8;json-parse-financial;56;59;58;57;56;59;59;57;56;58 19:15:00 INFO - PROCESS | 1895 | |9;json-stringify-tinderbox;65;70;67;75;80;74;72;73;69;69 19:15:00 INFO - PROCESS | 1895 | |10;stanford-crypto-aes;90;83;82;96;81;100;80;99;113;112 19:15:00 INFO - PROCESS | 1895 | |11;stanford-crypto-ccm;158;137;152;160;169;159;167;157;186;143 19:15:00 INFO - PROCESS | 1895 | |12;stanford-crypto-pbkdf2;169;172;182;241;180;181;232;170;162;237 19:15:00 INFO - PROCESS | 1895 | |13;stanford-crypto-sha256-iterative;70;69;69;71;82;93;91;72;67;70 19:15:00 INFO - PROCESS | 1895 | __end_tp_report 19:15:00 INFO - PROCESS | 1895 | __start_cc_report 19:15:00 INFO - PROCESS | 1895 | _x_x_mozilla_cycle_collect,991 19:15:00 INFO - PROCESS | 1895 | __end_cc_report 19:15:00 INFO - PROCESS | 1895 | __startTimestamp1473560100651__endTimestamp 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | ------- Summary: start ------- 19:15:00 INFO - PROCESS | 1895 | Number of tests: 14 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#0] ai-astar Cycles:10 Average:117.30 Median:117.00 stddev:6.18 (5.3%) stddev-sans-first:5.57 19:15:00 INFO - PROCESS | 1895 | Values: 108.0 116.0 117.0 115.0 117.0 116.0 118.0 117.0 116.0 133.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:153.50 stddev:23.99 (15.6%) stddev-sans-first:24.27 19:15:00 INFO - PROCESS | 1895 | Values: 163.0 144.0 173.0 165.0 127.0 135.0 130.0 100.0 167.0 121.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#2] audio-dft Cycles:10 Average:223.70 Median:207.50 stddev:37.71 (18.2%) stddev-sans-first:38.74 19:15:00 INFO - PROCESS | 1895 | Values: 197.0 204.0 211.0 275.0 204.0 282.0 276.0 203.0 191.0 194.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#3] audio-fft Cycles:10 Average:83.00 Median:65.00 stddev:32.48 (50.0%) stddev-sans-first:34.05 19:15:00 INFO - PROCESS | 1895 | Values: 69.0 83.0 78.0 148.0 59.0 58.0 61.0 60.0 136.0 78.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:71.50 stddev:21.90 (30.6%) stddev-sans-first:22.26 19:15:00 INFO - PROCESS | 1895 | Values: 69.0 77.0 130.0 71.0 72.0 86.0 107.0 70.0 73.0 113.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:136.00 stddev:19.34 (14.2%) stddev-sans-first:18.75 19:15:00 INFO - PROCESS | 1895 | Values: 135.0 131.0 92.0 97.0 137.0 86.0 107.0 101.0 109.0 132.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:94.50 stddev:24.41 (25.8%) stddev-sans-first:25.36 19:15:00 INFO - PROCESS | 1895 | Values: 95.0 96.0 119.0 92.0 153.0 153.0 97.0 94.0 94.0 97.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#7] imaging-desaturate Cycles:10 Average:114.70 Median:84.50 stddev:27.86 (33.0%) stddev-sans-first:28.01 19:15:00 INFO - PROCESS | 1895 | Values: 140.0 99.0 108.0 145.0 78.0 146.0 152.0 93.0 95.0 91.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.27 (2.2%) stddev-sans-first:1.22 19:15:00 INFO - PROCESS | 1895 | Values: 56.0 59.0 58.0 57.0 56.0 59.0 59.0 57.0 56.0 58.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.50 stddev:4.35 (6.0%) stddev-sans-first:3.95 19:15:00 INFO - PROCESS | 1895 | Values: 65.0 70.0 67.0 75.0 80.0 74.0 72.0 73.0 69.0 69.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:82.50 stddev:12.45 (15.1%) stddev-sans-first:13.13 19:15:00 INFO - PROCESS | 1895 | Values: 90.0 83.0 82.0 96.0 81.0 100.0 80.0 99.0 113.0 112.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#11] stanford-crypto-ccm Cycles:10 Average:158.80 Median:159.50 stddev:13.69 (8.6%) stddev-sans-first:14.52 19:15:00 INFO - PROCESS | 1895 | Values: 158.0 137.0 152.0 160.0 169.0 159.0 167.0 157.0 186.0 143.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.60 Median:181.50 stddev:31.09 (17.1%) stddev-sans-first:31.78 19:15:00 INFO - PROCESS | 1895 | Values: 169.0 172.0 182.0 241.0 180.0 181.0 232.0 170.0 162.0 237.0 19:15:00 INFO - PROCESS | 1895 | 19:15:00 INFO - PROCESS | 1895 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.40 Median:71.50 stddev:9.65 (13.5%) stddev-sans-first:10.04 19:15:00 INFO - PROCESS | 1895 | Values: 70.0 69.0 69.0 71.0 82.0 93.0 91.0 72.0 67.0 70.0 19:15:00 INFO - PROCESS | 1895 | -------- Summary: end -------- 19:15:00 INFO - PROCESS | 1895 | 19:15:01 INFO - TEST-INFO | 1895: exit 0 19:15:01 INFO - TEST-OK | kraken | took 61460ms 19:15:01 INFO - SUITE-END | took 1175s 19:15:01 INFO - Completed test suite (00:19:35) 19:15:01 INFO - javascript benchmark 19:15:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7345.244603387587, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.7511399088075, 3354.077628652122, 3355.932881342373, 3390.1355154435614, 3416.9487457688138, 4844.636652251078, 5193.714345710984, 5615.101583746601, 5662.575306851987, 5665.801696940608, 4985.0, 5153.201253805666, 5447.828716825881, 5644.971775141129, 5768.538516918637, 4063.3092374296293, 4598.632109431237, 4707.788149533262, 5144.8713782155555, 5303.045451818671, 5109.0, 5296.894062118756, 5314.548263397596, 5333.519983201511, 5340.786368545273, 5182.378114626231, 5236.554892834114, 5237.869053273661, 5250.501202385767, 5260.947390526103, 5263.3947096083675, 5288.180332048539, 5298.576113910916, 5308.389535203424, 5329.387120481156, 5163.199704045879, 5208.713520756357, 5236.7119808410525, 5263.210518422229, 5293.655912365684, 4151.564085771011, 5653.971730141323, 5675.233843431125, 5678.091505359164, 5737.168110623936, 6168.414000669929, 6191.102290167945, 6238.532110091716, 6242.15730876335, 6245.46913512352, 6248.75004999804, 6248.968755156195, 6255.280642726054, 6256.436920677161, 6277.403646653561, 5721.971390143064, 5768.538516918637, 5810.041343178413, 5888.470037696585, 5923.348431672513, 5905.7932972346625, 5916.875793599203, 6197.0, 6250.249970003629, 6299.992001279773, 6040.486558642477, 6185.381461853779, 6189.84525386869, 6201.937980620226, 6225.972714502056, 4374.868753937387, 6061.787837425669, 6241.063840423973, 6270.3416141305415, 6291.5281353898645, 6113.938860611337, 6174.5060395168275, 6210.844728881814, 6256.155419018465, 6274.403931626488, 6216.067589861466, 6217.937820621826, 6218.31598524162, 6269.435750782361, 6288.811335659938, 4900.019996000814, 4955.008998200375, 5022.497750225021, 5023.874403139951, 5029.5221953914315, 4440.2007638625355, 4466.285394336891, 4466.330050492452, 4527.2077386457895, 4628.95371046292, 5282.181261904334, 5391.703456309862, 5407.83776486706, 5422.8373148805595, 5446.863828404243, 6126.111713801474, 6205.565610407228, 6209.472194863487, 6243.875122497525, 6262.2172228471445, 4468.4637843458995, 5328.413874473805, 5344.893102137936, 5397.082495975694, 5463.69949652765, 4876.341693871353, 5283.603729720209, 5300.257963885059, 5317.096093664087, 5319.973400133052, 6372.139761132281, 6395.20059992501, 6409.807705768834, 6410.391012853772, 6411.4870810336, 6104.359042300672, 6107.219158344477, 6124.899720462962, 6145.678679084019, 6160.599561028519, 6355.618662880242, 6383.425491705769, 6408.93591064102, 6415.294317624965, 6433.420992110732, 4746.92879606813, 5186.559142472932, 5911.349751527415, 6042.244719410074, 6230.190075290183, 5605.850800585788, 5750.712464376848, 5918.2898052234, 6216.564840461305, 6285.528585356208, 6119.60918786207, 6173.0, 6178.289496707756, 6181.412765787243, 6202.534809889366, 5994.610350327215, 6127.601705889103, 6136.754529818936, 6168.475679567287, 6193.907091393543, 5162.406323272721, 5849.94150058494, 5935.376785437639, 5990.700464976647, 6100.8779824404155, 6082.087686847009, 6088.299845517822, 6131.54013449002, 6143.047827586553, 6153.261608606996, 6003.459688628044, 6008.789692360747, 6045.6070355426755, 6047.9395206047375, 6048.213732214784], "value": 5607.324965776093, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.27379644975144, 459.5060310166572, 484.66073748376135, 518.5747686896747, 555.5527849950878, 40547.175377108026, 42257.57742422575, 42336.0, 42367.78816105919, 42466.787666061704, 42718.78640606793, 43062.78468607653, 43142.568574314246, 43201.56798432015, 43339.34990975138, 9779.853302200472, 9863.358881672668, 9900.0, 9977.351472154323, 9996.400215987027, 9717.31978761487, 9750.46372449515, 9767.706968790948, 9880.95059524705, 9894.06006429388, 9779.951100244525, 9851.901480985205, 9891.802163956716, 9916.504174791267, 9919.20646348294, 9770.560324785401, 9818.656347027856, 9871.60513579456, 9881.851772223386, 9887.159591434736, 9713.174380177694, 9788.804223915553, 9796.755081122958, 9804.166645835112, 9829.655962041294, 41023.58976410257, 41192.176156477006, 41324.0, 41369.1726165475, 41412.792936035126, 3108.8290144042066, 3305.983470082634, 3354.5407747629615, 3366.427707289742, 4043.939340909889, 30875.22811929697, 31118.0, 31279.06162815119, 31539.84230078858, 32612.021639350856, 2906.1717410537894, 3256.8697252109887, 3287.7534184935985, 3333.8999830005137, 3363.6468170841954, 482011.0, 485448.0, 488971.0, 490222.0, 490335.5483222597, 61142.69428652828, 61637.07544386838, 61746.765064698455, 61865.762684746056, 62116.0, 33896.830515847265, 35078.0, 35198.64801352005, 64953.42756148363, 65102.67448662821, 16266.51200463988, 16371.017738935701, 16434.75347869783, 16458.341666333214, 16482.587935301475, 12583.11918165738, 12736.299503527394, 12749.490020399266, 12756.61730148097, 12761.234325940473, 18215.544611384823, 19101.522461938563, 19117.90441047786, 19144.617107658047, 19222.519437014187, 8071.273585377344, 8161.428699990944, 8172.550509722022, 8218.301444377175, 8250.216229458312, 837.2548431895618, 931.0456781798575, 973.3673112476805, 986.4328011393477, 990.4948476277151, 26404.60393094105, 29962.0, 29985.40029199404, 30485.0, 30661.386772264428, 993.3989936088562, 1036.4247842447514, 1043.4209013997154, 1049.5854137615752, 1109.589451902785, 15449.613759656098, 15510.30203640839, 15573.14347710864, 15595.844041559667, 15889.761653575206, 537679.0, 671847.0, 675669.0, 677671.0, 679896.0, 57667.84664306774, 57719.71140144272, 58304.54238643983, 58308.0, 58693.0, 1322.927239001864, 1331.5938638715286, 1364.126958746403, 1366.8769810717083, 1367.0840536840142, 28622.0, 28803.711962880105, 29716.55425168581, 29791.0, 30489.390212195187, 51474.74262628663, 51737.48262517327, 51775.74112129415, 51815.74092129515, 51889.48110518847, 584379.0, 586035.0, 586580.0670996618, 587326.0, 588852.0557397186, 26354.86822565875, 26637.60043599309, 26866.731332686424, 26976.0, 27110.0, 46445.6066318011, 47386.52613473821, 47402.26324598326, 48680.26979595238, 48765.78085547889, 30982.22544436407, 32308.030759077265, 36391.3623886931, 37525.43711844379, 39057.8047109774, 36566.817165914, 38787.41818872776, 39033.0, 39111.6088839108, 39339.63581244146, 32323.0, 34623.65376346305, 38578.80710596429, 38805.0, 39049.82850514489, 32633.673663264013, 34461.0, 34881.8255908729, 35128.0, 35173.12067198341, 22222.111115555195, 24117.15589954343, 25834.741652583234, 25852.741472585032, 26193.73806261913], "value": 19938.611749112268, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.36629314432605, 368.11652035115736, 374.5243540703307, 381.44118865861543, 390.06968380412707, 19830.99999999998, 20900.37298881036, 21170.25904093355, 21215.363539093833, 21420.143194272212, 16381.672366552692, 20427.387178384633, 20917.47706307343, 21200.363989080313, 21254.89372553139, 5796.82609521715, 5859.44335288147, 6072.969635151818, 6094.146819445281, 6141.324454310023, 3884.7086468514835, 4078.796060196993, 6121.816345509619, 6162.969185154068, 6193.1948846649875, 5822.825315240529, 6096.4513193812545, 6118.724657390406, 6126.23422072241, 6126.632402055869, 6007.879842403149, 6110.572259941806, 6623.53635245533, 6677.198736151693, 6782.694778734969, 5813.854653633651, 5848.67832269225, 5903.232579764646, 5909.7636094556165, 5924.911126333065, 12912.483500659962, 13476.191428514321, 23053.0, 23574.29277121689, 24672.383190420205, 1950.6150633050552, 2102.2852230241792, 2179.553191595719, 2305.5965206089036, 2577.917274744593, 9520.90479095214, 9983.400995940267, 10088.89911100887, 10284.640037598649, 10540.104091152261, 1864.3015080370647, 1951.5804102118113, 1967.4687834284807, 1970.4778233768063, 2011.0347033424039, 107953.0, 122952.38523807413, 122976.0, 123568.0, 126535.0, 9268.72193834186, 11433.256838305484, 11508.769824603462, 11589.536418543332, 11811.704707382385, 14525.491607793676, 14531.491397801026, 14546.927265363816, 14574.344154513072, 14593.708125837424, 6860.382565569123, 6952.965235173791, 7449.813754656177, 8357.874631880526, 8589.914100858912, 8301.2943899768, 8346.374021948379, 8452.366072544584, 8603.913960860436, 8621.094785047606, 4124.670026397881, 4455.955440445554, 4471.0, 4471.821127154877, 4590.104929538707, 1468.9130043767748, 1867.6451474219855, 1924.2976313645418, 1925.6918892977055, 1933.806619338055, 712.6864179760888, 755.920628334028, 844.7212419901418, 875.9781005474914, 1045.5242864496636, 15855.0, 17232.224549895283, 20765.58468830645, 21300.36098917035, 21524.0, 781.1095351299523, 933.1741408853078, 941.5527624378366, 965.792354643755, 999.5252255178877, 9480.715578532656, 10886.149354609735, 11319.943400282946, 11336.0, 11339.206255562032, 495563.0, 500411.0, 506011.0, 568957.0, 583524.0, 109429.45285273522, 125000.0, 133496.0, 136997.0, 139337.0, 994.2145704893054, 1014.4167103915278, 1045.4145678419957, 1046.2571574182416, 1051.1853313681959, 19026.619467610846, 19063.332783352515, 19404.320848770225, 19732.210711571377, 19990.400287991382, 53499.0, 57179.71410142923, 57208.0, 57499.0, 57762.84474310574, 78992.60503697445, 79291.60354198192, 80411.59794200992, 80835.0, 81345.59327203326, 5485.945140548543, 7194.352508274281, 7220.422366210585, 7281.5631062136445, 7525.096988361432, 9055.604148444334, 10485.842712359168, 12689.936550317188, 12703.745925081632, 13103.017273704701, 9300.790897183322, 9934.0, 10723.463826808473, 10952.45237738094, 11345.0, 9817.852732209165, 9946.254030947563, 10279.612252346136, 10390.844137337795, 10797.406142662228, 11294.49174787153, 11576.710582235512, 11658.591949281734, 11724.237924534878, 11809.645710628694, 7206.819829504304, 7222.591594638995, 7300.598467084362, 7454.25457454272, 7538.924610753822, 8556.828863422572, 9939.105480506789, 11103.612048493938, 11283.887161128612, 11387.54449822031], "value": 9320.204946718106, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.7595632786097, 1413.7949997250396, 1591.9413589962676, 1830.954226144347, 1891.7067854482552, 8037.517748935068, 8301.792455188623, 8540.231379175873, 9124.361294709364, 9140.040295768948, 8602.0, 8888.822223555526, 8917.598708058136, 8984.595693193805, 8985.730428087152, 2377.4175327044854, 2387.319613910036, 2411.4574220800355, 2411.8191135664897, 2420.9394765130924, 2338.205010296499, 2346.155384061736, 2382.988085059581, 2383.1778036577352, 2391.892364843586, 2363.9054437822465, 2372.252740386781, 2378.63131214662, 2387.271882075966, 2401.4476670365825, 2334.6498025296173, 2339.953200935972, 2340.754220806816, 2341.508283260517, 2343.261872510154, 1882.7458293130526, 2161.740591129059, 2350.0129945422964, 2356.446235134742, 2357.834951553392, 8831.955840220822, 8863.867041994376, 8949.418287811272, 8952.820943581157, 8959.776005599848, 5231.686098834082, 5246.65896716716, 5386.949544838758, 5391.056565101092, 5423.403425623178, 3508.701760350373, 3611.4221724524214, 3688.483612294281, 3702.8148592570265, 3708.9258214835554, 954.6992697300328, 975.0201047946769, 1069.2301542889106, 1087.0596933652412, 1090.7709381029993, 11611.303321800635, 11634.47644855975, 11742.0, 11752.412379381083, 11758.941205293919, 4578.267477203632, 4690.179218636725, 4781.2350023996, 4790.904181916342, 4817.542333478314, 9958.053984871425, 10026.799464010679, 10044.899551004397, 10051.346662466916, 10064.39613623185, 5316.388615309212, 5362.061639213122, 5368.060589396918, 5392.218128371443, 5405.0, 4275.422817919603, 4388.363687265329, 4596.37948876904, 4729.95270047302, 4853.9029219415415, 3839.0594304395045, 3913.373860182357, 3966.5240171179066, 4002.3196056670436, 4002.9799851000557, 1257.591282833083, 1270.0855384123417, 1280.8847203751707, 1289.9097063205672, 1291.606060151644, 799.8760192170223, 828.2214718165026, 892.0588778838259, 900.293269783217, 942.123824842889, 5904.7342869570975, 5927.347991720907, 6026.276846778414, 6167.013277875519, 6389.744410223539, 886.1094599927033, 898.4609234459402, 906.2297047509564, 924.3206243411088, 935.9438433694, 2953.128826995998, 3370.241695618466, 3896.279188350164, 3982.26328129297, 4106.897327566835, 10631.255812093234, 10731.409772462433, 10810.513526891309, 10935.945320273348, 10976.670699879016, 10701.143908487302, 10787.190960677824, 10890.564377424973, 10901.454927253606, 10912.672619821418, 7992.16082311349, 8116.837663246702, 8120.878186827203, 8122.106568277485, 8129.837403251902, 5674.886502270014, 5966.0, 5977.193078934288, 5995.760169593167, 6070.878582428415, 9713.465759383302, 10030.64892728751, 10084.991500849856, 10181.847272290774, 10191.898081019095, 8187.181281871765, 10341.586336546754, 10365.378077315385, 10376.325538840254, 10377.896221037692, 6567.507436942344, 6769.221539523018, 6862.897056544056, 7044.718211271696, 7130.500864939404, 7335.716249656182, 8258.628361723617, 8353.289970352345, 8561.957190214009, 8570.657173712982, 6665.300143484864, 7295.124585049828, 7545.6981720730555, 7556.395488361028, 7562.659680314509, 6033.849153771191, 7374.225706300976, 7417.221191774786, 7427.034485516848, 7488.775336739906, 6936.306369363023, 6965.79102626922, 7048.330408611174, 7065.1521817382245, 7068.434525238072, 5006.07387633289, 5059.645824792229, 5078.111330517071, 5090.643654944119, 5156.97421512905, 3921.627445392683, 5013.122703526796, 5124.641275110706, 5162.6902385856965, 5204.973975130251], "value": 4565.1067648941325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.93861227754445, 308.68514115602073, 319.12241336325104, 319.29595242490313, 320.2394313505425, 4906.0, 4959.941624225947, 5164.328637277158, 5408.675479471234, 5631.324241091075, 5374.811881584145, 5455.918161227575, 5533.750981205845, 5589.636673616212, 5630.802921897734, 3736.0099573612947, 4036.636702696757, 4066.8373265069363, 4261.849300688812, 4269.743815371072, 3497.915646149689, 3869.187470631157, 4069.613386728256, 4121.096372778672, 4217.460626871185, 4202.768847713374, 4213.0, 4235.703500754948, 4237.7457352558795, 4270.252705776476, 4188.623023927846, 4224.598663126998, 4232.343986682069, 4257.936130958038, 4270.316749320125, 3680.8527658893613, 3688.889333320005, 3703.814809259526, 3711.46183803347, 3711.4989476420606, 4497.403421785252, 4784.856454306377, 5063.873403164914, 5157.329547158883, 5400.2198461077405, 4029.6171863672903, 4056.371262454324, 4883.975580122076, 4893.779779909877, 4906.656534042619, 5402.000629883484, 5904.789518148769, 5966.612170208922, 5966.955782738003, 5997.940020599782, 3071.0, 3405.8126803025825, 3457.1184347991352, 4046.6560342370935, 4061.8375264989363, 5332.440093790135, 5575.665460072368, 6054.576179667425, 6090.421409966046, 6158.538109641795, 3438.432658611351, 4153.356229784388, 4153.982274342806, 4170.624643782075, 4173.812178451916, 3403.370376480359, 3562.8420763252047, 3855.6890657176696, 4169.937450938239, 4190.009745370985, 3784.67536362271, 4241.87274381769, 4251.978740106341, 4621.376114224537, 4638.489766125724, 5498.395176530578, 5940.55445841555, 5954.702264886739, 5997.190379298826, 6056.576039677268, 1348.548236340817, 1379.3861731529373, 1387.8126452928927, 1443.6463066548763, 1452.7724073158165, 795.4511387142853, 957.8132264208551, 970.8107568229004, 982.1259079419323, 991.4101109839634, 3594.802285874302, 4032.737872038308, 4133.561198892668, 4299.935500967488, 4502.594766470967, 4284.207421627009, 4387.371323253934, 4444.955320552517, 4741.581793285449, 4973.701577905337, 4513.833359987225, 4542.341360502709, 4543.909801491351, 4561.794719237642, 4572.634189264851, 2402.0752010475744, 2745.531140839681, 2788.930276743057, 3056.449839028952, 3187.856546455415, 5859.853503662443, 6240.1031025680295, 6430.421262086435, 6450.387213214737, 6450.41946224842, 6145.081086483974, 6197.752089916443, 6337.429631333202, 6398.5521013528605, 6432.51756118293, 6010.909836352458, 6079.027355623168, 6354.809355719336, 6370.4073981504625, 6408.148373973537, 5125.129327795403, 5544.421843842632, 5807.912881306868, 6126.969365153146, 6154.261488621394, 5447.206458316656, 5540.69526176048, 5891.528677705859, 6038.7282572283475, 6081.6351018939, 6124.693765311806, 6388.2972872984865, 6397.616143031433, 6422.518311126591, 6451.741930322923, 3818.1953138531258, 3998.8200530976756, 4008.7394319369146, 4032.173404452139, 4051.5340735815757, 5084.796608135781, 5738.368779434342, 5843.590948633555, 5871.911921321269, 6061.515078793598, 4697.342372067981, 5003.799848006039, 5240.292560504283, 5635.323761148688, 5781.0, 5962.970185149046, 5978.282606087298, 6000.609960352737, 6021.608595441282, 6024.90962635552, 4415.867523974286, 5701.173329867229, 5779.488017591145, 5843.970780146072, 5919.023361145363, 5540.501354878081, 5861.472467477947, 5887.704704964901, 5966.910496342472, 5982.722353517256, 5529.585281104014, 5813.6221145625395, 5828.173335931985, 5830.708464576839, 5873.765049398147], "value": 4184.340131349549, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.4577392312227, 732.9413646908245, 746.2872956326709, 776.7475570439609, 821.7740121466597, 15143.24283785809, 15289.694206115872, 15681.52955411338, 15816.0, 15829.84170158298, 13784.793228101562, 14018.299085045757, 14019.0, 14082.014259001873, 14098.295085245722, 829.4981536170635, 847.7075408983891, 851.2551517422255, 851.6082602003055, 852.8081181734091, 675.3853992866492, 693.4106009891602, 829.6100832608664, 847.631280393028, 860.7632900952226, 899.4783025844994, 907.2061945797427, 908.9863652045225, 909.0137201136741, 913.8720579118929, 856.6102423397339, 862.5169904853302, 900.2348004196446, 905.063259526387, 906.0893801729286, 633.5691729623854, 650.4633677216316, 679.3410391919879, 682.011083928306, 684.0525871667776, 15072.773908391382, 15078.095314281069, 15153.393864245418, 15248.847511524853, 15261.847381526153, 14312.355943982442, 14488.927555362261, 14538.345774440175, 14889.851101488955, 14930.730887874544, 11907.523699052113, 11983.520659173622, 12035.27788332694, 12267.325297108655, 12361.011119110539, 3794.6584807367194, 3845.1540661054523, 3862.1117143057254, 3974.284628766821, 3976.5426975897562, 109731.0, 110190.89809101887, 112877.0, 113356.43321783339, 113576.43211783971, 11349.205555611028, 11369.715757106138, 11397.658070257905, 11530.0, 11737.706557336134, 22949.426264343525, 23023.654645180002, 23170.189043383736, 23212.535749284918, 23283.650745238836, 6984.3364880336285, 7722.53664780115, 8037.1514551653445, 8054.798630034296, 8232.888560044317, 3378.6621337866018, 4378.978105109454, 4422.469303683579, 4449.510553839075, 4605.6085232755595, 3582.1402863313137, 3681.9815900920325, 3731.869384571581, 3732.906677333034, 3738.345789486829, 664.7473959895306, 670.956387834799, 676.090658064902, 678.0, 694.944404447643, 601.6360102138143, 611.8894207014255, 681.5842336174929, 686.7836631461074, 691.68182635987, 9186.5760807075, 9358.57886395114, 9445.952770236105, 9544.0, 9891.208703303719, 569.956978728925, 661.1999480628493, 689.9344562266677, 700.7722490190708, 702.2304979992873, 4502.639788816887, 4654.97672511642, 4664.976675116671, 4720.52536647106, 4722.952770472251, 96244.51877740567, 144452.0, 159102.0, 161233.0, 162673.0, 136706.0, 138580.0, 139628.30185849007, 140963.0, 142858.1428371427, 118959.0, 120121.0, 120602.99999999824, 120961.79038209506, 121323.0, 8946.821063578822, 9135.634574617206, 9160.450372977377, 9222.907770922206, 9306.976232614543, 65641.34358656353, 65753.67123164353, 66098.66950665216, 66910.66178676301, 67874.3212567868, 61991.07013394713, 62436.37563624488, 73775.63112184619, 86905.0, 91038.54480727554, 13345.86654133485, 13361.0, 13382.197068175941, 13520.256385898476, 14106.506272280421, 14803.481878134213, 14861.702765944838, 14916.328765205375, 15017.549473515812, 15234.3906243749, 13729.382177802223, 13883.0, 14065.789013164605, 14134.0, 14178.858211417753, 13649.454021839016, 13702.314884255547, 13774.586762397144, 13912.234827084607, 14046.367913444124, 12431.751364972832, 12453.626391208278, 12784.296863672587, 13163.47346106145, 13800.792988104986, 10962.945185274342, 11075.280106793032, 11358.943205284251, 11501.0, 11531.884681153417, 8792.868106978272, 9699.854502182614, 11485.0, 11618.70953226176, 11769.764604708029], "value": 7889.300862890235, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1639.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 117.0, 115.0, 117.0, 116.0, 118.0, 117.0, 116.0, 133.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 144.0, 173.0, 165.0, 127.0, 135.0, 130.0, 100.0, 167.0, 121.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 204.0, 211.0, 275.0, 204.0, 282.0, 276.0, 203.0, 191.0, 194.0], "value": 223.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 83.0, 78.0, 148.0, 59.0, 58.0, 61.0, 60.0, 136.0, 78.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 130.0, 71.0, 72.0, 86.0, 107.0, 70.0, 73.0, 113.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 92.0, 97.0, 137.0, 86.0, 107.0, 101.0, 109.0, 132.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 119.0, 92.0, 153.0, 153.0, 97.0, 94.0, 94.0, 97.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 99.0, 108.0, 145.0, 78.0, 146.0, 152.0, 93.0, 95.0, 91.0], "value": 114.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 57.0, 56.0, 59.0, 59.0, 57.0, 56.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 67.0, 75.0, 80.0, 74.0, 72.0, 73.0, 69.0, 69.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 82.0, 96.0, 81.0, 100.0, 80.0, 99.0, 113.0, 112.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 137.0, 152.0, 160.0, 169.0, 159.0, 167.0, 157.0, 186.0, 143.0], "value": 158.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 182.0, 241.0, 180.0, 181.0, 232.0, 170.0, 162.0, 237.0], "value": 192.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 71.0, 82.0, 93.0, 91.0, 72.0, 67.0, 70.0], "value": 75.4, "unit": "score"}]}]} 19:15:01 INFO - Return code: 0 19:15:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:15:01 ERROR - # TBPL SUCCESS # 19:15:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:15:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:15:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:15:01 INFO - 2016-09-10 19:15:01.971 screenresolution[1906:11626] starting screenresolution argv=screenresolution get 19:15:01 INFO - 2016-09-10 19:15:01.973 screenresolution[1906:11626] Display 0: 1600x1200x32@60 19:15:01 INFO - 2016-09-10 19:15:01.980 screenresolution[1907:11629] starting screenresolution argv=screenresolution list 19:15:01 INFO - Available Modes on Display 0 19:15:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:15:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:15:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:15:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:15:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:15:02 INFO - Intel Iris: 19:15:02 INFO - Chipset Model: Intel Iris 19:15:02 INFO - Type: GPU 19:15:02 INFO - Bus: Built-In 19:15:02 INFO - VRAM (Dynamic, Max): 1536 MB 19:15:02 INFO - Vendor: Intel (0x8086) 19:15:02 INFO - Device ID: 0x0a2e 19:15:02 INFO - Revision ID: 0x0009 19:15:02 INFO - Displays: 19:15:02 INFO - Display: 19:15:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:15:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:15:02 INFO - Display Serial Number: 1600x1200 60 19:15:02 INFO - Main Display: Yes 19:15:02 INFO - Mirror: Off 19:15:02 INFO - Online: Yes 19:15:02 INFO - Rotation: Supported 19:15:02 INFO - Return code: 0 19:15:02 INFO - Running post-action listener: _resource_record_post_action 19:15:02 INFO - [mozharness: 2016-09-11 02:15:02.219562Z] Finished run-tests step (success) 19:15:02 INFO - Running post-run listener: _resource_record_post_run 19:15:02 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 33135616; Write bytes: 712965632; Read time: 310; Write time: 2151 19:15:02 INFO - TinderboxPrint: CPU usage
54.6% 19:15:02 INFO - TinderboxPrint: I/O read bytes / time
33,135,616 / 310 19:15:02 INFO - TinderboxPrint: I/O write bytes / time
712,965,632 / 2,151 19:15:02 INFO - TinderboxPrint: CPU idle
2,197.9 (45.4%) 19:15:02 INFO - TinderboxPrint: CPU system
983.5 (20.3%) 19:15:02 INFO - TinderboxPrint: CPU user
1,660.2 (34.3%) 19:15:02 INFO - TinderboxPrint: Swap in / out
435,564,544 / 0 19:15:02 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398674432; Write bytes: 393623552; Read time: 29813; Write time: 734 19:15:02 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 31054848; Write bytes: 302556672; Read time: 285; Write time: 1382 19:15:02 INFO - Running post-run listener: _upload_blobber_files 19:15:02 INFO - Blob upload gear active. 19:15:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:15:02 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 19:15:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:15:03 INFO - (blobuploader) - INFO - Open directory for files ... 19:15:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:15:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:15:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:04 INFO - (blobuploader) - INFO - Done attempting. 19:15:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:15:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:05 INFO - (blobuploader) - INFO - Done attempting. 19:15:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:15:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:15:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:15:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:15:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:15:08 INFO - (blobuploader) - INFO - Done attempting. 19:15:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:15:08 INFO - Return code: 0 19:15:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:15:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:15:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc6e0bbed99122f9310d84ed8c435443f883f5d14892e1bf56ec917833be2693e55ad421e25828321b5c18c3690d22eb4a7a0488072bac985d37291be3b20259", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6bcd3579135d7645f08348a6974bfd3d610982b9da7b5b4c04af2f4fc5cc5a56a3e7103152ad281c11407ce898555242e3b590dec61eccffde36b5833d86e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c8a1fb05075cd3e85f9fb8dfa98b78782b5e0099a4abb83e72a579bc2b94959080bd1a7fa3d4b816255048f55101396146318a0f2c2f931a49859e651c259a6"} 19:15:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:15:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:15:08 INFO - Contents: 19:15:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc6e0bbed99122f9310d84ed8c435443f883f5d14892e1bf56ec917833be2693e55ad421e25828321b5c18c3690d22eb4a7a0488072bac985d37291be3b20259", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6bcd3579135d7645f08348a6974bfd3d610982b9da7b5b4c04af2f4fc5cc5a56a3e7103152ad281c11407ce898555242e3b590dec61eccffde36b5833d86e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c8a1fb05075cd3e85f9fb8dfa98b78782b5e0099a4abb83e72a579bc2b94959080bd1a7fa3d4b816255048f55101396146318a0f2c2f931a49859e651c259a6"} 19:15:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:15:08 INFO - Copying logs to upload dir... 19:15:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:15:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.775379 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 19:15:08.246456) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 19:15:08.252130) ========= 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.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc6e0bbed99122f9310d84ed8c435443f883f5d14892e1bf56ec917833be2693e55ad421e25828321b5c18c3690d22eb4a7a0488072bac985d37291be3b20259", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6bcd3579135d7645f08348a6974bfd3d610982b9da7b5b4c04af2f4fc5cc5a56a3e7103152ad281c11407ce898555242e3b590dec61eccffde36b5833d86e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c8a1fb05075cd3e85f9fb8dfa98b78782b5e0099a4abb83e72a579bc2b94959080bd1a7fa3d4b816255048f55101396146318a0f2c2f931a49859e651c259a6"} build_url:https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010794 build_url: 'https://queue.taskcluster.net/v1/task/VIWybJXlR263_-4JWufdJQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc6e0bbed99122f9310d84ed8c435443f883f5d14892e1bf56ec917833be2693e55ad421e25828321b5c18c3690d22eb4a7a0488072bac985d37291be3b20259", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/62c6bcd3579135d7645f08348a6974bfd3d610982b9da7b5b4c04af2f4fc5cc5a56a3e7103152ad281c11407ce898555242e3b590dec61eccffde36b5833d86e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c8a1fb05075cd3e85f9fb8dfa98b78782b5e0099a4abb83e72a579bc2b94959080bd1a7fa3d4b816255048f55101396146318a0f2c2f931a49859e651c259a6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 19:15:08.289149) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 19:15:08.289977) ========= 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.P6E7PmAie7/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.JCvgNk92DH/Listeners TMPDIR=/var/folders/6q/y_pvszzd4sd4pdvxdygmrt4r00000w/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.005285 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 19:15:08.341597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 19:15:08.342345) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 19:15:08.343130) ========= ========= Total master_lag: 0.31 =========