builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0129 starttime: 1473290562.4 results: success (0) buildid: 20160907145315 builduid: 096e552e9c28401dac78a3ae63c19cc0 revision: a1e635fc39e261b45e5799809a051b6b51a10f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.401921) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.402400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.402735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.435670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.436067) ========= 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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-07 16:22:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.6M=0s 2016-09-07 16:22:42 (54.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.620098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.620466) ========= 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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.027734 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:42.669350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 16:22:42.669788) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a1e635fc39e261b45e5799809a051b6b51a10f59 --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 a1e635fc39e261b45e5799809a051b6b51a10f59 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-07 16:22:42,750 truncating revision to first 12 chars 2016-09-07 16:22:42,750 Setting DEBUG logging. 2016-09-07 16:22:42,751 attempt 1/10 2016-09-07 16:22:42,751 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a1e635fc39e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 16:22:43,846 unpacking tar archive at: fx-team-a1e635fc39e2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.374001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 16:22:44.065597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:44.065921) ========= script_repo_revision: a1e635fc39e261b45e5799809a051b6b51a10f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:44.066296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:44.066575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 16:22:44.085183) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-07 16:22:44.085599) ========= /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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:22:44 INFO - MultiFileLogger online at 20160907 16:22:44 in /builds/slave/test 16:22:44 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 16:22:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:22:44 INFO - {'append_to_log': False, 16:22:44 INFO - 'base_work_dir': '/builds/slave/test', 16:22:44 INFO - 'blob_upload_branch': 'Fx-Team', 16:22:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:22:44 INFO - 'branch': 'Fx-Team', 16:22:44 INFO - 'buildbot_json_path': 'buildprops.json', 16:22:44 INFO - 'config_files': ('talos/mac_config.py',), 16:22:44 INFO - 'default_actions': ('clobber', 16:22:44 INFO - 'read-buildbot-config', 16:22:44 INFO - 'download-and-extract', 16:22:44 INFO - 'populate-webroot', 16:22:44 INFO - 'create-virtualenv', 16:22:44 INFO - 'install', 16:22:44 INFO - 'run-tests'), 16:22:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:22:44 INFO - 'download_minidump_stackwalk': True, 16:22:44 INFO - 'download_symbols': 'ondemand', 16:22:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:22:44 INFO - 'tooltool.py': '/tools/tooltool.py', 16:22:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:22:44 INFO - '/tools/misc-python/virtualenv.py')}, 16:22:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:22:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:22:44 INFO - 'installer_path': 'installer.exe', 16:22:44 INFO - 'log_level': 'info', 16:22:44 INFO - 'log_name': 'talos', 16:22:44 INFO - 'log_to_console': True, 16:22:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:22:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:22:44 INFO - 'opt_config_files': (), 16:22:44 INFO - 'pip_index': False, 16:22:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:22:44 INFO - 'cmd': ('bash', 16:22:44 INFO - '-c', 16:22:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:22:44 INFO - 'enabled': True, 16:22:44 INFO - 'halt_on_failure': False, 16:22:44 INFO - 'name': 'check_screen_resolution'},), 16:22:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:22:44 INFO - 'cmd': ('bash', 16:22:44 INFO - '-c', 16:22:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:22:44 INFO - 'enabled': True, 16:22:44 INFO - 'halt_on_failure': False, 16:22:44 INFO - 'name': 'check_screen_resolution'},), 16:22:44 INFO - 'run_cmd_checks_enabled': True, 16:22:44 INFO - 'sps_profile': False, 16:22:44 INFO - 'sps_profile_interval': 0, 16:22:44 INFO - 'suite': 'dromaeojs-e10s', 16:22:44 INFO - 'system_bits': '32', 16:22:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:22:44 INFO - 'title': 't-yosemite-r7-0129', 16:22:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:22:44 INFO - 'use_talos_json': True, 16:22:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:22:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:22:44 INFO - 'work_dir': 'build'} 16:22:44 INFO - [mozharness: 2016-09-07 23:22:44.289799Z] Running clobber step. 16:22:44 INFO - Running pre-action listener: _resource_record_pre_action 16:22:44 INFO - Running main action method: clobber 16:22:44 INFO - rmtree: /builds/slave/test/build 16:22:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:22:46 INFO - Running post-action listener: _resource_record_post_action 16:22:46 INFO - [mozharness: 2016-09-07 23:22:46.614496Z] Finished clobber step (success) 16:22:46 INFO - [mozharness: 2016-09-07 23:22:46.614638Z] Running read-buildbot-config step. 16:22:46 INFO - Running pre-action listener: _resource_record_pre_action 16:22:46 INFO - Running main action method: read_buildbot_config 16:22:46 INFO - Using buildbot properties: 16:22:46 INFO - { 16:22:46 INFO - "project": "", 16:22:46 INFO - "product": "firefox", 16:22:46 INFO - "script_repo_revision": "production", 16:22:46 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:22:46 INFO - "repository": "", 16:22:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:22:46 INFO - "buildid": "20160907145315", 16:22:46 INFO - "pgo_build": "False", 16:22:46 INFO - "basedir": "/builds/slave/test", 16:22:46 INFO - "buildnumber": 43, 16:22:46 INFO - "slavename": "t-yosemite-r7-0129", 16:22:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:22:46 INFO - "platform": "yosemite_r7", 16:22:46 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 16:22:46 INFO - "branch": "fx-team", 16:22:46 INFO - "repo_path": "integration/fx-team", 16:22:46 INFO - "revision": "a1e635fc39e261b45e5799809a051b6b51a10f59", 16:22:46 INFO - "stage_platform": "macosx64", 16:22:46 INFO - "builduid": "096e552e9c28401dac78a3ae63c19cc0", 16:22:46 INFO - "slavebuilddir": "test" 16:22:46 INFO - } 16:22:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:22:46 INFO - Running post-action listener: _resource_record_post_action 16:22:46 INFO - [mozharness: 2016-09-07 23:22:46.618154Z] Finished read-buildbot-config step (success) 16:22:46 INFO - [mozharness: 2016-09-07 23:22:46.618302Z] Running download-and-extract step. 16:22:46 INFO - Running pre-action listener: _resource_record_pre_action 16:22:46 INFO - Running main action method: download_and_extract 16:22:46 INFO - mkdir: /builds/slave/test/build/tests 16:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:46 INFO - trying https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:22:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/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 16:22:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/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 16:22:47 INFO - Downloaded 1199 bytes. 16:22:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:22:47 INFO - Using the following test package requirements: 16:22:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:22:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:22:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:22:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'jsshell-mac.zip'], 16:22:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:22:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:22:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:22:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:22:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:22:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:22:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:22:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:22:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:22:48 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:22:55 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 16:22:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:22:55 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:23:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:23:03 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 16:23:04 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')]} 16:23:04 INFO - trying https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:23:04 INFO - Downloading https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/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 16:23:06 INFO - Downloaded 111551766 bytes. 16:23:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:23:06 INFO - mkdir: /builds/slave/test/properties 16:23:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:23:06 INFO - Writing to file /builds/slave/test/properties/build_url 16:23:06 INFO - Contents: 16:23:06 INFO - build_url:https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:23:07 INFO - Running post-action listener: _resource_record_post_action 16:23:07 INFO - Running post-action listener: set_extra_try_arguments 16:23:07 INFO - [mozharness: 2016-09-07 23:23:07.756886Z] Finished download-and-extract step (success) 16:23:07 INFO - [mozharness: 2016-09-07 23:23:07.757038Z] Running populate-webroot step. 16:23:07 INFO - Running pre-action listener: _resource_record_pre_action 16:23:07 INFO - Running main action method: populate_webroot 16:23:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:23:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:23:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:23:07 INFO - '--tppagecycles', 16:23:07 INFO - '7'], 16:23:07 INFO - 'tests': ['tsvgm']}}, 16:23:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:23:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:23:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:23:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:23:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:23:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:23:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['damp', 'tps']}, 16:23:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'tests': ['damp', 'tps']}, 16:23:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['dromaeo_dom']}, 16:23:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:23:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['basic_compositor_video']}, 16:23:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:23:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'other-e10s': {'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['a11yr', 16:23:07 INFO - 'ts_paint', 16:23:07 INFO - 'tpaint', 16:23:07 INFO - 'sessionrestore', 16:23:07 INFO - 'sessionrestore_no_auto_restore', 16:23:07 INFO - 'tabpaint']}, 16:23:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['tsvgx', 16:23:07 INFO - 'tsvgr_opacity', 16:23:07 INFO - 'tart', 16:23:07 INFO - 'tscrollx', 16:23:07 INFO - 'cart']}, 16:23:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:23:07 INFO - 'tsvgr_opacity', 16:23:07 INFO - 'tart', 16:23:07 INFO - 'tscrollx', 16:23:07 INFO - 'cart']}, 16:23:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'talos_options': ['--disable-e10s'], 16:23:07 INFO - 'tests': ['tp5o']}, 16:23:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'tests': ['tp5o']}, 16:23:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'talos_options': ['--disable-e10s', 16:23:07 INFO - '--xperf_path', 16:23:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:23:07 INFO - 'tests': ['tp5n']}, 16:23:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:23:07 INFO - 'talos_options': ['--xperf_path', 16:23:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:23:07 INFO - 'tests': ['tp5n']}}, 16:23:07 INFO - 'talos.zip': {'path': '', 16:23:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:23:07 INFO - Running post-action listener: _resource_record_post_action 16:23:07 INFO - [mozharness: 2016-09-07 23:23:07.776286Z] Finished populate-webroot step (success) 16:23:07 INFO - [mozharness: 2016-09-07 23:23:07.776395Z] Running create-virtualenv step. 16:23:07 INFO - Running pre-action listener: _resource_record_pre_action 16:23:07 INFO - Running main action method: create_virtualenv 16:23:07 INFO - Creating virtualenv /builds/slave/test/build/venv 16:23:07 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 16:23:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:23:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:23:07 INFO - Using real prefix '/tools/python27' 16:23:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:23:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:23:11 INFO - Installing pip.................done. 16:23:11 INFO - Return code: 0 16:23:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:23:11 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')]} 16:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:11 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')]} 16:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:11 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:11 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 16:23:11 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 16:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:11 INFO - 'HOME': '/Users/cltbld', 16:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:11 INFO - 'LOGNAME': 'cltbld', 16:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:11 INFO - 'MOZ_NO_REMOTE': '1', 16:23:11 INFO - 'NO_EM_RESTART': '1', 16:23:11 INFO - 'PAGER': '/bin/cat', 16:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:11 INFO - 'PWD': '/builds/slave/test', 16:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:11 INFO - 'SHELL': '/bin/bash', 16:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:11 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:11 INFO - 'USER': 'cltbld', 16:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:11 INFO - 'XPC_FLAGS': '0x0', 16:23:11 INFO - 'XPC_SERVICE_NAME': '0', 16:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:11 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 16:23:11 INFO - Cleaning up... 16:23:11 INFO - Return code: 0 16:23:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:23:11 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')]} 16:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:11 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')]} 16:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:11 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:11 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 16:23:11 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 16:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:11 INFO - 'HOME': '/Users/cltbld', 16:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:11 INFO - 'LOGNAME': 'cltbld', 16:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:11 INFO - 'MOZ_NO_REMOTE': '1', 16:23:11 INFO - 'NO_EM_RESTART': '1', 16:23:11 INFO - 'PAGER': '/bin/cat', 16:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:11 INFO - 'PWD': '/builds/slave/test', 16:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:11 INFO - 'SHELL': '/bin/bash', 16:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:11 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:11 INFO - 'USER': 'cltbld', 16:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:11 INFO - 'XPC_FLAGS': '0x0', 16:23:11 INFO - 'XPC_SERVICE_NAME': '0', 16:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:12 INFO - Downloading/unpacking psutil>=3.1.1 16:23:12 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 16:23:12 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 16:23:12 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 16:23:12 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 16:23:12 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 16:23:12 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 16:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:23:14 INFO - Installing collected packages: psutil 16:23:14 INFO - Running setup.py install for psutil 16:23:14 INFO - building 'psutil._psutil_osx' extension 16:23:14 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 16:23:15 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 16:23:15 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 16:23:15 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 16:23:15 INFO - building 'psutil._psutil_posix' extension 16:23:15 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 16:23:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:23:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:23:15 INFO - ^ 16:23:15 INFO - 1 warning generated. 16:23:15 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 16:23:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:23:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:23:16 INFO - Successfully installed psutil 16:23:16 INFO - Cleaning up... 16:23:16 INFO - Return code: 0 16:23:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:16 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:16 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 16:23:16 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 16:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:16 INFO - 'HOME': '/Users/cltbld', 16:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:16 INFO - 'LOGNAME': 'cltbld', 16:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:16 INFO - 'MOZ_NO_REMOTE': '1', 16:23:16 INFO - 'NO_EM_RESTART': '1', 16:23:16 INFO - 'PAGER': '/bin/cat', 16:23:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:16 INFO - 'PWD': '/builds/slave/test', 16:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:16 INFO - 'SHELL': '/bin/bash', 16:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:16 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:16 INFO - 'USER': 'cltbld', 16:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:16 INFO - 'XPC_FLAGS': '0x0', 16:23:16 INFO - 'XPC_SERVICE_NAME': '0', 16:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:23:16 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 16:23:16 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 16:23:16 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 16:23:16 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 16:23:16 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 16:23:16 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 16:23:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:23:18 INFO - Installing collected packages: mozsystemmonitor 16:23:18 INFO - Running setup.py install for mozsystemmonitor 16:23:19 INFO - Successfully installed mozsystemmonitor 16:23:19 INFO - Cleaning up... 16:23:19 INFO - Return code: 0 16:23:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:23:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:19 INFO - 'HOME': '/Users/cltbld', 16:23:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:19 INFO - 'LOGNAME': 'cltbld', 16:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:19 INFO - 'MOZ_NO_REMOTE': '1', 16:23:19 INFO - 'NO_EM_RESTART': '1', 16:23:19 INFO - 'PAGER': '/bin/cat', 16:23:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:19 INFO - 'PWD': '/builds/slave/test', 16:23:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:19 INFO - 'SHELL': '/bin/bash', 16:23:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:19 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:19 INFO - 'USER': 'cltbld', 16:23:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:19 INFO - 'XPC_FLAGS': '0x0', 16:23:19 INFO - 'XPC_SERVICE_NAME': '0', 16:23:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:19 INFO - Downloading/unpacking jsonschema==2.5.1 16:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:23:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:23:21 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 16:23:21 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 16:23:21 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 16:23:21 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 16:23:21 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 16:23:21 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 16:23:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:23:22 INFO - warning: no files found matching '*.txt' 16:23:22 INFO - no previously-included directories found matching 'build' 16:23:22 INFO - no previously-included directories found matching 'dist' 16:23:22 INFO - no previously-included directories found matching '.git*' 16:23:22 INFO - Installing collected packages: jsonschema, functools32 16:23:22 INFO - Running setup.py install for functools32 16:23:22 INFO - warning: no files found matching '*.txt' 16:23:22 INFO - no previously-included directories found matching 'build' 16:23:22 INFO - no previously-included directories found matching 'dist' 16:23:22 INFO - no previously-included directories found matching '.git*' 16:23:22 INFO - Successfully installed jsonschema functools32 16:23:22 INFO - Cleaning up... 16:23:22 INFO - Return code: 0 16:23:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:22 INFO - 'HOME': '/Users/cltbld', 16:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:22 INFO - 'LOGNAME': 'cltbld', 16:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:22 INFO - 'MOZ_NO_REMOTE': '1', 16:23:22 INFO - 'NO_EM_RESTART': '1', 16:23:22 INFO - 'PAGER': '/bin/cat', 16:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:22 INFO - 'PWD': '/builds/slave/test', 16:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:22 INFO - 'SHELL': '/bin/bash', 16:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:22 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:22 INFO - 'USER': 'cltbld', 16:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:22 INFO - 'XPC_FLAGS': '0x0', 16:23:22 INFO - 'XPC_SERVICE_NAME': '0', 16:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:23:23 INFO - Cleaning up... 16:23:23 INFO - Return code: 0 16:23:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:23 INFO - 'HOME': '/Users/cltbld', 16:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:23 INFO - 'LOGNAME': 'cltbld', 16:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:23 INFO - 'MOZ_NO_REMOTE': '1', 16:23:23 INFO - 'NO_EM_RESTART': '1', 16:23:23 INFO - 'PAGER': '/bin/cat', 16:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:23 INFO - 'PWD': '/builds/slave/test', 16:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:23 INFO - 'SHELL': '/bin/bash', 16:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:23 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:23 INFO - 'USER': 'cltbld', 16:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:23 INFO - 'XPC_FLAGS': '0x0', 16:23:23 INFO - 'XPC_SERVICE_NAME': '0', 16:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:23 INFO - Downloading/unpacking blobuploader==1.2.4 16:23:23 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 16:23:23 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 16:23:23 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 16:23:23 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 16:23:23 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 16:23:23 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 16:23:25 INFO - Downloading blobuploader-1.2.4.tar.gz 16:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:23:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:23:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:27 INFO - Downloading docopt-0.6.1.tar.gz 16:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:23:27 INFO - Installing collected packages: blobuploader, requests, docopt 16:23:27 INFO - Running setup.py install for blobuploader 16:23:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:23:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:23:27 INFO - Running setup.py install for requests 16:23:27 INFO - Running setup.py install for docopt 16:23:27 INFO - Successfully installed blobuploader requests docopt 16:23:27 INFO - Cleaning up... 16:23:28 INFO - Return code: 0 16:23:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23: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')]} 16:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23: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')]} 16:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:28 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:28 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 16:23:28 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 16:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:28 INFO - 'HOME': '/Users/cltbld', 16:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:28 INFO - 'LOGNAME': 'cltbld', 16:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:28 INFO - 'MOZ_NO_REMOTE': '1', 16:23:28 INFO - 'NO_EM_RESTART': '1', 16:23:28 INFO - 'PAGER': '/bin/cat', 16:23:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:28 INFO - 'PWD': '/builds/slave/test', 16:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:28 INFO - 'SHELL': '/bin/bash', 16:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:28 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:28 INFO - 'USER': 'cltbld', 16:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:28 INFO - 'XPC_FLAGS': '0x0', 16:23:28 INFO - 'XPC_SERVICE_NAME': '0', 16:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-v6a6PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-1FSurh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-YDVzqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-_gKNma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-rqY8wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0mg4SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:23:28 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ApxxpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-14BrFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-2xm9tZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-yCLrv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Zo4PiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-AEmYuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-d4RiC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-khx4wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-8Y0jXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-qBeIhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:23:29 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-4YDrxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:23:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:23:30 INFO - Running setup.py install for manifestparser 16:23:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:23:30 INFO - Running setup.py install for mozcrash 16:23:30 INFO - Running setup.py install for mozdebug 16:23:30 INFO - Running setup.py install for mozdevice 16:23:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:23:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:23:30 INFO - Running setup.py install for mozfile 16:23:30 INFO - Running setup.py install for mozhttpd 16:23:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:23:30 INFO - Running setup.py install for mozinfo 16:23:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Running setup.py install for mozInstall 16:23:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Running setup.py install for mozleak 16:23:31 INFO - Running setup.py install for mozlog 16:23:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Running setup.py install for moznetwork 16:23:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:23:31 INFO - Running setup.py install for mozprocess 16:23:31 INFO - Running setup.py install for mozprofile 16:23:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:23:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:23:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:23:32 INFO - Running setup.py install for mozrunner 16:23:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:23:32 INFO - Running setup.py install for mozscreenshot 16:23:32 INFO - Running setup.py install for moztest 16:23:32 INFO - Running setup.py install for mozversion 16:23:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:23:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:23:32 INFO - Cleaning up... 16:23:32 INFO - Return code: 0 16:23:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:32 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:32 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 16:23:32 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 16:23:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:32 INFO - 'HOME': '/Users/cltbld', 16:23:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:32 INFO - 'LOGNAME': 'cltbld', 16:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:32 INFO - 'MOZ_NO_REMOTE': '1', 16:23:32 INFO - 'NO_EM_RESTART': '1', 16:23:32 INFO - 'PAGER': '/bin/cat', 16:23:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:32 INFO - 'PWD': '/builds/slave/test', 16:23:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:32 INFO - 'SHELL': '/bin/bash', 16:23:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:32 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:32 INFO - 'USER': 'cltbld', 16:23:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:32 INFO - 'XPC_FLAGS': '0x0', 16:23:32 INFO - 'XPC_SERVICE_NAME': '0', 16:23:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:23:32 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-rdTukC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-6uplYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-9ushsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Rl9woJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-fE1QkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-KVeU7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-E03vPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-oL1Ba1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Z8a883-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-hqugSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:23:33 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)) 16:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:23:33 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-jc_0Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Ia1sQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-WUUa_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Ozzhlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-8AUtLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ZjzqKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:23:34 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)) 16:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:23:34 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-koDSMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:23:34 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)) 16:23:34 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)) 16:23:34 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)) 16:23:34 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)) 16:23:34 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)) 16:23:34 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)) 16:23:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:37 INFO - Downloading blessings-1.6.tar.gz 16:23:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:23:37 INFO - Installing collected packages: blessings 16:23:37 INFO - Running setup.py install for blessings 16:23:37 INFO - Successfully installed blessings 16:23:37 INFO - Cleaning up... 16:23:37 INFO - Return code: 0 16:23:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:23:37 INFO - Reading from file tmpfile_stdout 16:23:37 INFO - Current package versions: 16:23:37 INFO - blessings == 1.6 16:23:37 INFO - blobuploader == 1.2.4 16:23:37 INFO - docopt == 0.6.1 16:23:37 INFO - functools32 == 3.2.3-2 16:23:37 INFO - jsonschema == 2.5.1 16:23:37 INFO - manifestparser == 1.1 16:23:37 INFO - mozInstall == 1.12 16:23:37 INFO - mozcrash == 0.17 16:23:37 INFO - mozdebug == 0.1 16:23:37 INFO - mozdevice == 0.48 16:23:37 INFO - mozfile == 1.2 16:23:37 INFO - mozhttpd == 0.7 16:23:37 INFO - mozinfo == 0.9 16:23:37 INFO - mozleak == 0.1 16:23:37 INFO - mozlog == 3.3 16:23:37 INFO - moznetwork == 0.27 16:23:37 INFO - mozprocess == 0.23 16:23:37 INFO - mozprofile == 0.28 16:23:37 INFO - mozrunner == 6.12 16:23:37 INFO - mozscreenshot == 0.1 16:23:37 INFO - mozsystemmonitor == 0.3 16:23:37 INFO - moztest == 0.7 16:23:37 INFO - mozversion == 1.4 16:23:37 INFO - psutil == 3.1.1 16:23:37 INFO - requests == 1.2.3 16:23:37 INFO - wsgiref == 0.1.2 16:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:23:37 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')]} 16:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:37 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')]} 16:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:37 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:37 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 16:23:37 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 16:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:37 INFO - 'HOME': '/Users/cltbld', 16:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:37 INFO - 'LOGNAME': 'cltbld', 16:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:37 INFO - 'MOZ_NO_REMOTE': '1', 16:23:37 INFO - 'NO_EM_RESTART': '1', 16:23:37 INFO - 'PAGER': '/bin/cat', 16:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:37 INFO - 'PWD': '/builds/slave/test', 16:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:37 INFO - 'SHELL': '/bin/bash', 16:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:37 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:37 INFO - 'USER': 'cltbld', 16:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:37 INFO - 'XPC_FLAGS': '0x0', 16:23:37 INFO - 'XPC_SERVICE_NAME': '0', 16:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 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)) 16:23:38 INFO - Cleaning up... 16:23:38 INFO - Return code: 0 16:23:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:23:38 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')]} 16:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:38 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')]} 16:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:38 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 0x104d5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104def618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc32c712f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, '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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 16:23:38 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 16:23:38 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 16:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:38 INFO - 'HOME': '/Users/cltbld', 16:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:38 INFO - 'LOGNAME': 'cltbld', 16:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:38 INFO - 'MOZ_NO_REMOTE': '1', 16:23:38 INFO - 'NO_EM_RESTART': '1', 16:23:38 INFO - 'PAGER': '/bin/cat', 16:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:38 INFO - 'PWD': '/builds/slave/test', 16:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:38 INFO - 'SHELL': '/bin/bash', 16:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:23:38 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:38 INFO - 'USER': 'cltbld', 16:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:38 INFO - 'XPC_FLAGS': '0x0', 16:23:38 INFO - 'XPC_SERVICE_NAME': '0', 16:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:23:38 INFO - Cleaning up... 16:23:38 INFO - Return code: 0 16:23:38 INFO - Running post-action listener: _resource_record_post_action 16:23:38 INFO - Running post-action listener: _start_resource_monitoring 16:23:38 INFO - Starting resource monitoring. 16:23:38 INFO - [mozharness: 2016-09-07 23:23:38.347760Z] Finished create-virtualenv step (success) 16:23:38 INFO - [mozharness: 2016-09-07 23:23:38.348742Z] Running install step. 16:23:38 INFO - Running pre-action listener: _resource_record_pre_action 16:23:38 INFO - Running main action method: install 16:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:23:38 INFO - Reading from file tmpfile_stdout 16:23:38 INFO - Detecting whether we're running mozinstall >=1.0... 16:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:23:38 INFO - Reading from file tmpfile_stdout 16:23:38 INFO - Output received: 16:23:38 INFO - Usage: mozinstall [options] installer 16:23:38 INFO - Options: 16:23:38 INFO - -h, --help show this help message and exit 16:23:38 INFO - -d DEST, --destination=DEST 16:23:38 INFO - Directory to install application into. [default: 16:23:38 INFO - "/builds/slave/test"] 16:23:38 INFO - --app=APP Application being installed. [default: firefox] 16:23:38 INFO - mkdir: /builds/slave/test/build/application 16:23:38 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'] 16:23:38 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 16:24:12 INFO - Reading from file tmpfile_stdout 16:24:12 INFO - Output received: 16:24:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:24:12 INFO - Running post-action listener: _resource_record_post_action 16:24:12 INFO - [mozharness: 2016-09-07 23:24:12.735677Z] Finished install step (success) 16:24:12 INFO - [mozharness: 2016-09-07 23:24:12.735826Z] Running run-tests step. 16:24:12 INFO - Running pre-action listener: _resource_record_pre_action 16:24:12 INFO - Running main action method: run_tests 16:24:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:24:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:24:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:24:12 INFO - 2016-09-07 16:24:12.750 screenresolution[1630:7375] starting screenresolution argv=screenresolution get 16:24:12 INFO - 2016-09-07 16:24:12.752 screenresolution[1630:7375] Display 0: 1600x1200x32@60 16:24:12 INFO - 2016-09-07 16:24:12.758 screenresolution[1631:7377] starting screenresolution argv=screenresolution list 16:24:12 INFO - Available Modes on Display 0 16:24:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:24:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:24:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:24:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:24:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:24:12 INFO - Intel Iris: 16:24:12 INFO - Chipset Model: Intel Iris 16:24:12 INFO - Type: GPU 16:24:12 INFO - Bus: Built-In 16:24:12 INFO - VRAM (Dynamic, Max): 1536 MB 16:24:12 INFO - Vendor: Intel (0x8086) 16:24:12 INFO - Device ID: 0x0a2e 16:24:12 INFO - Revision ID: 0x0009 16:24:12 INFO - Displays: 16:24:12 INFO - Display: 16:24:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:24:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:24:12 INFO - Display Serial Number: 1600x1200 60 16:24:12 INFO - Main Display: Yes 16:24:12 INFO - Mirror: Off 16:24:12 INFO - Online: Yes 16:24:12 INFO - Rotation: Supported 16:24:12 INFO - Return code: 0 16:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:24:12 INFO - Python 2.7.3 16:24:12 INFO - Return code: 0 16:24:12 INFO - grabbing minidump binary from tooltool 16:24:12 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')]} 16:24:12 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 0x104cbed30>, '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 0x104a23db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:24:12 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 16:24:12 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 16:24:12 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 16:24:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:24:13 INFO - Return code: 0 16:24:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:24:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:24:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:24:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:24:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:24:13 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/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 16:24:13 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/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 16:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gExIAXTMlN/Render', 16:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:13 INFO - 'HOME': '/Users/cltbld', 16:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:13 INFO - 'LOGNAME': 'cltbld', 16:24:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:24:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:13 INFO - 'MOZ_NO_REMOTE': '1', 16:24:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:24:13 INFO - 'NO_EM_RESTART': '1', 16:24:13 INFO - 'PAGER': '/bin/cat', 16:24:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:13 INFO - 'PWD': '/builds/slave/test', 16:24:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:13 INFO - 'SHELL': '/bin/bash', 16:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7rVjuy1Fq/Listeners', 16:24:13 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 16:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:13 INFO - 'USER': 'cltbld', 16:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:13 INFO - 'XPC_FLAGS': '0x0', 16:24:13 INFO - 'XPC_SERVICE_NAME': '0', 16:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:13 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/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 16:24:13 INFO - mozversion application_buildid: 20160907145315 16:24:13 INFO - mozversion application_changeset: a1e635fc39e261b45e5799809a051b6b51a10f59 16:24:13 INFO - mozversion application_display_name: Nightly 16:24:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:24:13 INFO - mozversion application_name: Firefox 16:24:13 INFO - mozversion application_remotingname: firefox 16:24:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:24:13 INFO - mozversion application_vendor: Mozilla 16:24:13 INFO - mozversion application_version: 51.0a1 16:24:13 INFO - mozversion platform_buildid: 20160907145315 16:24:13 INFO - mozversion platform_changeset: a1e635fc39e261b45e5799809a051b6b51a10f59 16:24:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:24:13 INFO - mozversion platform_version: 51.0a1 16:24:13 INFO - using testdate: 1473290653 16:24:13 INFO - actual date: 1473290653 16:24:13 INFO - starting webserver on 'localhost:49266' 16:24:13 INFO - SUITE-START | Running 2 tests 16:24:13 INFO - TEST-START | dromaeo_css 16:24:13 INFO - operating with platform_type : mac_ 16:24:13 INFO - Initialising browser for dromaeo_css test... 16:24:13 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpEaiLie/profile http://localhost:49266/getInfo.html) 16:24:16 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 16:24:16 INFO - PROCESS | 1641 | colorDepth:24 16:24:16 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 16:24:16 INFO - PROCESS | 1641 | __metrics 16:24:16 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 16:24:16 INFO - TEST-INFO | 1641: exit 0 16:24:16 INFO - Browser initialized. 16:24:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:24:16 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpEaiLie/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:28 INFO - PROCESS | 1644 | RSS: Main: 244154368 16:24:28 INFO - PROCESS | 1644 | 16:25:18 INFO - PROCESS | 1644 | 1473290718551 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] 16:27:31 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:27:33 INFO - PROCESS | 1644 | RSS: Main: 259411968 16:27:33 INFO - PROCESS | 1644 | 16:30:35 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:30:36 INFO - PROCESS | 1644 | RSS: Main: 267063296 16:30:36 INFO - PROCESS | 1644 | 16:33:37 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:33:38 INFO - PROCESS | 1644 | RSS: Main: 272826368 16:33:38 INFO - PROCESS | 1644 | 16:36:40 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:36:41 INFO - PROCESS | 1644 | RSS: Main: 268374016 16:36:41 INFO - PROCESS | 1644 | 16:39:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:39:44 INFO - PROCESS | 1644 | RSS: Main: 273629184 16:39:44 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 16:42:47 INFO - PROCESS | 1644 | RSS: Main: 277381120 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | __start_tp_report 16:42:47 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 16:42:47 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 16:42:47 INFO - PROCESS | 1644 | |i|pagename|runs| 16:42:47 INFO - PROCESS | 1644 | |0;dojo.html;3428.9314213715725;3444.173398384387;3475.6871881530656;3497.0033540440973;3502.562179727534;5743.827685169452;5859.9707001464985;5889.970550147244;5933.851653708665;5967.970160149204;4220;5243.79024839007;5408.513233808956;5684.573656975723;5882.32353279373;4720.929186062212;4954.058728841526;5223.529882310591;5247.449017853128;5407.567394608422;4777.04459108178;5375.00562395958;5444.809431669893;5445.292112025431;5491.478309560605;4865.099956508039;5269.104252277122;5298.125809241471;5317.9468205317835;5322.175062865262;4284.121755040209;5305.893882122375;5324.201369794562;5334.0665383557725;5360.142377219626;5274.156135018417;5327.067763141474;5335.733213339317;5362.410134885159;5381.730913454351;5751.7699292028265;5950.672713000827;5971.28344598651;5976.103584462322;5988.970055149696;6161.322254551996;6169.691515424256;6226.066090086478;6282.623042617458;6305.180326557564;5305.920411193835;6265.780697675605;6301.905471417932;6305.0857625644485;6316.30520642729;5445.346558412936;5766.192733017381;5875.059990401558;5930.169776231331;5961;6231.034189700604;6293.30773614902;6313.747450102036;6405.519586031066;6423.229212494531;6171.783987560414;6175.938240617626;6228.750849965951;6241.469475094668;6269.153664255358;6109.829807539378;6213.409726076015;6284.4344009039405;6291.276503202099;6305.779297724557;6154.753809847556;6188.9690551546955;6217;6226.40849119333;6226.968865155736;6229.345918678564;6234.719437625317;6253.468455181361;6254.937450625435;6262.1546091278005;5015.14742493777;5055.823046193365;5066.974665126724;5068.721220332917;5078.923816142761;4547.1815073287125;4556.2937744649635;4588.931166032512;4598.804310879195;4622.6764126510825;5880.029795083763;5914.792982245601;5953.553483488756;5971.19388882504;5983.521318294526;6175.660338681416;6266.310705822356;6323.9683801580695;6354.745810167634;6389.744410223632;5880.500157486662;5888.852778680567;5955.34491205967;5957.821265362046;6024.096385542116;4361.629261512743;5431.53831924283;5506.91739623906;5573.331200255995;5575.470330318694;6274.247090349188;6352.936470635234;6559;6567.211934567787;6578.04618330349;6164.106204600397;6283.183186185766;6289.119523266655;6304.5271604628915;6304.71628776697;6482.870342593027;6484.5460817742305;6489.9026514603265;6506.3818937200895;6511.186101737283;5102.36220472441;5263.894582137794;5659.886802263849;6204.5967012144065;6273;4970.65205435616;5513.283273174462;6059.575829691879;6111.682900513672;6242.969909964805;6206.472449841814;6239.344868788712;6241.344658810759;6258.092576576462;6264.467520260829;4663.903982564163;6062.393760623902;6128.07537951499;6232.688365581794;6240.812775616739;5048.318477005557;5115.514026167508;6150.969245153746;6170.660613666291;6180.6291622502795;5182.24857395668;6165.568410211242;6238.500919926304;6264.154339164337;6285.59143655661;6037.154798328325;6065.968785306421;6105.755769769159;6157.322694503511;6157.415045570664 16:42:47 INFO - PROCESS | 1644 | |1;ext.html;487.0113669251419;528.5190476666234;534.8315280686584;557.5456003357264;600.2227115884934;38667.61332386675;40577.79711101444;40647.39028914569;40716;40741;40906.795466022704;41338.37992430116;41378.79310603443;41388.17223655525;41426;9691.176250018756;9721.611135554569;9747.415155090683;9906.752331191707;9918.156956658657;9705.757356066084;9760.17038551724;9795.461249631267;9797.363171393872;9802.019798020212;9808.46053467059;9822.754431139207;9848;9863.802723945553;9883.406995580288;9668.516574171263;9728.805423891517;9786.266030047775;9810.117089461912;9821.607135714563;9715.36850104748;9756.609735610638;9783.0706082922;9794.706158815246;9835.606575736962;39394.606053939664;40025.79987100075;40560.391594126115;40843;40861.591384086074;3361.2605226850055;3406.369821583015;3561.821908904544;3722.3113723961155;3988.4615576897036;30172;30352.84823575868;30514;30846.53730194049;31559.84220078908;2847.7721782257368;3218.855151518187;3224.4840825468045;3225.79032362898;3245.6429792722784;375526.590771599;380104.9999999972;380861;380887;381698;55720;57037.42962570404;57135;57220.427795722346;57235;32698.67301327004;35224.471632925524;59138.40861591415;59703;59750;14339.78490322646;16197.59506012359;16221.675566488604;16224.269907854175;16291.289414611296;12355.62933112008;12413.31726755019;12466.314352710688;12474;12501.49994000248;17786.644267114585;17844.643107137785;18210.726839097424;18212.81787182138;18340.541486462946;7206.774848275806;7812.882806757903;8023.719169829144;8117.918820811717;8212.917870821335;843.9831203375898;879.8046199650238;912.7489940266347;948.4262021477045;960.6493629825063;26758.063467778535;29373.85313073421;29734.702652973192;29962;30009.534601618667;1065.041462683591;1072.5924148823397;1092.1481244629201;1179.3985067615406;1213.6541085790507;15063.47277845292;15486.690266194613;15624.609384765245;15653;15686.529404117895;188342;189925.15112273328;191067;191100;191288;57002.714986424806;57618;58089;58196;58247.70876145592;1190.5237904838134;1263.5893334666087;1287.073307218801;1366.3509832829327;1385.7852032934709;28765.568516471852;29611.703882961756;29723.108306750833;30032.549511756904;30232.84883575568;43446.78276608723;49533;50223.995520090124;50274.49725502698;50301.742456437416;493384;494214;496477;496743;498557;26067.348316292246;26230.475390492466;26280.817363218317;26609.467810644066;26627.467450651267;46168.769156154005;47394;47642.761786190844;47799.56601301966;48101.51898480971;36776;38100.61899380971;38173.045673858374;39515.80242098771;39589.40615890821;36253.64956205272;39002.804985974886;39638.801805990784;39653;40624.59375406208;38129;38488;38772.8368148956;38853.805730971166;39065.60934390734;28756.13731587972;33473.82841600532;34736;35083.29833403369;36573;26041.739582603932;26328.473430531667;26454;26828.195154145407;26853.865730671223 16:42:47 INFO - PROCESS | 1644 | |2;jquery.html;335.82704906972896;351.95952465466473;378.67623182179256;382.94638750574927;404.7692815095395;15269.923650381746;16312.918435407806;18592.90703546482;20601.48496287592;20729.481762955933;19783.307584234553;20099.899500502477;20419.979001049924;20668.17327306906;20670.27654032109;5743.942560574404;5767.567432442563;5955.821325360246;5993.850153746148;5998.1602575639445;5798.507126894219;5873.530117590582;5921.585489015771;5934.376890426508;5955.88088238235;5646.153077038436;5821.7380217890295;5838.620489668179;5874.295084589855;5892.204552385416;5711.628744131618;5736.541076713853;5804.042333015069;5866.208061911651;5866.882662346729;4273.423087883159;5058.064258112261;5096.286519887219;5117.309163262949;5141.100307446182;21074.789252107435;21701.131954721794;22007;25047.874760626262;25093.37266568332;2024.6153230886084;2477.872597702516;2603.4402603440353;2965.940681186386;3038.7265146136733;10097.091261786401;10379.636712715019;12734.790194931467;15141.924290378587;15628.453004144914;1808.1230603157458;1847.78750443698;1849.8057703941026;1855.3969959762996;1912.1586501939103;100151;129960;130489;132367;133564;9926.950365248273;11110.833337499944;11156.609518666808;11578.536858525733;11648.53405863773;14275.714485710436;14392.85607143936;14563.85436145625;14569.543045695558;14593;6169.876602468015;8200.507969521848;8471.449355791854;8487.618057187317;8624.655013799504;8386.664533418718;8414.873776893352;8430.831383372299;8530.019047809581;8621.78445538854;3623.2391197848747;4265.893352666209;4520.954790452053;4529.886752831139;4659.091477161921;1917.3289348727833;1929.8456123510086;1937.7328525625053;1943.834774044222;1976.9110390032483;846.6020970144023;862.9041117780383;878.7978764884112;894.4096896048577;939.281449690987;16383;20136.798632013786;21213.575728485343;21384.14463421477;21417;914.3736540469799;920.5121285718579;954.370115723619;966.970990870275;975.5951280218629;8622.611982460805;10188.337758045704;11153.665390038312;11156.442177891235;11168.66494005181;481958;571636;577380;593408;593628;134374;137422.25155497034;140272;140560;141131;969.680005598151;974.64425484699;989.618996686281;992.2558081439065;995.4674249276602;18017.72973405426;18624;18662;18727.90636046811;18737.969411682487;51957.48042519678;53179.468205317455;54423.18365224604;56671.86656266934;56727.71636141793;83296;83342;83353;84405;86032;6199.535034872312;6881.243063262937;7389.519681220703;7944.761657150295;8055.597220138852;9747.512624368896;11739.882601173878;11994.700132496408;12340.753184936071;12377.504899803907;8149.348052155695;10392.22058345612;10409.427481488288;10720.463976801284;10753.032227099598;9300;10593.788124237626;10783.299085559414;10843.837342439712;10976.396298203676;10566.735831604272;10569.894301056891;10918.617848375268;10941.124710023338;11085.722856928642;7746.49647772893;7874.685012599661;7944.04671439431;8011.919880801351;8019.518828870287;8418.915810841812;10381.584736610754;10594.523246454082;10677.679669609925;10686.465676715978 16:42:47 INFO - PROCESS | 1644 | |3;mootools.html;1418.9929050354747;1536.0246243635293;1636.0919689572283;1701.953298721286;1884.4817675139343;7817.257360550746;7983.163872309372;8628.56857157142;8650.437721548104;8666.220040196396;7342.706291748324;8804.207621314097;8823.779405514866;8838.204561589455;8858.247049000842;2004.2584243829804;2389.82076344274;2408.2654790288957;2410.09621391978;2414.8671823049726;2316.6409206573007;2324.267855625481;2332.521833024226;2353.411647088228;2375.429896824758;2352.4471749139025;2389.3668177932864;2398.0767404549288;2404.495056038234;2413.625887987365;2364.609839376518;2368.277675309039;2381.428457170275;2384.117876385731;2390.7489713580085;2347.495288512961;2366.5621859955963;2367.5028244068767;2375.717112759108;2380.9523809523885;8751;8818.779530511725;8841.734747957507;8890.822183556293;8925.018247992715;5235.450277720823;5292.232626269208;5345.385280692732;5366.4633536646415;5373.543248823855;3589.28214357127;3659.3413185626578;3672.6327367263325;3718.442233664945;3785;1094.7427354571669;1099.4777480696685;1100.3617901617122;1116.1963386361888;1116.6538373104363;11231.831522527087;11413.714657133554;11433.59982400612;11522.366269855154;11556.24884382521;3789.7536660117003;4047.6357127858632;4613;4791.377120974251;4806.759662016885;9714.61141554344;9802.362846415102;9862.211023118134;9887.851682224773;9922;5290.8941821164135;5316.76074576645;5319.989202051675;5329.866753331198;5340.706261155596;4399.494058183286;4741.146593613183;4842.975785121052;4860.9513904861205;4879.585235254972;3203.3913556424204;3883.980580097081;3909.6285852843935;3972.6890126258268;4010.47863777707;989.9802003960025;1107.828286615576;1167.9941600291945;1288.7229245712217;1300.7918733002675;909.4361495872602;929.1823195587839;929.2890938432105;932.2635118256637;954.0554850697898;5770.711464426846;5932.940670593325;6039.48664363534;6157.076438534166;6164.969175154185;425.9467566554181;438.31185039487696;441.2256489860339;444.9021215332622;452.8822506148425;3935.626115519021;4020.8994775130273;4044.2113787811095;4059.188162367538;4098.631123198927;8972.506512141765;9727.513624318897;10573.682789516326;10679.733006674895;10680.679579612624;10299.742506437398;10620.468976551141;10650.307729997681;10657.25399222047;10673.946630266799;8025.277725004661;8154.510729356258;8176.018877734591;8177.591120443954;8216.876746848682;5664.49019588239;5828.184054232326;5841.357450680337;5842.444967728059;5923.466887980103;9804.166645835185;9817.803643927224;9820.65627703027;9834.360766550151;9842.557084931339;9879.407235565888;10075.84886226722;10154.492275386348;10247.7438064049;10252.384856908311;6964.129483814523;7353.926460735538;7507.249275072668;7532.945387645625;7622.695092196472;7589.772306830804;8505.489670619783;8533.701320453754;8536.189062039097;8551.615177316911;7408.9629551851895;7416.221296763762;7547.73582924595;7620.69517219305;7644.923550764421;7340.0457940467395;7469.365103966224;7484.25157484269;7572.356349710336;7641;6993.2657071006815;6994.055802466602;7024.8595028098125;7036.366726994595;7125.287471252833;4944.53026962438;4958.8760280993265;5028.094942910311;5107.259447380184;5141.768620412166;5023.447420783784;5023.648344615842;5196.610254231024;5197.038547868657;5220.477952204749 16:42:47 INFO - PROCESS | 1644 | |4;prototype.html;302.1404105320363;308.25556281579986;314.782799868091;316.6532646751806;318.6208411989732;4400.229959757045;5561.4716601922855;5660.5471562275025;5726.37009928908;5749.856253593657;5517.903196785216;5661.801836935708;5665.006170649682;5675;5699.207388379695;4281.764502952336;4286.314189729652;4301.440812694345;4339.305711086226;4343.370211319357;4062.4515690381863;4257.190693955072;4257.233697934386;4318.870433886988;4326.870193894172;3460.411730005905;3460.965390346102;4119.546849846514;4203.369494575808;4205.69852665656;3465.6187819339852;3513.5608048993877;3620.945685814715;4184.4141820145205;4201.138766552849;2907.8836846526115;3456.9827150864335;3616.782993020427;3650.6349365063547;3665.981670091659;5494.725263736837;5541.445855414467;5547.916781248285;5549.861253468655;5581.832545023656;4273.743575385487;4468.374427580109;4699.036697477009;4889.364382630235;4924.015196960622;5649.548036157139;5925.170476133345;5932.228810254639;5971.462568368825;5980.880382392328;3482.9129271768156;3918.647321741057;3948.4669569608022;3956.7032472564383;3957.821898014596;5343.465653434664;5511.830697349041;6332.905006424908;6371.362863713592;6391.584547004476;3759.77807212657;3867.961320386816;3964.306246406867;4062.5734297898885;4087.4073259377224;3437.914052148716;4119.217348703737;4121.52602450716;4139.234241665302;4159.334506478949;4034.374671925856;4795.856124316207;4839.782209800567;4843.8304659337455;4868.95131048685;5797.275340582427;5998.730057147439;6006.858696847585;6140.785072522441;6141.539384546177;1313.1267706974945;1343.482759137739;1429.8999070064995;1463.5463006468028;1471.1026273972868;836.3058661311097;839.8236370362171;957.5978089202551;975.9609615615437;1001.2090448545567;3752.643498867658;4298.355246712956;4667.436408803089;4680.315086568832;4683.976580117078;4807.95192048082;4979.107938983236;5142.071723103836;5237.983376400113;5295.040834891115;4115.065919018057;4277.615014648696;4604.664647252259;4694.788734506956;4738.573528382462;2788.9123241935663;2899.797014209027;3197.2326641605846;3198.5362122492575;3323.185819474196;6086.908696369558;6127.417895299939;6418.042497038448;6440.227172739207;6539.4169303125;5652.18043383707;5841.9415805842245;6221.906671399842;6232.937670623326;6333.2133861100965;6349.2698339690605;6382.904256436064;6456.644884531396;6461.676916154173;6521.739130434825;5102.770375333044;5360.919586206132;5644.548436125182;5867.618604790675;6160.414760597736;5783.595148339576;6024.337322894566;6032.215811944419;6052.273727152769;6062.696865156637;5570.832875013756;6351.428371446592;6357.841053973688;6364.968175159094;6392.264889537577;3167.3823604397844;3674.5039419678;3769.7748231825317;3864.30442520349;4072.959270407258;5134.76893539799;5336.439673834347;5970.910436343545;6023.819285421444;6324.683765811783;4732.810687572596;5437.619366644297;5555.833325000256;6045.183900173596;6076;5669.829905102853;5752.9712351437975;6023.710989823839;6042.577019608585;6056.728087101582;5513.476219759116;5648.971755141198;5858.740370820294;5892.823215303548;5935.881282374242;5285.207218917193;5863.120531920246;6045.183900173596;6103.664298463627;6117.879881260678;4930.556249937523;5247.134222853187;5581.553475721851;5917.201177840936;5930.873134104507 16:42:47 INFO - PROCESS | 1644 | |5;yui.html;659.6207180870999;673.8787018336698;715.0275625149792;767.3592550220566;816.7876352148442;14938.327775250113;15561.84438155618;15562.455314063982;15576.53270401887;15596.142212178325;14114.858851411484;14121.223332716721;14185.503507377243;14227.644308892282;14253.28733563323;840.6553313141613;841.3479553346144;860.746079906426;862.8188080503071;868.2402897464718;853.73960941913;854.3336197765722;855.2003876375578;855.2516548020482;855.8758979947935;707.4906067631323;886.2068448738376;913.1918252346687;917.7017469322464;920.9769755756126;891.1266958380791;891.8038031633031;892.0366004714892;893.5041052216018;893.6425429828056;647.6146692717825;656.4977791989132;656.9737210511573;657.7369052379039;658.5324419661998;15139.243037848173;15205.923970380078;15211.923940380228;15227.923860380737;15251.313690883937;10962.671119866416;13302.667433314147;13528.188308701509;13535.729285414236;13798;12488.50045998159;12863.742725145445;12867.292298923556;12883.871161288362;12983.02627302956;4219.662427005832;4236.322188449864;4254.319308910591;4258.233517966764;4321.351797230409;110155.44922275418;123961.38019309936;124811;131456.34271828676;132570;10807.40559269232;10849;10863.51114199863;10914.508847101899;10962.780744385067;21101.68347474789;21109.68335474969;21152.894235528725;21170.788292116882;21210.575788484453;7893.486923349964;8017.879731804028;8058.919410805817;8189.8771518427275;8231.794205144799;4505.189065968158;4509.120721459284;4511.70673906195;4702;4736.715797052188;3347.0962840033053;3400.0819778659625;3419.8119103449053;3425.1265927188906;3472.618012018676;609.9085137229451;624.5846512069423;664.226176504367;670.33971538035;675.2943174382782;472.5676006454056;518.4815184815185;587.9206307148565;597.5966222799593;610.3652201710261;8640.567971601395;8825.69110081144;8891.333150013645;9053.90946090544;9140.6800761973;497.9896525503341;508.3061620887462;608.1060840564363;624.6699809709808;646.7186773753432;3533;4351.8694439166875;4418.712783669115;4511.616512596466;4561.521040290788;90635.54682226546;102711;130805.69194308126;132076;134344.32827835798;96815.54776678355;102041.97958020473;115308;132252.3387383057;141273.17453650868;101503;114815;125310.37344813399;126007;129656.27577931693;9840.11438970496;10061;10101.191904647758;10187.7453063674;10217.13154381886;68192.3180768186;68261;69235.96146057712;69238.65380673064;70974.2256443593;90119.64820527828;96337;102371;102749.45875812018;109299;11090.223684342018;13327.00047496461;13482.123661961941;13728.725425491633;14186.858131418554;15027.699446011236;15270.618234544225;15337.309821057856;15347.386104555693;15485.690286194438;13983;14159.22124283133;14204;14327.35526901271;14724.852751472778;14008.019438639198;14066.367013484212;14102.435902563782;14166.78749818733;14225;11699.941500292443;13826;13916.304184790923;14007.509737159151;14029;11142.220044596801;11288.49201785938;11457.828132578185;11524.366159861289;11632.360220187971;8897.73306800797;9098.454092754457;10531.368117912949;11756.353400563052;11781.469833857669 16:42:47 INFO - PROCESS | 1644 | __end_tp_report 16:42:47 INFO - PROCESS | 1644 | __start_cc_report 16:42:47 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,366 16:42:47 INFO - PROCESS | 1644 | __end_cc_report 16:42:47 INFO - PROCESS | 1644 | __startTimestamp1473291767351__endTimestamp 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | ------- Summary: start ------- 16:42:47 INFO - PROCESS | 1644 | Number of tests: 6 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5752.01 Median:5983.52 stddev:680.38 (11.4%) stddev-sans-first:657.61 16:42:47 INFO - PROCESS | 1644 | Values: 3428.9 3444.2 3475.7 3497.0 3502.6 5743.8 5860.0 5890.0 5933.9 5968.0 4220.0 5243.8 5408.5 5684.6 5882.3 4720.9 4954.1 5223.5 5247.4 5407.6 4777.0 5375.0 5444.8 5445.3 5491.5 4865.1 5269.1 5298.1 5317.9 5322.2 4284.1 5305.9 5324.2 5334.1 5360.1 5274.2 5327.1 5335.7 5362.4 5381.7 5751.8 5950.7 5971.3 5976.1 5989.0 6161.3 6169.7 6226.1 6282.6 6305.2 5305.9 6265.8 6301.9 6305.1 6316.3 5445.3 5766.2 5875.1 5930.2 5961.0 6231.0 6293.3 6313.7 6405.5 6423.2 6171.8 6175.9 6228.8 6241.5 6269.2 6109.8 6213.4 6284.4 6291.3 6305.8 6154.8 6189.0 6217.0 6226.4 6227.0 6229.3 6234.7 6253.5 6254.9 6262.2 5015.1 5055.8 5067.0 5068.7 5078.9 4547.2 4556.3 4588.9 4598.8 4622.7 5880.0 5914.8 5953.6 5971.2 5983.5 6175.7 6266.3 6324.0 6354.7 6389.7 5880.5 5888.9 5955.3 5957.8 6024.1 4361.6 5431.5 5506.9 5573.3 5575.5 6274.2 6352.9 6559.0 6567.2 6578.0 6164.1 6283.2 6289.1 6304.5 6304.7 6482.9 6484.5 6489.9 6506.4 6511.2 5102.4 5263.9 5659.9 6204.6 6273.0 4970.7 5513.3 6059.6 6111.7 6243.0 6206.5 6239.3 6241.3 6258.1 6264.5 4663.9 6062.4 6128.1 6232.7 6240.8 5048.3 5115.5 6151.0 6170.7 6180.6 5182.2 6165.6 6238.5 6264.2 6285.6 6037.2 6066.0 6105.8 6157.3 6157.4 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:51936.09 Median:39034.21 stddev:100181.59 (256.7%) stddev-sans-first:100388.15 16:42:47 INFO - PROCESS | 1644 | Values: 487.0 528.5 534.8 557.5 600.2 38667.6 40577.8 40647.4 40716.0 40741.0 40906.8 41338.4 41378.8 41388.2 41426.0 9691.2 9721.6 9747.4 9906.8 9918.2 9705.8 9760.2 9795.5 9797.4 9802.0 9808.5 9822.8 9848.0 9863.8 9883.4 9668.5 9728.8 9786.3 9810.1 9821.6 9715.4 9756.6 9783.1 9794.7 9835.6 39394.6 40025.8 40560.4 40843.0 40861.6 3361.3 3406.4 3561.8 3722.3 3988.5 30172.0 30352.8 30514.0 30846.5 31559.8 2847.8 3218.9 3224.5 3225.8 3245.6 375526.6 380105.0 380861.0 380887.0 381698.0 55720.0 57037.4 57135.0 57220.4 57235.0 32698.7 35224.5 59138.4 59703.0 59750.0 14339.8 16197.6 16221.7 16224.3 16291.3 12355.6 12413.3 12466.3 12474.0 12501.5 17786.6 17844.6 18210.7 18212.8 18340.5 7206.8 7812.9 8023.7 8117.9 8212.9 844.0 879.8 912.7 948.4 960.6 26758.1 29373.9 29734.7 29962.0 30009.5 1065.0 1072.6 1092.1 1179.4 1213.7 15063.5 15486.7 15624.6 15653.0 15686.5 188342.0 189925.2 191067.0 191100.0 191288.0 57002.7 57618.0 58089.0 58196.0 58247.7 1190.5 1263.6 1287.1 1366.4 1385.8 28765.6 29611.7 29723.1 30032.5 30232.8 43446.8 49533.0 50224.0 50274.5 50301.7 493384.0 494214.0 496477.0 496743.0 498557.0 26067.3 26230.5 26280.8 26609.5 26627.5 46168.8 47394.0 47642.8 47799.6 48101.5 36776.0 38100.6 38173.0 39515.8 39589.4 36253.6 39002.8 39638.8 39653.0 40624.6 38129.0 38488.0 38772.8 38853.8 39065.6 28756.1 33473.8 34736.0 35083.3 36573.0 26041.7 26328.5 26454.0 26828.2 26853.9 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34515.71 Median:1987.60 stddev:95330.42 (4796.3%) stddev-sans-first:95563.31 16:42:47 INFO - PROCESS | 1644 | Values: 335.8 352.0 378.7 382.9 404.8 15269.9 16312.9 18592.9 20601.5 20729.5 19783.3 20099.9 20420.0 20668.2 20670.3 5743.9 5767.6 5955.8 5993.9 5998.2 5798.5 5873.5 5921.6 5934.4 5955.9 5646.2 5821.7 5838.6 5874.3 5892.2 5711.6 5736.5 5804.0 5866.2 5866.9 4273.4 5058.1 5096.3 5117.3 5141.1 21074.8 21701.1 22007.0 25047.9 25093.4 2024.6 2477.9 2603.4 2965.9 3038.7 10097.1 10379.6 12734.8 15141.9 15628.5 1808.1 1847.8 1849.8 1855.4 1912.2 100151.0 129960.0 130489.0 132367.0 133564.0 9927.0 11110.8 11156.6 11578.5 11648.5 14275.7 14392.9 14563.9 14569.5 14593.0 6169.9 8200.5 8471.4 8487.6 8624.7 8386.7 8414.9 8430.8 8530.0 8621.8 3623.2 4265.9 4521.0 4529.9 4659.1 1917.3 1929.8 1937.7 1943.8 1976.9 846.6 862.9 878.8 894.4 939.3 16383.0 20136.8 21213.6 21384.1 21417.0 914.4 920.5 954.4 967.0 975.6 8622.6 10188.3 11153.7 11156.4 11168.7 481958.0 571636.0 577380.0 593408.0 593628.0 134374.0 137422.3 140272.0 140560.0 141131.0 969.7 974.6 989.6 992.3 995.5 18017.7 18624.0 18662.0 18727.9 18738.0 51957.5 53179.5 54423.2 56671.9 56727.7 83296.0 83342.0 83353.0 84405.0 86032.0 6199.5 6881.2 7389.5 7944.8 8055.6 9747.5 11739.9 11994.7 12340.8 12377.5 8149.3 10392.2 10409.4 10720.5 10753.0 9300.0 10593.8 10783.3 10843.8 10976.4 10566.7 10569.9 10918.6 10941.1 11085.7 7746.5 7874.7 7944.0 8011.9 8019.5 8418.9 10381.6 10594.5 10677.7 10686.5 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5666.70 Median:5067.68 stddev:3167.78 (62.5%) stddev-sans-first:3160.58 16:42:47 INFO - PROCESS | 1644 | Values: 1419.0 1536.0 1636.1 1702.0 1884.5 7817.3 7983.2 8628.6 8650.4 8666.2 7342.7 8804.2 8823.8 8838.2 8858.2 2004.3 2389.8 2408.3 2410.1 2414.9 2316.6 2324.3 2332.5 2353.4 2375.4 2352.4 2389.4 2398.1 2404.5 2413.6 2364.6 2368.3 2381.4 2384.1 2390.7 2347.5 2366.6 2367.5 2375.7 2381.0 8751.0 8818.8 8841.7 8890.8 8925.0 5235.5 5292.2 5345.4 5366.5 5373.5 3589.3 3659.3 3672.6 3718.4 3785.0 1094.7 1099.5 1100.4 1116.2 1116.7 11231.8 11413.7 11433.6 11522.4 11556.2 3789.8 4047.6 4613.0 4791.4 4806.8 9714.6 9802.4 9862.2 9887.9 9922.0 5290.9 5316.8 5320.0 5329.9 5340.7 4399.5 4741.1 4843.0 4861.0 4879.6 3203.4 3884.0 3909.6 3972.7 4010.5 990.0 1107.8 1168.0 1288.7 1300.8 909.4 929.2 929.3 932.3 954.1 5770.7 5932.9 6039.5 6157.1 6165.0 425.9 438.3 441.2 444.9 452.9 3935.6 4020.9 4044.2 4059.2 4098.6 8972.5 9727.5 10573.7 10679.7 10680.7 10299.7 10620.5 10650.3 10657.3 10673.9 8025.3 8154.5 8176.0 8177.6 8216.9 5664.5 5828.2 5841.4 5842.4 5923.5 9804.2 9817.8 9820.7 9834.4 9842.6 9879.4 10075.8 10154.5 10247.7 10252.4 6964.1 7353.9 7507.2 7532.9 7622.7 7589.8 8505.5 8533.7 8536.2 8551.6 7409.0 7416.2 7547.7 7620.7 7644.9 7340.0 7469.4 7484.3 7572.4 7641.0 6993.3 6994.1 7024.9 7036.4 7125.3 4944.5 4958.9 5028.1 5107.3 5141.8 5023.4 5023.6 5196.6 5197.0 5220.5 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4716.86 Median:5266.17 stddev:1511.37 (28.7%) stddev-sans-first:1478.84 16:42:47 INFO - PROCESS | 1644 | Values: 302.1 308.3 314.8 316.7 318.6 4400.2 5561.5 5660.5 5726.4 5749.9 5517.9 5661.8 5665.0 5675.0 5699.2 4281.8 4286.3 4301.4 4339.3 4343.4 4062.5 4257.2 4257.2 4318.9 4326.9 3460.4 3461.0 4119.5 4203.4 4205.7 3465.6 3513.6 3620.9 4184.4 4201.1 2907.9 3457.0 3616.8 3650.6 3666.0 5494.7 5541.4 5547.9 5549.9 5581.8 4273.7 4468.4 4699.0 4889.4 4924.0 5649.5 5925.2 5932.2 5971.5 5980.9 3482.9 3918.6 3948.5 3956.7 3957.8 5343.5 5511.8 6332.9 6371.4 6391.6 3759.8 3868.0 3964.3 4062.6 4087.4 3437.9 4119.2 4121.5 4139.2 4159.3 4034.4 4795.9 4839.8 4843.8 4869.0 5797.3 5998.7 6006.9 6140.8 6141.5 1313.1 1343.5 1429.9 1463.5 1471.1 836.3 839.8 957.6 976.0 1001.2 3752.6 4298.4 4667.4 4680.3 4684.0 4808.0 4979.1 5142.1 5238.0 5295.0 4115.1 4277.6 4604.7 4694.8 4738.6 2788.9 2899.8 3197.2 3198.5 3323.2 6086.9 6127.4 6418.0 6440.2 6539.4 5652.2 5841.9 6221.9 6232.9 6333.2 6349.3 6382.9 6456.6 6461.7 6521.7 5102.8 5360.9 5644.5 5867.6 6160.4 5783.6 6024.3 6032.2 6052.3 6062.7 5570.8 6351.4 6357.8 6365.0 6392.3 3167.4 3674.5 3769.8 3864.3 4073.0 5134.8 5336.4 5970.9 6023.8 6324.7 4732.8 5437.6 5555.8 6045.2 6076.0 5669.8 5753.0 6023.7 6042.6 6056.7 5513.5 5649.0 5858.7 5892.8 5935.9 5285.2 5863.1 6045.2 6103.7 6117.9 4930.6 5247.1 5581.6 5917.2 5930.9 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:24838.27 Median:21161.84 stddev:38882.07 (183.7%) stddev-sans-first:38948.77 16:42:47 INFO - PROCESS | 1644 | Values: 659.6 673.9 715.0 767.4 816.8 14938.3 15561.8 15562.5 15576.5 15596.1 14114.9 14121.2 14185.5 14227.6 14253.3 840.7 841.3 860.7 862.8 868.2 853.7 854.3 855.2 855.3 855.9 707.5 886.2 913.2 917.7 921.0 891.1 891.8 892.0 893.5 893.6 647.6 656.5 657.0 657.7 658.5 15139.2 15205.9 15211.9 15227.9 15251.3 10962.7 13302.7 13528.2 13535.7 13798.0 12488.5 12863.7 12867.3 12883.9 12983.0 4219.7 4236.3 4254.3 4258.2 4321.4 110155.4 123961.4 124811.0 131456.3 132570.0 10807.4 10849.0 10863.5 10914.5 10962.8 21101.7 21109.7 21152.9 21170.8 21210.6 7893.5 8017.9 8058.9 8189.9 8231.8 4505.2 4509.1 4511.7 4702.0 4736.7 3347.1 3400.1 3419.8 3425.1 3472.6 609.9 624.6 664.2 670.3 675.3 472.6 518.5 587.9 597.6 610.4 8640.6 8825.7 8891.3 9053.9 9140.7 498.0 508.3 608.1 624.7 646.7 3533.0 4351.9 4418.7 4511.6 4561.5 90635.5 102711.0 130805.7 132076.0 134344.3 96815.5 102042.0 115308.0 132252.3 141273.2 101503.0 114815.0 125310.4 126007.0 129656.3 9840.1 10061.0 10101.2 10187.7 10217.1 68192.3 68261.0 69236.0 69238.7 70974.2 90119.6 96337.0 102371.0 102749.5 109299.0 11090.2 13327.0 13482.1 13728.7 14186.9 15027.7 15270.6 15337.3 15347.4 15485.7 13983.0 14159.2 14204.0 14327.4 14724.9 14008.0 14066.4 14102.4 14166.8 14225.0 11699.9 13826.0 13916.3 14007.5 14029.0 11142.2 11288.5 11457.8 11524.4 11632.4 8897.7 9098.5 10531.4 11756.4 11781.5 16:42:47 INFO - PROCESS | 1644 | -------- Summary: end -------- 16:42:47 INFO - PROCESS | 1644 | 16:42:47 INFO - TEST-INFO | 1644: exit 0 16:42:47 INFO - TEST-OK | dromaeo_css | took 1114598ms 16:42:47 INFO - TEST-START | kraken 16:42:47 INFO - operating with platform_type : mac_ 16:42:47 INFO - Initialising browser for kraken test... 16:42:47 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpNSbpXN/profile http://localhost:49266/getInfo.html) 16:42:50 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 16:42:50 INFO - PROCESS | 1656 | colorDepth:24 16:42:50 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 16:42:50 INFO - PROCESS | 1656 | __metrics 16:42:50 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 16:42:51 INFO - TEST-INFO | 1656: exit 0 16:42:51 INFO - Browser initialized. 16:42:51 INFO - Running cycle 1/1 for kraken test... 16:42:51 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpNSbpXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:03 INFO - PROCESS | 1658 | RSS: Main: 243732480 16:43:03 INFO - PROCESS | 1658 | 16:43:06 INFO - PROCESS | 1658 | 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) 16:43:07 INFO - PROCESS | 1658 | RSS: Main: 256815104 16:43:07 INFO - PROCESS | 1658 | 16:43:10 INFO - PROCESS | 1658 | 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) 16:43:10 INFO - PROCESS | 1658 | RSS: Main: 265490432 16:43:10 INFO - PROCESS | 1658 | 16:43:14 INFO - PROCESS | 1658 | 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) 16:43:15 INFO - PROCESS | 1658 | RSS: Main: 264302592 16:43:15 INFO - PROCESS | 1658 | 16:43:17 INFO - PROCESS | 1658 | 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) 16:43:17 INFO - PROCESS | 1658 | RSS: Main: 266653696 16:43:17 INFO - PROCESS | 1658 | 16:43:19 INFO - PROCESS | 1658 | 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) 16:43:20 INFO - PROCESS | 1658 | RSS: Main: 267190272 16:43:20 INFO - PROCESS | 1658 | 16:43:23 INFO - PROCESS | 1658 | 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) 16:43:24 INFO - PROCESS | 1658 | RSS: Main: 267436032 16:43:24 INFO - PROCESS | 1658 | 16:43:27 INFO - PROCESS | 1658 | 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) 16:43:27 INFO - PROCESS | 1658 | RSS: Main: 267571200 16:43:27 INFO - PROCESS | 1658 | 16:43:30 INFO - PROCESS | 1658 | 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) 16:43:31 INFO - PROCESS | 1658 | RSS: Main: 268025856 16:43:31 INFO - PROCESS | 1658 | 16:43:32 INFO - PROCESS | 1658 | 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) 16:43:33 INFO - PROCESS | 1658 | RSS: Main: 267497472 16:43:33 INFO - PROCESS | 1658 | 16:43:35 INFO - PROCESS | 1658 | 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) 16:43:36 INFO - PROCESS | 1658 | RSS: Main: 267481088 16:43:36 INFO - PROCESS | 1658 | 16:43:38 INFO - PROCESS | 1658 | 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) 16:43:38 INFO - PROCESS | 1658 | RSS: Main: 267837440 16:43:38 INFO - PROCESS | 1658 | 16:43:41 INFO - PROCESS | 1658 | 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) 16:43:42 INFO - PROCESS | 1658 | RSS: Main: 267984896 16:43:42 INFO - PROCESS | 1658 | 16:43:45 INFO - PROCESS | 1658 | 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) 16:43:46 INFO - PROCESS | 1658 | RSS: Main: 267964416 16:43:46 INFO - PROCESS | 1658 | 16:43:47 INFO - PROCESS | 1658 | 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) 16:43:48 INFO - PROCESS | 1658 | RSS: Main: 269926400 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | __start_tp_report 16:43:48 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 16:43:48 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 16:43:48 INFO - PROCESS | 1658 | |i|pagename|runs| 16:43:48 INFO - PROCESS | 1658 | |0;ai-astar;88;125;115;159;169;118;116;116;115;122 16:43:48 INFO - PROCESS | 1658 | |1;audio-beat-detection;144;128;173;172;139;166;148;110;163;112 16:43:48 INFO - PROCESS | 1658 | |2;audio-dft;211;253;280;237;199;200;194;204;192;193 16:43:48 INFO - PROCESS | 1658 | |3;audio-fft;69;101;102;141;59;58;62;64;132;59 16:43:48 INFO - PROCESS | 1658 | |4;audio-oscillator;69;73;120;72;72;113;115;72;100;145 16:43:48 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;89;87;89;87;136;131;95;89;133;87 16:43:48 INFO - PROCESS | 1658 | |6;imaging-darkroom;95;96;99;152;149;95;96;115;124;96 16:43:48 INFO - PROCESS | 1658 | |7;imaging-desaturate;90;93;92;108;93;117;146;90;101;83 16:43:48 INFO - PROCESS | 1658 | |8;json-parse-financial;55;58;58;57;57;58;57;58;56;59 16:43:48 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;67;69;69;75;72;78;76;69;69;71 16:43:48 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;85;89;90;88;92;93;90;90;93;90 16:43:48 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;155;173;136;153;165;161;178;165;194;147 16:43:48 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;176;176;174;176;178;178;177;178;176;178 16:43:48 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;71;71;72;72;91;95;91;74;73;70 16:43:48 INFO - PROCESS | 1658 | __end_tp_report 16:43:48 INFO - PROCESS | 1658 | __start_cc_report 16:43:48 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,961 16:43:48 INFO - PROCESS | 1658 | __end_cc_report 16:43:48 INFO - PROCESS | 1658 | __startTimestamp1473291828186__endTimestamp 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | ------- Summary: start ------- 16:43:48 INFO - PROCESS | 1658 | Number of tests: 14 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:124.30 Median:123.50 stddev:23.28 (18.8%) stddev-sans-first:20.65 16:43:48 INFO - PROCESS | 1658 | Values: 88.0 125.0 115.0 159.0 169.0 118.0 116.0 116.0 115.0 122.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:155.50 stddev:23.39 (15.0%) stddev-sans-first:24.80 16:43:48 INFO - PROCESS | 1658 | Values: 144.0 128.0 173.0 172.0 139.0 166.0 148.0 110.0 163.0 112.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:216.30 Median:207.50 stddev:30.20 (14.6%) stddev-sans-first:31.97 16:43:48 INFO - PROCESS | 1658 | Values: 211.0 253.0 280.0 237.0 199.0 200.0 194.0 204.0 192.0 193.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:84.70 Median:59.00 stddev:31.97 (54.2%) stddev-sans-first:33.40 16:43:48 INFO - PROCESS | 1658 | Values: 69.0 101.0 102.0 141.0 59.0 58.0 62.0 64.0 132.0 59.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:70.50 stddev:27.12 (38.5%) stddev-sans-first:27.07 16:43:48 INFO - PROCESS | 1658 | Values: 69.0 73.0 120.0 72.0 72.0 113.0 115.0 72.0 100.0 145.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:102.30 Median:88.00 stddev:21.57 (24.5%) stddev-sans-first:22.34 16:43:48 INFO - PROCESS | 1658 | Values: 89.0 87.0 89.0 87.0 136.0 131.0 95.0 89.0 133.0 87.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:111.70 Median:95.50 stddev:22.70 (23.8%) stddev-sans-first:23.25 16:43:48 INFO - PROCESS | 1658 | Values: 95.0 96.0 99.0 152.0 149.0 95.0 96.0 115.0 124.0 96.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:90.00 stddev:18.57 (20.6%) stddev-sans-first:19.24 16:43:48 INFO - PROCESS | 1658 | Values: 90.0 93.0 92.0 108.0 93.0 117.0 146.0 90.0 101.0 83.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.16 (2.0%) stddev-sans-first:0.88 16:43:48 INFO - PROCESS | 1658 | Values: 55.0 58.0 58.0 57.0 57.0 58.0 57.0 58.0 56.0 59.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:3.66 (5.1%) stddev-sans-first:3.50 16:43:48 INFO - PROCESS | 1658 | Values: 67.0 69.0 69.0 75.0 72.0 78.0 76.0 69.0 69.0 71.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:90.00 stddev:2.40 (2.7%) stddev-sans-first:1.74 16:43:48 INFO - PROCESS | 1658 | Values: 85.0 89.0 90.0 88.0 92.0 93.0 90.0 90.0 93.0 90.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:165.00 stddev:16.49 (10.0%) stddev-sans-first:17.25 16:43:48 INFO - PROCESS | 1658 | Values: 155.0 173.0 136.0 153.0 165.0 161.0 178.0 165.0 194.0 147.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:177.50 stddev:1.34 (0.8%) stddev-sans-first:1.39 16:43:48 INFO - PROCESS | 1658 | Values: 176.0 176.0 174.0 176.0 178.0 178.0 177.0 178.0 176.0 178.0 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:73.50 stddev:10.01 (13.6%) stddev-sans-first:10.29 16:43:48 INFO - PROCESS | 1658 | Values: 71.0 71.0 72.0 72.0 91.0 95.0 91.0 74.0 73.0 70.0 16:43:48 INFO - PROCESS | 1658 | -------- Summary: end -------- 16:43:48 INFO - PROCESS | 1658 | 16:43:48 INFO - TEST-INFO | 1658: exit 0 16:43:48 INFO - TEST-OK | kraken | took 61043ms 16:43:48 INFO - SUITE-END | took 1175s 16:43:49 INFO - Completed test suite (00:19:36) 16:43:49 INFO - javascript benchmark 16:43:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7304.615896348898, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3428.9314213715725, 3444.173398384387, 3475.6871881530656, 3497.0033540440973, 3502.562179727534, 5743.827685169452, 5859.9707001464985, 5889.970550147244, 5933.851653708665, 5967.970160149204, 4220.0, 5243.79024839007, 5408.513233808956, 5684.573656975723, 5882.32353279373, 4720.929186062212, 4954.058728841526, 5223.529882310591, 5247.449017853128, 5407.567394608422, 4777.04459108178, 5375.00562395958, 5444.809431669893, 5445.292112025431, 5491.478309560605, 4865.099956508039, 5269.104252277122, 5298.125809241471, 5317.9468205317835, 5322.175062865262, 4284.121755040209, 5305.893882122375, 5324.201369794562, 5334.0665383557725, 5360.142377219626, 5274.156135018417, 5327.067763141474, 5335.733213339317, 5362.410134885159, 5381.730913454351, 5751.7699292028265, 5950.672713000827, 5971.28344598651, 5976.103584462322, 5988.970055149696, 6161.322254551996, 6169.691515424256, 6226.066090086478, 6282.623042617458, 6305.180326557564, 5305.920411193835, 6265.780697675605, 6301.905471417932, 6305.0857625644485, 6316.30520642729, 5445.346558412936, 5766.192733017381, 5875.059990401558, 5930.169776231331, 5961.0, 6231.034189700604, 6293.30773614902, 6313.747450102036, 6405.519586031066, 6423.229212494531, 6171.783987560414, 6175.938240617626, 6228.750849965951, 6241.469475094668, 6269.153664255358, 6109.829807539378, 6213.409726076015, 6284.4344009039405, 6291.276503202099, 6305.779297724557, 6154.753809847556, 6188.9690551546955, 6217.0, 6226.40849119333, 6226.968865155736, 6229.345918678564, 6234.719437625317, 6253.468455181361, 6254.937450625435, 6262.1546091278005, 5015.14742493777, 5055.823046193365, 5066.974665126724, 5068.721220332917, 5078.923816142761, 4547.1815073287125, 4556.2937744649635, 4588.931166032512, 4598.804310879195, 4622.6764126510825, 5880.029795083763, 5914.792982245601, 5953.553483488756, 5971.19388882504, 5983.521318294526, 6175.660338681416, 6266.310705822356, 6323.9683801580695, 6354.745810167634, 6389.744410223632, 5880.500157486662, 5888.852778680567, 5955.34491205967, 5957.821265362046, 6024.096385542116, 4361.629261512743, 5431.53831924283, 5506.91739623906, 5573.331200255995, 5575.470330318694, 6274.247090349188, 6352.936470635234, 6559.0, 6567.211934567787, 6578.04618330349, 6164.106204600397, 6283.183186185766, 6289.119523266655, 6304.5271604628915, 6304.71628776697, 6482.870342593027, 6484.5460817742305, 6489.9026514603265, 6506.3818937200895, 6511.186101737283, 5102.36220472441, 5263.894582137794, 5659.886802263849, 6204.5967012144065, 6273.0, 4970.65205435616, 5513.283273174462, 6059.575829691879, 6111.682900513672, 6242.969909964805, 6206.472449841814, 6239.344868788712, 6241.344658810759, 6258.092576576462, 6264.467520260829, 4663.903982564163, 6062.393760623902, 6128.07537951499, 6232.688365581794, 6240.812775616739, 5048.318477005557, 5115.514026167508, 6150.969245153746, 6170.660613666291, 6180.6291622502795, 5182.24857395668, 6165.568410211242, 6238.500919926304, 6264.154339164337, 6285.59143655661, 6037.154798328325, 6065.968785306421, 6105.755769769159, 6157.322694503511, 6157.415045570664], "value": 5712.8842576705865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.0113669251419, 528.5190476666234, 534.8315280686584, 557.5456003357264, 600.2227115884934, 38667.61332386675, 40577.79711101444, 40647.39028914569, 40716.0, 40741.0, 40906.795466022704, 41338.37992430116, 41378.79310603443, 41388.17223655525, 41426.0, 9691.176250018756, 9721.611135554569, 9747.415155090683, 9906.752331191707, 9918.156956658657, 9705.757356066084, 9760.17038551724, 9795.461249631267, 9797.363171393872, 9802.019798020212, 9808.46053467059, 9822.754431139207, 9848.0, 9863.802723945553, 9883.406995580288, 9668.516574171263, 9728.805423891517, 9786.266030047775, 9810.117089461912, 9821.607135714563, 9715.36850104748, 9756.609735610638, 9783.0706082922, 9794.706158815246, 9835.606575736962, 39394.606053939664, 40025.79987100075, 40560.391594126115, 40843.0, 40861.591384086074, 3361.2605226850055, 3406.369821583015, 3561.821908904544, 3722.3113723961155, 3988.4615576897036, 30172.0, 30352.84823575868, 30514.0, 30846.53730194049, 31559.84220078908, 2847.7721782257368, 3218.855151518187, 3224.4840825468045, 3225.79032362898, 3245.6429792722784, 375526.590771599, 380104.9999999972, 380861.0, 380887.0, 381698.0, 55720.0, 57037.42962570404, 57135.0, 57220.427795722346, 57235.0, 32698.67301327004, 35224.471632925524, 59138.40861591415, 59703.0, 59750.0, 14339.78490322646, 16197.59506012359, 16221.675566488604, 16224.269907854175, 16291.289414611296, 12355.62933112008, 12413.31726755019, 12466.314352710688, 12474.0, 12501.49994000248, 17786.644267114585, 17844.643107137785, 18210.726839097424, 18212.81787182138, 18340.541486462946, 7206.774848275806, 7812.882806757903, 8023.719169829144, 8117.918820811717, 8212.917870821335, 843.9831203375898, 879.8046199650238, 912.7489940266347, 948.4262021477045, 960.6493629825063, 26758.063467778535, 29373.85313073421, 29734.702652973192, 29962.0, 30009.534601618667, 1065.041462683591, 1072.5924148823397, 1092.1481244629201, 1179.3985067615406, 1213.6541085790507, 15063.47277845292, 15486.690266194613, 15624.609384765245, 15653.0, 15686.529404117895, 188342.0, 189925.15112273328, 191067.0, 191100.0, 191288.0, 57002.714986424806, 57618.0, 58089.0, 58196.0, 58247.70876145592, 1190.5237904838134, 1263.5893334666087, 1287.073307218801, 1366.3509832829327, 1385.7852032934709, 28765.568516471852, 29611.703882961756, 29723.108306750833, 30032.549511756904, 30232.84883575568, 43446.78276608723, 49533.0, 50223.995520090124, 50274.49725502698, 50301.742456437416, 493384.0, 494214.0, 496477.0, 496743.0, 498557.0, 26067.348316292246, 26230.475390492466, 26280.817363218317, 26609.467810644066, 26627.467450651267, 46168.769156154005, 47394.0, 47642.761786190844, 47799.56601301966, 48101.51898480971, 36776.0, 38100.61899380971, 38173.045673858374, 39515.80242098771, 39589.40615890821, 36253.64956205272, 39002.804985974886, 39638.801805990784, 39653.0, 40624.59375406208, 38129.0, 38488.0, 38772.8368148956, 38853.805730971166, 39065.60934390734, 28756.13731587972, 33473.82841600532, 34736.0, 35083.29833403369, 36573.0, 26041.739582603932, 26328.473430531667, 26454.0, 26828.195154145407, 26853.865730671223], "value": 18984.693035561104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.82704906972896, 351.95952465466473, 378.67623182179256, 382.94638750574927, 404.7692815095395, 15269.923650381746, 16312.918435407806, 18592.90703546482, 20601.48496287592, 20729.481762955933, 19783.307584234553, 20099.899500502477, 20419.979001049924, 20668.17327306906, 20670.27654032109, 5743.942560574404, 5767.567432442563, 5955.821325360246, 5993.850153746148, 5998.1602575639445, 5798.507126894219, 5873.530117590582, 5921.585489015771, 5934.376890426508, 5955.88088238235, 5646.153077038436, 5821.7380217890295, 5838.620489668179, 5874.295084589855, 5892.204552385416, 5711.628744131618, 5736.541076713853, 5804.042333015069, 5866.208061911651, 5866.882662346729, 4273.423087883159, 5058.064258112261, 5096.286519887219, 5117.309163262949, 5141.100307446182, 21074.789252107435, 21701.131954721794, 22007.0, 25047.874760626262, 25093.37266568332, 2024.6153230886084, 2477.872597702516, 2603.4402603440353, 2965.940681186386, 3038.7265146136733, 10097.091261786401, 10379.636712715019, 12734.790194931467, 15141.924290378587, 15628.453004144914, 1808.1230603157458, 1847.78750443698, 1849.8057703941026, 1855.3969959762996, 1912.1586501939103, 100151.0, 129960.0, 130489.0, 132367.0, 133564.0, 9926.950365248273, 11110.833337499944, 11156.609518666808, 11578.536858525733, 11648.53405863773, 14275.714485710436, 14392.85607143936, 14563.85436145625, 14569.543045695558, 14593.0, 6169.876602468015, 8200.507969521848, 8471.449355791854, 8487.618057187317, 8624.655013799504, 8386.664533418718, 8414.873776893352, 8430.831383372299, 8530.019047809581, 8621.78445538854, 3623.2391197848747, 4265.893352666209, 4520.954790452053, 4529.886752831139, 4659.091477161921, 1917.3289348727833, 1929.8456123510086, 1937.7328525625053, 1943.834774044222, 1976.9110390032483, 846.6020970144023, 862.9041117780383, 878.7978764884112, 894.4096896048577, 939.281449690987, 16383.0, 20136.798632013786, 21213.575728485343, 21384.14463421477, 21417.0, 914.3736540469799, 920.5121285718579, 954.370115723619, 966.970990870275, 975.5951280218629, 8622.611982460805, 10188.337758045704, 11153.665390038312, 11156.442177891235, 11168.66494005181, 481958.0, 571636.0, 577380.0, 593408.0, 593628.0, 134374.0, 137422.25155497034, 140272.0, 140560.0, 141131.0, 969.680005598151, 974.64425484699, 989.618996686281, 992.2558081439065, 995.4674249276602, 18017.72973405426, 18624.0, 18662.0, 18727.90636046811, 18737.969411682487, 51957.48042519678, 53179.468205317455, 54423.18365224604, 56671.86656266934, 56727.71636141793, 83296.0, 83342.0, 83353.0, 84405.0, 86032.0, 6199.535034872312, 6881.243063262937, 7389.519681220703, 7944.761657150295, 8055.597220138852, 9747.512624368896, 11739.882601173878, 11994.700132496408, 12340.753184936071, 12377.504899803907, 8149.348052155695, 10392.22058345612, 10409.427481488288, 10720.463976801284, 10753.032227099598, 9300.0, 10593.788124237626, 10783.299085559414, 10843.837342439712, 10976.396298203676, 10566.735831604272, 10569.894301056891, 10918.617848375268, 10941.124710023338, 11085.722856928642, 7746.49647772893, 7874.685012599661, 7944.04671439431, 8011.919880801351, 8019.518828870287, 8418.915810841812, 10381.584736610754, 10594.523246454082, 10677.679669609925, 10686.465676715978], "value": 9424.309793292361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.9929050354747, 1536.0246243635293, 1636.0919689572283, 1701.953298721286, 1884.4817675139343, 7817.257360550746, 7983.163872309372, 8628.56857157142, 8650.437721548104, 8666.220040196396, 7342.706291748324, 8804.207621314097, 8823.779405514866, 8838.204561589455, 8858.247049000842, 2004.2584243829804, 2389.82076344274, 2408.2654790288957, 2410.09621391978, 2414.8671823049726, 2316.6409206573007, 2324.267855625481, 2332.521833024226, 2353.411647088228, 2375.429896824758, 2352.4471749139025, 2389.3668177932864, 2398.0767404549288, 2404.495056038234, 2413.625887987365, 2364.609839376518, 2368.277675309039, 2381.428457170275, 2384.117876385731, 2390.7489713580085, 2347.495288512961, 2366.5621859955963, 2367.5028244068767, 2375.717112759108, 2380.9523809523885, 8751.0, 8818.779530511725, 8841.734747957507, 8890.822183556293, 8925.018247992715, 5235.450277720823, 5292.232626269208, 5345.385280692732, 5366.4633536646415, 5373.543248823855, 3589.28214357127, 3659.3413185626578, 3672.6327367263325, 3718.442233664945, 3785.0, 1094.7427354571669, 1099.4777480696685, 1100.3617901617122, 1116.1963386361888, 1116.6538373104363, 11231.831522527087, 11413.714657133554, 11433.59982400612, 11522.366269855154, 11556.24884382521, 3789.7536660117003, 4047.6357127858632, 4613.0, 4791.377120974251, 4806.759662016885, 9714.61141554344, 9802.362846415102, 9862.211023118134, 9887.851682224773, 9922.0, 5290.8941821164135, 5316.76074576645, 5319.989202051675, 5329.866753331198, 5340.706261155596, 4399.494058183286, 4741.146593613183, 4842.975785121052, 4860.9513904861205, 4879.585235254972, 3203.3913556424204, 3883.980580097081, 3909.6285852843935, 3972.6890126258268, 4010.47863777707, 989.9802003960025, 1107.828286615576, 1167.9941600291945, 1288.7229245712217, 1300.7918733002675, 909.4361495872602, 929.1823195587839, 929.2890938432105, 932.2635118256637, 954.0554850697898, 5770.711464426846, 5932.940670593325, 6039.48664363534, 6157.076438534166, 6164.969175154185, 425.9467566554181, 438.31185039487696, 441.2256489860339, 444.9021215332622, 452.8822506148425, 3935.626115519021, 4020.8994775130273, 4044.2113787811095, 4059.188162367538, 4098.631123198927, 8972.506512141765, 9727.513624318897, 10573.682789516326, 10679.733006674895, 10680.679579612624, 10299.742506437398, 10620.468976551141, 10650.307729997681, 10657.25399222047, 10673.946630266799, 8025.277725004661, 8154.510729356258, 8176.018877734591, 8177.591120443954, 8216.876746848682, 5664.49019588239, 5828.184054232326, 5841.357450680337, 5842.444967728059, 5923.466887980103, 9804.166645835185, 9817.803643927224, 9820.65627703027, 9834.360766550151, 9842.557084931339, 9879.407235565888, 10075.84886226722, 10154.492275386348, 10247.7438064049, 10252.384856908311, 6964.129483814523, 7353.926460735538, 7507.249275072668, 7532.945387645625, 7622.695092196472, 7589.772306830804, 8505.489670619783, 8533.701320453754, 8536.189062039097, 8551.615177316911, 7408.9629551851895, 7416.221296763762, 7547.73582924595, 7620.69517219305, 7644.923550764421, 7340.0457940467395, 7469.365103966224, 7484.25157484269, 7572.356349710336, 7641.0, 6993.2657071006815, 6994.055802466602, 7024.8595028098125, 7036.366726994595, 7125.287471252833, 4944.53026962438, 4958.8760280993265, 5028.094942910311, 5107.259447380184, 5141.768620412166, 5023.447420783784, 5023.648344615842, 5196.610254231024, 5197.038547868657, 5220.477952204749], "value": 4485.247876705357, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.1404105320363, 308.25556281579986, 314.782799868091, 316.6532646751806, 318.6208411989732, 4400.229959757045, 5561.4716601922855, 5660.5471562275025, 5726.37009928908, 5749.856253593657, 5517.903196785216, 5661.801836935708, 5665.006170649682, 5675.0, 5699.207388379695, 4281.764502952336, 4286.314189729652, 4301.440812694345, 4339.305711086226, 4343.370211319357, 4062.4515690381863, 4257.190693955072, 4257.233697934386, 4318.870433886988, 4326.870193894172, 3460.411730005905, 3460.965390346102, 4119.546849846514, 4203.369494575808, 4205.69852665656, 3465.6187819339852, 3513.5608048993877, 3620.945685814715, 4184.4141820145205, 4201.138766552849, 2907.8836846526115, 3456.9827150864335, 3616.782993020427, 3650.6349365063547, 3665.981670091659, 5494.725263736837, 5541.445855414467, 5547.916781248285, 5549.861253468655, 5581.832545023656, 4273.743575385487, 4468.374427580109, 4699.036697477009, 4889.364382630235, 4924.015196960622, 5649.548036157139, 5925.170476133345, 5932.228810254639, 5971.462568368825, 5980.880382392328, 3482.9129271768156, 3918.647321741057, 3948.4669569608022, 3956.7032472564383, 3957.821898014596, 5343.465653434664, 5511.830697349041, 6332.905006424908, 6371.362863713592, 6391.584547004476, 3759.77807212657, 3867.961320386816, 3964.306246406867, 4062.5734297898885, 4087.4073259377224, 3437.914052148716, 4119.217348703737, 4121.52602450716, 4139.234241665302, 4159.334506478949, 4034.374671925856, 4795.856124316207, 4839.782209800567, 4843.8304659337455, 4868.95131048685, 5797.275340582427, 5998.730057147439, 6006.858696847585, 6140.785072522441, 6141.539384546177, 1313.1267706974945, 1343.482759137739, 1429.8999070064995, 1463.5463006468028, 1471.1026273972868, 836.3058661311097, 839.8236370362171, 957.5978089202551, 975.9609615615437, 1001.2090448545567, 3752.643498867658, 4298.355246712956, 4667.436408803089, 4680.315086568832, 4683.976580117078, 4807.95192048082, 4979.107938983236, 5142.071723103836, 5237.983376400113, 5295.040834891115, 4115.065919018057, 4277.615014648696, 4604.664647252259, 4694.788734506956, 4738.573528382462, 2788.9123241935663, 2899.797014209027, 3197.2326641605846, 3198.5362122492575, 3323.185819474196, 6086.908696369558, 6127.417895299939, 6418.042497038448, 6440.227172739207, 6539.4169303125, 5652.18043383707, 5841.9415805842245, 6221.906671399842, 6232.937670623326, 6333.2133861100965, 6349.2698339690605, 6382.904256436064, 6456.644884531396, 6461.676916154173, 6521.739130434825, 5102.770375333044, 5360.919586206132, 5644.548436125182, 5867.618604790675, 6160.414760597736, 5783.595148339576, 6024.337322894566, 6032.215811944419, 6052.273727152769, 6062.696865156637, 5570.832875013756, 6351.428371446592, 6357.841053973688, 6364.968175159094, 6392.264889537577, 3167.3823604397844, 3674.5039419678, 3769.7748231825317, 3864.30442520349, 4072.959270407258, 5134.76893539799, 5336.439673834347, 5970.910436343545, 6023.819285421444, 6324.683765811783, 4732.810687572596, 5437.619366644297, 5555.833325000256, 6045.183900173596, 6076.0, 5669.829905102853, 5752.9712351437975, 6023.710989823839, 6042.577019608585, 6056.728087101582, 5513.476219759116, 5648.971755141198, 5858.740370820294, 5892.823215303548, 5935.881282374242, 5285.207218917193, 5863.120531920246, 6045.183900173596, 6103.664298463627, 6117.879881260678, 4930.556249937523, 5247.134222853187, 5581.553475721851, 5917.201177840936, 5930.873134104507], "value": 4238.9073209353855, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.6207180870999, 673.8787018336698, 715.0275625149792, 767.3592550220566, 816.7876352148442, 14938.327775250113, 15561.84438155618, 15562.455314063982, 15576.53270401887, 15596.142212178325, 14114.858851411484, 14121.223332716721, 14185.503507377243, 14227.644308892282, 14253.28733563323, 840.6553313141613, 841.3479553346144, 860.746079906426, 862.8188080503071, 868.2402897464718, 853.73960941913, 854.3336197765722, 855.2003876375578, 855.2516548020482, 855.8758979947935, 707.4906067631323, 886.2068448738376, 913.1918252346687, 917.7017469322464, 920.9769755756126, 891.1266958380791, 891.8038031633031, 892.0366004714892, 893.5041052216018, 893.6425429828056, 647.6146692717825, 656.4977791989132, 656.9737210511573, 657.7369052379039, 658.5324419661998, 15139.243037848173, 15205.923970380078, 15211.923940380228, 15227.923860380737, 15251.313690883937, 10962.671119866416, 13302.667433314147, 13528.188308701509, 13535.729285414236, 13798.0, 12488.50045998159, 12863.742725145445, 12867.292298923556, 12883.871161288362, 12983.02627302956, 4219.662427005832, 4236.322188449864, 4254.319308910591, 4258.233517966764, 4321.351797230409, 110155.44922275418, 123961.38019309936, 124811.0, 131456.34271828676, 132570.0, 10807.40559269232, 10849.0, 10863.51114199863, 10914.508847101899, 10962.780744385067, 21101.68347474789, 21109.68335474969, 21152.894235528725, 21170.788292116882, 21210.575788484453, 7893.486923349964, 8017.879731804028, 8058.919410805817, 8189.8771518427275, 8231.794205144799, 4505.189065968158, 4509.120721459284, 4511.70673906195, 4702.0, 4736.715797052188, 3347.0962840033053, 3400.0819778659625, 3419.8119103449053, 3425.1265927188906, 3472.618012018676, 609.9085137229451, 624.5846512069423, 664.226176504367, 670.33971538035, 675.2943174382782, 472.5676006454056, 518.4815184815185, 587.9206307148565, 597.5966222799593, 610.3652201710261, 8640.567971601395, 8825.69110081144, 8891.333150013645, 9053.90946090544, 9140.6800761973, 497.9896525503341, 508.3061620887462, 608.1060840564363, 624.6699809709808, 646.7186773753432, 3533.0, 4351.8694439166875, 4418.712783669115, 4511.616512596466, 4561.521040290788, 90635.54682226546, 102711.0, 130805.69194308126, 132076.0, 134344.32827835798, 96815.54776678355, 102041.97958020473, 115308.0, 132252.3387383057, 141273.17453650868, 101503.0, 114815.0, 125310.37344813399, 126007.0, 129656.27577931693, 9840.11438970496, 10061.0, 10101.191904647758, 10187.7453063674, 10217.13154381886, 68192.3180768186, 68261.0, 69235.96146057712, 69238.65380673064, 70974.2256443593, 90119.64820527828, 96337.0, 102371.0, 102749.45875812018, 109299.0, 11090.223684342018, 13327.00047496461, 13482.123661961941, 13728.725425491633, 14186.858131418554, 15027.699446011236, 15270.618234544225, 15337.309821057856, 15347.386104555693, 15485.690286194438, 13983.0, 14159.22124283133, 14204.0, 14327.35526901271, 14724.852751472778, 14008.019438639198, 14066.367013484212, 14102.435902563782, 14166.78749818733, 14225.0, 11699.941500292443, 13826.0, 13916.304184790923, 14007.509737159151, 14029.0, 11142.220044596801, 11288.49201785938, 11457.828132578185, 11524.366159861289, 11632.360220187971, 8897.73306800797, 9098.454092754457, 10531.368117912949, 11756.353400563052, 11781.469833857669], "value": 7816.139317654446, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 125.0, 115.0, 159.0, 169.0, 118.0, 116.0, 116.0, 115.0, 122.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 128.0, 173.0, 172.0, 139.0, 166.0, 148.0, 110.0, 163.0, 112.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 253.0, 280.0, 237.0, 199.0, 200.0, 194.0, 204.0, 192.0, 193.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 101.0, 102.0, 141.0, 59.0, 58.0, 62.0, 64.0, 132.0, 59.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 120.0, 72.0, 72.0, 113.0, 115.0, 72.0, 100.0, 145.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 136.0, 131.0, 95.0, 89.0, 133.0, 87.0], "value": 102.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 99.0, 152.0, 149.0, 95.0, 96.0, 115.0, 124.0, 96.0], "value": 111.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 92.0, 108.0, 93.0, 117.0, 146.0, 90.0, 101.0, 83.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 58.0, 57.0, 57.0, 58.0, 57.0, 58.0, 56.0, 59.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 75.0, 72.0, 78.0, 76.0, 69.0, 69.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 90.0, 88.0, 92.0, 93.0, 90.0, 90.0, 93.0, 90.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 173.0, 136.0, 153.0, 165.0, 161.0, 178.0, 165.0, 194.0, 147.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 174.0, 176.0, 178.0, 178.0, 177.0, 178.0, 176.0, 178.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 72.0, 91.0, 95.0, 91.0, 74.0, 73.0, 70.0], "value": 78.0, "unit": "score"}]}]} 16:43:49 INFO - Return code: 0 16:43:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:43:49 ERROR - # TBPL SUCCESS # 16:43:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:43:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:43:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:43:49 INFO - 2016-09-07 16:43:49.543 screenresolution[1663:10333] starting screenresolution argv=screenresolution get 16:43:49 INFO - 2016-09-07 16:43:49.545 screenresolution[1663:10333] Display 0: 1600x1200x32@60 16:43:49 INFO - 2016-09-07 16:43:49.551 screenresolution[1664:10336] starting screenresolution argv=screenresolution list 16:43:49 INFO - Available Modes on Display 0 16:43:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:43:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:43:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:43:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:43:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:43:49 INFO - Intel Iris: 16:43:49 INFO - Chipset Model: Intel Iris 16:43:49 INFO - Type: GPU 16:43:49 INFO - Bus: Built-In 16:43:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:43:49 INFO - Vendor: Intel (0x8086) 16:43:49 INFO - Device ID: 0x0a2e 16:43:49 INFO - Revision ID: 0x0009 16:43:49 INFO - Displays: 16:43:49 INFO - Display: 16:43:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:43:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:43:49 INFO - Display Serial Number: 1600x1200 60 16:43:49 INFO - Main Display: Yes 16:43:49 INFO - Mirror: Off 16:43:49 INFO - Online: Yes 16:43:49 INFO - Rotation: Supported 16:43:49 INFO - Return code: 0 16:43:49 INFO - Running post-action listener: _resource_record_post_action 16:43:49 INFO - [mozharness: 2016-09-07 23:43:49.789180Z] Finished run-tests step (success) 16:43:49 INFO - Running post-run listener: _resource_record_post_run 16:43:49 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38532096; Write bytes: 705051648; Read time: 375; Write time: 1945 16:43:49 INFO - TinderboxPrint: CPU usage
54.5% 16:43:49 INFO - TinderboxPrint: I/O read bytes / time
38,532,096 / 375 16:43:49 INFO - TinderboxPrint: I/O write bytes / time
705,051,648 / 1,945 16:43:49 INFO - TinderboxPrint: CPU idle
2,203.6 (45.5%) 16:43:49 INFO - TinderboxPrint: CPU system
979.5 (20.2%) 16:43:49 INFO - TinderboxPrint: CPU user
1,658.7 (34.3%) 16:43:49 INFO - TinderboxPrint: Swap in / out
444,207,104 / 0 16:43:49 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400591360; Write bytes: 393148416; Read time: 29551; Write time: 731 16:43:49 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36434944; Write bytes: 294999040; Read time: 352; Write time: 1177 16:43:50 INFO - Running post-run listener: _upload_blobber_files 16:43:50 INFO - Blob upload gear active. 16:43:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:43:50 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 16:43:50 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'] 16:43:50 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 16:43:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:51 INFO - (blobuploader) - INFO - Done attempting. 16:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:53 INFO - (blobuploader) - INFO - Done attempting. 16:43:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:43:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:55 INFO - (blobuploader) - INFO - Done attempting. 16:43:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:43:55 INFO - Return code: 0 16:43:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:43:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:43:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75dcc4055c43c6d8de19de59d4d67eb3b563ae9e2a7638cefa1b7caa0b4d1381f20948f99dc5ae77ba61b1a4b9e78f03c066512bfbba58c71c2f3cb0da41ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca4a68bb28fe06846187f100aca38f6055518859542a2aed967f2ea6bead3299b80d6b5f309341447768a36d802ebe8c513ec4d16240df36ad3b7f3088a569f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff1718317e869e907c3727b41cbc9e2a6738aa34c075fe4101d056bc68908b7fe32fc8edb480cbf92c83447b46abf41d56da184c44a93c7ed3605187cda85a65"} 16:43:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:43:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:43:55 INFO - Contents: 16:43:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/75dcc4055c43c6d8de19de59d4d67eb3b563ae9e2a7638cefa1b7caa0b4d1381f20948f99dc5ae77ba61b1a4b9e78f03c066512bfbba58c71c2f3cb0da41ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca4a68bb28fe06846187f100aca38f6055518859542a2aed967f2ea6bead3299b80d6b5f309341447768a36d802ebe8c513ec4d16240df36ad3b7f3088a569f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff1718317e869e907c3727b41cbc9e2a6738aa34c075fe4101d056bc68908b7fe32fc8edb480cbf92c83447b46abf41d56da184c44a93c7ed3605187cda85a65"} 16:43:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:43:55 INFO - Copying logs to upload dir... 16:43:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:43:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.770070 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-07 16:43:55.888013) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 16:43:55.891911) ========= 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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/75dcc4055c43c6d8de19de59d4d67eb3b563ae9e2a7638cefa1b7caa0b4d1381f20948f99dc5ae77ba61b1a4b9e78f03c066512bfbba58c71c2f3cb0da41ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca4a68bb28fe06846187f100aca38f6055518859542a2aed967f2ea6bead3299b80d6b5f309341447768a36d802ebe8c513ec4d16240df36ad3b7f3088a569f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff1718317e869e907c3727b41cbc9e2a6738aa34c075fe4101d056bc68908b7fe32fc8edb480cbf92c83447b46abf41d56da184c44a93c7ed3605187cda85a65"} build_url:https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/VnycP6VPQXOVdjc1W_JLew/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/75dcc4055c43c6d8de19de59d4d67eb3b563ae9e2a7638cefa1b7caa0b4d1381f20948f99dc5ae77ba61b1a4b9e78f03c066512bfbba58c71c2f3cb0da41ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ca4a68bb28fe06846187f100aca38f6055518859542a2aed967f2ea6bead3299b80d6b5f309341447768a36d802ebe8c513ec4d16240df36ad3b7f3088a569f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff1718317e869e907c3727b41cbc9e2a6738aa34c075fe4101d056bc68908b7fe32fc8edb480cbf92c83447b46abf41d56da184c44a93c7ed3605187cda85a65"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 16:43:55.924868) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:43:55.925190) ========= 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.gExIAXTMlN/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.j7rVjuy1Fq/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005194 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 16:43:55.974650) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 16:43:55.975126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 16:43:55.975634) ========= ========= Total master_lag: 0.16 =========