builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0265 starttime: 1473702290.8 results: success (0) buildid: 20160912083315 builduid: 4ef3bc89c33a4a4b8f30a390af75897a revision: 5b64b2e8e518c80e39c53aa4872e80661bd8834d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:50.798121) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:50.798548) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:50.799375) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:50.834520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:50.835038) ========= 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.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 10:44:50-- 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% 25.8M=0s 2016-09-12 10:44:51 (25.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.520807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:51.380627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:51.380981) ========= 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.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.025760 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:51.432179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 10:44:51.432963) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5b64b2e8e518c80e39c53aa4872e80661bd8834d --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 5b64b2e8e518c80e39c53aa4872e80661bd8834d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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-12 10:44:51,516 truncating revision to first 12 chars 2016-09-12 10:44:51,516 Setting DEBUG logging. 2016-09-12 10:44:51,516 attempt 1/10 2016-09-12 10:44:51,516 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5b64b2e8e518?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 10:44:53,310 unpacking tar archive at: fx-team-5b64b2e8e518/testing/mozharness/ program finished with exit code 0 elapsedTime=2.062497 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-12 10:44:53.522736) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:53.523054) ========= script_repo_revision: 5b64b2e8e518c80e39c53aa4872e80661bd8834d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:53.523535) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:53.523856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 10:44:53.543230) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 10:44:53.544086) ========= /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.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:44:53 INFO - MultiFileLogger online at 20160912 10:44:53 in /builds/slave/test 10:44:53 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 10:44:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:44:53 INFO - {'append_to_log': False, 10:44:53 INFO - 'base_work_dir': '/builds/slave/test', 10:44:53 INFO - 'blob_upload_branch': 'Fx-Team', 10:44:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:44:53 INFO - 'branch': 'Fx-Team', 10:44:53 INFO - 'buildbot_json_path': 'buildprops.json', 10:44:53 INFO - 'config_files': ('talos/mac_config.py',), 10:44:53 INFO - 'default_actions': ('clobber', 10:44:53 INFO - 'read-buildbot-config', 10:44:53 INFO - 'download-and-extract', 10:44:53 INFO - 'populate-webroot', 10:44:53 INFO - 'create-virtualenv', 10:44:53 INFO - 'install', 10:44:53 INFO - 'run-tests'), 10:44:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:44:53 INFO - 'download_minidump_stackwalk': True, 10:44:53 INFO - 'download_symbols': 'ondemand', 10:44:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:44:53 INFO - 'tooltool.py': '/tools/tooltool.py', 10:44:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:44:53 INFO - '/tools/misc-python/virtualenv.py')}, 10:44:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:44:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:44:53 INFO - 'installer_path': 'installer.exe', 10:44:53 INFO - 'log_level': 'info', 10:44:53 INFO - 'log_name': 'talos', 10:44:53 INFO - 'log_to_console': True, 10:44:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:44:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:44:53 INFO - 'opt_config_files': (), 10:44:53 INFO - 'pip_index': False, 10:44:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:53 INFO - 'cmd': ('bash', 10:44:53 INFO - '-c', 10:44:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:53 INFO - 'enabled': True, 10:44:53 INFO - 'halt_on_failure': False, 10:44:53 INFO - 'name': 'check_screen_resolution'},), 10:44:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:53 INFO - 'cmd': ('bash', 10:44:53 INFO - '-c', 10:44:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:53 INFO - 'enabled': True, 10:44:53 INFO - 'halt_on_failure': False, 10:44:53 INFO - 'name': 'check_screen_resolution'},), 10:44:53 INFO - 'run_cmd_checks_enabled': True, 10:44:53 INFO - 'sps_profile': False, 10:44:53 INFO - 'sps_profile_interval': 0, 10:44:53 INFO - 'suite': 'dromaeojs-e10s', 10:44:53 INFO - 'system_bits': '32', 10:44:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:44:53 INFO - 'title': 't-yosemite-r7-0265', 10:44:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:44:53 INFO - 'use_talos_json': True, 10:44:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:44:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:44:53 INFO - 'work_dir': 'build'} 10:44:53 INFO - [mozharness: 2016-09-12 17:44:53.739578Z] Running clobber step. 10:44:53 INFO - Running pre-action listener: _resource_record_pre_action 10:44:53 INFO - Running main action method: clobber 10:44:53 INFO - rmtree: /builds/slave/test/build 10:44:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:44:55 INFO - Running post-action listener: _resource_record_post_action 10:44:55 INFO - [mozharness: 2016-09-12 17:44:55.801298Z] Finished clobber step (success) 10:44:55 INFO - [mozharness: 2016-09-12 17:44:55.801521Z] Running read-buildbot-config step. 10:44:55 INFO - Running pre-action listener: _resource_record_pre_action 10:44:55 INFO - Running main action method: read_buildbot_config 10:44:55 INFO - Using buildbot properties: 10:44:55 INFO - { 10:44:55 INFO - "project": "", 10:44:55 INFO - "product": "firefox", 10:44:55 INFO - "script_repo_revision": "production", 10:44:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 10:44:55 INFO - "repository": "", 10:44:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 10:44:55 INFO - "buildid": "20160912083315", 10:44:55 INFO - "pgo_build": "False", 10:44:55 INFO - "basedir": "/builds/slave/test", 10:44:55 INFO - "buildnumber": 114, 10:44:55 INFO - "slavename": "t-yosemite-r7-0265", 10:44:55 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:44:55 INFO - "platform": "yosemite_r7", 10:44:55 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 10:44:55 INFO - "branch": "fx-team", 10:44:55 INFO - "repo_path": "integration/fx-team", 10:44:55 INFO - "revision": "5b64b2e8e518c80e39c53aa4872e80661bd8834d", 10:44:55 INFO - "stage_platform": "macosx64", 10:44:55 INFO - "builduid": "4ef3bc89c33a4a4b8f30a390af75897a", 10:44:55 INFO - "slavebuilddir": "test" 10:44:55 INFO - } 10:44:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:44:55 INFO - Running post-action listener: _resource_record_post_action 10:44:55 INFO - [mozharness: 2016-09-12 17:44:55.807502Z] Finished read-buildbot-config step (success) 10:44:55 INFO - [mozharness: 2016-09-12 17:44:55.807738Z] Running download-and-extract step. 10:44:55 INFO - Running pre-action listener: _resource_record_pre_action 10:44:55 INFO - Running main action method: download_and_extract 10:44:55 INFO - mkdir: /builds/slave/test/build/tests 10:44:55 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')]} 10:44:55 INFO - trying https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:44:55 INFO - Downloading https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/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 10:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/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 10:44:57 INFO - Downloaded 1199 bytes. 10:44:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:44:57 INFO - Using the following test package requirements: 10:44:57 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:44:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:44:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:44:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'jsshell-mac.zip'], 10:44:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:44:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:44:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:44:57 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:44:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:44:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:44:57 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:44:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:44:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:44:58 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:45:05 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 10:45:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:45:07 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:45:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:45:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:15 INFO - trying https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:45:15 INFO - Downloading https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:45:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/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 10:45:18 INFO - Downloaded 111631371 bytes. 10:45:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:45:18 INFO - mkdir: /builds/slave/test/properties 10:45:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:45:18 INFO - Writing to file /builds/slave/test/properties/build_url 10:45:18 INFO - Contents: 10:45:18 INFO - build_url:https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:45:20 INFO - Running post-action listener: _resource_record_post_action 10:45:20 INFO - Running post-action listener: set_extra_try_arguments 10:45:20 INFO - [mozharness: 2016-09-12 17:45:20.381528Z] Finished download-and-extract step (success) 10:45:20 INFO - [mozharness: 2016-09-12 17:45:20.381654Z] Running populate-webroot step. 10:45:20 INFO - Running pre-action listener: _resource_record_pre_action 10:45:20 INFO - Running main action method: populate_webroot 10:45:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:45:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:45:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:45:20 INFO - '--tppagecycles', 10:45:20 INFO - '7'], 10:45:20 INFO - 'tests': ['tsvgm']}}, 10:45:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:45:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:45:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:45:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:45:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:45:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:45:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['damp', 'tps']}, 10:45:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'tests': ['damp', 'tps']}, 10:45:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['dromaeo_dom']}, 10:45:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:45:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['basic_compositor_video']}, 10:45:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:45:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'other-e10s': {'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['a11yr', 10:45:20 INFO - 'ts_paint', 10:45:20 INFO - 'tpaint', 10:45:20 INFO - 'sessionrestore', 10:45:20 INFO - 'sessionrestore_no_auto_restore', 10:45:20 INFO - 'tabpaint']}, 10:45:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['tsvgx', 10:45:20 INFO - 'tsvgr_opacity', 10:45:20 INFO - 'tart', 10:45:20 INFO - 'tscrollx', 10:45:20 INFO - 'cart']}, 10:45:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:45:20 INFO - 'tsvgr_opacity', 10:45:20 INFO - 'tart', 10:45:20 INFO - 'tscrollx', 10:45:20 INFO - 'cart']}, 10:45:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'talos_options': ['--disable-e10s'], 10:45:20 INFO - 'tests': ['tp5o']}, 10:45:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'tests': ['tp5o']}, 10:45:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'talos_options': ['--disable-e10s', 10:45:20 INFO - '--xperf_path', 10:45:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:45:20 INFO - 'tests': ['tp5n']}, 10:45:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:45:20 INFO - 'talos_options': ['--xperf_path', 10:45:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:45:20 INFO - 'tests': ['tp5n']}}, 10:45:20 INFO - 'talos.zip': {'path': '', 10:45:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:45:20 INFO - Running post-action listener: _resource_record_post_action 10:45:20 INFO - [mozharness: 2016-09-12 17:45:20.400641Z] Finished populate-webroot step (success) 10:45:20 INFO - [mozharness: 2016-09-12 17:45:20.400754Z] Running create-virtualenv step. 10:45:20 INFO - Running pre-action listener: _resource_record_pre_action 10:45:20 INFO - Running main action method: create_virtualenv 10:45:20 INFO - Creating virtualenv /builds/slave/test/build/venv 10:45: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 10:45:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:45:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:45:20 INFO - Using real prefix '/tools/python27' 10:45:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:45:21 INFO - Installing distribute.............................................................................................................................................................................................done. 10:45:24 INFO - Installing pip.................done. 10:45:24 INFO - Return code: 0 10:45:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45: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')]} 10:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45: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', 'pip>=1.5'] in /builds/slave/test/build 10:45: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 pip>=1.5 10:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:24 INFO - 'HOME': '/Users/cltbld', 10:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:24 INFO - 'LOGNAME': 'cltbld', 10:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:24 INFO - 'MOZ_NO_REMOTE': '1', 10:45:24 INFO - 'NO_EM_RESTART': '1', 10:45:24 INFO - 'PAGER': '/bin/cat', 10:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:24 INFO - 'PWD': '/builds/slave/test', 10:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:24 INFO - 'SHELL': '/bin/bash', 10:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:24 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:24 INFO - 'USER': 'cltbld', 10:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:24 INFO - 'XPC_FLAGS': '0x0', 10:45:24 INFO - 'XPC_SERVICE_NAME': '0', 10:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45: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 10:45:24 INFO - Cleaning up... 10:45:24 INFO - Return code: 0 10:45:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45: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')]} 10:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45: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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45: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 10:45: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 10:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:24 INFO - 'HOME': '/Users/cltbld', 10:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:24 INFO - 'LOGNAME': 'cltbld', 10:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:24 INFO - 'MOZ_NO_REMOTE': '1', 10:45:24 INFO - 'NO_EM_RESTART': '1', 10:45:24 INFO - 'PAGER': '/bin/cat', 10:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:24 INFO - 'PWD': '/builds/slave/test', 10:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:24 INFO - 'SHELL': '/bin/bash', 10:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:24 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:24 INFO - 'USER': 'cltbld', 10:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:24 INFO - 'XPC_FLAGS': '0x0', 10:45:24 INFO - 'XPC_SERVICE_NAME': '0', 10:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:24 INFO - Downloading/unpacking psutil>=3.1.1 10:45: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 10:45: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 10:45: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 10:45: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 10:45: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 10:45: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 10:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:45:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:27 INFO - Installing collected packages: psutil 10:45:27 INFO - Running setup.py install for psutil 10:45:27 INFO - building 'psutil._psutil_osx' extension 10:45: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 10:45: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:45: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:45:27 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 10:45:28 INFO - building 'psutil._psutil_posix' extension 10:45: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 10:45:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:45:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:45:28 INFO - ^ 10:45:28 INFO - 1 warning generated. 10:45: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 10:45:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:28 INFO - Successfully installed psutil 10:45:28 INFO - Cleaning up... 10:45:28 INFO - Return code: 0 10:45:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:28 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:28 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 10:45:28 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 10:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:28 INFO - 'HOME': '/Users/cltbld', 10:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:28 INFO - 'LOGNAME': 'cltbld', 10:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:28 INFO - 'MOZ_NO_REMOTE': '1', 10:45:28 INFO - 'NO_EM_RESTART': '1', 10:45:28 INFO - 'PAGER': '/bin/cat', 10:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:28 INFO - 'PWD': '/builds/slave/test', 10:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:28 INFO - 'SHELL': '/bin/bash', 10:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:28 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:28 INFO - 'USER': 'cltbld', 10:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:28 INFO - 'XPC_FLAGS': '0x0', 10:45:28 INFO - 'XPC_SERVICE_NAME': '0', 10:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:45:28 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 10:45:28 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 10:45:28 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 10:45:28 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 10:45:28 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 10:45:28 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 10:45:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:45:31 INFO - Installing collected packages: mozsystemmonitor 10:45:31 INFO - Running setup.py install for mozsystemmonitor 10:45:31 INFO - Successfully installed mozsystemmonitor 10:45:31 INFO - Cleaning up... 10:45:31 INFO - Return code: 0 10:45:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:31 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:31 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 10:45:31 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 10:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:31 INFO - 'HOME': '/Users/cltbld', 10:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:31 INFO - 'LOGNAME': 'cltbld', 10:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:31 INFO - 'MOZ_NO_REMOTE': '1', 10:45:31 INFO - 'NO_EM_RESTART': '1', 10:45:31 INFO - 'PAGER': '/bin/cat', 10:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:31 INFO - 'PWD': '/builds/slave/test', 10:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:31 INFO - 'SHELL': '/bin/bash', 10:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:31 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:31 INFO - 'USER': 'cltbld', 10:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:31 INFO - 'XPC_FLAGS': '0x0', 10:45:31 INFO - 'XPC_SERVICE_NAME': '0', 10:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:31 INFO - Downloading/unpacking jsonschema==2.5.1 10:45:31 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 10:45:31 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 10:45:31 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 10:45:31 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 10:45:31 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 10:45:31 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 10:45:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:45:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:45:33 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 10:45:33 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 10:45:33 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 10:45:33 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 10:45:33 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 10:45:33 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 10:45:34 INFO - Downloading functools32-3.2.3-2.tar.gz 10:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:45:34 INFO - warning: no files found matching '*.txt' 10:45:34 INFO - no previously-included directories found matching 'build' 10:45:34 INFO - no previously-included directories found matching 'dist' 10:45:34 INFO - no previously-included directories found matching '.git*' 10:45:34 INFO - Installing collected packages: jsonschema, functools32 10:45:34 INFO - Running setup.py install for functools32 10:45:34 INFO - warning: no files found matching '*.txt' 10:45:34 INFO - no previously-included directories found matching 'build' 10:45:34 INFO - no previously-included directories found matching 'dist' 10:45:34 INFO - no previously-included directories found matching '.git*' 10:45:34 INFO - Successfully installed jsonschema functools32 10:45:34 INFO - Cleaning up... 10:45:34 INFO - Return code: 0 10:45:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:45:34 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')]} 10:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:34 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')]} 10:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:34 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:34 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 10:45:34 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 10:45:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:34 INFO - 'HOME': '/Users/cltbld', 10:45:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:34 INFO - 'LOGNAME': 'cltbld', 10:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:34 INFO - 'MOZ_NO_REMOTE': '1', 10:45:34 INFO - 'NO_EM_RESTART': '1', 10:45:34 INFO - 'PAGER': '/bin/cat', 10:45:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:34 INFO - 'PWD': '/builds/slave/test', 10:45:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:34 INFO - 'SHELL': '/bin/bash', 10:45:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:34 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:34 INFO - 'USER': 'cltbld', 10:45:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:34 INFO - 'XPC_FLAGS': '0x0', 10:45:34 INFO - 'XPC_SERVICE_NAME': '0', 10:45:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:45:34 INFO - Cleaning up... 10:45:34 INFO - Return code: 0 10:45:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:45:34 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')]} 10:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:34 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')]} 10:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:34 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:34 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 10:45:34 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 10:45:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:34 INFO - 'HOME': '/Users/cltbld', 10:45:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:34 INFO - 'LOGNAME': 'cltbld', 10:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:34 INFO - 'MOZ_NO_REMOTE': '1', 10:45:34 INFO - 'NO_EM_RESTART': '1', 10:45:34 INFO - 'PAGER': '/bin/cat', 10:45:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:34 INFO - 'PWD': '/builds/slave/test', 10:45:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:34 INFO - 'SHELL': '/bin/bash', 10:45:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:34 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:34 INFO - 'USER': 'cltbld', 10:45:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:34 INFO - 'XPC_FLAGS': '0x0', 10:45:34 INFO - 'XPC_SERVICE_NAME': '0', 10:45:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:45:35 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 10:45:35 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 10:45:35 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 10:45:35 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 10:45:35 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 10:45:35 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 10:45:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:45:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:45:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:45:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:45: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 10:45: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 10:45: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 10:45: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 10:45: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 10:45: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 10:45:38 INFO - Downloading docopt-0.6.1.tar.gz 10:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:45:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:45:39 INFO - Running setup.py install for blobuploader 10:45:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:45:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:45:39 INFO - Running setup.py install for requests 10:45:39 INFO - Running setup.py install for docopt 10:45:39 INFO - Successfully installed blobuploader requests docopt 10:45:39 INFO - Cleaning up... 10:45:39 INFO - Return code: 0 10:45:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:39 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:39 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 10:45:39 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 10:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:39 INFO - 'HOME': '/Users/cltbld', 10:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:39 INFO - 'LOGNAME': 'cltbld', 10:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:39 INFO - 'MOZ_NO_REMOTE': '1', 10:45:39 INFO - 'NO_EM_RESTART': '1', 10:45:39 INFO - 'PAGER': '/bin/cat', 10:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:39 INFO - 'PWD': '/builds/slave/test', 10:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:39 INFO - 'SHELL': '/bin/bash', 10:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:39 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:39 INFO - 'USER': 'cltbld', 10:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:39 INFO - 'XPC_FLAGS': '0x0', 10:45:39 INFO - 'XPC_SERVICE_NAME': '0', 10:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FqKj_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ETsoUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-10uSRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-CsxBj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-eNvqFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-dn7ch3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-S23U_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:40 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-sLIo7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7_K5J5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-EOPdKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-iyO8XX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-KJM4vD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-l1Jb8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-sfC2xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-O5Qq2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-MAqkgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:41 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-u3ljDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:45:42 INFO - Running setup.py install for manifestparser 10:45:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:45:42 INFO - Running setup.py install for mozcrash 10:45:42 INFO - Running setup.py install for mozdebug 10:45:42 INFO - Running setup.py install for mozdevice 10:45:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:45:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:45:42 INFO - Running setup.py install for mozfile 10:45:42 INFO - Running setup.py install for mozhttpd 10:45:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:45:42 INFO - Running setup.py install for mozinfo 10:45:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Running setup.py install for mozInstall 10:45:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Running setup.py install for mozleak 10:45:43 INFO - Running setup.py install for mozlog 10:45:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Running setup.py install for moznetwork 10:45:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Running setup.py install for mozprocess 10:45:43 INFO - Running setup.py install for mozprofile 10:45:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:45:43 INFO - Running setup.py install for mozrunner 10:45:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:45:44 INFO - Running setup.py install for mozscreenshot 10:45:44 INFO - Running setup.py install for moztest 10:45:44 INFO - Running setup.py install for mozversion 10:45:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:45:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:45:44 INFO - Cleaning up... 10:45:44 INFO - Return code: 0 10:45:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:44 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:44 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 10:45:44 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 10:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:44 INFO - 'HOME': '/Users/cltbld', 10:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:44 INFO - 'LOGNAME': 'cltbld', 10:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:44 INFO - 'MOZ_NO_REMOTE': '1', 10:45:44 INFO - 'NO_EM_RESTART': '1', 10:45:44 INFO - 'PAGER': '/bin/cat', 10:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:44 INFO - 'PWD': '/builds/slave/test', 10:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:44 INFO - 'SHELL': '/bin/bash', 10:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:44 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:44 INFO - 'USER': 'cltbld', 10:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:44 INFO - 'XPC_FLAGS': '0x0', 10:45:44 INFO - 'XPC_SERVICE_NAME': '0', 10:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:44 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-C6pUwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:44 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)) 10:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:44 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-h0VCaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45: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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-w01JxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45: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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-LcTHgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-YSEQtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7NY76y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-b6RTNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7MGtb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-tUl2jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-EgIxN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:45 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)) 10:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-PLBh0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45: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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-55uPRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45: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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-B9QiC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:46 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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-pDric8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:46 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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-r9GuVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:46 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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-3cNwBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:46 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)) 10:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-bKf3l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:46 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)) 10:45:46 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)) 10:45:46 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)) 10:45:46 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)) 10:45:46 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)) 10:45:46 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)) 10:45:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:49 INFO - Downloading blessings-1.6.tar.gz 10:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:45:49 INFO - Installing collected packages: blessings 10:45:49 INFO - Running setup.py install for blessings 10:45:49 INFO - Successfully installed blessings 10:45:49 INFO - Cleaning up... 10:45:49 INFO - Return code: 0 10:45:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:45:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:49 INFO - Reading from file tmpfile_stdout 10:45:49 INFO - Current package versions: 10:45:49 INFO - blessings == 1.6 10:45:49 INFO - blobuploader == 1.2.4 10:45:49 INFO - docopt == 0.6.1 10:45:49 INFO - functools32 == 3.2.3-2 10:45:49 INFO - jsonschema == 2.5.1 10:45:49 INFO - manifestparser == 1.1 10:45:49 INFO - mozInstall == 1.12 10:45:49 INFO - mozcrash == 0.17 10:45:49 INFO - mozdebug == 0.1 10:45:49 INFO - mozdevice == 0.48 10:45:49 INFO - mozfile == 1.2 10:45:49 INFO - mozhttpd == 0.7 10:45:49 INFO - mozinfo == 0.9 10:45:49 INFO - mozleak == 0.1 10:45:49 INFO - mozlog == 3.3 10:45:49 INFO - moznetwork == 0.27 10:45:49 INFO - mozprocess == 0.23 10:45:49 INFO - mozprofile == 0.28 10:45:49 INFO - mozrunner == 6.12 10:45:49 INFO - mozscreenshot == 0.1 10:45:49 INFO - mozsystemmonitor == 0.3 10:45:49 INFO - moztest == 0.7 10:45:49 INFO - mozversion == 1.4 10:45:49 INFO - psutil == 3.1.1 10:45:49 INFO - requests == 1.2.3 10:45:49 INFO - wsgiref == 0.1.2 10:45:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:49 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 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:49 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 10:45:49 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 10:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:49 INFO - 'HOME': '/Users/cltbld', 10:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:49 INFO - 'LOGNAME': 'cltbld', 10:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:49 INFO - 'MOZ_NO_REMOTE': '1', 10:45:49 INFO - 'NO_EM_RESTART': '1', 10:45:49 INFO - 'PAGER': '/bin/cat', 10:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:49 INFO - 'PWD': '/builds/slave/test', 10:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:49 INFO - 'SHELL': '/bin/bash', 10:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:49 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:49 INFO - 'USER': 'cltbld', 10:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:49 INFO - 'XPC_FLAGS': '0x0', 10:45:49 INFO - 'XPC_SERVICE_NAME': '0', 10:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 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)) 10:45:49 INFO - Cleaning up... 10:45:49 INFO - Return code: 0 10:45:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bd3a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcb0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ca3d79e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, '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.V8c9k7zM38/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.2aQNnfm67D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 10:45:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:49 INFO - 'HOME': '/Users/cltbld', 10:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:49 INFO - 'LOGNAME': 'cltbld', 10:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:49 INFO - 'MOZ_NO_REMOTE': '1', 10:45:49 INFO - 'NO_EM_RESTART': '1', 10:45:49 INFO - 'PAGER': '/bin/cat', 10:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:49 INFO - 'PWD': '/builds/slave/test', 10:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:49 INFO - 'SHELL': '/bin/bash', 10:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:45:49 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:49 INFO - 'USER': 'cltbld', 10:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:49 INFO - 'XPC_FLAGS': '0x0', 10:45:49 INFO - 'XPC_SERVICE_NAME': '0', 10:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:45:50 INFO - Cleaning up... 10:45:50 INFO - Return code: 0 10:45:50 INFO - Running post-action listener: _resource_record_post_action 10:45:50 INFO - Running post-action listener: _start_resource_monitoring 10:45:50 INFO - Starting resource monitoring. 10:45:50 INFO - [mozharness: 2016-09-12 17:45:50.273807Z] Finished create-virtualenv step (success) 10:45:50 INFO - [mozharness: 2016-09-12 17:45:50.274844Z] Running install step. 10:45:50 INFO - Running pre-action listener: _resource_record_pre_action 10:45:50 INFO - Running main action method: install 10:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:50 INFO - Reading from file tmpfile_stdout 10:45:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:45:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:45:50 INFO - Reading from file tmpfile_stdout 10:45:50 INFO - Output received: 10:45:50 INFO - Usage: mozinstall [options] installer 10:45:50 INFO - Options: 10:45:50 INFO - -h, --help show this help message and exit 10:45:50 INFO - -d DEST, --destination=DEST 10:45:50 INFO - Directory to install application into. [default: 10:45:50 INFO - "/builds/slave/test"] 10:45:50 INFO - --app=APP Application being installed. [default: firefox] 10:45:50 INFO - mkdir: /builds/slave/test/build/application 10:45:50 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'] 10:45:50 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 10:46:25 INFO - Reading from file tmpfile_stdout 10:46:25 INFO - Output received: 10:46:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:46:25 INFO - Running post-action listener: _resource_record_post_action 10:46:25 INFO - [mozharness: 2016-09-12 17:46:25.041881Z] Finished install step (success) 10:46:25 INFO - [mozharness: 2016-09-12 17:46:25.042037Z] Running run-tests step. 10:46:25 INFO - Running pre-action listener: _resource_record_pre_action 10:46:25 INFO - Running main action method: run_tests 10:46:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:25 INFO - 2016-09-12 10:46:25.056 screenresolution[1643:7844] starting screenresolution argv=screenresolution get 10:46:25 INFO - 2016-09-12 10:46:25.059 screenresolution[1643:7844] Display 0: 1600x1200x32@60 10:46:25 INFO - 2016-09-12 10:46:25.065 screenresolution[1644:7846] starting screenresolution argv=screenresolution list 10:46:25 INFO - Available Modes on Display 0 10:46:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:25 INFO - Intel Iris: 10:46:25 INFO - Chipset Model: Intel Iris 10:46:25 INFO - Type: GPU 10:46:25 INFO - Bus: Built-In 10:46:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:25 INFO - Vendor: Intel (0x8086) 10:46:25 INFO - Device ID: 0x0a2e 10:46:25 INFO - Revision ID: 0x0009 10:46:25 INFO - Displays: 10:46:25 INFO - Display: 10:46:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:25 INFO - Display Serial Number: 1600x1200 60 10:46:25 INFO - Main Display: Yes 10:46:25 INFO - Mirror: Off 10:46:25 INFO - Online: Yes 10:46:25 INFO - Rotation: Supported 10:46:25 INFO - Return code: 0 10:46:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:46:25 INFO - Python 2.7.3 10:46:25 INFO - Return code: 0 10:46:25 INFO - grabbing minidump binary from tooltool 10:46: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')]} 10:46: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 0x10bb4ed30>, '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 0x10b974db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb46b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:46: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 10:46: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 10:46: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 10:46:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:46:25 INFO - Return code: 0 10:46:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:46:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:46:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:46:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:46:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:46: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/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 10:46: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/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0265 --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 10:46:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V8c9k7zM38/Render', 10:46:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:46:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:46:25 INFO - 'HOME': '/Users/cltbld', 10:46:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:46:25 INFO - 'LOGNAME': 'cltbld', 10:46:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:46:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:25 INFO - 'MOZ_NO_REMOTE': '1', 10:46:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:46:25 INFO - 'NO_EM_RESTART': '1', 10:46:25 INFO - 'PAGER': '/bin/cat', 10:46:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:25 INFO - 'PWD': '/builds/slave/test', 10:46:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:46:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:46:25 INFO - 'SHELL': '/bin/bash', 10:46:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2aQNnfm67D/Listeners', 10:46:25 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 10:46:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:46:25 INFO - 'USER': 'cltbld', 10:46:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:46:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:25 INFO - 'XPC_FLAGS': '0x0', 10:46:25 INFO - 'XPC_SERVICE_NAME': '0', 10:46:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:46: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/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 10:46:25 INFO - mozversion application_buildid: 20160912083315 10:46:25 INFO - mozversion application_changeset: 5b64b2e8e518c80e39c53aa4872e80661bd8834d 10:46:25 INFO - mozversion application_display_name: Nightly 10:46:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:46:25 INFO - mozversion application_name: Firefox 10:46:25 INFO - mozversion application_remotingname: firefox 10:46:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:46:25 INFO - mozversion application_vendor: Mozilla 10:46:25 INFO - mozversion application_version: 51.0a1 10:46:25 INFO - mozversion platform_buildid: 20160912083315 10:46:25 INFO - mozversion platform_changeset: 5b64b2e8e518c80e39c53aa4872e80661bd8834d 10:46:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:46:25 INFO - mozversion platform_version: 51.0a1 10:46:25 INFO - using testdate: 1473702385 10:46:25 INFO - actual date: 1473702385 10:46:25 INFO - starting webserver on 'localhost:49266' 10:46:25 INFO - SUITE-START | Running 2 tests 10:46:25 INFO - TEST-START | dromaeo_css 10:46:25 INFO - operating with platform_type : mac_ 10:46:25 INFO - Initialising browser for dromaeo_css test... 10:46:25 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmp_0r0mo/profile http://localhost:49266/getInfo.html) 10:46:28 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 10:46:28 INFO - PROCESS | 1654 | colorDepth:24 10:46:28 INFO - PROCESS | 1654 | Browser inner width/height: 1024/663 10:46:28 INFO - PROCESS | 1654 | __metrics 10:46:28 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 10:46:29 INFO - TEST-INFO | 1654: exit 0 10:46:29 INFO - Browser initialized. 10:46:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:46:29 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmp_0r0mo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:41 INFO - PROCESS | 1658 | RSS: Main: 257720320 10:46:41 INFO - PROCESS | 1658 | 10:47:31 INFO - PROCESS | 1658 | 1473702451144 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] 10:49:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 10:49:44 INFO - PROCESS | 1658 | RSS: Main: 274190336 10:49:44 INFO - PROCESS | 1658 | 10:52:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 10:52:47 INFO - PROCESS | 1658 | RSS: Main: 280604672 10:52:47 INFO - PROCESS | 1658 | 10:55:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 10:55:50 INFO - PROCESS | 1658 | RSS: Main: 284389376 10:55:50 INFO - PROCESS | 1658 | 10:58:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 10:58:52 INFO - PROCESS | 1658 | RSS: Main: 280629248 10:58:52 INFO - PROCESS | 1658 | 11:01:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 11:01:56 INFO - PROCESS | 1658 | RSS: Main: 286068736 11:01:56 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 11:04:59 INFO - PROCESS | 1658 | RSS: Main: 288808960 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | __start_tp_report 11:04:59 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 11:04:59 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 11:04:59 INFO - PROCESS | 1658 | |i|pagename|runs| 11:04:59 INFO - PROCESS | 1658 | |0;dojo.html;2918.255844759586;3384.103212648647;3385.966140338599;3417.521546983421;3460.290640418714;5637.295338082739;5696.829095127143;5699.202111704365;5702.401247868971;5734.082546792514;5617.9157312640245;5665.886682266352;5706.429357064291;5737.540996720263;5771.538276937856;4770.928436073462;5142.202958541432;5218.217267409881;5291.550218231455;5313.654612450197;3764.5482542094987;3863.632954869283;4322.7190232634775;4649.302604609302;5271.42014378418;4628.63455280693;4714.269288260325;5141.871453213662;5256.421793602701;5260.658057226268;5099.923501147486;5187.636865419422;5232.843014709565;5280.445553216934;5306.257124002643;5184.11869982104;5184.974075129638;5216.7913283468615;5217.634765566412;5239.266502689627;4611.423572053493;5648.85877853057;5804.013317735995;5805.5065319447895;5807.448292412257;5018.2974383586325;6114.755409783604;6144.033909149074;6195.2255968004;6282.591631543981;6239.719212635397;6250.812475625738;6265.906011409833;6272.372762723737;6289.276733175698;5824.767009319622;5846.123081537803;5850.385709500441;5856.180134781134;5899.675517846474;6166.537509686702;6192.0711893215475;6202.81391558254;6210.5652604317265;6238.968805155945;6110.205673262448;6118.296395914527;6207.472364849038;6215.285242197115;6228.159198508143;6271.811845644638;6272.905906411407;6274.058891166362;6289.842753931189;6291.905621415683;6107.633541987495;6131.254333328352;6151.446369826737;6174.845628859314;6178.969105154535;6230.532710046765;6271.153394291803;6289.622622642656;6293.3391993840905;6296.716647750771;5006.148954677714;5012.8496145115705;5019.749012549358;5021.297018417425;5041.899162016739;4393.340998850198;4397.560243975577;4407.118576284756;4444.000099977479;4508.752018639007;5662.17898404729;5666.518345940559;5734.426557344315;5750.2237197978575;5754.108113242371;6209.472194863487;6252.499800015988;6266.467350275187;6271.122042913996;6278.591891527036;5600.915986260209;5657.9434205658235;5679.233303503974;5735.856603584861;5777.768889244468;5415.268938693304;5489.204065410493;5501;5520.944790552124;5523;6323.336049714806;6326.082718005863;6330.905036424458;6344.953082741481;6353.142325786144;6125.84685382869;6196;6275.02737075754;6277.654728989767;6295.590786599039;6213.565050446426;6228.22147231596;6267.6866156692895;6318.052292156213;6336.778212762531;6192.628442293477;6251.34360892118;6254;6262.655553944577;6267.686615669107;5774.653520788766;6291.276503202191;6310;6312.873742525216;6314.431701146735;4473.642108631237;6080.0575910733905;6104.9389506106145;6105.065535583811;6194.5354098443695;6137.6931153441255;6168.475679567287;6168.475679567287;6171.814845554641;6197.070439434121;5232.529072383351;5871.150587565075;5963.254593175853;5992.760289588542;6109.75560977556;5036.647434679684;5416.9729151353995;5858.736356863866;6086.1175129606845;6165.568410211422;4821.879453013562;5042.470540593332;5599.600099975006;6003.489703375262;6046.909296360646 11:04:59 INFO - PROCESS | 1658 | |1;ext.html;471.5590922487472;487.0332390205442;490.6148673291467;501.13053851567525;555.5250261026822;35456.64543354566;40193.39709904354;40233;40248.59751402485;40276.798616006876;32614;40669.389959150634;41067;41102.383464247985;41156;9581.61673533058;9798.902010979906;9812.362196457207;9813.460259685715;9814.214862810994;9695.951520242425;9787.755306117368;9790.069943355405;9791.363561368524;9806.558704858262;9754.561044753003;9792.020797920222;9792.069753373453;9809.362391444569;9823.263255255884;9806;9807.558659860288;9850.704478865646;9852.95073524635;9856.408615483095;8341.24928756415;9751.414915105117;9802.90197098027;9835.065668761456;9854.704358869245;32203.677963220303;39477.80261098705;39491.21017579661;39631.603683963076;39668;3284.096873359821;3340.615829179627;3817.8854634361014;4012.177503611782;4172.4784401949755;30629;31156.37687246265;31217;31256.843715781502;31805.363892722016;3212.9196770080703;3214.5981752280964;3215.1962009497624;3312.420326442863;3371.494275858616;399286;471451;492447.53776231245;493032;494299;31027;31974.840125799223;31991.040268791974;58271.70864145652;58647.41352586505;33552.496712549335;33677.66322336794;33862;33924;33969.830150849084;14715.632109197142;14805.111693298437;14903.254837258093;14905.627359316104;14926.253687315591;12584.307863067435;12649.557265495663;12722;12722.681932951751;12760.042996775279;7577.318041376303;7619.580923049175;7692.153863075057;7724.7682569523;7738.187490313549;2510.397504598883;2983.0908218740024;3162.5730526378643;3214.11611806755;3221.8872339468007;819.9631016604267;882.7793051737066;942.0861764088822;975.8877729061107;1017.6438246613626;26322.210333690018;29115;29378;29719.851400742857;30399;905.5743800413795;1009.3842755918884;1015.6394479959656;1040.1357817595147;1042.2756184451885;14157.504487342894;14777.778333325008;15040.924795375953;15278;15302.540923772305;667838;671290;672359;673117;674715;53955;54062.729686352104;54188.45811541913;54493.182602261;54553.45446545563;1189.649053529211;1341.0411555737694;1369.1921766157816;1376.3393571085935;1377.1668140774686;23262.883685581462;26222.08222712196;26685.8665706678;27725.336479811274;29596.408071838872;36525.45211821772;38302.808485957394;48313.75843120762;48356.75821620869;48406.5159348402;552438;554926;556000.7599695966;556143;557225;25813.096541620955;25860.741392585835;26100.086496972515;26161.73838261593;26204;44694.77652611716;45790.77104614455;46039.539604603524;46302;46904.29643555281;37898;37972.86081417562;37981.43027854529;38498.80750596229;38997;37961.62038379581;38110.614874834326;38554.421683675326;38749.03127421837;38766.41850372303;35116.82441587776;36741.81629091944;37247.44128838124;38154;38590.614093858705;32203.35593288168;32904.67095329016;33416;33637.32725345528;33879.98360049202;25114.874425627753;25147.87426062858;25303.620445693698;25638.359041024123;25725.614115788652 11:04:59 INFO - PROCESS | 1658 | |2;jquery.html;372.1626340733348;378.3322435900635;381.7003652133075;386.34321653189585;401.5923837305134;18652.90673546632;20393.49016274594;20434.386968390954;20554.177832886668;20629.690554641693;19960.600787984233;20272.69590956133;20423.387298381072;20662;20812.271570495;5802.709864506758;5816.912746308798;6062.18160548327;6077.1491991121275;6160.907586386208;3870.9612903870884;4055.35114381699;5592.664440133584;5690.231818704463;6097.664628445434;5869.9706501467435;6107.664078475682;6150.261968563756;6160.753569857223;6188.659623720693;5938.376470470603;6139.508839292868;6680.799576012703;6735.932640673628;6740.460763138937;5207.958408318352;5800.071988481823;5859.326177489601;5876.29484461863;5901.911471327933;23485.295441136794;23919.847213750672;24585.754142458525;25272.494550109077;25899.482010359876;2140.4541841830387;2382.3805810489225;2513.9120130795336;2732.1120635793445;2909.883604655832;9781.315307928448;9799.363041402286;10400.739981500446;12842.550510732079;15064.472743453927;1301.3818436242805;1749.8425141737177;1800.774903137108;1972.6646470099975;1992.571597106629;110479;117029.8297017036;117908;120478;121590;11391.373474458818;11415.828762568568;11420.371879546705;11445.427728613537;11740.882591173979;14218.146911185362;14314.141151530941;14314.355853986388;14321.069130506483;14522.564323070324;7504.007497102336;7756.263155000266;8279;8494.980602327636;8564.657413703506;7162.92837071633;7622.894680271441;8532.23209911111;8557.272631826365;8746.86879696805;3739.9439008414897;4081.328073308682;4452.510223875371;4473.172463094273;4547.47704014036;1563.8436156384498;1624.301550333368;1664.96670066598;1882.1812511557516;1930.0639189992842;796.5977181523409;894.4454438248198;928.150742071011;955.6511873166244;982.651158838602;16812.45086624667;20130.993450327718;20610.38168854937;21248.25631102904;21789.23737669174;844.9450785698909;881.5239770523973;902.4359775140537;915.4985823249875;925.9814803704023;7805.804854878674;10636.734081648021;10986.560537578567;11088.223824332219;11136.276142050741;588295;588602;605611.8877622422;613636.863631358;616214;106204.93795061951;146758.26620866827;148123;149930;152482;1007.8488226766044;1010.722051435861;1037.621268237088;1038.127972503101;1038.776663017455;18383.63232735311;18467.538311541783;18773;18803.905980470012;19017.429477115707;56660.71669641625;58297;58591.70704146452;58761.41238587559;58801.70599146977;87344;87712.1228787704;89170;89248.21503570022;89628.7592810185;7929.643166057426;8047.718329858427;8182.140875208018;8205.466644668077;8341.332693384637;12110;12424.37878106073;12448.253104813379;12832;12878;8459.873101903599;9145.542722863964;10976.945115274373;11345.886541134483;11409.25839820409;9036.59335329925;9047.728568142966;9694.418334899929;10162.695119146438;11276.492557834754;10966.725831854268;11194.104471642086;11604.47779849892;11672.124590655905;11794.997425218953;6388.744450222125;6520.673966301761;8169.387295952709;8229.25936665703;8464.57677116154;10926.945365273123;11059.502322395672;11199.440027998731;11268.323900565993;11323.094152467947 11:04:59 INFO - PROCESS | 1658 | |3;mootools.html;1538.4615384615383;1562.656215632561;1574.8582627563517;1791.2566284991728;1911.7705875294967;8167.632456539455;8429.283510901581;8648.654053837847;9232.492212928286;9263.444193348396;8626.22363987241;8945.64217431302;8955.46267223967;9033.638654453815;9041.683541076063;2050.3643870400188;2335.4044718596733;2395.928122156329;2396.8322217444784;2416.142269494331;2375.6199008158696;2382.868942208178;2408.614621660535;2412.3969007748065;2418.7944024757917;2389.9761002390014;2391.210900402863;2399.904003839853;2421.443068094339;2421.7457166997474;2375.4298968247667;2385.2486466762916;2393.820463465247;2394.317619478449;2400.2559206645997;2378.1676413255223;2385.0817435287368;2385.3917251100856;2391.354334329739;2403;8970.551472426418;9098.408603440821;9138.725838224864;9141.862872056925;9147.176754092097;5319.654222475527;5439.347278326626;5485.533729632986;5494.670319780826;5522.812595291991;3619.6199399063244;3717.646823551758;3773.339665558544;3781.3760729479604;3803.5435747710453;1095.1020163465962;1097.3196618096752;1099.230538622959;1111.794318051155;1114.6210288501868;11983.520659173622;12032.33822139782;12118.697032574257;12130.090243231793;12136.15046946714;4425.203463376623;4526.705764125321;4700.506446823034;4705.505921878222;4714.599259062949;9305.674301399418;9327.673531426368;9388.483633400228;9395.154436100784;9402.05979402068;5346.331708536433;5352.785888564492;5353.892922141613;5360.276362691065;5373.543248823816;4497.392851965008;4662.347271382009;4851.248056551241;4996.475370086161;5011.348524691767;3732.757370770891;3825.885223443301;3865.3235683755793;3874.748141370802;3910.413437984268;1177.5937301631095;1195.205188549622;1199.5022065842577;1205.3611585859508;1209.564556759566;803.6664784114527;810.1412502747107;810.2990912860452;825.4551995682821;825.533573530953;5190.2993095932325;5483.314585676791;5592.440755924375;5850;5873.3832947539895;424.92776228041305;430.8169028163081;439.10203633569427;440.9536998615099;457.22728588685015;3776.3013842439236;3862.8261728222296;4064.5732198119363;4085.816138273785;4119.114390406077;10781.353118812896;11130.666080017612;11138.888611113947;11187.384693841917;11217.214794964275;10695.197860160519;10834.241603087708;11151.219414640897;11179;11271.380074096003;8362.163783621589;8384.700371442435;8428.620712067972;8430.934421066755;8455.32357411418;4907.484714104968;5669.829905102853;5774.39368866263;5905.970470147794;6080.7567697291615;9392.342536022414;9779.657711980046;10275.640352587623;10430.061294483532;10447.373157610567;9886.85169722469;10245.641402550875;10262.538185781807;10411.479426028516;10459.895401045893;7014.438844892498;7260.891086633599;7467.514611550232;7543.962280188564;7704.653290601825;8396.412251142361;8481.491110533389;8578.828423431622;8604.65581376763;8633.611487482953;7616.124145723313;7766.611669416619;7781.455298129303;7850.057993040871;7901.249381308767;6720.865582688417;7222.674979625825;7542.47202695806;7562.659680314289;7719.459637825523;7002.194747603887;7099.432045436456;7149.428045756223;7154.2488038757265;7155.356017958408;4636.397268355092;4844.903101938012;4862.41651001882;5047.2429135629945;5076.796928122833;3788.4885540451687;4341.804618792099;4733.887536429005;5112.821051263188;5218.921716174336 11:04:59 INFO - PROCESS | 1658 | |4;prototype.html;302.82587512180487;309.9473089574772;316.86216495824317;317.58396500584223;321.2835377109047;5295.894082118365;5314.697899014742;5441.211024401459;5540.750666220019;5607.878424315133;5265.236540701596;5385.569154467643;5398.973005134969;5471.398146203915;5474.644148130369;3477.72178225742;4128.339465685491;4182.6862985276075;4197.433346498229;4205.621494065534;4090.6318431341174;4163.875083747477;4179.707420480568;4214.072903961123;4232.49210094789;3250.951235731466;4069.3285607874664;4083.775392353419;4156.236102991522;4185;3704.666580007799;3930.6265904739003;4143.254214241435;4222.1766755482695;4227.281362168439;3660.5973342932257;3679.22736225393;3679.7056235501095;3680.815959202056;3682.4292234703394;4576.977115114403;5191.884869078561;5243.2659427680155;5263.552598029172;5295.4969277918535;4150.83396664133;4769.904601907942;4779.808807647724;4808.735519546423;4812.230043193107;5549.056660367707;5714.771409143629;5760;5770.653760774367;5848.444397782204;3964.484616999772;3989.085239085255;4017.995501124719;4023.4970628671417;4036.777977211252;5360.775278259556;5423.918641220384;5768.2501274834;5802.738876750556;6128.509719222451;3233.7089661930067;3402;3725.4353171667963;4053.095045328691;4086.0376227235015;3463.8441270142903;3912.4326972589383;3929.3869576731154;4093.774842383638;4130.277201489727;4729.739864307496;4742.383490146259;4752.857414277577;4774.116788394089;4777.211760059621;5283.260343551906;5755.841559415219;5786.537077033827;5843.561732869966;5854.511832471268;1140.4582823158933;1321.557278311757;1321.9537316193887;1421.3603878254826;1441.7116576684705;941.9491858727347;950.7195377363694;966.4006069954237;969.2391472693902;970.6893793985856;5154.561862241677;5255.737213139328;5341.275949265902;5415.675059496442;5426.782928682887;4488.94509790199;5021.844975655619;5164.992826398891;5252.501012403815;5916.349201587822;5040.722760248222;5046.495350464924;5258.2638430619745;5336.332958380202;5364.329458817648;2288.1648198407793;2312.537492501506;2543.2370288913253;2619.5022945640267;2680.4721308854155;4550.635949124062;4606.907861842811;4821.397325334333;5022.397312322471;5248.921266181011;4564.612007979293;5023.321851549994;5127.820676212595;5668.51817595509;6164.815055548341;4872.707637541759;5434.858679677233;5741.827745167651;5749.396313387034;6264.968675156686;6081;6084.783043391428;6118.357572455006;6133.141360209485;6155.230596175478;4612.74629895359;4980.576650984707;5938.792142275;5964.135200396005;5975.462208401264;5692;6259.06114082873;6271;6279.340669229848;6292.3078461368295;3724.5158129444085;3807.3337165996395;3885.9805700972447;3988.700847436512;3993.6405723485027;5229.47705229474;5235.764390602508;5537.840242305423;5951.345352011362;6054.576179667381;4877.902471943784;5258.50044245796;5693.089105743061;5906.873525530891;5914.467697907209;4898.657094003385;5419.34967803866;5575.8884822304135;5670.177824215383;5931.199288096224;5415.268938693226;5430.918536222039;5729.627574207663;5771.7402716877;5842.415758424124;5773.1051686988585;5803.430890002703;5847.619904706181;5923.851903702442;5966.373530779206;5455.345358556999;5797.4782269595935;5825.796097136749;5832.300123984979;5891.882162356815 11:04:59 INFO - PROCESS | 1658 | |5;yui.html;686.591478070548;687.4225650453618;689.6448329110509;702.6135625406027;744.95157814742;15320.540383788477;15468.22658867055;15593.922030389833;15763.527094187179;15884.841151588465;14095.859041409583;14285.92857035716;14331.78502322466;14365.28173591319;14372.353244104015;677.0893148714971;866.6620018192895;881.4006475596592;886.313107341812;886.3441053620306;694.8818700820847;860.9797390092729;887.5961437545933;893.3657103456553;894.2845723421237;921.3412410126758;932.5803388475214;932.7574830544073;934.8924873639517;945.678469320431;902.3863772634638;921.8525035994243;931.3061768982152;932.8507438809758;934.397313732642;670.4368330602268;690.8134803602999;691.1153723234269;691.6576294734139;695.9826004349881;14994.175320357375;15075.020123691926;15098.849011509854;15100.622484437978;15121.16833574153;14179.14925104497;14288.285585720672;14386.496472623408;14422.927885360506;14734.926325368411;10531.84202236967;11949.940250298692;11954.222975506564;12109.03127749778;12327.445264963098;3750.362438385481;3806.5460681406544;3843.13529455873;3954.4068389741483;3965.940510892339;110345;113649;113814.43092784483;114426;123779.14331285127;10614.734631634117;10757.78484430307;10807.945960270306;10846.403447810446;10878.945605272082;22626.66060009101;22708;22736.772632273798;22763.658545121507;22777.88611056934;7987.640556174986;8094.554799485967;8142.982127234096;8169.673213071529;8333.33333333332;4414.6247568957;4617.12274667812;4626.699264547794;4683.695559788603;4717.268823332389;2965.940681186364;3614.566252049771;3680.392735198662;3684.6499582539614;3701.259748050347;659.4954859532462;671.1208317104608;716.6989864257024;725.372552741885;733.3546479098359;632.4718859752066;646.1470858466872;665.1353240786957;666.6899891550355;685.413971054751;8928.419652722552;9004.680280517736;9492.952535237418;9698;10060.748481288027;595.9493443057389;641.8748344072955;648.4617767253169;678.9898151527731;697.9860402791916;4095.6141180687578;4289;4400.647948164139;4451.844185453494;4751.6436267279405;152801.23599382155;158364;162005;162421;163775;123064;123230;123954;126399;128201.35899320444;77592;96741;99839.50080249697;122497.38751306364;122963;9189.540522973692;9377.156055954996;9411.450052748243;9866.506674666094;10102.848457273;45930.162793487885;49399;51482.970340593725;54275.72862135664;55287.44712552823;45528.544714553755;51341.7432912848;75751.62124189344;84708.57645711732;85637.57181214304;12703.237805731686;12724;12939.547115851277;13223;13271.336433178496;14583.562493125222;14732.484363047242;14959.626009349853;15031.098134111988;15320.310586023434;9162.908370916206;9926.851097233402;10898.782024359627;12121.27272363661;12313.56902508408;13241.205527668371;13502.662433438849;13624.795628065785;13970.860291396955;14033.649158771112;13222.4049917752;13284.468621255042;13470.865291347352;13493.527726529917;13817.309134543433;10723.94638026836;11118.555257789598;11146.944265278622;11234.3259404436;11360.464188866084;9715;10161.441120738431;11245.71885702864;11258.718532036764;11561.132915031574 11:04:59 INFO - PROCESS | 1658 | __end_tp_report 11:04:59 INFO - PROCESS | 1658 | __start_cc_report 11:04:59 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,378 11:04:59 INFO - PROCESS | 1658 | __end_cc_report 11:04:59 INFO - PROCESS | 1658 | __startTimestamp1473703499696__endTimestamp 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | ------- Summary: start ------- 11:04:59 INFO - PROCESS | 1658 | Number of tests: 6 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5674.14 Median:5856.18 stddev:713.32 (12.2%) stddev-sans-first:681.96 11:04:59 INFO - PROCESS | 1658 | Values: 2918.3 3384.1 3386.0 3417.5 3460.3 5637.3 5696.8 5699.2 5702.4 5734.1 5617.9 5665.9 5706.4 5737.5 5771.5 4770.9 5142.2 5218.2 5291.6 5313.7 3764.5 3863.6 4322.7 4649.3 5271.4 4628.6 4714.3 5141.9 5256.4 5260.7 5099.9 5187.6 5232.8 5280.4 5306.3 5184.1 5185.0 5216.8 5217.6 5239.3 4611.4 5648.9 5804.0 5805.5 5807.4 5018.3 6114.8 6144.0 6195.2 6282.6 6239.7 6250.8 6265.9 6272.4 6289.3 5824.8 5846.1 5850.4 5856.2 5899.7 6166.5 6192.1 6202.8 6210.6 6239.0 6110.2 6118.3 6207.5 6215.3 6228.2 6271.8 6272.9 6274.1 6289.8 6291.9 6107.6 6131.3 6151.4 6174.8 6179.0 6230.5 6271.2 6289.6 6293.3 6296.7 5006.1 5012.8 5019.7 5021.3 5041.9 4393.3 4397.6 4407.1 4444.0 4508.8 5662.2 5666.5 5734.4 5750.2 5754.1 6209.5 6252.5 6266.5 6271.1 6278.6 5600.9 5657.9 5679.2 5735.9 5777.8 5415.3 5489.2 5501.0 5520.9 5523.0 6323.3 6326.1 6330.9 6345.0 6353.1 6125.8 6196.0 6275.0 6277.7 6295.6 6213.6 6228.2 6267.7 6318.1 6336.8 6192.6 6251.3 6254.0 6262.7 6267.7 5774.7 6291.3 6310.0 6312.9 6314.4 4473.6 6080.1 6104.9 6105.1 6194.5 6137.7 6168.5 6168.5 6171.8 6197.1 5232.5 5871.2 5963.3 5992.8 6109.8 5036.6 5417.0 5858.7 6086.1 6165.6 4821.9 5042.5 5599.6 6003.5 6046.9 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:67564.83 Median:38526.61 stddev:153447.60 (398.3%) stddev-sans-first:153795.37 11:04:59 INFO - PROCESS | 1658 | Values: 471.6 487.0 490.6 501.1 555.5 35456.6 40193.4 40233.0 40248.6 40276.8 32614.0 40669.4 41067.0 41102.4 41156.0 9581.6 9798.9 9812.4 9813.5 9814.2 9696.0 9787.8 9790.1 9791.4 9806.6 9754.6 9792.0 9792.1 9809.4 9823.3 9806.0 9807.6 9850.7 9853.0 9856.4 8341.2 9751.4 9802.9 9835.1 9854.7 32203.7 39477.8 39491.2 39631.6 39668.0 3284.1 3340.6 3817.9 4012.2 4172.5 30629.0 31156.4 31217.0 31256.8 31805.4 3212.9 3214.6 3215.2 3312.4 3371.5 399286.0 471451.0 492447.5 493032.0 494299.0 31027.0 31974.8 31991.0 58271.7 58647.4 33552.5 33677.7 33862.0 33924.0 33969.8 14715.6 14805.1 14903.3 14905.6 14926.3 12584.3 12649.6 12722.0 12722.7 12760.0 7577.3 7619.6 7692.2 7724.8 7738.2 2510.4 2983.1 3162.6 3214.1 3221.9 820.0 882.8 942.1 975.9 1017.6 26322.2 29115.0 29378.0 29719.9 30399.0 905.6 1009.4 1015.6 1040.1 1042.3 14157.5 14777.8 15040.9 15278.0 15302.5 667838.0 671290.0 672359.0 673117.0 674715.0 53955.0 54062.7 54188.5 54493.2 54553.5 1189.6 1341.0 1369.2 1376.3 1377.2 23262.9 26222.1 26685.9 27725.3 29596.4 36525.5 38302.8 48313.8 48356.8 48406.5 552438.0 554926.0 556000.8 556143.0 557225.0 25813.1 25860.7 26100.1 26161.7 26204.0 44694.8 45790.8 46039.5 46302.0 46904.3 37898.0 37972.9 37981.4 38498.8 38997.0 37961.6 38110.6 38554.4 38749.0 38766.4 35116.8 36741.8 37247.4 38154.0 38590.6 32203.4 32904.7 33416.0 33637.3 33880.0 25114.9 25147.9 25303.6 25638.4 25725.6 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35812.66 Median:2821.00 stddev:101105.77 (3584.0%) stddev-sans-first:101354.38 11:04:59 INFO - PROCESS | 1658 | Values: 372.2 378.3 381.7 386.3 401.6 18652.9 20393.5 20434.4 20554.2 20629.7 19960.6 20272.7 20423.4 20662.0 20812.3 5802.7 5816.9 6062.2 6077.1 6160.9 3871.0 4055.4 5592.7 5690.2 6097.7 5870.0 6107.7 6150.3 6160.8 6188.7 5938.4 6139.5 6680.8 6735.9 6740.5 5208.0 5800.1 5859.3 5876.3 5901.9 23485.3 23919.8 24585.8 25272.5 25899.5 2140.5 2382.4 2513.9 2732.1 2909.9 9781.3 9799.4 10400.7 12842.6 15064.5 1301.4 1749.8 1800.8 1972.7 1992.6 110479.0 117029.8 117908.0 120478.0 121590.0 11391.4 11415.8 11420.4 11445.4 11740.9 14218.1 14314.1 14314.4 14321.1 14522.6 7504.0 7756.3 8279.0 8495.0 8564.7 7162.9 7622.9 8532.2 8557.3 8746.9 3739.9 4081.3 4452.5 4473.2 4547.5 1563.8 1624.3 1665.0 1882.2 1930.1 796.6 894.4 928.2 955.7 982.7 16812.5 20131.0 20610.4 21248.3 21789.2 844.9 881.5 902.4 915.5 926.0 7805.8 10636.7 10986.6 11088.2 11136.3 588295.0 588602.0 605611.9 613636.9 616214.0 106204.9 146758.3 148123.0 149930.0 152482.0 1007.8 1010.7 1037.6 1038.1 1038.8 18383.6 18467.5 18773.0 18803.9 19017.4 56660.7 58297.0 58591.7 58761.4 58801.7 87344.0 87712.1 89170.0 89248.2 89628.8 7929.6 8047.7 8182.1 8205.5 8341.3 12110.0 12424.4 12448.3 12832.0 12878.0 8459.9 9145.5 10976.9 11345.9 11409.3 9036.6 9047.7 9694.4 10162.7 11276.5 10966.7 11194.1 11604.5 11672.1 11795.0 6388.7 6520.7 8169.4 8229.3 8464.6 10926.9 11059.5 11199.4 11268.3 11323.1 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5749.10 Median:4710.05 stddev:3299.15 (70.0%) stddev-sans-first:3293.23 11:04:59 INFO - PROCESS | 1658 | Values: 1538.5 1562.7 1574.9 1791.3 1911.8 8167.6 8429.3 8648.7 9232.5 9263.4 8626.2 8945.6 8955.5 9033.6 9041.7 2050.4 2335.4 2395.9 2396.8 2416.1 2375.6 2382.9 2408.6 2412.4 2418.8 2390.0 2391.2 2399.9 2421.4 2421.7 2375.4 2385.2 2393.8 2394.3 2400.3 2378.2 2385.1 2385.4 2391.4 2403.0 8970.6 9098.4 9138.7 9141.9 9147.2 5319.7 5439.3 5485.5 5494.7 5522.8 3619.6 3717.6 3773.3 3781.4 3803.5 1095.1 1097.3 1099.2 1111.8 1114.6 11983.5 12032.3 12118.7 12130.1 12136.2 4425.2 4526.7 4700.5 4705.5 4714.6 9305.7 9327.7 9388.5 9395.2 9402.1 5346.3 5352.8 5353.9 5360.3 5373.5 4497.4 4662.3 4851.2 4996.5 5011.3 3732.8 3825.9 3865.3 3874.7 3910.4 1177.6 1195.2 1199.5 1205.4 1209.6 803.7 810.1 810.3 825.5 825.5 5190.3 5483.3 5592.4 5850.0 5873.4 424.9 430.8 439.1 441.0 457.2 3776.3 3862.8 4064.6 4085.8 4119.1 10781.4 11130.7 11138.9 11187.4 11217.2 10695.2 10834.2 11151.2 11179.0 11271.4 8362.2 8384.7 8428.6 8430.9 8455.3 4907.5 5669.8 5774.4 5906.0 6080.8 9392.3 9779.7 10275.6 10430.1 10447.4 9886.9 10245.6 10262.5 10411.5 10459.9 7014.4 7260.9 7467.5 7544.0 7704.7 8396.4 8481.5 8578.8 8604.7 8633.6 7616.1 7766.6 7781.5 7850.1 7901.2 6720.9 7222.7 7542.5 7562.7 7719.5 7002.2 7099.4 7149.4 7154.2 7155.4 4636.4 4844.9 4862.4 5047.2 5076.8 3788.5 4341.8 4733.9 5112.8 5218.9 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4613.96 Median:5232.62 stddev:1435.86 (27.4%) stddev-sans-first:1402.96 11:04:59 INFO - PROCESS | 1658 | Values: 302.8 309.9 316.9 317.6 321.3 5295.9 5314.7 5441.2 5540.8 5607.9 5265.2 5385.6 5399.0 5471.4 5474.6 3477.7 4128.3 4182.7 4197.4 4205.6 4090.6 4163.9 4179.7 4214.1 4232.5 3251.0 4069.3 4083.8 4156.2 4185.0 3704.7 3930.6 4143.3 4222.2 4227.3 3660.6 3679.2 3679.7 3680.8 3682.4 4577.0 5191.9 5243.3 5263.6 5295.5 4150.8 4769.9 4779.8 4808.7 4812.2 5549.1 5714.8 5760.0 5770.7 5848.4 3964.5 3989.1 4018.0 4023.5 4036.8 5360.8 5423.9 5768.3 5802.7 6128.5 3233.7 3402.0 3725.4 4053.1 4086.0 3463.8 3912.4 3929.4 4093.8 4130.3 4729.7 4742.4 4752.9 4774.1 4777.2 5283.3 5755.8 5786.5 5843.6 5854.5 1140.5 1321.6 1322.0 1421.4 1441.7 941.9 950.7 966.4 969.2 970.7 5154.6 5255.7 5341.3 5415.7 5426.8 4488.9 5021.8 5165.0 5252.5 5916.3 5040.7 5046.5 5258.3 5336.3 5364.3 2288.2 2312.5 2543.2 2619.5 2680.5 4550.6 4606.9 4821.4 5022.4 5248.9 4564.6 5023.3 5127.8 5668.5 6164.8 4872.7 5434.9 5741.8 5749.4 6265.0 6081.0 6084.8 6118.4 6133.1 6155.2 4612.7 4980.6 5938.8 5964.1 5975.5 5692.0 6259.1 6271.0 6279.3 6292.3 3724.5 3807.3 3886.0 3988.7 3993.6 5229.5 5235.8 5537.8 5951.3 6054.6 4877.9 5258.5 5693.1 5906.9 5914.5 4898.7 5419.3 5575.9 5670.2 5931.2 5415.3 5430.9 5729.6 5771.7 5842.4 5773.1 5803.4 5847.6 5923.9 5966.4 5455.3 5797.5 5825.8 5832.3 5891.9 11:04:59 INFO - PROCESS | 1658 | 11:04:59 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24078.84 Median:3725.81 stddev:39351.63 (1056.2%) stddev-sans-first:39422.82 11:04:59 INFO - PROCESS | 1658 | Values: 686.6 687.4 689.6 702.6 745.0 15320.5 15468.2 15593.9 15763.5 15884.8 14095.9 14285.9 14331.8 14365.3 14372.4 677.1 866.7 881.4 886.3 886.3 694.9 861.0 887.6 893.4 894.3 921.3 932.6 932.8 934.9 945.7 902.4 921.9 931.3 932.9 934.4 670.4 690.8 691.1 691.7 696.0 14994.2 15075.0 15098.8 15100.6 15121.2 14179.1 14288.3 14386.5 14422.9 14734.9 10531.8 11949.9 11954.2 12109.0 12327.4 3750.4 3806.5 3843.1 3954.4 3965.9 110345.0 113649.0 113814.4 114426.0 123779.1 10614.7 10757.8 10807.9 10846.4 10878.9 22626.7 22708.0 22736.8 22763.7 22777.9 7987.6 8094.6 8143.0 8169.7 8333.3 4414.6 4617.1 4626.7 4683.7 4717.3 2965.9 3614.6 3680.4 3684.6 3701.3 659.5 671.1 716.7 725.4 733.4 632.5 646.1 665.1 666.7 685.4 8928.4 9004.7 9493.0 9698.0 10060.7 595.9 641.9 648.5 679.0 698.0 4095.6 4289.0 4400.6 4451.8 4751.6 152801.2 158364.0 162005.0 162421.0 163775.0 123064.0 123230.0 123954.0 126399.0 128201.4 77592.0 96741.0 99839.5 122497.4 122963.0 9189.5 9377.2 9411.5 9866.5 10102.8 45930.2 49399.0 51483.0 54275.7 55287.4 45528.5 51341.7 75751.6 84708.6 85637.6 12703.2 12724.0 12939.5 13223.0 13271.3 14583.6 14732.5 14959.6 15031.1 15320.3 9162.9 9926.9 10898.8 12121.3 12313.6 13241.2 13502.7 13624.8 13970.9 14033.6 13222.4 13284.5 13470.9 13493.5 13817.3 10723.9 11118.6 11146.9 11234.3 11360.5 9715.0 10161.4 11245.7 11258.7 11561.1 11:04:59 INFO - PROCESS | 1658 | -------- Summary: end -------- 11:04:59 INFO - PROCESS | 1658 | 11:05:00 INFO - TEST-INFO | 1658: exit 0 11:05:00 INFO - TEST-OK | dromaeo_css | took 1114696ms 11:05:00 INFO - TEST-START | kraken 11:05:00 INFO - operating with platform_type : mac_ 11:05:00 INFO - Initialising browser for kraken test... 11:05:00 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpI4uA8H/profile http://localhost:49266/getInfo.html) 11:05:03 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 11:05:03 INFO - PROCESS | 1666 | colorDepth:24 11:05:03 INFO - PROCESS | 1666 | Browser inner width/height: 1024/663 11:05:03 INFO - PROCESS | 1666 | __metrics 11:05:03 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 11:05:03 INFO - TEST-INFO | 1666: exit 0 11:05:03 INFO - Browser initialized. 11:05:03 INFO - Running cycle 1/1 for kraken test... 11:05:03 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpI4uA8H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:05:15 INFO - PROCESS | 1668 | RSS: Main: 257699840 11:05:15 INFO - PROCESS | 1668 | 11:05:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 11:05:19 INFO - PROCESS | 1668 | RSS: Main: 268156928 11:05:19 INFO - PROCESS | 1668 | 11:05:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 11:05:22 INFO - PROCESS | 1668 | RSS: Main: 276746240 11:05:22 INFO - PROCESS | 1668 | 11:05:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 11:05:26 INFO - PROCESS | 1668 | RSS: Main: 272543744 11:05:26 INFO - PROCESS | 1668 | 11:05:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 11:05:29 INFO - PROCESS | 1668 | RSS: Main: 273965056 11:05:29 INFO - PROCESS | 1668 | 11:05:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:05:32 INFO - PROCESS | 1668 | RSS: Main: 273780736 11:05:32 INFO - PROCESS | 1668 | 11:05:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 11:05:35 INFO - PROCESS | 1668 | RSS: Main: 274104320 11:05:35 INFO - PROCESS | 1668 | 11:05:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 11:05:39 INFO - PROCESS | 1668 | RSS: Main: 274075648 11:05:39 INFO - PROCESS | 1668 | 11:05:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 11:05:42 INFO - PROCESS | 1668 | RSS: Main: 274722816 11:05:42 INFO - PROCESS | 1668 | 11:05:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:05:44 INFO - PROCESS | 1668 | RSS: Main: 274157568 11:05:44 INFO - PROCESS | 1668 | 11:05:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:05:47 INFO - PROCESS | 1668 | RSS: Main: 274337792 11:05:47 INFO - PROCESS | 1668 | 11:05:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:05:50 INFO - PROCESS | 1668 | RSS: Main: 274526208 11:05:50 INFO - PROCESS | 1668 | 11:05:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:05:54 INFO - PROCESS | 1668 | RSS: Main: 274522112 11:05:54 INFO - PROCESS | 1668 | 11:05:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:05:57 INFO - PROCESS | 1668 | RSS: Main: 274800640 11:05:57 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 11:05:59 INFO - PROCESS | 1668 | RSS: Main: 276811776 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | __start_tp_report 11:05:59 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 11:05:59 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 11:05:59 INFO - PROCESS | 1668 | |i|pagename|runs| 11:05:59 INFO - PROCESS | 1668 | |0;ai-astar;116;109;116;118;117;101;117;115;116;116 11:05:59 INFO - PROCESS | 1668 | |1;audio-beat-detection;142;111;131;159;111;132;130;111;161;112 11:05:59 INFO - PROCESS | 1668 | |2;audio-dft;200;205;228;226;246;277;193;239;277;205 11:05:59 INFO - PROCESS | 1668 | |3;audio-fft;67;83;75;145;85;76;88;94;160;51 11:05:59 INFO - PROCESS | 1668 | |4;audio-oscillator;69;73;114;72;71;98;107;69;73;110 11:05:59 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;92;86;83;130;128;86;92;87 11:05:59 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;100;98;98;101;99;99;103;100;100 11:05:59 INFO - PROCESS | 1668 | |7;imaging-desaturate;91;93;93;90;103;143;148;90;90;91 11:05:59 INFO - PROCESS | 1668 | |8;json-parse-financial;56;60;58;56;57;58;58;58;57;56 11:05:59 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;63;104;109;88;83;75;71;68;69;104 11:05:59 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;92;86;91;90;87;86;87;87;91;89 11:05:59 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;162;143;135;167;154;151;149;154;186;143 11:05:59 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;176;173;170;182;234;175;174;172;173;175 11:05:59 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;69;70;78;90;89;69;67;68;68;73 11:05:59 INFO - PROCESS | 1668 | __end_tp_report 11:05:59 INFO - PROCESS | 1668 | __start_cc_report 11:05:59 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,961 11:05:59 INFO - PROCESS | 1668 | __end_cc_report 11:05:59 INFO - PROCESS | 1668 | __startTimestamp1473703559605__endTimestamp 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | ------- Summary: start ------- 11:05:59 INFO - PROCESS | 1668 | Number of tests: 14 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:114.10 Median:116.00 stddev:5.22 (4.5%) stddev-sans-first:5.49 11:05:59 INFO - PROCESS | 1668 | Values: 116.0 109.0 116.0 118.0 117.0 101.0 117.0 115.0 116.0 116.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:131.50 stddev:19.32 (14.7%) stddev-sans-first:19.99 11:05:59 INFO - PROCESS | 1668 | Values: 142.0 111.0 131.0 159.0 111.0 132.0 130.0 111.0 161.0 112.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:229.60 Median:233.50 stddev:30.35 (13.0%) stddev-sans-first:30.25 11:05:59 INFO - PROCESS | 1668 | Values: 200.0 205.0 228.0 226.0 246.0 277.0 193.0 239.0 277.0 205.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:92.40 Median:79.50 stddev:34.05 (42.8%) stddev-sans-first:34.85 11:05:59 INFO - PROCESS | 1668 | Values: 67.0 83.0 75.0 145.0 85.0 76.0 88.0 94.0 160.0 51.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:85.60 Median:71.50 stddev:19.09 (26.7%) stddev-sans-first:19.28 11:05:59 INFO - PROCESS | 1668 | Values: 69.0 73.0 114.0 72.0 71.0 98.0 107.0 69.0 73.0 110.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:87.00 stddev:17.61 (20.2%) stddev-sans-first:18.50 11:05:59 INFO - PROCESS | 1668 | Values: 89.0 87.0 92.0 86.0 83.0 130.0 128.0 86.0 92.0 87.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:98.00 stddev:1.49 (1.5%) stddev-sans-first:1.56 11:05:59 INFO - PROCESS | 1668 | Values: 99.0 100.0 98.0 98.0 101.0 99.0 99.0 103.0 100.0 100.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:103.20 Median:90.50 stddev:22.66 (25.0%) stddev-sans-first:23.60 11:05:59 INFO - PROCESS | 1668 | Values: 91.0 93.0 93.0 90.0 103.0 143.0 148.0 90.0 90.0 91.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.26 (2.2%) stddev-sans-first:1.24 11:05:59 INFO - PROCESS | 1668 | Values: 56.0 60.0 58.0 56.0 57.0 58.0 58.0 58.0 57.0 56.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:70.00 stddev:17.03 (24.3%) stddev-sans-first:16.39 11:05:59 INFO - PROCESS | 1668 | Values: 63.0 104.0 109.0 88.0 83.0 75.0 71.0 68.0 69.0 104.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:89.50 stddev:2.27 (2.5%) stddev-sans-first:2.05 11:05:59 INFO - PROCESS | 1668 | Values: 92.0 86.0 91.0 90.0 87.0 86.0 87.0 87.0 91.0 89.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:154.40 Median:154.00 stddev:14.50 (9.4%) stddev-sans-first:15.12 11:05:59 INFO - PROCESS | 1668 | Values: 162.0 143.0 135.0 167.0 154.0 151.0 149.0 154.0 186.0 143.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:175.00 stddev:19.10 (10.9%) stddev-sans-first:20.19 11:05:59 INFO - PROCESS | 1668 | Values: 176.0 173.0 170.0 182.0 234.0 175.0 174.0 172.0 173.0 175.0 11:05:59 INFO - PROCESS | 1668 | 11:05:59 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:71.50 stddev:8.72 (12.2%) stddev-sans-first:9.06 11:05:59 INFO - PROCESS | 1668 | Values: 69.0 70.0 78.0 90.0 89.0 69.0 67.0 68.0 68.0 73.0 11:05:59 INFO - PROCESS | 1668 | -------- Summary: end -------- 11:05:59 INFO - PROCESS | 1668 | 11:06:00 INFO - TEST-INFO | 1668: exit 0 11:06:00 INFO - TEST-OK | kraken | took 60212ms 11:06:00 INFO - SUITE-END | took 1174s 11:06:00 INFO - Completed test suite (00:19:35) 11:06:00 INFO - javascript benchmark 11:06:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7218.214620130478, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2918.255844759586, 3384.103212648647, 3385.966140338599, 3417.521546983421, 3460.290640418714, 5637.295338082739, 5696.829095127143, 5699.202111704365, 5702.401247868971, 5734.082546792514, 5617.9157312640245, 5665.886682266352, 5706.429357064291, 5737.540996720263, 5771.538276937856, 4770.928436073462, 5142.202958541432, 5218.217267409881, 5291.550218231455, 5313.654612450197, 3764.5482542094987, 3863.632954869283, 4322.7190232634775, 4649.302604609302, 5271.42014378418, 4628.63455280693, 4714.269288260325, 5141.871453213662, 5256.421793602701, 5260.658057226268, 5099.923501147486, 5187.636865419422, 5232.843014709565, 5280.445553216934, 5306.257124002643, 5184.11869982104, 5184.974075129638, 5216.7913283468615, 5217.634765566412, 5239.266502689627, 4611.423572053493, 5648.85877853057, 5804.013317735995, 5805.5065319447895, 5807.448292412257, 5018.2974383586325, 6114.755409783604, 6144.033909149074, 6195.2255968004, 6282.591631543981, 6239.719212635397, 6250.812475625738, 6265.906011409833, 6272.372762723737, 6289.276733175698, 5824.767009319622, 5846.123081537803, 5850.385709500441, 5856.180134781134, 5899.675517846474, 6166.537509686702, 6192.0711893215475, 6202.81391558254, 6210.5652604317265, 6238.968805155945, 6110.205673262448, 6118.296395914527, 6207.472364849038, 6215.285242197115, 6228.159198508143, 6271.811845644638, 6272.905906411407, 6274.058891166362, 6289.842753931189, 6291.905621415683, 6107.633541987495, 6131.254333328352, 6151.446369826737, 6174.845628859314, 6178.969105154535, 6230.532710046765, 6271.153394291803, 6289.622622642656, 6293.3391993840905, 6296.716647750771, 5006.148954677714, 5012.8496145115705, 5019.749012549358, 5021.297018417425, 5041.899162016739, 4393.340998850198, 4397.560243975577, 4407.118576284756, 4444.000099977479, 4508.752018639007, 5662.17898404729, 5666.518345940559, 5734.426557344315, 5750.2237197978575, 5754.108113242371, 6209.472194863487, 6252.499800015988, 6266.467350275187, 6271.122042913996, 6278.591891527036, 5600.915986260209, 5657.9434205658235, 5679.233303503974, 5735.856603584861, 5777.768889244468, 5415.268938693304, 5489.204065410493, 5501.0, 5520.944790552124, 5523.0, 6323.336049714806, 6326.082718005863, 6330.905036424458, 6344.953082741481, 6353.142325786144, 6125.84685382869, 6196.0, 6275.02737075754, 6277.654728989767, 6295.590786599039, 6213.565050446426, 6228.22147231596, 6267.6866156692895, 6318.052292156213, 6336.778212762531, 6192.628442293477, 6251.34360892118, 6254.0, 6262.655553944577, 6267.686615669107, 5774.653520788766, 6291.276503202191, 6310.0, 6312.873742525216, 6314.431701146735, 4473.642108631237, 6080.0575910733905, 6104.9389506106145, 6105.065535583811, 6194.5354098443695, 6137.6931153441255, 6168.475679567287, 6168.475679567287, 6171.814845554641, 6197.070439434121, 5232.529072383351, 5871.150587565075, 5963.254593175853, 5992.760289588542, 6109.75560977556, 5036.647434679684, 5416.9729151353995, 5858.736356863866, 6086.1175129606845, 6165.568410211422, 4821.879453013562, 5042.470540593332, 5599.600099975006, 6003.489703375262, 6046.909296360646], "value": 5627.67335335352, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.5590922487472, 487.0332390205442, 490.6148673291467, 501.13053851567525, 555.5250261026822, 35456.64543354566, 40193.39709904354, 40233.0, 40248.59751402485, 40276.798616006876, 32614.0, 40669.389959150634, 41067.0, 41102.383464247985, 41156.0, 9581.61673533058, 9798.902010979906, 9812.362196457207, 9813.460259685715, 9814.214862810994, 9695.951520242425, 9787.755306117368, 9790.069943355405, 9791.363561368524, 9806.558704858262, 9754.561044753003, 9792.020797920222, 9792.069753373453, 9809.362391444569, 9823.263255255884, 9806.0, 9807.558659860288, 9850.704478865646, 9852.95073524635, 9856.408615483095, 8341.24928756415, 9751.414915105117, 9802.90197098027, 9835.065668761456, 9854.704358869245, 32203.677963220303, 39477.80261098705, 39491.21017579661, 39631.603683963076, 39668.0, 3284.096873359821, 3340.615829179627, 3817.8854634361014, 4012.177503611782, 4172.4784401949755, 30629.0, 31156.37687246265, 31217.0, 31256.843715781502, 31805.363892722016, 3212.9196770080703, 3214.5981752280964, 3215.1962009497624, 3312.420326442863, 3371.494275858616, 399286.0, 471451.0, 492447.53776231245, 493032.0, 494299.0, 31027.0, 31974.840125799223, 31991.040268791974, 58271.70864145652, 58647.41352586505, 33552.496712549335, 33677.66322336794, 33862.0, 33924.0, 33969.830150849084, 14715.632109197142, 14805.111693298437, 14903.254837258093, 14905.627359316104, 14926.253687315591, 12584.307863067435, 12649.557265495663, 12722.0, 12722.681932951751, 12760.042996775279, 7577.318041376303, 7619.580923049175, 7692.153863075057, 7724.7682569523, 7738.187490313549, 2510.397504598883, 2983.0908218740024, 3162.5730526378643, 3214.11611806755, 3221.8872339468007, 819.9631016604267, 882.7793051737066, 942.0861764088822, 975.8877729061107, 1017.6438246613626, 26322.210333690018, 29115.0, 29378.0, 29719.851400742857, 30399.0, 905.5743800413795, 1009.3842755918884, 1015.6394479959656, 1040.1357817595147, 1042.2756184451885, 14157.504487342894, 14777.778333325008, 15040.924795375953, 15278.0, 15302.540923772305, 667838.0, 671290.0, 672359.0, 673117.0, 674715.0, 53955.0, 54062.729686352104, 54188.45811541913, 54493.182602261, 54553.45446545563, 1189.649053529211, 1341.0411555737694, 1369.1921766157816, 1376.3393571085935, 1377.1668140774686, 23262.883685581462, 26222.08222712196, 26685.8665706678, 27725.336479811274, 29596.408071838872, 36525.45211821772, 38302.808485957394, 48313.75843120762, 48356.75821620869, 48406.5159348402, 552438.0, 554926.0, 556000.7599695966, 556143.0, 557225.0, 25813.096541620955, 25860.741392585835, 26100.086496972515, 26161.73838261593, 26204.0, 44694.77652611716, 45790.77104614455, 46039.539604603524, 46302.0, 46904.29643555281, 37898.0, 37972.86081417562, 37981.43027854529, 38498.80750596229, 38997.0, 37961.62038379581, 38110.614874834326, 38554.421683675326, 38749.03127421837, 38766.41850372303, 35116.82441587776, 36741.81629091944, 37247.44128838124, 38154.0, 38590.614093858705, 32203.35593288168, 32904.67095329016, 33416.0, 33637.32725345528, 33879.98360049202, 25114.874425627753, 25147.87426062858, 25303.620445693698, 25638.359041024123, 25725.614115788652], "value": 18135.22534303378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.1626340733348, 378.3322435900635, 381.7003652133075, 386.34321653189585, 401.5923837305134, 18652.90673546632, 20393.49016274594, 20434.386968390954, 20554.177832886668, 20629.690554641693, 19960.600787984233, 20272.69590956133, 20423.387298381072, 20662.0, 20812.271570495, 5802.709864506758, 5816.912746308798, 6062.18160548327, 6077.1491991121275, 6160.907586386208, 3870.9612903870884, 4055.35114381699, 5592.664440133584, 5690.231818704463, 6097.664628445434, 5869.9706501467435, 6107.664078475682, 6150.261968563756, 6160.753569857223, 6188.659623720693, 5938.376470470603, 6139.508839292868, 6680.799576012703, 6735.932640673628, 6740.460763138937, 5207.958408318352, 5800.071988481823, 5859.326177489601, 5876.29484461863, 5901.911471327933, 23485.295441136794, 23919.847213750672, 24585.754142458525, 25272.494550109077, 25899.482010359876, 2140.4541841830387, 2382.3805810489225, 2513.9120130795336, 2732.1120635793445, 2909.883604655832, 9781.315307928448, 9799.363041402286, 10400.739981500446, 12842.550510732079, 15064.472743453927, 1301.3818436242805, 1749.8425141737177, 1800.774903137108, 1972.6646470099975, 1992.571597106629, 110479.0, 117029.8297017036, 117908.0, 120478.0, 121590.0, 11391.373474458818, 11415.828762568568, 11420.371879546705, 11445.427728613537, 11740.882591173979, 14218.146911185362, 14314.141151530941, 14314.355853986388, 14321.069130506483, 14522.564323070324, 7504.007497102336, 7756.263155000266, 8279.0, 8494.980602327636, 8564.657413703506, 7162.92837071633, 7622.894680271441, 8532.23209911111, 8557.272631826365, 8746.86879696805, 3739.9439008414897, 4081.328073308682, 4452.510223875371, 4473.172463094273, 4547.47704014036, 1563.8436156384498, 1624.301550333368, 1664.96670066598, 1882.1812511557516, 1930.0639189992842, 796.5977181523409, 894.4454438248198, 928.150742071011, 955.6511873166244, 982.651158838602, 16812.45086624667, 20130.993450327718, 20610.38168854937, 21248.25631102904, 21789.23737669174, 844.9450785698909, 881.5239770523973, 902.4359775140537, 915.4985823249875, 925.9814803704023, 7805.804854878674, 10636.734081648021, 10986.560537578567, 11088.223824332219, 11136.276142050741, 588295.0, 588602.0, 605611.8877622422, 613636.863631358, 616214.0, 106204.93795061951, 146758.26620866827, 148123.0, 149930.0, 152482.0, 1007.8488226766044, 1010.722051435861, 1037.621268237088, 1038.127972503101, 1038.776663017455, 18383.63232735311, 18467.538311541783, 18773.0, 18803.905980470012, 19017.429477115707, 56660.71669641625, 58297.0, 58591.70704146452, 58761.41238587559, 58801.70599146977, 87344.0, 87712.1228787704, 89170.0, 89248.21503570022, 89628.7592810185, 7929.643166057426, 8047.718329858427, 8182.140875208018, 8205.466644668077, 8341.332693384637, 12110.0, 12424.37878106073, 12448.253104813379, 12832.0, 12878.0, 8459.873101903599, 9145.542722863964, 10976.945115274373, 11345.886541134483, 11409.25839820409, 9036.59335329925, 9047.728568142966, 9694.418334899929, 10162.695119146438, 11276.492557834754, 10966.725831854268, 11194.104471642086, 11604.47779849892, 11672.124590655905, 11794.997425218953, 6388.744450222125, 6520.673966301761, 8169.387295952709, 8229.25936665703, 8464.57677116154, 10926.945365273123, 11059.502322395672, 11199.440027998731, 11268.323900565993, 11323.094152467947], "value": 9540.838637444302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.4615384615383, 1562.656215632561, 1574.8582627563517, 1791.2566284991728, 1911.7705875294967, 8167.632456539455, 8429.283510901581, 8648.654053837847, 9232.492212928286, 9263.444193348396, 8626.22363987241, 8945.64217431302, 8955.46267223967, 9033.638654453815, 9041.683541076063, 2050.3643870400188, 2335.4044718596733, 2395.928122156329, 2396.8322217444784, 2416.142269494331, 2375.6199008158696, 2382.868942208178, 2408.614621660535, 2412.3969007748065, 2418.7944024757917, 2389.9761002390014, 2391.210900402863, 2399.904003839853, 2421.443068094339, 2421.7457166997474, 2375.4298968247667, 2385.2486466762916, 2393.820463465247, 2394.317619478449, 2400.2559206645997, 2378.1676413255223, 2385.0817435287368, 2385.3917251100856, 2391.354334329739, 2403.0, 8970.551472426418, 9098.408603440821, 9138.725838224864, 9141.862872056925, 9147.176754092097, 5319.654222475527, 5439.347278326626, 5485.533729632986, 5494.670319780826, 5522.812595291991, 3619.6199399063244, 3717.646823551758, 3773.339665558544, 3781.3760729479604, 3803.5435747710453, 1095.1020163465962, 1097.3196618096752, 1099.230538622959, 1111.794318051155, 1114.6210288501868, 11983.520659173622, 12032.33822139782, 12118.697032574257, 12130.090243231793, 12136.15046946714, 4425.203463376623, 4526.705764125321, 4700.506446823034, 4705.505921878222, 4714.599259062949, 9305.674301399418, 9327.673531426368, 9388.483633400228, 9395.154436100784, 9402.05979402068, 5346.331708536433, 5352.785888564492, 5353.892922141613, 5360.276362691065, 5373.543248823816, 4497.392851965008, 4662.347271382009, 4851.248056551241, 4996.475370086161, 5011.348524691767, 3732.757370770891, 3825.885223443301, 3865.3235683755793, 3874.748141370802, 3910.413437984268, 1177.5937301631095, 1195.205188549622, 1199.5022065842577, 1205.3611585859508, 1209.564556759566, 803.6664784114527, 810.1412502747107, 810.2990912860452, 825.4551995682821, 825.533573530953, 5190.2993095932325, 5483.314585676791, 5592.440755924375, 5850.0, 5873.3832947539895, 424.92776228041305, 430.8169028163081, 439.10203633569427, 440.9536998615099, 457.22728588685015, 3776.3013842439236, 3862.8261728222296, 4064.5732198119363, 4085.816138273785, 4119.114390406077, 10781.353118812896, 11130.666080017612, 11138.888611113947, 11187.384693841917, 11217.214794964275, 10695.197860160519, 10834.241603087708, 11151.219414640897, 11179.0, 11271.380074096003, 8362.163783621589, 8384.700371442435, 8428.620712067972, 8430.934421066755, 8455.32357411418, 4907.484714104968, 5669.829905102853, 5774.39368866263, 5905.970470147794, 6080.7567697291615, 9392.342536022414, 9779.657711980046, 10275.640352587623, 10430.061294483532, 10447.373157610567, 9886.85169722469, 10245.641402550875, 10262.538185781807, 10411.479426028516, 10459.895401045893, 7014.438844892498, 7260.891086633599, 7467.514611550232, 7543.962280188564, 7704.653290601825, 8396.412251142361, 8481.491110533389, 8578.828423431622, 8604.65581376763, 8633.611487482953, 7616.124145723313, 7766.611669416619, 7781.455298129303, 7850.057993040871, 7901.249381308767, 6720.865582688417, 7222.674979625825, 7542.47202695806, 7562.659680314289, 7719.459637825523, 7002.194747603887, 7099.432045436456, 7149.428045756223, 7154.2488038757265, 7155.356017958408, 4636.397268355092, 4844.903101938012, 4862.41651001882, 5047.2429135629945, 5076.796928122833, 3788.4885540451687, 4341.804618792099, 4733.887536429005, 5112.821051263188, 5218.921716174336], "value": 4510.029993589284, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.82587512180487, 309.9473089574772, 316.86216495824317, 317.58396500584223, 321.2835377109047, 5295.894082118365, 5314.697899014742, 5441.211024401459, 5540.750666220019, 5607.878424315133, 5265.236540701596, 5385.569154467643, 5398.973005134969, 5471.398146203915, 5474.644148130369, 3477.72178225742, 4128.339465685491, 4182.6862985276075, 4197.433346498229, 4205.621494065534, 4090.6318431341174, 4163.875083747477, 4179.707420480568, 4214.072903961123, 4232.49210094789, 3250.951235731466, 4069.3285607874664, 4083.775392353419, 4156.236102991522, 4185.0, 3704.666580007799, 3930.6265904739003, 4143.254214241435, 4222.1766755482695, 4227.281362168439, 3660.5973342932257, 3679.22736225393, 3679.7056235501095, 3680.815959202056, 3682.4292234703394, 4576.977115114403, 5191.884869078561, 5243.2659427680155, 5263.552598029172, 5295.4969277918535, 4150.83396664133, 4769.904601907942, 4779.808807647724, 4808.735519546423, 4812.230043193107, 5549.056660367707, 5714.771409143629, 5760.0, 5770.653760774367, 5848.444397782204, 3964.484616999772, 3989.085239085255, 4017.995501124719, 4023.4970628671417, 4036.777977211252, 5360.775278259556, 5423.918641220384, 5768.2501274834, 5802.738876750556, 6128.509719222451, 3233.7089661930067, 3402.0, 3725.4353171667963, 4053.095045328691, 4086.0376227235015, 3463.8441270142903, 3912.4326972589383, 3929.3869576731154, 4093.774842383638, 4130.277201489727, 4729.739864307496, 4742.383490146259, 4752.857414277577, 4774.116788394089, 4777.211760059621, 5283.260343551906, 5755.841559415219, 5786.537077033827, 5843.561732869966, 5854.511832471268, 1140.4582823158933, 1321.557278311757, 1321.9537316193887, 1421.3603878254826, 1441.7116576684705, 941.9491858727347, 950.7195377363694, 966.4006069954237, 969.2391472693902, 970.6893793985856, 5154.561862241677, 5255.737213139328, 5341.275949265902, 5415.675059496442, 5426.782928682887, 4488.94509790199, 5021.844975655619, 5164.992826398891, 5252.501012403815, 5916.349201587822, 5040.722760248222, 5046.495350464924, 5258.2638430619745, 5336.332958380202, 5364.329458817648, 2288.1648198407793, 2312.537492501506, 2543.2370288913253, 2619.5022945640267, 2680.4721308854155, 4550.635949124062, 4606.907861842811, 4821.397325334333, 5022.397312322471, 5248.921266181011, 4564.612007979293, 5023.321851549994, 5127.820676212595, 5668.51817595509, 6164.815055548341, 4872.707637541759, 5434.858679677233, 5741.827745167651, 5749.396313387034, 6264.968675156686, 6081.0, 6084.783043391428, 6118.357572455006, 6133.141360209485, 6155.230596175478, 4612.74629895359, 4980.576650984707, 5938.792142275, 5964.135200396005, 5975.462208401264, 5692.0, 6259.06114082873, 6271.0, 6279.340669229848, 6292.3078461368295, 3724.5158129444085, 3807.3337165996395, 3885.9805700972447, 3988.700847436512, 3993.6405723485027, 5229.47705229474, 5235.764390602508, 5537.840242305423, 5951.345352011362, 6054.576179667381, 4877.902471943784, 5258.50044245796, 5693.089105743061, 5906.873525530891, 5914.467697907209, 4898.657094003385, 5419.34967803866, 5575.8884822304135, 5670.177824215383, 5931.199288096224, 5415.268938693226, 5430.918536222039, 5729.627574207663, 5771.7402716877, 5842.415758424124, 5773.1051686988585, 5803.430890002703, 5847.619904706181, 5923.851903702442, 5966.373530779206, 5455.345358556999, 5797.4782269595935, 5825.796097136749, 5832.300123984979, 5891.882162356815], "value": 4158.727098803841, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.591478070548, 687.4225650453618, 689.6448329110509, 702.6135625406027, 744.95157814742, 15320.540383788477, 15468.22658867055, 15593.922030389833, 15763.527094187179, 15884.841151588465, 14095.859041409583, 14285.92857035716, 14331.78502322466, 14365.28173591319, 14372.353244104015, 677.0893148714971, 866.6620018192895, 881.4006475596592, 886.313107341812, 886.3441053620306, 694.8818700820847, 860.9797390092729, 887.5961437545933, 893.3657103456553, 894.2845723421237, 921.3412410126758, 932.5803388475214, 932.7574830544073, 934.8924873639517, 945.678469320431, 902.3863772634638, 921.8525035994243, 931.3061768982152, 932.8507438809758, 934.397313732642, 670.4368330602268, 690.8134803602999, 691.1153723234269, 691.6576294734139, 695.9826004349881, 14994.175320357375, 15075.020123691926, 15098.849011509854, 15100.622484437978, 15121.16833574153, 14179.14925104497, 14288.285585720672, 14386.496472623408, 14422.927885360506, 14734.926325368411, 10531.84202236967, 11949.940250298692, 11954.222975506564, 12109.03127749778, 12327.445264963098, 3750.362438385481, 3806.5460681406544, 3843.13529455873, 3954.4068389741483, 3965.940510892339, 110345.0, 113649.0, 113814.43092784483, 114426.0, 123779.14331285127, 10614.734631634117, 10757.78484430307, 10807.945960270306, 10846.403447810446, 10878.945605272082, 22626.66060009101, 22708.0, 22736.772632273798, 22763.658545121507, 22777.88611056934, 7987.640556174986, 8094.554799485967, 8142.982127234096, 8169.673213071529, 8333.33333333332, 4414.6247568957, 4617.12274667812, 4626.699264547794, 4683.695559788603, 4717.268823332389, 2965.940681186364, 3614.566252049771, 3680.392735198662, 3684.6499582539614, 3701.259748050347, 659.4954859532462, 671.1208317104608, 716.6989864257024, 725.372552741885, 733.3546479098359, 632.4718859752066, 646.1470858466872, 665.1353240786957, 666.6899891550355, 685.413971054751, 8928.419652722552, 9004.680280517736, 9492.952535237418, 9698.0, 10060.748481288027, 595.9493443057389, 641.8748344072955, 648.4617767253169, 678.9898151527731, 697.9860402791916, 4095.6141180687578, 4289.0, 4400.647948164139, 4451.844185453494, 4751.6436267279405, 152801.23599382155, 158364.0, 162005.0, 162421.0, 163775.0, 123064.0, 123230.0, 123954.0, 126399.0, 128201.35899320444, 77592.0, 96741.0, 99839.50080249697, 122497.38751306364, 122963.0, 9189.540522973692, 9377.156055954996, 9411.450052748243, 9866.506674666094, 10102.848457273, 45930.162793487885, 49399.0, 51482.970340593725, 54275.72862135664, 55287.44712552823, 45528.544714553755, 51341.7432912848, 75751.62124189344, 84708.57645711732, 85637.57181214304, 12703.237805731686, 12724.0, 12939.547115851277, 13223.0, 13271.336433178496, 14583.562493125222, 14732.484363047242, 14959.626009349853, 15031.098134111988, 15320.310586023434, 9162.908370916206, 9926.851097233402, 10898.782024359627, 12121.27272363661, 12313.56902508408, 13241.205527668371, 13502.662433438849, 13624.795628065785, 13970.860291396955, 14033.649158771112, 13222.4049917752, 13284.468621255042, 13470.865291347352, 13493.527726529917, 13817.309134543433, 10723.94638026836, 11118.555257789598, 11146.944265278622, 11234.3259404436, 11360.464188866084, 9715.0, 10161.441120738431, 11245.71885702864, 11258.718532036764, 11561.132915031574], "value": 7743.870268501325, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 116.0, 118.0, 117.0, 101.0, 117.0, 115.0, 116.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 131.0, 159.0, 111.0, 132.0, 130.0, 111.0, 161.0, 112.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 205.0, 228.0, 226.0, 246.0, 277.0, 193.0, 239.0, 277.0, 205.0], "value": 229.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 83.0, 75.0, 145.0, 85.0, 76.0, 88.0, 94.0, 160.0, 51.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 114.0, 72.0, 71.0, 98.0, 107.0, 69.0, 73.0, 110.0], "value": 85.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 92.0, 86.0, 83.0, 130.0, 128.0, 86.0, 92.0, 87.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 98.0, 98.0, 101.0, 99.0, 99.0, 103.0, 100.0, 100.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 93.0, 90.0, 103.0, 143.0, 148.0, 90.0, 90.0, 91.0], "value": 103.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 58.0, 56.0, 57.0, 58.0, 58.0, 58.0, 57.0, 56.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 104.0, 109.0, 88.0, 83.0, 75.0, 71.0, 68.0, 69.0, 104.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 86.0, 91.0, 90.0, 87.0, 86.0, 87.0, 87.0, 91.0, 89.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 143.0, 135.0, 167.0, 154.0, 151.0, 149.0, 154.0, 186.0, 143.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 173.0, 170.0, 182.0, 234.0, 175.0, 174.0, 172.0, 173.0, 175.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 78.0, 90.0, 89.0, 69.0, 67.0, 68.0, 68.0, 73.0], "value": 74.1, "unit": "score"}]}]} 11:06:00 INFO - Return code: 0 11:06:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:06:00 ERROR - # TBPL SUCCESS # 11:06:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:06:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:06:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:06:00 INFO - 2016-09-12 11:06:00.930 screenresolution[1673:10818] starting screenresolution argv=screenresolution get 11:06:00 INFO - 2016-09-12 11:06:00.933 screenresolution[1673:10818] Display 0: 1600x1200x32@60 11:06:00 INFO - 2016-09-12 11:06:00.939 screenresolution[1674:10821] starting screenresolution argv=screenresolution list 11:06:00 INFO - Available Modes on Display 0 11:06:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:06:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:06:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:06:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:06:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:06:01 INFO - Intel Iris: 11:06:01 INFO - Chipset Model: Intel Iris 11:06:01 INFO - Type: GPU 11:06:01 INFO - Bus: Built-In 11:06:01 INFO - VRAM (Dynamic, Max): 1536 MB 11:06:01 INFO - Vendor: Intel (0x8086) 11:06:01 INFO - Device ID: 0x0a2e 11:06:01 INFO - Revision ID: 0x0009 11:06:01 INFO - Displays: 11:06:01 INFO - Display: 11:06:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:06:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:06:01 INFO - Display Serial Number: 1600x1200 60 11:06:01 INFO - Main Display: Yes 11:06:01 INFO - Mirror: Off 11:06:01 INFO - Online: Yes 11:06:01 INFO - Rotation: Supported 11:06:01 INFO - Return code: 0 11:06:01 INFO - Running post-action listener: _resource_record_post_action 11:06:01 INFO - [mozharness: 2016-09-12 18:06:01.175994Z] Finished run-tests step (success) 11:06:01 INFO - Running post-run listener: _resource_record_post_run 11:06:01 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31538176; Write bytes: 708825088; Read time: 196; Write time: 2072 11:06:01 INFO - TinderboxPrint: CPU usage
54.5% 11:06:01 INFO - TinderboxPrint: I/O read bytes / time
31,538,176 / 196 11:06:01 INFO - TinderboxPrint: I/O write bytes / time
708,825,088 / 2,072 11:06:01 INFO - TinderboxPrint: CPU idle
2,204.3 (45.5%) 11:06:01 INFO - TinderboxPrint: CPU system
980.5 (20.3%) 11:06:01 INFO - TinderboxPrint: CPU user
1,656.9 (34.2%) 11:06:01 INFO - TinderboxPrint: Swap in / out
433,065,984 / 0 11:06:01 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390271488; Write bytes: 384181760; Read time: 29127; Write time: 840 11:06:01 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30604288; Write bytes: 300489216; Read time: 183; Write time: 1174 11:06:01 INFO - Running post-run listener: _upload_blobber_files 11:06:01 INFO - Blob upload gear active. 11:06:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:06:01 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 11:06:01 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'] 11:06:01 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 11:06:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:06:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:06:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:06:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:06:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:06:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:06:02 INFO - (blobuploader) - INFO - Done attempting. 11:06:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:06:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:06:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:06:04 INFO - (blobuploader) - INFO - Done attempting. 11:06:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:06:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:06:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:06:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:06:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:06:06 INFO - (blobuploader) - INFO - Done attempting. 11:06:06 INFO - (blobuploader) - INFO - Iteration through files over. 11:06:06 INFO - Return code: 0 11:06:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:06:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:06:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dc67d08c916dcd2c8dfaa37a7278cf1cec349af3e5fe7708667c298dc5df8a3d80aeb98de05cae74daa7b9a839377e29a28e0879662280087315e39cbda3e0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e5833c4f8f8037efd5faa3be7ca5e09e55cb70cb3479a548854ee2bd00239c4da5aec7feca7caffecc878e057a3bebeb7c9001e36a79b01ce0388606924c53b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707a8733de0d1ce27154f1fdf383ecf3b6951cca60e8e64b86d70f6b3c411c860b86c7a663d00e8f336e2f25f65e9be07697569d0f573379794c6016bd63c330"} 11:06:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:06:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:06:06 INFO - Contents: 11:06:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dc67d08c916dcd2c8dfaa37a7278cf1cec349af3e5fe7708667c298dc5df8a3d80aeb98de05cae74daa7b9a839377e29a28e0879662280087315e39cbda3e0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e5833c4f8f8037efd5faa3be7ca5e09e55cb70cb3479a548854ee2bd00239c4da5aec7feca7caffecc878e057a3bebeb7c9001e36a79b01ce0388606924c53b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707a8733de0d1ce27154f1fdf383ecf3b6951cca60e8e64b86d70f6b3c411c860b86c7a663d00e8f336e2f25f65e9be07697569d0f573379794c6016bd63c330"} 11:06:06 INFO - Running post-run listener: copy_logs_to_upload_dir 11:06:06 INFO - Copying logs to upload dir... 11:06:06 INFO - mkdir: /builds/slave/test/build/upload/logs 11:06:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.351177 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-12 11:06:06.937568) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 11:06:06.941209) ========= 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.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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/1dc67d08c916dcd2c8dfaa37a7278cf1cec349af3e5fe7708667c298dc5df8a3d80aeb98de05cae74daa7b9a839377e29a28e0879662280087315e39cbda3e0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e5833c4f8f8037efd5faa3be7ca5e09e55cb70cb3479a548854ee2bd00239c4da5aec7feca7caffecc878e057a3bebeb7c9001e36a79b01ce0388606924c53b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707a8733de0d1ce27154f1fdf383ecf3b6951cca60e8e64b86d70f6b3c411c860b86c7a663d00e8f336e2f25f65e9be07697569d0f573379794c6016bd63c330"} build_url:https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010801 build_url: 'https://queue.taskcluster.net/v1/task/KPlFEpjoS1aHJ2FnsL_rpw/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/1dc67d08c916dcd2c8dfaa37a7278cf1cec349af3e5fe7708667c298dc5df8a3d80aeb98de05cae74daa7b9a839377e29a28e0879662280087315e39cbda3e0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e5833c4f8f8037efd5faa3be7ca5e09e55cb70cb3479a548854ee2bd00239c4da5aec7feca7caffecc878e057a3bebeb7c9001e36a79b01ce0388606924c53b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707a8733de0d1ce27154f1fdf383ecf3b6951cca60e8e64b86d70f6b3c411c860b86c7a663d00e8f336e2f25f65e9be07697569d0f573379794c6016bd63c330"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 11:06:06.974703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:06:06.975066) ========= 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.V8c9k7zM38/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.2aQNnfm67D/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 11:06:07.023614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 11:06:07.023961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 11:06:07.024322) ========= ========= Total master_lag: 0.19 =========