builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0160 starttime: 1472999430.07 results: success (0) buildid: 20160904053249 builduid: 8ad8479158484a23a29b5ec0d3e5f3ed revision: e9ca1d928e35ae987bf3265408b48f04620badf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.072710) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.079185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.079532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.264477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.264826) ========= 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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-04 07:30:30-- 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% 13.5M=0.001s 2016-09-04 07:30:30 (13.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182527 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.544567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.544892) ========= 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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.030891 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:30.648800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 07:30:30.649155) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e9ca1d928e35ae987bf3265408b48f04620badf5 --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 e9ca1d928e35ae987bf3265408b48f04620badf5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-04 07:30:30,725 truncating revision to first 12 chars 2016-09-04 07:30:30,725 Setting DEBUG logging. 2016-09-04 07:30:30,725 attempt 1/10 2016-09-04 07:30:30,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e9ca1d928e35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-04 07:30:31,624 unpacking tar archive at: fx-team-e9ca1d928e35/testing/mozharness/ program finished with exit code 0 elapsedTime=1.159591 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 07:30:31.835298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:31.835633) ========= script_repo_revision: e9ca1d928e35ae987bf3265408b48f04620badf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:31.836060) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:31.836431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 07:30:31.869311) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-04 07:30:31.869709) ========= /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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:30:32 INFO - MultiFileLogger online at 20160904 07:30:32 in /builds/slave/test 07:30:32 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 07:30:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:32 INFO - {'append_to_log': False, 07:30:32 INFO - 'base_work_dir': '/builds/slave/test', 07:30:32 INFO - 'blob_upload_branch': 'Fx-Team', 07:30:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:32 INFO - 'branch': 'Fx-Team', 07:30:32 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:32 INFO - 'config_files': ('talos/mac_config.py',), 07:30:32 INFO - 'default_actions': ('clobber', 07:30:32 INFO - 'read-buildbot-config', 07:30:32 INFO - 'download-and-extract', 07:30:32 INFO - 'populate-webroot', 07:30:32 INFO - 'create-virtualenv', 07:30:32 INFO - 'install', 07:30:32 INFO - 'run-tests'), 07:30:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:32 INFO - 'download_minidump_stackwalk': True, 07:30:32 INFO - 'download_symbols': 'ondemand', 07:30:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:32 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:32 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:32 INFO - 'installer_path': 'installer.exe', 07:30:32 INFO - 'log_level': 'info', 07:30:32 INFO - 'log_name': 'talos', 07:30:32 INFO - 'log_to_console': True, 07:30:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:30:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:30:32 INFO - 'opt_config_files': (), 07:30:32 INFO - 'pip_index': False, 07:30:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:32 INFO - 'cmd': ('bash', 07:30:32 INFO - '-c', 07:30:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:32 INFO - 'enabled': True, 07:30:32 INFO - 'halt_on_failure': False, 07:30:32 INFO - 'name': 'check_screen_resolution'},), 07:30:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:30:32 INFO - 'cmd': ('bash', 07:30:32 INFO - '-c', 07:30:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:30:32 INFO - 'enabled': True, 07:30:32 INFO - 'halt_on_failure': False, 07:30:32 INFO - 'name': 'check_screen_resolution'},), 07:30:32 INFO - 'run_cmd_checks_enabled': True, 07:30:32 INFO - 'sps_profile': False, 07:30:32 INFO - 'sps_profile_interval': 0, 07:30:32 INFO - 'suite': 'dromaeojs-e10s', 07:30:32 INFO - 'system_bits': '32', 07:30:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:32 INFO - 'title': 't-yosemite-r7-0160', 07:30:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:32 INFO - 'use_talos_json': True, 07:30:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:32 INFO - 'work_dir': 'build'} 07:30:32 INFO - [mozharness: 2016-09-04 14:30:32.063141Z] Running clobber step. 07:30:32 INFO - Running pre-action listener: _resource_record_pre_action 07:30:32 INFO - Running main action method: clobber 07:30:32 INFO - rmtree: /builds/slave/test/build 07:30:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:34 INFO - Running post-action listener: _resource_record_post_action 07:30:34 INFO - [mozharness: 2016-09-04 14:30:34.192756Z] Finished clobber step (success) 07:30:34 INFO - [mozharness: 2016-09-04 14:30:34.192918Z] Running read-buildbot-config step. 07:30:34 INFO - Running pre-action listener: _resource_record_pre_action 07:30:34 INFO - Running main action method: read_buildbot_config 07:30:34 INFO - Using buildbot properties: 07:30:34 INFO - { 07:30:34 INFO - "project": "", 07:30:34 INFO - "product": "firefox", 07:30:34 INFO - "script_repo_revision": "production", 07:30:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 07:30:34 INFO - "repository": "", 07:30:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 07:30:34 INFO - "buildid": "20160904053249", 07:30:34 INFO - "pgo_build": "False", 07:30:34 INFO - "basedir": "/builds/slave/test", 07:30:34 INFO - "buildnumber": 779, 07:30:34 INFO - "slavename": "t-yosemite-r7-0160", 07:30:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:30:34 INFO - "platform": "yosemite_r7", 07:30:34 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 07:30:34 INFO - "branch": "fx-team", 07:30:34 INFO - "repo_path": "integration/fx-team", 07:30:34 INFO - "revision": "e9ca1d928e35ae987bf3265408b48f04620badf5", 07:30:34 INFO - "stage_platform": "macosx64", 07:30:34 INFO - "builduid": "8ad8479158484a23a29b5ec0d3e5f3ed", 07:30:34 INFO - "slavebuilddir": "test" 07:30:34 INFO - } 07:30:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:30:34 INFO - Running post-action listener: _resource_record_post_action 07:30:34 INFO - [mozharness: 2016-09-04 14:30:34.196365Z] Finished read-buildbot-config step (success) 07:30:34 INFO - [mozharness: 2016-09-04 14:30:34.196502Z] Running download-and-extract step. 07:30:34 INFO - Running pre-action listener: _resource_record_pre_action 07:30:34 INFO - Running main action method: download_and_extract 07:30:34 INFO - mkdir: /builds/slave/test/build/tests 07:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:34 INFO - trying https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:30:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/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 07:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/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 07:30:35 INFO - Downloaded 1199 bytes. 07:30:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:30:35 INFO - Using the following test package requirements: 07:30:35 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:30:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:30:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:30:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'jsshell-mac.zip'], 07:30:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:30:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:30:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:30:35 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:30:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:30:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:30:35 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:30:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:30:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:30:36 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:30:42 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 07:30:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:30:43 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:30:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:30:51 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:30:52 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')]} 07:30:52 INFO - trying https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:30:52 INFO - Downloading https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/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 07:30:54 INFO - Downloaded 111482881 bytes. 07:30:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:30:54 INFO - mkdir: /builds/slave/test/properties 07:30:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:54 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:54 INFO - Contents: 07:30:54 INFO - build_url:https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:30:55 INFO - Running post-action listener: _resource_record_post_action 07:30:55 INFO - Running post-action listener: set_extra_try_arguments 07:30:55 INFO - [mozharness: 2016-09-04 14:30:55.600856Z] Finished download-and-extract step (success) 07:30:55 INFO - [mozharness: 2016-09-04 14:30:55.601006Z] Running populate-webroot step. 07:30:55 INFO - Running pre-action listener: _resource_record_pre_action 07:30:55 INFO - Running main action method: populate_webroot 07:30:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:55 INFO - '--tppagecycles', 07:30:55 INFO - '7'], 07:30:55 INFO - 'tests': ['tsvgm']}}, 07:30:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['damp', 'tps']}, 07:30:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'tests': ['damp', 'tps']}, 07:30:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['dromaeo_dom']}, 07:30:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['basic_compositor_video']}, 07:30:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:30:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['a11yr', 07:30:55 INFO - 'ts_paint', 07:30:55 INFO - 'tpaint', 07:30:55 INFO - 'sessionrestore', 07:30:55 INFO - 'sessionrestore_no_auto_restore', 07:30:55 INFO - 'tabpaint']}, 07:30:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['tsvgx', 07:30:55 INFO - 'tsvgr_opacity', 07:30:55 INFO - 'tart', 07:30:55 INFO - 'tscrollx', 07:30:55 INFO - 'cart']}, 07:30:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:55 INFO - 'tsvgr_opacity', 07:30:55 INFO - 'tart', 07:30:55 INFO - 'tscrollx', 07:30:55 INFO - 'cart']}, 07:30:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'talos_options': ['--disable-e10s'], 07:30:55 INFO - 'tests': ['tp5o']}, 07:30:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'tests': ['tp5o']}, 07:30:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'talos_options': ['--disable-e10s', 07:30:55 INFO - '--xperf_path', 07:30:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:55 INFO - 'tests': ['tp5n']}, 07:30:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:30:55 INFO - 'talos_options': ['--xperf_path', 07:30:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:55 INFO - 'tests': ['tp5n']}}, 07:30:55 INFO - 'talos.zip': {'path': '', 07:30:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:55 INFO - Running post-action listener: _resource_record_post_action 07:30:55 INFO - [mozharness: 2016-09-04 14:30:55.619845Z] Finished populate-webroot step (success) 07:30:55 INFO - [mozharness: 2016-09-04 14:30:55.619987Z] Running create-virtualenv step. 07:30:55 INFO - Running pre-action listener: _resource_record_pre_action 07:30:55 INFO - Running main action method: create_virtualenv 07:30:55 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30:55 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 07:30:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:55 INFO - Using real prefix '/tools/python27' 07:30:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:56 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:59 INFO - Installing pip.................done. 07:30:59 INFO - Return code: 0 07:30:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:30:59 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 07:30:59 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 07:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:59 INFO - 'HOME': '/Users/cltbld', 07:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:59 INFO - 'LOGNAME': 'cltbld', 07:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:59 INFO - 'MOZ_NO_REMOTE': '1', 07:30:59 INFO - 'NO_EM_RESTART': '1', 07:30:59 INFO - 'PAGER': '/bin/cat', 07:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:59 INFO - 'PWD': '/builds/slave/test', 07:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:59 INFO - 'SHELL': '/bin/bash', 07:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:30:59 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:59 INFO - 'USER': 'cltbld', 07:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:59 INFO - 'XPC_FLAGS': '0x0', 07:30:59 INFO - 'XPC_SERVICE_NAME': '0', 07:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:59 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 07:30:59 INFO - Cleaning up... 07:30:59 INFO - Return code: 0 07:30:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:30:59 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:30:59 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 07:30:59 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 07:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:30:59 INFO - 'HOME': '/Users/cltbld', 07:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:30:59 INFO - 'LOGNAME': 'cltbld', 07:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:59 INFO - 'MOZ_NO_REMOTE': '1', 07:30:59 INFO - 'NO_EM_RESTART': '1', 07:30:59 INFO - 'PAGER': '/bin/cat', 07:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:59 INFO - 'PWD': '/builds/slave/test', 07:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:30:59 INFO - 'SHELL': '/bin/bash', 07:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:30:59 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:30:59 INFO - 'USER': 'cltbld', 07:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:59 INFO - 'XPC_FLAGS': '0x0', 07:30:59 INFO - 'XPC_SERVICE_NAME': '0', 07:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:59 INFO - Downloading/unpacking psutil>=3.1.1 07:30:59 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 07:30:59 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 07:30:59 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 07:30:59 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 07:30:59 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 07:30:59 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 07:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:02 INFO - Installing collected packages: psutil 07:31:02 INFO - Running setup.py install for psutil 07:31:02 INFO - building 'psutil._psutil_osx' extension 07:31:02 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 07:31:03 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 07:31:03 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 07:31:03 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 07:31:03 INFO - building 'psutil._psutil_posix' extension 07:31:03 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 07:31:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:31:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:31:03 INFO - ^ 07:31:03 INFO - 1 warning generated. 07:31:03 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 07:31:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:04 INFO - Successfully installed psutil 07:31:04 INFO - Cleaning up... 07:31:04 INFO - Return code: 0 07:31:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31: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')]} 07:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:04 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:04 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 07:31:04 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 07:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:04 INFO - 'HOME': '/Users/cltbld', 07:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:04 INFO - 'LOGNAME': 'cltbld', 07:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:04 INFO - 'MOZ_NO_REMOTE': '1', 07:31:04 INFO - 'NO_EM_RESTART': '1', 07:31:04 INFO - 'PAGER': '/bin/cat', 07:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:04 INFO - 'PWD': '/builds/slave/test', 07:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:04 INFO - 'SHELL': '/bin/bash', 07:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:04 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:04 INFO - 'USER': 'cltbld', 07:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:04 INFO - 'XPC_FLAGS': '0x0', 07:31:04 INFO - 'XPC_SERVICE_NAME': '0', 07:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31:04 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 07:31:04 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 07:31:04 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 07:31:04 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 07:31:04 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 07:31:04 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 07:31:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:31:06 INFO - Installing collected packages: mozsystemmonitor 07:31:06 INFO - Running setup.py install for mozsystemmonitor 07:31:07 INFO - Successfully installed mozsystemmonitor 07:31:07 INFO - Cleaning up... 07:31:07 INFO - Return code: 0 07:31:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:31:07 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')]} 07:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:07 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')]} 07:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:07 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:07 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 07:31:07 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 07:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:07 INFO - 'HOME': '/Users/cltbld', 07:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:07 INFO - 'LOGNAME': 'cltbld', 07:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:07 INFO - 'MOZ_NO_REMOTE': '1', 07:31:07 INFO - 'NO_EM_RESTART': '1', 07:31:07 INFO - 'PAGER': '/bin/cat', 07:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:07 INFO - 'PWD': '/builds/slave/test', 07:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:07 INFO - 'SHELL': '/bin/bash', 07:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:07 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:07 INFO - 'USER': 'cltbld', 07:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:07 INFO - 'XPC_FLAGS': '0x0', 07:31:07 INFO - 'XPC_SERVICE_NAME': '0', 07:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:07 INFO - Downloading/unpacking jsonschema==2.5.1 07:31:07 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 07:31:07 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 07:31:07 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 07:31:07 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 07:31:07 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 07:31:07 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 07:31:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:31:09 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 07:31:09 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 07:31:09 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 07:31:09 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 07:31:09 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 07:31:09 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 07:31:10 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:10 INFO - warning: no files found matching '*.txt' 07:31:10 INFO - no previously-included directories found matching 'build' 07:31:10 INFO - no previously-included directories found matching 'dist' 07:31:10 INFO - no previously-included directories found matching '.git*' 07:31:10 INFO - Installing collected packages: jsonschema, functools32 07:31:10 INFO - Running setup.py install for functools32 07:31:10 INFO - warning: no files found matching '*.txt' 07:31:10 INFO - no previously-included directories found matching 'build' 07:31:10 INFO - no previously-included directories found matching 'dist' 07:31:10 INFO - no previously-included directories found matching '.git*' 07:31:10 INFO - Successfully installed jsonschema functools32 07:31:10 INFO - Cleaning up... 07:31:10 INFO - Return code: 0 07:31:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:31:10 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')]} 07:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:10 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')]} 07:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:10 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:10 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 07:31:10 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 07:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:10 INFO - 'HOME': '/Users/cltbld', 07:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:10 INFO - 'LOGNAME': 'cltbld', 07:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:10 INFO - 'MOZ_NO_REMOTE': '1', 07:31:10 INFO - 'NO_EM_RESTART': '1', 07:31:10 INFO - 'PAGER': '/bin/cat', 07:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:10 INFO - 'PWD': '/builds/slave/test', 07:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:10 INFO - 'SHELL': '/bin/bash', 07:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:10 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:10 INFO - 'USER': 'cltbld', 07:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:10 INFO - 'XPC_FLAGS': '0x0', 07:31:10 INFO - 'XPC_SERVICE_NAME': '0', 07:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:31:10 INFO - Cleaning up... 07:31:10 INFO - Return code: 0 07:31:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:31:10 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')]} 07:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:10 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')]} 07:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:10 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:10 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 07:31:10 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 07:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:10 INFO - 'HOME': '/Users/cltbld', 07:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:10 INFO - 'LOGNAME': 'cltbld', 07:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:10 INFO - 'MOZ_NO_REMOTE': '1', 07:31:10 INFO - 'NO_EM_RESTART': '1', 07:31:10 INFO - 'PAGER': '/bin/cat', 07:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:10 INFO - 'PWD': '/builds/slave/test', 07:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:10 INFO - 'SHELL': '/bin/bash', 07:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:10 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:10 INFO - 'USER': 'cltbld', 07:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:10 INFO - 'XPC_FLAGS': '0x0', 07:31:10 INFO - 'XPC_SERVICE_NAME': '0', 07:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:11 INFO - Downloading/unpacking blobuploader==1.2.4 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:11 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 07:31:13 INFO - Downloading blobuploader-1.2.4.tar.gz 07:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:31:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:13 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 07:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:31:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:31:14 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 07:31:14 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 07:31:14 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 07:31:14 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 07:31:14 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 07:31:14 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 07:31:14 INFO - Downloading docopt-0.6.1.tar.gz 07:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:31:14 INFO - Installing collected packages: blobuploader, requests, docopt 07:31:14 INFO - Running setup.py install for blobuploader 07:31:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:31:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:31:15 INFO - Running setup.py install for requests 07:31:15 INFO - Running setup.py install for docopt 07:31:15 INFO - Successfully installed blobuploader requests docopt 07:31:15 INFO - Cleaning up... 07:31:15 INFO - Return code: 0 07:31:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:15 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:15 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 07:31:15 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 07:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:15 INFO - 'HOME': '/Users/cltbld', 07:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:15 INFO - 'LOGNAME': 'cltbld', 07:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:15 INFO - 'MOZ_NO_REMOTE': '1', 07:31:15 INFO - 'NO_EM_RESTART': '1', 07:31:15 INFO - 'PAGER': '/bin/cat', 07:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:15 INFO - 'PWD': '/builds/slave/test', 07:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:15 INFO - 'SHELL': '/bin/bash', 07:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:15 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:15 INFO - 'USER': 'cltbld', 07:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:15 INFO - 'XPC_FLAGS': '0x0', 07:31:15 INFO - 'XPC_SERVICE_NAME': '0', 07:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WHzWlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-xA46QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-n0PJDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-GDDbWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-p325Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_aTRou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-e5k00f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MwaVnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:16 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-9xO3nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-VT4qOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-gWKicO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-iPHPdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IQvO_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Wrmbbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Y4tX6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-pFTRSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-elhnlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:17 INFO - Running setup.py install for manifestparser 07:31:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Running setup.py install for mozcrash 07:31:18 INFO - Running setup.py install for mozdebug 07:31:18 INFO - Running setup.py install for mozdevice 07:31:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Running setup.py install for mozfile 07:31:18 INFO - Running setup.py install for mozhttpd 07:31:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Running setup.py install for mozinfo 07:31:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:18 INFO - Running setup.py install for mozInstall 07:31:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Running setup.py install for mozleak 07:31:19 INFO - Running setup.py install for mozlog 07:31:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Running setup.py install for moznetwork 07:31:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Running setup.py install for mozprocess 07:31:19 INFO - Running setup.py install for mozprofile 07:31:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:19 INFO - Running setup.py install for mozrunner 07:31:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:20 INFO - Running setup.py install for mozscreenshot 07:31:20 INFO - Running setup.py install for moztest 07:31:20 INFO - Running setup.py install for mozversion 07:31:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:20 INFO - Cleaning up... 07:31:20 INFO - Return code: 0 07:31:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:20 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')]} 07:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 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')]} 07:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:20 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 07:31:20 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 07:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:20 INFO - 'HOME': '/Users/cltbld', 07:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:20 INFO - 'LOGNAME': 'cltbld', 07:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:20 INFO - 'MOZ_NO_REMOTE': '1', 07:31:20 INFO - 'NO_EM_RESTART': '1', 07:31:20 INFO - 'PAGER': '/bin/cat', 07:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:20 INFO - 'PWD': '/builds/slave/test', 07:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:20 INFO - 'SHELL': '/bin/bash', 07:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:20 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:20 INFO - 'USER': 'cltbld', 07:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:20 INFO - 'XPC_FLAGS': '0x0', 07:31:20 INFO - 'XPC_SERVICE_NAME': '0', 07:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-fEDnV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:20 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)) 07:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-uk5p_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:20 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)) 07:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:20 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-D1Kr39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-CayoM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-b1ZpEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ORnbkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-BHYMqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-xLkOFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Yljk_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-plKeuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_fVVbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:21 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)) 07:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:21 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-kscxfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:22 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)) 07:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-kOlX5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:22 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)) 07:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-R7wXj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:22 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)) 07:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-E8PZNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:22 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)) 07:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-lHxvsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:22 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)) 07:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:22 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-BXvtbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:22 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)) 07:31:22 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)) 07:31:22 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)) 07:31:22 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)) 07:31:22 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)) 07:31:22 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)) 07:31:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:25 INFO - Downloading blessings-1.6.tar.gz 07:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:25 INFO - Installing collected packages: blessings 07:31:25 INFO - Running setup.py install for blessings 07:31:25 INFO - Successfully installed blessings 07:31:25 INFO - Cleaning up... 07:31:25 INFO - Return code: 0 07:31:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:25 INFO - Reading from file tmpfile_stdout 07:31:25 INFO - Current package versions: 07:31:25 INFO - blessings == 1.6 07:31:25 INFO - blobuploader == 1.2.4 07:31:25 INFO - docopt == 0.6.1 07:31:25 INFO - functools32 == 3.2.3-2 07:31:25 INFO - jsonschema == 2.5.1 07:31:25 INFO - manifestparser == 1.1 07:31:25 INFO - mozInstall == 1.12 07:31:25 INFO - mozcrash == 0.17 07:31:25 INFO - mozdebug == 0.1 07:31:25 INFO - mozdevice == 0.48 07:31:25 INFO - mozfile == 1.2 07:31:25 INFO - mozhttpd == 0.7 07:31:25 INFO - mozinfo == 0.9 07:31:25 INFO - mozleak == 0.1 07:31:25 INFO - mozlog == 3.3 07:31:25 INFO - moznetwork == 0.27 07:31:25 INFO - mozprocess == 0.23 07:31:25 INFO - mozprofile == 0.28 07:31:25 INFO - mozrunner == 6.12 07:31:25 INFO - mozscreenshot == 0.1 07:31:25 INFO - mozsystemmonitor == 0.3 07:31:25 INFO - moztest == 0.7 07:31:25 INFO - mozversion == 1.4 07:31:25 INFO - psutil == 3.1.1 07:31:25 INFO - requests == 1.2.3 07:31:25 INFO - wsgiref == 0.1.2 07:31:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 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 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:25 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 07:31:25 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 07:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:25 INFO - 'HOME': '/Users/cltbld', 07:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:25 INFO - 'LOGNAME': 'cltbld', 07:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:25 INFO - 'MOZ_NO_REMOTE': '1', 07:31:25 INFO - 'NO_EM_RESTART': '1', 07:31:25 INFO - 'PAGER': '/bin/cat', 07:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:25 INFO - 'PWD': '/builds/slave/test', 07:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:25 INFO - 'SHELL': '/bin/bash', 07:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:25 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:25 INFO - 'USER': 'cltbld', 07:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:25 INFO - 'XPC_FLAGS': '0x0', 07:31:25 INFO - 'XPC_SERVICE_NAME': '0', 07:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 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)) 07:31:26 INFO - Cleaning up... 07:31:26 INFO - Return code: 0 07:31:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109053a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090e8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6b058f0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, '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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 07:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:26 INFO - 'HOME': '/Users/cltbld', 07:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:26 INFO - 'LOGNAME': 'cltbld', 07:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:26 INFO - 'MOZ_NO_REMOTE': '1', 07:31:26 INFO - 'NO_EM_RESTART': '1', 07:31:26 INFO - 'PAGER': '/bin/cat', 07:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:26 INFO - 'PWD': '/builds/slave/test', 07:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:26 INFO - 'SHELL': '/bin/bash', 07:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:31:26 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:26 INFO - 'USER': 'cltbld', 07:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:26 INFO - 'XPC_FLAGS': '0x0', 07:31:26 INFO - 'XPC_SERVICE_NAME': '0', 07:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:26 INFO - Cleaning up... 07:31:26 INFO - Return code: 0 07:31:26 INFO - Running post-action listener: _resource_record_post_action 07:31:26 INFO - Running post-action listener: _start_resource_monitoring 07:31:26 INFO - Starting resource monitoring. 07:31:26 INFO - [mozharness: 2016-09-04 14:31:26.311703Z] Finished create-virtualenv step (success) 07:31:26 INFO - [mozharness: 2016-09-04 14:31:26.312674Z] Running install step. 07:31:26 INFO - Running pre-action listener: _resource_record_pre_action 07:31:26 INFO - Running main action method: install 07:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:26 INFO - Reading from file tmpfile_stdout 07:31:26 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:26 INFO - Reading from file tmpfile_stdout 07:31:26 INFO - Output received: 07:31:26 INFO - Usage: mozinstall [options] installer 07:31:26 INFO - Options: 07:31:26 INFO - -h, --help show this help message and exit 07:31:26 INFO - -d DEST, --destination=DEST 07:31:26 INFO - Directory to install application into. [default: 07:31:26 INFO - "/builds/slave/test"] 07:31:26 INFO - --app=APP Application being installed. [default: firefox] 07:31:26 INFO - mkdir: /builds/slave/test/build/application 07:31:26 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'] 07:31:26 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 07:32:01 INFO - Reading from file tmpfile_stdout 07:32:01 INFO - Output received: 07:32:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:32:01 INFO - Running post-action listener: _resource_record_post_action 07:32:01 INFO - [mozharness: 2016-09-04 14:32:01.143045Z] Finished install step (success) 07:32:01 INFO - [mozharness: 2016-09-04 14:32:01.143218Z] Running run-tests step. 07:32:01 INFO - Running pre-action listener: _resource_record_pre_action 07:32:01 INFO - Running main action method: run_tests 07:32:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:01 INFO - 2016-09-04 07:32:01.157 screenresolution[1745:16368] starting screenresolution argv=screenresolution get 07:32:01 INFO - 2016-09-04 07:32:01.160 screenresolution[1745:16368] Display 0: 1600x1200x32@60 07:32:01 INFO - 2016-09-04 07:32:01.166 screenresolution[1746:16371] starting screenresolution argv=screenresolution list 07:32:01 INFO - Available Modes on Display 0 07:32:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:01 INFO - Intel Iris: 07:32:01 INFO - Chipset Model: Intel Iris 07:32:01 INFO - Type: GPU 07:32:01 INFO - Bus: Built-In 07:32:01 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:01 INFO - Vendor: Intel (0x8086) 07:32:01 INFO - Device ID: 0x0a2e 07:32:01 INFO - Revision ID: 0x0009 07:32:01 INFO - Displays: 07:32:01 INFO - Display: 07:32:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:01 INFO - Display Serial Number: 1600x1200 60 07:32:01 INFO - Main Display: Yes 07:32:01 INFO - Mirror: Off 07:32:01 INFO - Online: Yes 07:32:01 INFO - Rotation: Supported 07:32:01 INFO - Return code: 0 07:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:01 INFO - Python 2.7.3 07:32:01 INFO - Return code: 0 07:32:01 INFO - grabbing minidump binary from tooltool 07:32:01 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')]} 07:32:01 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 0x108fb7d30>, '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 0x108d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fafb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:01 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 07:32:01 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 07:32:01 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 07:32:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:01 INFO - Return code: 0 07:32:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:01 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/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 07:32:01 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/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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 07:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aAR2lCo3ga/Render', 07:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:01 INFO - 'HOME': '/Users/cltbld', 07:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:01 INFO - 'LOGNAME': 'cltbld', 07:32:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:01 INFO - 'MOZ_NO_REMOTE': '1', 07:32:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:01 INFO - 'NO_EM_RESTART': '1', 07:32:01 INFO - 'PAGER': '/bin/cat', 07:32:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:01 INFO - 'PWD': '/builds/slave/test', 07:32:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:01 INFO - 'SHELL': '/bin/bash', 07:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KFqMJGJ0qR/Listeners', 07:32:01 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 07:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:01 INFO - 'USER': 'cltbld', 07:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:01 INFO - 'XPC_FLAGS': '0x0', 07:32:01 INFO - 'XPC_SERVICE_NAME': '0', 07:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:01 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/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 07:32:01 INFO - mozversion application_buildid: 20160904053249 07:32:01 INFO - mozversion application_changeset: e9ca1d928e35ae987bf3265408b48f04620badf5 07:32:01 INFO - mozversion application_display_name: Nightly 07:32:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:01 INFO - mozversion application_name: Firefox 07:32:01 INFO - mozversion application_remotingname: firefox 07:32:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:32:01 INFO - mozversion application_vendor: Mozilla 07:32:01 INFO - mozversion application_version: 51.0a1 07:32:01 INFO - mozversion platform_buildid: 20160904053249 07:32:01 INFO - mozversion platform_changeset: e9ca1d928e35ae987bf3265408b48f04620badf5 07:32:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:32:01 INFO - mozversion platform_version: 51.0a1 07:32:01 INFO - using testdate: 1472999521 07:32:01 INFO - actual date: 1472999521 07:32:01 INFO - starting webserver on 'localhost:49271' 07:32:01 INFO - SUITE-START | Running 2 tests 07:32:01 INFO - TEST-START | dromaeo_css 07:32:01 INFO - operating with platform_type : mac_ 07:32:01 INFO - Initialising browser for dromaeo_css test... 07:32:01 INFO - TEST-INFO | started process 1756 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmptiN2jy/profile http://localhost:49271/getInfo.html) 07:32:04 INFO - PROCESS | 1756 | __metrics Screen width/height:1600/1200 07:32:04 INFO - PROCESS | 1756 | colorDepth:24 07:32:04 INFO - PROCESS | 1756 | Browser inner width/height: 1024/689 07:32:04 INFO - PROCESS | 1756 | __metrics 07:32:04 ERROR - PROCESS | 1756 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 07:32:05 INFO - TEST-INFO | 1756: exit 0 07:32:05 INFO - Browser initialized. 07:32:05 INFO - Running cycle 1/1 for dromaeo_css test... 07:32:05 INFO - TEST-INFO | started process 1760 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmptiN2jy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:17 INFO - PROCESS | 1760 | RSS: Main: 243916800 07:32:17 INFO - PROCESS | 1760 | 07:33:06 INFO - PROCESS | 1760 | 1472999586994 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] 07:35:19 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:35:20 INFO - PROCESS | 1760 | RSS: Main: 266547200 07:35:20 INFO - PROCESS | 1760 | 07:38:22 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:38:23 INFO - PROCESS | 1760 | RSS: Main: 273461248 07:38:23 INFO - PROCESS | 1760 | 07:41:25 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:41:25 INFO - PROCESS | 1760 | RSS: Main: 273707008 07:41:25 INFO - PROCESS | 1760 | 07:44:27 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 07:44:28 INFO - PROCESS | 1760 | RSS: Main: 268853248 07:44:28 INFO - PROCESS | 1760 | 07:47:31 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 07:47:32 INFO - PROCESS | 1760 | RSS: Main: 275329024 07:47:32 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 07:50:34 INFO - PROCESS | 1760 | RSS: Main: 278212608 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | __start_tp_report 07:50:34 INFO - PROCESS | 1760 | _x_x_mozilla_page_load 07:50:34 INFO - PROCESS | 1760 | _x_x_mozilla_page_load_details 07:50:34 INFO - PROCESS | 1760 | |i|pagename|runs| 07:50:34 INFO - PROCESS | 1760 | |0;dojo.html;3395.983020084899;3407.5740532433447;3421;3425.349183655105;3427.537282466866;5825.388334224904;5827.70861456927;5830.212921255629;5840.036393994997;5849.444302791233;5474.37044739855;5612.158176273561;5842.094475356316;5863.237779088723;5927.466528012478;5185.637005409613;5251.921221181686;5328.46715328468;5348.117560602496;5367.919481207785;5268.446813084628;5425.810096646618;5437.238786569884;5454.781808727646;5461;5184.351956005499;5195.428502864682;5284.207368894658;5328.813491527817;5339.813106541272;5261.631685781997;5273.841784746463;5279.44565820591;5299.284596579451;5333.49331813481;5221.477852214786;5231.921521177185;5244.609043665258;5290.788368465257;5290.894182116374;5378.596605254583;5714.000049991277;5768.278965129359;5826.7377967991115;5852.882942341172;5233.084210263189;5767.480926716574;5788.942110578882;6269.216347956505;6296.464800491964;5867.090600956859;6284.685765711742;6303.464205542488;6321.968390158066;6329.651869147195;5742.425757424307;5832.737526811304;5899.528037756969;5903.439173278532;5907.4683278505145;6381.3937675920715;6391.137196478416;6397.072424498421;6403;6404.135441715402;6212;6235.127082208494;6236.064590311399;6255.280642726145;6270.090836828635;5166.948330516722;6141.884937092159;6247.12540244357;6252.812415627538;6293.590916590407;6136.2636483622255;6138.694953005908;6151.692415379213;6201.565890387629;6259.655718935503;6181.258249010058;6230.439260466489;6262.812115636538;6266.373362663697;6302.432781049728;4355.932796464886;4897.510248975074;4979.278004689299;4985.975070124626;5013.674111182761;3412.897613071612;4311.590398912098;4421.137878113737;4433.490148632884;4529.773511324421;5287.7884884604955;5908.438698323738;5917.970410147922;5943.286805583358;5949.791757288474;4738.881527961828;6152.692365381713;6224.346443623354;6243.500519958392;6298.874022519524;5865.149553314745;5973;5989.251343582052;6041.607295525777;6058;5474.616776825584;5486.890262194734;5496.230527726121;5505.532029777433;5516.282883225155;6214.689265536615;6305.90541141874;6440.098386225932;6465.482761379172;6502.447291980235;6192.628442293477;6272.121902933502;6281.309056003927;6291.182146320949;6302.936970630235;6341.397567231105;6383.106365108795;6411.807645770635;6420.325865784025;6432.967835160982;6099.176611157437;6174.845628859314;6239.594426362272;6244.219472565929;6246.843828904313;6219.844503887439;6286.622802631857;6293.307736149111;6299.929012068051;6337.873242535031;4588.403507543998;6210.7515699373325;6211.068339748893;6227.501799856092;6230.283517395376;5308.177232528964;6024.7288872001745;6193.225846769154;6197.876042479035;6206.410391012847;4939.456659767495;5881;6010.549208809444;6065.090236464389;6139.9079013813935;6132.724027418688;6193.287771906108;6200.441960223421;6207.565470417208;6208.782692605737;5890.4993075589055;5999.340072592098;6039.305479869696;6083.695815209133;6114.929887269621 07:50:34 INFO - PROCESS | 1760 | |1;ext.html;475.747853637572;475.7644965741956;488.3114808120551;524.3026774390062;536.758458693588;37921.556084201264;40317.19365612686;40323.79838100809;40366.99999999996;40392.79803600985;40907;41321.38017929733;41474;41502;41611.37582936251;9522.80954380912;9728;9740.269479789009;9750.90249097507;9779;9608.423494590312;9698.951505242463;9708.757281067994;9718.854217186748;9724.88163861158;9667.371620844622;9697.175740062103;9698.9515052425;9724.854127188062;9749.610015599368;9563.856542151872;9661.90338096617;9674.75813104671;9698.981606931311;9700.417974921493;9665.806683866283;9684.661036863676;9738.853917191247;9745.220382369464;9747.463889486075;39583;39611.82134982575;39684;39800.20399592021;39824.60175398267;3430.4168291390524;3434.347473979936;3438.7249020078334;3865.091703449687;4026.5570787213383;23430.882845585664;24314.635280470808;26758.9158059968;27402.26561571679;30635.846820765753;3224.27453822888;3247.3018301065385;3247.772655914087;3254.96745032549;3349.882754103619;450108;505734;512011;515391;518951;50510.74744626253;60441;61021.69489152526;61150.77698446006;61215.38784612097;33685.494717579255;35638.28723425517;53732.46267537274;64543.677281613294;64566.67716661387;16215.756763648555;16448.83551164473;16569.585760356087;16606.25271862769;16676.499705008868;12732.427040783186;12764;12875.420606072748;12898.419571119322;12900.967922566173;18709.906450467934;18759;18864.962427066643;18907.338243161423;18942.715859262124;6319.620822750558;8269.379796515286;8277.04813946392;8285.461445006054;8355.582220888931;830.2444775254489;878.1174919206157;885.7342797160826;897.1387468030764;950.3870003847618;21146.89426552888;29723.256918577208;30233.395332093674;30448.54327185094;30462.54306185409;960.6877764726491;1035.5598870480017;1075.7041813501194;1083.1064371893062;1106.9667909962714;14989.925050374897;15326.23368831554;15643.139627320606;15667.608309792118;16032.839671603368;690540;693839.0616093775;694049.529752348;694941;696003;56792;56799.43200568024;57252.42747572554;57298.42701573014;57569.13646295309;1300.7008388070797;1334.2794890759076;1361.7276544691101;1363.1548439967285;1369.945202191921;26590.734092658826;29692.257693557833;29739.836422901244;30296.697033029388;30327.69672303337;48477.03045939132;48983;49216.75391623019;49331.75334123307;49378.01243975029;504621;595300;598150;598292;599358;26149.869250653624;26327.281369292177;26568;26615.733842661324;26615.7338426621;45765.77117114393;46278.76860615675;46427;47141.52858471509;47229.2915606273;38857.80571097126;39118.413223802236;39147.84686842251;40372.1925561493;40408.39387409132;38307;38755;38805.6119438802;39627.60372396239;40329.79010629686;37059.6294037067;37438;37872;37987.810060949516;38053.42919856149;28977.68178545669;33908.830455847565;34329;34536;34605.48091778575;24972.625410619217;25260.74739252584;25286.24141275765;25617.743822562283;25766.84049217752 07:50:34 INFO - PROCESS | 1760 | |2;jquery.html;373.0803569201918;373.08407858706863;379.1280055871496;382.6212050070431;398.63126607887716;18762.2495100196;20270.391888243375;20533.589328213446;20808;20814.687779683318;20463;20575;20590.89704551479;20879.851608161545;20960.26639067633;5758.654480731148;6018.578699491049;6050.727717252712;6131.7240724167295;6142.539309551779;3859.2281543691233;5865.794697185599;6091.78678746244;6119.1739115219325;6126.203593532857;6115.938840611604;6146.108814221935;6149.13912052313;6158.137860699506;6171.074338849164;5896.823095307126;6109.35851735568;6126.540509461808;6136.294326152505;6157.230346206724;5719.428057194247;5872.941270587325;5900.911486327708;5909.970450147722;5911.911321330141;12819.038572107129;13065.608031759062;13121.671958201026;13142.802857957138;13204.60386188416;2111.9366419007456;2119.618468675638;2144.3995681209285;2216.523447458804;2532.4428625702317;9097.40866843653;9536.761580960461;10265.589376425009;10303.536340864679;10339.63811266602;1780.7328900664875;1809.3938530592268;1902.5433895864892;1911.7036859286693;1944.1251436853472;90896.09103909008;116954;120759.3962030193;124453.37773311166;124619.3769031149;10156.542955567018;10527.947360263302;10932;11206.495707693173;11250.943745281385;14020.228887411291;14022.649433764238;14032.158070515801;14042.859571404155;14056.71886562263;7885.684572617031;7914.960425197837;8035.799105022421;8144.5520496373265;8234.752957411287;7814.648340824677;7880.2513761192595;7928.484648497829;7999.600019999093;8057.274845263954;3655.7440979131206;4433.6009759121835;4436.046250056189;4455.242608756519;4457.375967364572;1395.1559306619552;1404.5365029540228;1880.2103116691023;1936.7772706138974;1990.6615875301234;830.8255266394125;886.4061079077034;981.8085473332632;986.6152200641756;989.188865130586;16641.750373744402;17030.31878724862;19837.702434463496;20268.797312026985;20980.895095524425;933.2160984772823;952.9714108576753;953.8426159683574;992.9772334495481;997.2122023601273;10787.892121078688;11065.834012489819;11096.33421994683;11120;11145.498452569655;443862;545231;573921;586849;587988;130062;131615;133104;133531;137628.31185844008;941.5245301123026;955.5604421965974;975.2636759246724;976.6679329028022;978.6427953796955;18598.907005464887;18679.906600467453;18689.53276168107;18883.90558047201;19782.364274852724;54182.45817541774;55323;55438.72280638571;55498.72250638721;55560.444395555525;78678;79181;79825.60087199527;79845.60077199577;81508.59245703733;7075;7166.53417527859;7271.963640181977;7411.887612030546;7567.962160189164;8832.602532886163;12308.876911230773;12651.114421990373;12913.16064455845;13149.539766108523;5655.519280861173;7415.480916335805;7860.292573668397;7944.6822127114265;8029.197080292158;7590.620468976419;8031.35749140079;8078.151794061774;8105.8378832424205;8205.589720513832;4832.710037397767;4852.490488498657;7023.70061538617;8047.959760201162;8533.658653653783;11589.420528973349;12501.56244531437;12569.62291131231;12844.486220551073;13011.674708132372;11084.944575277072;11781.527309086365;11968.581099661471;12033.037357011593;12151.75696486083 07:50:34 INFO - PROCESS | 1760 | |3;mootools.html;1332.3404914567288;1383.7509248335302;1677.3793696332352;1881.9623607527842;1892.1296203746267;7936.6825326986855;8412.242898139166;8545.4445461045;8962.641494340234;8980.371374003815;8548;8768.649254029831;8771.56142192891;8776;8785.868211976825;2363.8345315827855;2376.8455050421753;2392.64110383443;2395.8682272475094;2397.5564520563753;2350.6944097267333;2360.114956891166;2373.667686523888;2387.259949415678;2394.1261439574605;2373.2405630198346;2374.0741110966737;2385.4751954569965;2397.7602239776056;2399.1483023526657;2347.260612906929;2356.257778799681;2368.348704106376;2370.395549134968;2378.9048438062455;2346.3547524430746;2346.498241125681;2356.2460012795914;2359.4691194481275;2367.3134790910617;8790.296776257885;8833;8850.982137054258;8872.955635221848;8953.552322383919;4284.550122237151;5269.367675878919;5281.339832520935;5356.11624082026;5477.808276710296;3572.4284114541547;3617.113807117247;3644.1071937375245;3668.816559172031;3672.981635091834;1078.8219943709278;1095.4632230207221;1096.1395304685864;1107.617871834216;1114.587602587048;11448.94275528617;11469.483873225725;11546.653600392086;11562.768744625144;11583.18917675763;4538.500764915857;4604.1252162089095;4790.610722890323;4806.014766972762;4809.927851082237;9737.123658870736;9815.214782817358;9828.557714902703;9858.457784821903;9877.950610247046;5357.276767636397;5366.704831234241;5377.3816011159215;5380.78476860929;5386.649867758583;4405.823767049282;4775.689580177277;4923.433805112458;4925.876853078702;4944.579710724557;3863.8068096595057;3936.527616685959;3996.300647386696;4035.7780322082567;4050.797460126982;1309.646395473236;1318.6901078246603;1320.3398300849576;1328.4619729009573;1332.9400176991865;882.8214333864235;920.6961702638114;934.168589954927;947.6740717169414;965.0542468380886;4873.390826146731;5676.120201368794;5721.284839395075;5976.910346344808;6054.424829641177;423.89826441654395;441.30274166816355;443.4368352192762;933.7012156109979;1012.2964539644876;3996.18078293949;4013.1171142348635;4024.5975402460103;4049.9595004049584;4291.034517233597;8915.643374264957;10390.324628899098;10541.31481453703;10683.30558513694;10776.730581735519;10665.146788256921;10668.786624267626;10670;10691.572337106583;10748.355098694103;7686.730964416219;7994.64024118916;8104.311133553596;8144.592770361577;8235.629396677165;5746.655200687972;5923.259592550931;6008.429199226067;6075.149479072845;6099.237595300588;8220.876686849582;9961.50192490387;9966.651167209113;10054.798904022025;10054.899451005396;9939.950300248742;10049.598016079275;10242.385456872313;10264;10296.742581435525;6896.586204827727;7281.672324745505;7419.4806363554035;7475.850482990201;7574.772756817304;8330.458520196167;8404.705835295736;8421.19998600132;8425.157484251526;8449.986001679837;7343.265673432614;7468.9626551866895;7493.9250607495405;7581.241875812595;7592.278733520307;7325.047743793273;7352.632368381666;7376.7418140364825;7508.399328053852;7639.388848891964;6861.313868613099;6950.443964482931;6976.965115174392;6978.895316570154;6980.301969802979;4908.607311415007;4991.450940396626;5068.847934561969;5082.2122571001555;5089.185730283137;5002.549770520671;5081.441041485507;5133.998870220271;5200.713960732196;5205.869853253547 07:50:34 INFO - PROCESS | 1760 | |4;prototype.html;270.87856275021403;271.11616131411597;283.8240291019568;305.45781238302027;312.79980812280144;4800.4719480857075;4990.884343547866;5238.633295669305;5269.86825329367;5501.459591314433;4737.644676649256;5444.972775136119;5451.700156491402;5484.643498172616;5495.6153069285165;4096.549379568245;4153.421699754089;4157.584241575833;4164.791760411982;4165.6250937415625;3586.605473397937;3844.384898416254;4152.418661387408;4186.790660466964;4196.454460920076;4081.5918408159096;4092.7135100542973;4094.324436467979;4110.77390743509;4111.54772974974;3704.907377315562;3805.676517496016;4113.424120623115;4146.502419709624;4149.502059752849;3572.463840548563;3579.4451859961487;3582.6954708849516;3591.1740299730945;3591.317649646559;4933.753312334369;5271.42014378418;5337.946620533823;5344.6793192408195;5382.219578161144;3889.2221555688693;4120.505539335299;4683.484816670163;4709.95290047102;4714.986277950257;5755.4244575542525;5779.422057794229;5788.594798364116;5788.6816225107605;5796.449337312991;3348.631650518441;3402.387570237356;3603.045193023851;3858.5176852893387;3862.555806082308;4983.975080124613;5740.827775166752;5951.6726580038085;5984.611000285011;6071.696415179224;3097.845107744604;3159.699828516287;3217.889828009333;3911.6088391161225;3935.763854168759;3339.282054358325;3509.5613048368955;3862.3349888577354;3995.32079546472;4017.2568074906235;4051.554329023805;4504.8466073748505;4632.559906808848;4641.837535686234;4660.040874045006;5035.899282014339;5117.257997590403;5539.731558380575;5803.82588522345;5827.562932780059;1167.7839599673919;1281.6923938254752;1354.7019655675736;1362.6457121148574;1373.9381727822272;787.4920676163957;822.9711960081369;870.6996086350199;881.4711173295971;920.6409500294891;4226.661867050629;4540.909181816345;4925.482824303396;5333.919991200135;5375.650582712111;5672.290963629546;5832.512709259112;5896.909071821727;5929.466348028698;5936.495397891142;4705.035467729107;4722.574968252805;5338.46615338463;5359.008583412081;5377.27406800077;2297.6208925527103;2385.594448943684;2398.8440751351104;2631.8026148038975;2657.3888005758786;4646.304099003839;5070.594352451795;5180.715060671624;5207.1668533034535;5207.895842083137;4294.549072347421;4532.342810292489;4882.216282381183;5643.5767317451355;6160.907586386208;5472.699001554953;5641.787015791624;5971.789888128858;6231.937680623317;6252.218472690914;5759.971200143972;5873.3832947539895;6067.484263837447;6115.877682446415;6128.356522565066;4917.426423544574;5154.1237989542615;5605.719714014364;5920.970395147997;5999;4911.582515486224;6087.66517841523;6197.380261973947;6291.1192433058495;6293.96853015732;3135.6707545708286;3441.6577534760963;3531.9691177573513;3714.257148570243;3944.467496887998;5413.945860541344;5654.321481422256;5888.941110588839;5937.673427961331;5940.435658612425;5013.874653133701;5813.215215945793;5825.592208545361;5835.94164058354;5889.116632505158;4387.868363949086;5624.228368064047;5779.971100144472;5841.795537156179;5873.94126058751;5435.891282174255;5701.806988086685;5724.570657200643;5744.454276843693;5812.418758124323;5943.58394912352;5943.821685349273;5944.375840536682;5948.375420580776;5957.3446920837805;4708.858734237979;5478.835634930959;5817.709114544171;5820.359760426265;5842.561807864342 07:50:34 INFO - PROCESS | 1760 | |5;yui.html;667.6728403082491;738.1843063414926;785.1363500149835;811.9756407307783;838.4047326398257;12981.870181298184;15239.238038098092;16247.431339903105;16249.51251462455;16260.674786504263;14706.852931470683;14820.6294842629;14842.628934276649;14843.777343339858;14884.851151488481;833.0836080311655;870.4994628088842;871.7602659268689;877.3463769491738;878.9296856251484;866.8136350684601;875.829213303406;878.2271600991119;878.4816957994782;879.3492815316652;879.2570278114982;891.3983061433506;917.2845180759018;919.8528235482324;924.1774820409807;917.0966597901096;917.7338571814166;918.2149262380635;918.2883265469248;918.76112210825;673.0375562944987;676.4689718570947;677.2381071294793;677.806825054857;678.067656971664;15119.54641360761;15403.3838646454;15441.613959650986;15442.45951391696;15538.61153471161;12344.81482777759;13271.402786874614;13903.374348154357;14150.820981184828;14284.928575357057;11657.242279251821;11766.764664706658;11791.23356981802;11908.702282442922;12131.757364852654;3737.4331177824592;3817.522809648794;3819.7135214858995;3824.4836947012077;3954.584768599313;114237;124128;124847.71333807423;127147;128169;10629.362238265574;10668.839967400496;10834.620788272372;10856.78286434267;11113.666590002313;20055.09752061161;22245.221417250643;22328.776712232993;22342.55314893693;22379.888100559394;7926.8810967835525;7966.442349035628;7981;8238.011438627404;8258.215469530385;4438.889027774331;4448.317068313098;4460;4472.373867658531;4535.886602834956;3589.587197472324;3684.5394325709285;3688.170161713647;3706.777593344408;3709.295233905549;668.2582333609689;675.5575098310615;675.898615207713;682.0860047536326;688.9552179108442;648.115322584678;652.9063818104751;655.4330504113907;665.2615596687673;666.8799616069057;9327.533623318806;9543;9551.379160354554;9630.855537166948;9833.4099954003;631.2992578238152;633.2223374638364;664.6178447392768;666.6033709942576;666.8399584099781;3614.8192590370377;3777.8299976501125;4160.708750387505;4363.323684828857;4555.498895121534;155228.44771552365;163419;170160.19359225492;170384.1480792588;174968;144004;146154.26922865317;147282.527174729;148780;150242.49757502505;120231;122563;125529;126180;127215.99999999815;8177.591120444073;8740.519271439878;8812.383133180872;8872.86690699627;9005.414648047856;45438.18427235941;48356.03287934292;49898;58939;66063.67872642617;88360.55819721117;89083;93034.53482732543;93591.1281774347;94948.52525737327;10677.9466102669;12933.805992909925;13101.541446049343;13194.538191163263;13313.600591982256;14880.85119148838;14971.251437428304;15308.540743777705;15592.688146236786;15660.92169539145;13912.304384780922;13944.372503237175;14066.507672231422;14266.928665356607;14548.418063277773;13428.530001449903;13641.249731264876;13651;13735.175889446666;14175.432982680577;13617.79573306421;13860.861391385957;14159.22124283133;14229.715405692035;14385.496507622183;10210.489475526343;11325.71685707864;11327.812181352368;11427.942860285646;11677.591284305008;9512.762180945532;9544.618215271588;10588;10860.456977151269;11689.473973671375 07:50:34 INFO - PROCESS | 1760 | __end_tp_report 07:50:34 INFO - PROCESS | 1760 | __start_cc_report 07:50:34 INFO - PROCESS | 1760 | _x_x_mozilla_cycle_collect,390 07:50:34 INFO - PROCESS | 1760 | __end_cc_report 07:50:34 INFO - PROCESS | 1760 | __startTimestamp1473000634624__endTimestamp 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | ------- Summary: start ------- 07:50:34 INFO - PROCESS | 1760 | Number of tests: 6 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#0] dojo.html Cycles:165 Average:5756.88 Median:5973.00 stddev:676.20 (11.3%) stddev-sans-first:652.42 07:50:34 INFO - PROCESS | 1760 | Values: 3396.0 3407.6 3421.0 3425.3 3427.5 5825.4 5827.7 5830.2 5840.0 5849.4 5474.4 5612.2 5842.1 5863.2 5927.5 5185.6 5251.9 5328.5 5348.1 5367.9 5268.4 5425.8 5437.2 5454.8 5461.0 5184.4 5195.4 5284.2 5328.8 5339.8 5261.6 5273.8 5279.4 5299.3 5333.5 5221.5 5231.9 5244.6 5290.8 5290.9 5378.6 5714.0 5768.3 5826.7 5852.9 5233.1 5767.5 5788.9 6269.2 6296.5 5867.1 6284.7 6303.5 6322.0 6329.7 5742.4 5832.7 5899.5 5903.4 5907.5 6381.4 6391.1 6397.1 6403.0 6404.1 6212.0 6235.1 6236.1 6255.3 6270.1 5166.9 6141.9 6247.1 6252.8 6293.6 6136.3 6138.7 6151.7 6201.6 6259.7 6181.3 6230.4 6262.8 6266.4 6302.4 4355.9 4897.5 4979.3 4986.0 5013.7 3412.9 4311.6 4421.1 4433.5 4529.8 5287.8 5908.4 5918.0 5943.3 5949.8 4738.9 6152.7 6224.3 6243.5 6298.9 5865.1 5973.0 5989.3 6041.6 6058.0 5474.6 5486.9 5496.2 5505.5 5516.3 6214.7 6305.9 6440.1 6465.5 6502.4 6192.6 6272.1 6281.3 6291.2 6302.9 6341.4 6383.1 6411.8 6420.3 6433.0 6099.2 6174.8 6239.6 6244.2 6246.8 6219.8 6286.6 6293.3 6299.9 6337.9 4588.4 6210.8 6211.1 6227.5 6230.3 5308.2 6024.7 6193.2 6197.9 6206.4 4939.5 5881.0 6010.5 6065.1 6139.9 6132.7 6193.3 6200.4 6207.6 6208.8 5890.5 5999.3 6039.3 6083.7 6114.9 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#1] ext.html Cycles:180 Average:71473.07 Median:40326.79 stddev:159900.82 (396.5%) stddev-sans-first:160260.53 07:50:34 INFO - PROCESS | 1760 | Values: 475.7 475.8 488.3 524.3 536.8 37921.6 40317.2 40323.8 40367.0 40392.8 40907.0 41321.4 41474.0 41502.0 41611.4 9522.8 9728.0 9740.3 9750.9 9779.0 9608.4 9699.0 9708.8 9718.9 9724.9 9667.4 9697.2 9699.0 9724.9 9749.6 9563.9 9661.9 9674.8 9699.0 9700.4 9665.8 9684.7 9738.9 9745.2 9747.5 39583.0 39611.8 39684.0 39800.2 39824.6 3430.4 3434.3 3438.7 3865.1 4026.6 23430.9 24314.6 26758.9 27402.3 30635.8 3224.3 3247.3 3247.8 3255.0 3349.9 450108.0 505734.0 512011.0 515391.0 518951.0 50510.7 60441.0 61021.7 61150.8 61215.4 33685.5 35638.3 53732.5 64543.7 64566.7 16215.8 16448.8 16569.6 16606.3 16676.5 12732.4 12764.0 12875.4 12898.4 12901.0 18709.9 18759.0 18865.0 18907.3 18942.7 6319.6 8269.4 8277.0 8285.5 8355.6 830.2 878.1 885.7 897.1 950.4 21146.9 29723.3 30233.4 30448.5 30462.5 960.7 1035.6 1075.7 1083.1 1107.0 14989.9 15326.2 15643.1 15667.6 16032.8 690540.0 693839.1 694049.5 694941.0 696003.0 56792.0 56799.4 57252.4 57298.4 57569.1 1300.7 1334.3 1361.7 1363.2 1369.9 26590.7 29692.3 29739.8 30296.7 30327.7 48477.0 48983.0 49216.8 49331.8 49378.0 504621.0 595300.0 598150.0 598292.0 599358.0 26149.9 26327.3 26568.0 26615.7 26615.7 45765.8 46278.8 46427.0 47141.5 47229.3 38857.8 39118.4 39147.8 40372.2 40408.4 38307.0 38755.0 38805.6 39627.6 40329.8 37059.6 37438.0 37872.0 37987.8 38053.4 28977.7 33908.8 34329.0 34536.0 34605.5 24972.6 25260.7 25286.2 25617.7 25766.8 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#2] jquery.html Cycles:180 Average:33157.34 Median:224158.62 stddev:92621.10 (41.3%) stddev-sans-first:92848.21 07:50:34 INFO - PROCESS | 1760 | Values: 373.1 373.1 379.1 382.6 398.6 18762.2 20270.4 20533.6 20808.0 20814.7 20463.0 20575.0 20590.9 20879.9 20960.3 5758.7 6018.6 6050.7 6131.7 6142.5 3859.2 5865.8 6091.8 6119.2 6126.2 6115.9 6146.1 6149.1 6158.1 6171.1 5896.8 6109.4 6126.5 6136.3 6157.2 5719.4 5872.9 5900.9 5910.0 5911.9 12819.0 13065.6 13121.7 13142.8 13204.6 2111.9 2119.6 2144.4 2216.5 2532.4 9097.4 9536.8 10265.6 10303.5 10339.6 1780.7 1809.4 1902.5 1911.7 1944.1 90896.1 116954.0 120759.4 124453.4 124619.4 10156.5 10527.9 10932.0 11206.5 11250.9 14020.2 14022.6 14032.2 14042.9 14056.7 7885.7 7915.0 8035.8 8144.6 8234.8 7814.6 7880.3 7928.5 7999.6 8057.3 3655.7 4433.6 4436.0 4455.2 4457.4 1395.2 1404.5 1880.2 1936.8 1990.7 830.8 886.4 981.8 986.6 989.2 16641.8 17030.3 19837.7 20268.8 20980.9 933.2 953.0 953.8 993.0 997.2 10787.9 11065.8 11096.3 11120.0 11145.5 443862.0 545231.0 573921.0 586849.0 587988.0 130062.0 131615.0 133104.0 133531.0 137628.3 941.5 955.6 975.3 976.7 978.6 18598.9 18679.9 18689.5 18883.9 19782.4 54182.5 55323.0 55438.7 55498.7 55560.4 78678.0 79181.0 79825.6 79845.6 81508.6 7075.0 7166.5 7272.0 7411.9 7568.0 8832.6 12308.9 12651.1 12913.2 13149.5 5655.5 7415.5 7860.3 7944.7 8029.2 7590.6 8031.4 8078.2 8105.8 8205.6 4832.7 4852.5 7023.7 8048.0 8533.7 11589.4 12501.6 12569.6 12844.5 13011.7 11084.9 11781.5 11968.6 12033.0 12151.8 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#3] mootools.html Cycles:180 Average:5691.70 Median:4935.23 stddev:3162.40 (64.1%) stddev-sans-first:3154.30 07:50:34 INFO - PROCESS | 1760 | Values: 1332.3 1383.8 1677.4 1882.0 1892.1 7936.7 8412.2 8545.4 8962.6 8980.4 8548.0 8768.6 8771.6 8776.0 8785.9 2363.8 2376.8 2392.6 2395.9 2397.6 2350.7 2360.1 2373.7 2387.3 2394.1 2373.2 2374.1 2385.5 2397.8 2399.1 2347.3 2356.3 2368.3 2370.4 2378.9 2346.4 2346.5 2356.2 2359.5 2367.3 8790.3 8833.0 8851.0 8873.0 8953.6 4284.6 5269.4 5281.3 5356.1 5477.8 3572.4 3617.1 3644.1 3668.8 3673.0 1078.8 1095.5 1096.1 1107.6 1114.6 11448.9 11469.5 11546.7 11562.8 11583.2 4538.5 4604.1 4790.6 4806.0 4809.9 9737.1 9815.2 9828.6 9858.5 9878.0 5357.3 5366.7 5377.4 5380.8 5386.6 4405.8 4775.7 4923.4 4925.9 4944.6 3863.8 3936.5 3996.3 4035.8 4050.8 1309.6 1318.7 1320.3 1328.5 1332.9 882.8 920.7 934.2 947.7 965.1 4873.4 5676.1 5721.3 5976.9 6054.4 423.9 441.3 443.4 933.7 1012.3 3996.2 4013.1 4024.6 4050.0 4291.0 8915.6 10390.3 10541.3 10683.3 10776.7 10665.1 10668.8 10670.0 10691.6 10748.4 7686.7 7994.6 8104.3 8144.6 8235.6 5746.7 5923.3 6008.4 6075.1 6099.2 8220.9 9961.5 9966.7 10054.8 10054.9 9940.0 10049.6 10242.4 10264.0 10296.7 6896.6 7281.7 7419.5 7475.9 7574.8 8330.5 8404.7 8421.2 8425.2 8450.0 7343.3 7469.0 7493.9 7581.2 7592.3 7325.0 7352.6 7376.7 7508.4 7639.4 6861.3 6950.4 6977.0 6978.9 6980.3 4908.6 4991.5 5068.8 5082.2 5089.2 5002.5 5081.4 5134.0 5200.7 5205.9 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#4] prototype.html Cycles:180 Average:4562.56 Median:5093.93 stddev:1476.67 (29.0%) stddev-sans-first:1445.25 07:50:34 INFO - PROCESS | 1760 | Values: 270.9 271.1 283.8 305.5 312.8 4800.5 4990.9 5238.6 5269.9 5501.5 4737.6 5445.0 5451.7 5484.6 5495.6 4096.5 4153.4 4157.6 4164.8 4165.6 3586.6 3844.4 4152.4 4186.8 4196.5 4081.6 4092.7 4094.3 4110.8 4111.5 3704.9 3805.7 4113.4 4146.5 4149.5 3572.5 3579.4 3582.7 3591.2 3591.3 4933.8 5271.4 5337.9 5344.7 5382.2 3889.2 4120.5 4683.5 4710.0 4715.0 5755.4 5779.4 5788.6 5788.7 5796.4 3348.6 3402.4 3603.0 3858.5 3862.6 4984.0 5740.8 5951.7 5984.6 6071.7 3097.8 3159.7 3217.9 3911.6 3935.8 3339.3 3509.6 3862.3 3995.3 4017.3 4051.6 4504.8 4632.6 4641.8 4660.0 5035.9 5117.3 5539.7 5803.8 5827.6 1167.8 1281.7 1354.7 1362.6 1373.9 787.5 823.0 870.7 881.5 920.6 4226.7 4540.9 4925.5 5333.9 5375.7 5672.3 5832.5 5896.9 5929.5 5936.5 4705.0 4722.6 5338.5 5359.0 5377.3 2297.6 2385.6 2398.8 2631.8 2657.4 4646.3 5070.6 5180.7 5207.2 5207.9 4294.5 4532.3 4882.2 5643.6 6160.9 5472.7 5641.8 5971.8 6231.9 6252.2 5760.0 5873.4 6067.5 6115.9 6128.4 4917.4 5154.1 5605.7 5921.0 5999.0 4911.6 6087.7 6197.4 6291.1 6294.0 3135.7 3441.7 3532.0 3714.3 3944.5 5413.9 5654.3 5888.9 5937.7 5940.4 5013.9 5813.2 5825.6 5835.9 5889.1 4387.9 5624.2 5780.0 5841.8 5873.9 5435.9 5701.8 5724.6 5744.5 5812.4 5943.6 5943.8 5944.4 5948.4 5957.3 4708.9 5478.8 5817.7 5820.4 5842.6 07:50:34 INFO - PROCESS | 1760 | 07:50:34 INFO - PROCESS | 1760 | [#5] yui.html Cycles:180 Average:26455.11 Median:3686.35 stddev:44123.28 (1196.9%) stddev-sans-first:44204.58 07:50:34 INFO - PROCESS | 1760 | Values: 667.7 738.2 785.1 812.0 838.4 12981.9 15239.2 16247.4 16249.5 16260.7 14706.9 14820.6 14842.6 14843.8 14884.9 833.1 870.5 871.8 877.3 878.9 866.8 875.8 878.2 878.5 879.3 879.3 891.4 917.3 919.9 924.2 917.1 917.7 918.2 918.3 918.8 673.0 676.5 677.2 677.8 678.1 15119.5 15403.4 15441.6 15442.5 15538.6 12344.8 13271.4 13903.4 14150.8 14284.9 11657.2 11766.8 11791.2 11908.7 12131.8 3737.4 3817.5 3819.7 3824.5 3954.6 114237.0 124128.0 124847.7 127147.0 128169.0 10629.4 10668.8 10834.6 10856.8 11113.7 20055.1 22245.2 22328.8 22342.6 22379.9 7926.9 7966.4 7981.0 8238.0 8258.2 4438.9 4448.3 4460.0 4472.4 4535.9 3589.6 3684.5 3688.2 3706.8 3709.3 668.3 675.6 675.9 682.1 689.0 648.1 652.9 655.4 665.3 666.9 9327.5 9543.0 9551.4 9630.9 9833.4 631.3 633.2 664.6 666.6 666.8 3614.8 3777.8 4160.7 4363.3 4555.5 155228.4 163419.0 170160.2 170384.1 174968.0 144004.0 146154.3 147282.5 148780.0 150242.5 120231.0 122563.0 125529.0 126180.0 127216.0 8177.6 8740.5 8812.4 8872.9 9005.4 45438.2 48356.0 49898.0 58939.0 66063.7 88360.6 89083.0 93034.5 93591.1 94948.5 10677.9 12933.8 13101.5 13194.5 13313.6 14880.9 14971.3 15308.5 15592.7 15660.9 13912.3 13944.4 14066.5 14266.9 14548.4 13428.5 13641.2 13651.0 13735.2 14175.4 13617.8 13860.9 14159.2 14229.7 14385.5 10210.5 11325.7 11327.8 11427.9 11677.6 9512.8 9544.6 10588.0 10860.5 11689.5 07:50:34 INFO - PROCESS | 1760 | -------- Summary: end -------- 07:50:34 INFO - PROCESS | 1760 | 07:50:35 INFO - TEST-INFO | 1760: exit 0 07:50:35 INFO - TEST-OK | dromaeo_css | took 1113497ms 07:50:35 INFO - TEST-START | kraken 07:50:35 INFO - operating with platform_type : mac_ 07:50:35 INFO - Initialising browser for kraken test... 07:50:35 INFO - TEST-INFO | started process 1767 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmphGKi1x/profile http://localhost:49271/getInfo.html) 07:50:37 INFO - PROCESS | 1767 | __metrics Screen width/height:1600/1200 07:50:37 INFO - PROCESS | 1767 | colorDepth:24 07:50:37 INFO - PROCESS | 1767 | Browser inner width/height: 1024/689 07:50:37 INFO - PROCESS | 1767 | __metrics 07:50:38 ERROR - PROCESS | 1767 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 07:50:38 INFO - TEST-INFO | 1767: exit 0 07:50:38 INFO - Browser initialized. 07:50:38 INFO - Running cycle 1/1 for kraken test... 07:50:38 INFO - TEST-INFO | started process 1769 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmphGKi1x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:50 INFO - PROCESS | 1769 | RSS: Main: 244027392 07:50:50 INFO - PROCESS | 1769 | 07:50:53 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:54 INFO - PROCESS | 1769 | RSS: Main: 256663552 07:50:54 INFO - PROCESS | 1769 | 07:50:57 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 07:50:58 INFO - PROCESS | 1769 | RSS: Main: 265306112 07:50:58 INFO - PROCESS | 1769 | 07:51:01 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 07:51:02 INFO - PROCESS | 1769 | RSS: Main: 263946240 07:51:02 INFO - PROCESS | 1769 | 07:51:04 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 07:51:05 INFO - PROCESS | 1769 | RSS: Main: 266264576 07:51:05 INFO - PROCESS | 1769 | 07:51:07 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:51:07 INFO - PROCESS | 1769 | RSS: Main: 266952704 07:51:07 INFO - PROCESS | 1769 | 07:51:10 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 07:51:11 INFO - PROCESS | 1769 | RSS: Main: 266993664 07:51:11 INFO - PROCESS | 1769 | 07:51:14 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 07:51:15 INFO - PROCESS | 1769 | RSS: Main: 267276288 07:51:15 INFO - PROCESS | 1769 | 07:51:17 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 07:51:18 INFO - PROCESS | 1769 | RSS: Main: 267325440 07:51:18 INFO - PROCESS | 1769 | 07:51:19 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:51:20 INFO - PROCESS | 1769 | RSS: Main: 267087872 07:51:20 INFO - PROCESS | 1769 | 07:51:22 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:51:23 INFO - PROCESS | 1769 | RSS: Main: 267255808 07:51:23 INFO - PROCESS | 1769 | 07:51:25 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:51:26 INFO - PROCESS | 1769 | RSS: Main: 267579392 07:51:26 INFO - PROCESS | 1769 | 07:51:29 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:29 INFO - PROCESS | 1769 | RSS: Main: 267685888 07:51:29 INFO - PROCESS | 1769 | 07:51:32 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:33 INFO - PROCESS | 1769 | RSS: Main: 267718656 07:51:33 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 07:51:35 INFO - PROCESS | 1769 | RSS: Main: 269811712 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | __start_tp_report 07:51:35 INFO - PROCESS | 1769 | _x_x_mozilla_page_load 07:51:35 INFO - PROCESS | 1769 | _x_x_mozilla_page_load_details 07:51:35 INFO - PROCESS | 1769 | |i|pagename|runs| 07:51:35 INFO - PROCESS | 1769 | |0;ai-astar;108;117;121;109;172;176;147;118;119;135 07:51:35 INFO - PROCESS | 1769 | |1;audio-beat-detection;153;131;170;149;141;164;162;114;158;113 07:51:35 INFO - PROCESS | 1769 | |2;audio-dft;205;259;277;223;199;281;188;179;279;271 07:51:35 INFO - PROCESS | 1769 | |3;audio-fft;74;81;72;149;61;60;59;60;135;60 07:51:35 INFO - PROCESS | 1769 | |4;audio-oscillator;75;98;107;95;59;96;143;104;105;116 07:51:35 INFO - PROCESS | 1769 | |5;imaging-gaussian-blur;93;88;82;116;131;87;91;90;80;90 07:51:35 INFO - PROCESS | 1769 | |6;imaging-darkroom;97;97;95;97;97;96;96;102;152;96 07:51:35 INFO - PROCESS | 1769 | |7;imaging-desaturate;92;103;94;91;94;91;91;92;91;90 07:51:35 INFO - PROCESS | 1769 | |8;json-parse-financial;57;59;57;58;56;58;58;57;57;55 07:51:35 INFO - PROCESS | 1769 | |9;json-stringify-tinderbox;68;69;74;72;78;103;99;74;72;69 07:51:35 INFO - PROCESS | 1769 | |10;stanford-crypto-aes;89;87;85;77;98;119;113;112;108;88 07:51:35 INFO - PROCESS | 1769 | |11;stanford-crypto-ccm;151;160;161;144;138;144;128;168;200;143 07:51:35 INFO - PROCESS | 1769 | |12;stanford-crypto-pbkdf2;173;175;178;232;189;172;177;172;170;173 07:51:35 INFO - PROCESS | 1769 | |13;stanford-crypto-sha256-iterative;70;71;74;82;92;93;92;94;75;69 07:51:35 INFO - PROCESS | 1769 | __end_tp_report 07:51:35 INFO - PROCESS | 1769 | __start_cc_report 07:51:35 INFO - PROCESS | 1769 | _x_x_mozilla_cycle_collect,973 07:51:35 INFO - PROCESS | 1769 | __end_cc_report 07:51:35 INFO - PROCESS | 1769 | __startTimestamp1473000695746__endTimestamp 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | ------- Summary: start ------- 07:51:35 INFO - PROCESS | 1769 | Number of tests: 14 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#0] ai-astar Cycles:10 Average:132.20 Median:128.00 stddev:24.91 (19.5%) stddev-sans-first:24.84 07:51:35 INFO - PROCESS | 1769 | Values: 108.0 117.0 121.0 109.0 172.0 176.0 147.0 118.0 119.0 135.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#1] audio-beat-detection Cycles:10 Average:145.50 Median:155.50 stddev:20.33 (13.1%) stddev-sans-first:21.38 07:51:35 INFO - PROCESS | 1769 | Values: 153.0 131.0 170.0 149.0 141.0 164.0 162.0 114.0 158.0 113.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#2] audio-dft Cycles:10 Average:236.10 Median:265.00 stddev:41.31 (15.6%) stddev-sans-first:42.26 07:51:35 INFO - PROCESS | 1769 | Values: 205.0 259.0 277.0 223.0 199.0 281.0 188.0 179.0 279.0 271.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#3] audio-fft Cycles:10 Average:81.10 Median:60.50 stddev:33.13 (54.8%) stddev-sans-first:35.04 07:51:35 INFO - PROCESS | 1769 | Values: 74.0 81.0 72.0 149.0 61.0 60.0 59.0 60.0 135.0 60.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:67.00 stddev:22.47 (33.5%) stddev-sans-first:21.97 07:51:35 INFO - PROCESS | 1769 | Values: 75.0 98.0 107.0 95.0 59.0 96.0 143.0 104.0 105.0 116.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:89.00 stddev:16.03 (18.0%) stddev-sans-first:16.99 07:51:35 INFO - PROCESS | 1769 | Values: 93.0 88.0 82.0 116.0 131.0 87.0 91.0 90.0 80.0 90.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:96.50 stddev:17.49 (18.1%) stddev-sans-first:18.44 07:51:35 INFO - PROCESS | 1769 | Values: 97.0 97.0 95.0 97.0 97.0 96.0 96.0 102.0 152.0 96.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:91.50 stddev:3.78 (4.1%) stddev-sans-first:4.00 07:51:35 INFO - PROCESS | 1769 | Values: 92.0 103.0 94.0 91.0 94.0 91.0 91.0 92.0 91.0 90.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.50 stddev:1.14 (2.0%) stddev-sans-first:1.20 07:51:35 INFO - PROCESS | 1769 | Values: 57.0 59.0 57.0 58.0 56.0 58.0 58.0 57.0 57.0 55.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#9] json-stringify-tinderbox Cycles:10 Average:77.80 Median:73.00 stddev:12.61 (17.3%) stddev-sans-first:12.87 07:51:35 INFO - PROCESS | 1769 | Values: 68.0 69.0 74.0 72.0 78.0 103.0 99.0 74.0 72.0 69.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#10] stanford-crypto-aes Cycles:10 Average:97.60 Median:86.00 stddev:14.42 (16.8%) stddev-sans-first:14.96 07:51:35 INFO - PROCESS | 1769 | Values: 89.0 87.0 85.0 77.0 98.0 119.0 113.0 112.0 108.0 88.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:155.50 stddev:20.16 (13.0%) stddev-sans-first:21.36 07:51:35 INFO - PROCESS | 1769 | Values: 151.0 160.0 161.0 144.0 138.0 144.0 128.0 168.0 200.0 143.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:176.00 stddev:18.67 (10.6%) stddev-sans-first:19.57 07:51:35 INFO - PROCESS | 1769 | Values: 173.0 175.0 178.0 232.0 189.0 172.0 177.0 172.0 170.0 173.0 07:51:35 INFO - PROCESS | 1769 | 07:51:35 INFO - PROCESS | 1769 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.20 Median:87.00 stddev:10.57 (12.1%) stddev-sans-first:10.41 07:51:35 INFO - PROCESS | 1769 | Values: 70.0 71.0 74.0 82.0 92.0 93.0 92.0 94.0 75.0 69.0 07:51:35 INFO - PROCESS | 1769 | -------- Summary: end -------- 07:51:35 INFO - PROCESS | 1769 | 07:51:36 INFO - TEST-INFO | 1769: exit 0 07:51:36 INFO - TEST-OK | kraken | took 61307ms 07:51:36 INFO - SUITE-END | took 1174s 07:51:36 INFO - Completed test suite (00:19:35) 07:51:36 INFO - javascript benchmark 07:51:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7304.590010423407, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.983020084899, 3407.5740532433447, 3421.0, 3425.349183655105, 3427.537282466866, 5825.388334224904, 5827.70861456927, 5830.212921255629, 5840.036393994997, 5849.444302791233, 5474.37044739855, 5612.158176273561, 5842.094475356316, 5863.237779088723, 5927.466528012478, 5185.637005409613, 5251.921221181686, 5328.46715328468, 5348.117560602496, 5367.919481207785, 5268.446813084628, 5425.810096646618, 5437.238786569884, 5454.781808727646, 5461.0, 5184.351956005499, 5195.428502864682, 5284.207368894658, 5328.813491527817, 5339.813106541272, 5261.631685781997, 5273.841784746463, 5279.44565820591, 5299.284596579451, 5333.49331813481, 5221.477852214786, 5231.921521177185, 5244.609043665258, 5290.788368465257, 5290.894182116374, 5378.596605254583, 5714.000049991277, 5768.278965129359, 5826.7377967991115, 5852.882942341172, 5233.084210263189, 5767.480926716574, 5788.942110578882, 6269.216347956505, 6296.464800491964, 5867.090600956859, 6284.685765711742, 6303.464205542488, 6321.968390158066, 6329.651869147195, 5742.425757424307, 5832.737526811304, 5899.528037756969, 5903.439173278532, 5907.4683278505145, 6381.3937675920715, 6391.137196478416, 6397.072424498421, 6403.0, 6404.135441715402, 6212.0, 6235.127082208494, 6236.064590311399, 6255.280642726145, 6270.090836828635, 5166.948330516722, 6141.884937092159, 6247.12540244357, 6252.812415627538, 6293.590916590407, 6136.2636483622255, 6138.694953005908, 6151.692415379213, 6201.565890387629, 6259.655718935503, 6181.258249010058, 6230.439260466489, 6262.812115636538, 6266.373362663697, 6302.432781049728, 4355.932796464886, 4897.510248975074, 4979.278004689299, 4985.975070124626, 5013.674111182761, 3412.897613071612, 4311.590398912098, 4421.137878113737, 4433.490148632884, 4529.773511324421, 5287.7884884604955, 5908.438698323738, 5917.970410147922, 5943.286805583358, 5949.791757288474, 4738.881527961828, 6152.692365381713, 6224.346443623354, 6243.500519958392, 6298.874022519524, 5865.149553314745, 5973.0, 5989.251343582052, 6041.607295525777, 6058.0, 5474.616776825584, 5486.890262194734, 5496.230527726121, 5505.532029777433, 5516.282883225155, 6214.689265536615, 6305.90541141874, 6440.098386225932, 6465.482761379172, 6502.447291980235, 6192.628442293477, 6272.121902933502, 6281.309056003927, 6291.182146320949, 6302.936970630235, 6341.397567231105, 6383.106365108795, 6411.807645770635, 6420.325865784025, 6432.967835160982, 6099.176611157437, 6174.845628859314, 6239.594426362272, 6244.219472565929, 6246.843828904313, 6219.844503887439, 6286.622802631857, 6293.307736149111, 6299.929012068051, 6337.873242535031, 4588.403507543998, 6210.7515699373325, 6211.068339748893, 6227.501799856092, 6230.283517395376, 5308.177232528964, 6024.7288872001745, 6193.225846769154, 6197.876042479035, 6206.410391012847, 4939.456659767495, 5881.0, 6010.549208809444, 6065.090236464389, 6139.9079013813935, 6132.724027418688, 6193.287771906108, 6200.441960223421, 6207.565470417208, 6208.782692605737, 5890.4993075589055, 5999.340072592098, 6039.305479869696, 6083.695815209133, 6114.929887269621], "value": 5715.486739666716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.747853637572, 475.7644965741956, 488.3114808120551, 524.3026774390062, 536.758458693588, 37921.556084201264, 40317.19365612686, 40323.79838100809, 40366.99999999996, 40392.79803600985, 40907.0, 41321.38017929733, 41474.0, 41502.0, 41611.37582936251, 9522.80954380912, 9728.0, 9740.269479789009, 9750.90249097507, 9779.0, 9608.423494590312, 9698.951505242463, 9708.757281067994, 9718.854217186748, 9724.88163861158, 9667.371620844622, 9697.175740062103, 9698.9515052425, 9724.854127188062, 9749.610015599368, 9563.856542151872, 9661.90338096617, 9674.75813104671, 9698.981606931311, 9700.417974921493, 9665.806683866283, 9684.661036863676, 9738.853917191247, 9745.220382369464, 9747.463889486075, 39583.0, 39611.82134982575, 39684.0, 39800.20399592021, 39824.60175398267, 3430.4168291390524, 3434.347473979936, 3438.7249020078334, 3865.091703449687, 4026.5570787213383, 23430.882845585664, 24314.635280470808, 26758.9158059968, 27402.26561571679, 30635.846820765753, 3224.27453822888, 3247.3018301065385, 3247.772655914087, 3254.96745032549, 3349.882754103619, 450108.0, 505734.0, 512011.0, 515391.0, 518951.0, 50510.74744626253, 60441.0, 61021.69489152526, 61150.77698446006, 61215.38784612097, 33685.494717579255, 35638.28723425517, 53732.46267537274, 64543.677281613294, 64566.67716661387, 16215.756763648555, 16448.83551164473, 16569.585760356087, 16606.25271862769, 16676.499705008868, 12732.427040783186, 12764.0, 12875.420606072748, 12898.419571119322, 12900.967922566173, 18709.906450467934, 18759.0, 18864.962427066643, 18907.338243161423, 18942.715859262124, 6319.620822750558, 8269.379796515286, 8277.04813946392, 8285.461445006054, 8355.582220888931, 830.2444775254489, 878.1174919206157, 885.7342797160826, 897.1387468030764, 950.3870003847618, 21146.89426552888, 29723.256918577208, 30233.395332093674, 30448.54327185094, 30462.54306185409, 960.6877764726491, 1035.5598870480017, 1075.7041813501194, 1083.1064371893062, 1106.9667909962714, 14989.925050374897, 15326.23368831554, 15643.139627320606, 15667.608309792118, 16032.839671603368, 690540.0, 693839.0616093775, 694049.529752348, 694941.0, 696003.0, 56792.0, 56799.43200568024, 57252.42747572554, 57298.42701573014, 57569.13646295309, 1300.7008388070797, 1334.2794890759076, 1361.7276544691101, 1363.1548439967285, 1369.945202191921, 26590.734092658826, 29692.257693557833, 29739.836422901244, 30296.697033029388, 30327.69672303337, 48477.03045939132, 48983.0, 49216.75391623019, 49331.75334123307, 49378.01243975029, 504621.0, 595300.0, 598150.0, 598292.0, 599358.0, 26149.869250653624, 26327.281369292177, 26568.0, 26615.733842661324, 26615.7338426621, 45765.77117114393, 46278.76860615675, 46427.0, 47141.52858471509, 47229.2915606273, 38857.80571097126, 39118.413223802236, 39147.84686842251, 40372.1925561493, 40408.39387409132, 38307.0, 38755.0, 38805.6119438802, 39627.60372396239, 40329.79010629686, 37059.6294037067, 37438.0, 37872.0, 37987.810060949516, 38053.42919856149, 28977.68178545669, 33908.830455847565, 34329.0, 34536.0, 34605.48091778575, 24972.625410619217, 25260.74739252584, 25286.24141275765, 25617.743822562283, 25766.84049217752], "value": 19782.79643900812, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.0803569201918, 373.08407858706863, 379.1280055871496, 382.6212050070431, 398.63126607887716, 18762.2495100196, 20270.391888243375, 20533.589328213446, 20808.0, 20814.687779683318, 20463.0, 20575.0, 20590.89704551479, 20879.851608161545, 20960.26639067633, 5758.654480731148, 6018.578699491049, 6050.727717252712, 6131.7240724167295, 6142.539309551779, 3859.2281543691233, 5865.794697185599, 6091.78678746244, 6119.1739115219325, 6126.203593532857, 6115.938840611604, 6146.108814221935, 6149.13912052313, 6158.137860699506, 6171.074338849164, 5896.823095307126, 6109.35851735568, 6126.540509461808, 6136.294326152505, 6157.230346206724, 5719.428057194247, 5872.941270587325, 5900.911486327708, 5909.970450147722, 5911.911321330141, 12819.038572107129, 13065.608031759062, 13121.671958201026, 13142.802857957138, 13204.60386188416, 2111.9366419007456, 2119.618468675638, 2144.3995681209285, 2216.523447458804, 2532.4428625702317, 9097.40866843653, 9536.761580960461, 10265.589376425009, 10303.536340864679, 10339.63811266602, 1780.7328900664875, 1809.3938530592268, 1902.5433895864892, 1911.7036859286693, 1944.1251436853472, 90896.09103909008, 116954.0, 120759.3962030193, 124453.37773311166, 124619.3769031149, 10156.542955567018, 10527.947360263302, 10932.0, 11206.495707693173, 11250.943745281385, 14020.228887411291, 14022.649433764238, 14032.158070515801, 14042.859571404155, 14056.71886562263, 7885.684572617031, 7914.960425197837, 8035.799105022421, 8144.5520496373265, 8234.752957411287, 7814.648340824677, 7880.2513761192595, 7928.484648497829, 7999.600019999093, 8057.274845263954, 3655.7440979131206, 4433.6009759121835, 4436.046250056189, 4455.242608756519, 4457.375967364572, 1395.1559306619552, 1404.5365029540228, 1880.2103116691023, 1936.7772706138974, 1990.6615875301234, 830.8255266394125, 886.4061079077034, 981.8085473332632, 986.6152200641756, 989.188865130586, 16641.750373744402, 17030.31878724862, 19837.702434463496, 20268.797312026985, 20980.895095524425, 933.2160984772823, 952.9714108576753, 953.8426159683574, 992.9772334495481, 997.2122023601273, 10787.892121078688, 11065.834012489819, 11096.33421994683, 11120.0, 11145.498452569655, 443862.0, 545231.0, 573921.0, 586849.0, 587988.0, 130062.0, 131615.0, 133104.0, 133531.0, 137628.31185844008, 941.5245301123026, 955.5604421965974, 975.2636759246724, 976.6679329028022, 978.6427953796955, 18598.907005464887, 18679.906600467453, 18689.53276168107, 18883.90558047201, 19782.364274852724, 54182.45817541774, 55323.0, 55438.72280638571, 55498.72250638721, 55560.444395555525, 78678.0, 79181.0, 79825.60087199527, 79845.60077199577, 81508.59245703733, 7075.0, 7166.53417527859, 7271.963640181977, 7411.887612030546, 7567.962160189164, 8832.602532886163, 12308.876911230773, 12651.114421990373, 12913.16064455845, 13149.539766108523, 5655.519280861173, 7415.480916335805, 7860.292573668397, 7944.6822127114265, 8029.197080292158, 7590.620468976419, 8031.35749140079, 8078.151794061774, 8105.8378832424205, 8205.589720513832, 4832.710037397767, 4852.490488498657, 7023.70061538617, 8047.959760201162, 8533.658653653783, 11589.420528973349, 12501.56244531437, 12569.62291131231, 12844.486220551073, 13011.674708132372, 11084.944575277072, 11781.527309086365, 11968.581099661471, 12033.037357011593, 12151.75696486083], "value": 9070.526944601355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.3404914567288, 1383.7509248335302, 1677.3793696332352, 1881.9623607527842, 1892.1296203746267, 7936.6825326986855, 8412.242898139166, 8545.4445461045, 8962.641494340234, 8980.371374003815, 8548.0, 8768.649254029831, 8771.56142192891, 8776.0, 8785.868211976825, 2363.8345315827855, 2376.8455050421753, 2392.64110383443, 2395.8682272475094, 2397.5564520563753, 2350.6944097267333, 2360.114956891166, 2373.667686523888, 2387.259949415678, 2394.1261439574605, 2373.2405630198346, 2374.0741110966737, 2385.4751954569965, 2397.7602239776056, 2399.1483023526657, 2347.260612906929, 2356.257778799681, 2368.348704106376, 2370.395549134968, 2378.9048438062455, 2346.3547524430746, 2346.498241125681, 2356.2460012795914, 2359.4691194481275, 2367.3134790910617, 8790.296776257885, 8833.0, 8850.982137054258, 8872.955635221848, 8953.552322383919, 4284.550122237151, 5269.367675878919, 5281.339832520935, 5356.11624082026, 5477.808276710296, 3572.4284114541547, 3617.113807117247, 3644.1071937375245, 3668.816559172031, 3672.981635091834, 1078.8219943709278, 1095.4632230207221, 1096.1395304685864, 1107.617871834216, 1114.587602587048, 11448.94275528617, 11469.483873225725, 11546.653600392086, 11562.768744625144, 11583.18917675763, 4538.500764915857, 4604.1252162089095, 4790.610722890323, 4806.014766972762, 4809.927851082237, 9737.123658870736, 9815.214782817358, 9828.557714902703, 9858.457784821903, 9877.950610247046, 5357.276767636397, 5366.704831234241, 5377.3816011159215, 5380.78476860929, 5386.649867758583, 4405.823767049282, 4775.689580177277, 4923.433805112458, 4925.876853078702, 4944.579710724557, 3863.8068096595057, 3936.527616685959, 3996.300647386696, 4035.7780322082567, 4050.797460126982, 1309.646395473236, 1318.6901078246603, 1320.3398300849576, 1328.4619729009573, 1332.9400176991865, 882.8214333864235, 920.6961702638114, 934.168589954927, 947.6740717169414, 965.0542468380886, 4873.390826146731, 5676.120201368794, 5721.284839395075, 5976.910346344808, 6054.424829641177, 423.89826441654395, 441.30274166816355, 443.4368352192762, 933.7012156109979, 1012.2964539644876, 3996.18078293949, 4013.1171142348635, 4024.5975402460103, 4049.9595004049584, 4291.034517233597, 8915.643374264957, 10390.324628899098, 10541.31481453703, 10683.30558513694, 10776.730581735519, 10665.146788256921, 10668.786624267626, 10670.0, 10691.572337106583, 10748.355098694103, 7686.730964416219, 7994.64024118916, 8104.311133553596, 8144.592770361577, 8235.629396677165, 5746.655200687972, 5923.259592550931, 6008.429199226067, 6075.149479072845, 6099.237595300588, 8220.876686849582, 9961.50192490387, 9966.651167209113, 10054.798904022025, 10054.899451005396, 9939.950300248742, 10049.598016079275, 10242.385456872313, 10264.0, 10296.742581435525, 6896.586204827727, 7281.672324745505, 7419.4806363554035, 7475.850482990201, 7574.772756817304, 8330.458520196167, 8404.705835295736, 8421.19998600132, 8425.157484251526, 8449.986001679837, 7343.265673432614, 7468.9626551866895, 7493.9250607495405, 7581.241875812595, 7592.278733520307, 7325.047743793273, 7352.632368381666, 7376.7418140364825, 7508.399328053852, 7639.388848891964, 6861.313868613099, 6950.443964482931, 6976.965115174392, 6978.895316570154, 6980.301969802979, 4908.607311415007, 4991.450940396626, 5068.847934561969, 5082.2122571001555, 5089.185730283137, 5002.549770520671, 5081.441041485507, 5133.998870220271, 5200.713960732196, 5205.869853253547], "value": 4561.860455717102, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.87856275021403, 271.11616131411597, 283.8240291019568, 305.45781238302027, 312.79980812280144, 4800.4719480857075, 4990.884343547866, 5238.633295669305, 5269.86825329367, 5501.459591314433, 4737.644676649256, 5444.972775136119, 5451.700156491402, 5484.643498172616, 5495.6153069285165, 4096.549379568245, 4153.421699754089, 4157.584241575833, 4164.791760411982, 4165.6250937415625, 3586.605473397937, 3844.384898416254, 4152.418661387408, 4186.790660466964, 4196.454460920076, 4081.5918408159096, 4092.7135100542973, 4094.324436467979, 4110.77390743509, 4111.54772974974, 3704.907377315562, 3805.676517496016, 4113.424120623115, 4146.502419709624, 4149.502059752849, 3572.463840548563, 3579.4451859961487, 3582.6954708849516, 3591.1740299730945, 3591.317649646559, 4933.753312334369, 5271.42014378418, 5337.946620533823, 5344.6793192408195, 5382.219578161144, 3889.2221555688693, 4120.505539335299, 4683.484816670163, 4709.95290047102, 4714.986277950257, 5755.4244575542525, 5779.422057794229, 5788.594798364116, 5788.6816225107605, 5796.449337312991, 3348.631650518441, 3402.387570237356, 3603.045193023851, 3858.5176852893387, 3862.555806082308, 4983.975080124613, 5740.827775166752, 5951.6726580038085, 5984.611000285011, 6071.696415179224, 3097.845107744604, 3159.699828516287, 3217.889828009333, 3911.6088391161225, 3935.763854168759, 3339.282054358325, 3509.5613048368955, 3862.3349888577354, 3995.32079546472, 4017.2568074906235, 4051.554329023805, 4504.8466073748505, 4632.559906808848, 4641.837535686234, 4660.040874045006, 5035.899282014339, 5117.257997590403, 5539.731558380575, 5803.82588522345, 5827.562932780059, 1167.7839599673919, 1281.6923938254752, 1354.7019655675736, 1362.6457121148574, 1373.9381727822272, 787.4920676163957, 822.9711960081369, 870.6996086350199, 881.4711173295971, 920.6409500294891, 4226.661867050629, 4540.909181816345, 4925.482824303396, 5333.919991200135, 5375.650582712111, 5672.290963629546, 5832.512709259112, 5896.909071821727, 5929.466348028698, 5936.495397891142, 4705.035467729107, 4722.574968252805, 5338.46615338463, 5359.008583412081, 5377.27406800077, 2297.6208925527103, 2385.594448943684, 2398.8440751351104, 2631.8026148038975, 2657.3888005758786, 4646.304099003839, 5070.594352451795, 5180.715060671624, 5207.1668533034535, 5207.895842083137, 4294.549072347421, 4532.342810292489, 4882.216282381183, 5643.5767317451355, 6160.907586386208, 5472.699001554953, 5641.787015791624, 5971.789888128858, 6231.937680623317, 6252.218472690914, 5759.971200143972, 5873.3832947539895, 6067.484263837447, 6115.877682446415, 6128.356522565066, 4917.426423544574, 5154.1237989542615, 5605.719714014364, 5920.970395147997, 5999.0, 4911.582515486224, 6087.66517841523, 6197.380261973947, 6291.1192433058495, 6293.96853015732, 3135.6707545708286, 3441.6577534760963, 3531.9691177573513, 3714.257148570243, 3944.467496887998, 5413.945860541344, 5654.321481422256, 5888.941110588839, 5937.673427961331, 5940.435658612425, 5013.874653133701, 5813.215215945793, 5825.592208545361, 5835.94164058354, 5889.116632505158, 4387.868363949086, 5624.228368064047, 5779.971100144472, 5841.795537156179, 5873.94126058751, 5435.891282174255, 5701.806988086685, 5724.570657200643, 5744.454276843693, 5812.418758124323, 5943.58394912352, 5943.821685349273, 5944.375840536682, 5948.375420580776, 5957.3446920837805, 4708.858734237979, 5478.835634930959, 5817.709114544171, 5820.359760426265, 5842.561807864342], "value": 4084.1549071348586, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.6728403082491, 738.1843063414926, 785.1363500149835, 811.9756407307783, 838.4047326398257, 12981.870181298184, 15239.238038098092, 16247.431339903105, 16249.51251462455, 16260.674786504263, 14706.852931470683, 14820.6294842629, 14842.628934276649, 14843.777343339858, 14884.851151488481, 833.0836080311655, 870.4994628088842, 871.7602659268689, 877.3463769491738, 878.9296856251484, 866.8136350684601, 875.829213303406, 878.2271600991119, 878.4816957994782, 879.3492815316652, 879.2570278114982, 891.3983061433506, 917.2845180759018, 919.8528235482324, 924.1774820409807, 917.0966597901096, 917.7338571814166, 918.2149262380635, 918.2883265469248, 918.76112210825, 673.0375562944987, 676.4689718570947, 677.2381071294793, 677.806825054857, 678.067656971664, 15119.54641360761, 15403.3838646454, 15441.613959650986, 15442.45951391696, 15538.61153471161, 12344.81482777759, 13271.402786874614, 13903.374348154357, 14150.820981184828, 14284.928575357057, 11657.242279251821, 11766.764664706658, 11791.23356981802, 11908.702282442922, 12131.757364852654, 3737.4331177824592, 3817.522809648794, 3819.7135214858995, 3824.4836947012077, 3954.584768599313, 114237.0, 124128.0, 124847.71333807423, 127147.0, 128169.0, 10629.362238265574, 10668.839967400496, 10834.620788272372, 10856.78286434267, 11113.666590002313, 20055.09752061161, 22245.221417250643, 22328.776712232993, 22342.55314893693, 22379.888100559394, 7926.8810967835525, 7966.442349035628, 7981.0, 8238.011438627404, 8258.215469530385, 4438.889027774331, 4448.317068313098, 4460.0, 4472.373867658531, 4535.886602834956, 3589.587197472324, 3684.5394325709285, 3688.170161713647, 3706.777593344408, 3709.295233905549, 668.2582333609689, 675.5575098310615, 675.898615207713, 682.0860047536326, 688.9552179108442, 648.115322584678, 652.9063818104751, 655.4330504113907, 665.2615596687673, 666.8799616069057, 9327.533623318806, 9543.0, 9551.379160354554, 9630.855537166948, 9833.4099954003, 631.2992578238152, 633.2223374638364, 664.6178447392768, 666.6033709942576, 666.8399584099781, 3614.8192590370377, 3777.8299976501125, 4160.708750387505, 4363.323684828857, 4555.498895121534, 155228.44771552365, 163419.0, 170160.19359225492, 170384.1480792588, 174968.0, 144004.0, 146154.26922865317, 147282.527174729, 148780.0, 150242.49757502505, 120231.0, 122563.0, 125529.0, 126180.0, 127215.99999999815, 8177.591120444073, 8740.519271439878, 8812.383133180872, 8872.86690699627, 9005.414648047856, 45438.18427235941, 48356.03287934292, 49898.0, 58939.0, 66063.67872642617, 88360.55819721117, 89083.0, 93034.53482732543, 93591.1281774347, 94948.52525737327, 10677.9466102669, 12933.805992909925, 13101.541446049343, 13194.538191163263, 13313.600591982256, 14880.85119148838, 14971.251437428304, 15308.540743777705, 15592.688146236786, 15660.92169539145, 13912.304384780922, 13944.372503237175, 14066.507672231422, 14266.928665356607, 14548.418063277773, 13428.530001449903, 13641.249731264876, 13651.0, 13735.175889446666, 14175.432982680577, 13617.79573306421, 13860.861391385957, 14159.22124283133, 14229.715405692035, 14385.496507622183, 10210.489475526343, 11325.71685707864, 11327.812181352368, 11427.942860285646, 11677.591284305008, 9512.762180945532, 9544.618215271588, 10588.0, 10860.456977151269, 11689.473973671375], "value": 7948.994517614021, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1633.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 121.0, 109.0, 172.0, 176.0, 147.0, 118.0, 119.0, 135.0], "value": 132.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 131.0, 170.0, 149.0, 141.0, 164.0, 162.0, 114.0, 158.0, 113.0], "value": 145.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 259.0, 277.0, 223.0, 199.0, 281.0, 188.0, 179.0, 279.0, 271.0], "value": 236.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 72.0, 149.0, 61.0, 60.0, 59.0, 60.0, 135.0, 60.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 98.0, 107.0, 95.0, 59.0, 96.0, 143.0, 104.0, 105.0, 116.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 88.0, 82.0, 116.0, 131.0, 87.0, 91.0, 90.0, 80.0, 90.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 95.0, 97.0, 97.0, 96.0, 96.0, 102.0, 152.0, 96.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 103.0, 94.0, 91.0, 94.0, 91.0, 91.0, 92.0, 91.0, 90.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 58.0, 56.0, 58.0, 58.0, 57.0, 57.0, 55.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 74.0, 72.0, 78.0, 103.0, 99.0, 74.0, 72.0, 69.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 85.0, 77.0, 98.0, 119.0, 113.0, 112.0, 108.0, 88.0], "value": 97.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 160.0, 161.0, 144.0, 138.0, 144.0, 128.0, 168.0, 200.0, 143.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 175.0, 178.0, 232.0, 189.0, 172.0, 177.0, 172.0, 170.0, 173.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 74.0, 82.0, 92.0, 93.0, 92.0, 94.0, 75.0, 69.0], "value": 81.2, "unit": "score"}]}]} 07:51:36 INFO - Return code: 0 07:51:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:37 ERROR - # TBPL SUCCESS # 07:51:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:37 INFO - 2016-09-04 07:51:37.078 screenresolution[1774:19166] starting screenresolution argv=screenresolution get 07:51:37 INFO - 2016-09-04 07:51:37.080 screenresolution[1774:19166] Display 0: 1600x1200x32@60 07:51:37 INFO - 2016-09-04 07:51:37.086 screenresolution[1775:19169] starting screenresolution argv=screenresolution list 07:51:37 INFO - Available Modes on Display 0 07:51:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:37 INFO - Intel Iris: 07:51:37 INFO - Chipset Model: Intel Iris 07:51:37 INFO - Type: GPU 07:51:37 INFO - Bus: Built-In 07:51:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:37 INFO - Vendor: Intel (0x8086) 07:51:37 INFO - Device ID: 0x0a2e 07:51:37 INFO - Revision ID: 0x0009 07:51:37 INFO - Displays: 07:51:37 INFO - Display: 07:51:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:37 INFO - Display Serial Number: 1600x1200 60 07:51:37 INFO - Main Display: Yes 07:51:37 INFO - Mirror: Off 07:51:37 INFO - Online: Yes 07:51:37 INFO - Rotation: Supported 07:51:37 INFO - Return code: 0 07:51:37 INFO - Running post-action listener: _resource_record_post_action 07:51:37 INFO - [mozharness: 2016-09-04 14:51:37.323794Z] Finished run-tests step (success) 07:51:37 INFO - Running post-run listener: _resource_record_post_run 07:51:37 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32545792; Write bytes: 707195392; Read time: 322; Write time: 2106 07:51:37 INFO - TinderboxPrint: CPU usage
54.4% 07:51:37 INFO - TinderboxPrint: I/O read bytes / time
32,545,792 / 322 07:51:37 INFO - TinderboxPrint: I/O write bytes / time
707,195,392 / 2,106 07:51:37 INFO - TinderboxPrint: CPU idle
2,206.5 (45.6%) 07:51:37 INFO - TinderboxPrint: CPU system
982.3 (20.3%) 07:51:37 INFO - TinderboxPrint: CPU user
1,653.0 (34.1%) 07:51:37 INFO - TinderboxPrint: Swap in / out
433,946,624 / 0 07:51:37 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387807744; Write bytes: 383162368; Read time: 28962; Write time: 727 07:51:37 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31611904; Write bytes: 297777664; Read time: 307; Write time: 1329 07:51:37 INFO - Running post-run listener: _upload_blobber_files 07:51:37 INFO - Blob upload gear active. 07:51:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:37 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 07:51:37 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'] 07:51:37 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 07:51:38 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:51:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:51:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:39 INFO - (blobuploader) - INFO - Done attempting. 07:51:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:51:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:51:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:40 INFO - (blobuploader) - INFO - Done attempting. 07:51:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:51:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:43 INFO - (blobuploader) - INFO - Done attempting. 07:51:43 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:43 INFO - Return code: 0 07:51:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f1e0a5dc224de541343eb8dd61871958c07536efb90c622e4c21add1ef20bc1776d9eca5dd1572b6c17cd29e4eeb6aab1eed469e6d958eb4b3fcbb19b1a826", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7aff996705141d53f054b4988b3b70c2af9579d47b8d80b78eb82f4703acad97d7aa8b15be443a486cac3123e1235988d77b9fd5344603307d518a6d4612277", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/517ceeb67cc45b64d04e3f39a94c2c5b0e8d7414ec758fee71721299a7bbea907d6f2391ac70504132ad144a88aa2ccf45ff08c93390e70ef68949e42ed7eb21"} 07:51:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:43 INFO - Contents: 07:51:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76f1e0a5dc224de541343eb8dd61871958c07536efb90c622e4c21add1ef20bc1776d9eca5dd1572b6c17cd29e4eeb6aab1eed469e6d958eb4b3fcbb19b1a826", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7aff996705141d53f054b4988b3b70c2af9579d47b8d80b78eb82f4703acad97d7aa8b15be443a486cac3123e1235988d77b9fd5344603307d518a6d4612277", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/517ceeb67cc45b64d04e3f39a94c2c5b0e8d7414ec758fee71721299a7bbea907d6f2391ac70504132ad144a88aa2ccf45ff08c93390e70ef68949e42ed7eb21"} 07:51:43 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:43 INFO - Copying logs to upload dir... 07:51:43 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.220131 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-04 07:51:43.140954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 07:51:43.144875) ========= 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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/76f1e0a5dc224de541343eb8dd61871958c07536efb90c622e4c21add1ef20bc1776d9eca5dd1572b6c17cd29e4eeb6aab1eed469e6d958eb4b3fcbb19b1a826", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7aff996705141d53f054b4988b3b70c2af9579d47b8d80b78eb82f4703acad97d7aa8b15be443a486cac3123e1235988d77b9fd5344603307d518a6d4612277", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/517ceeb67cc45b64d04e3f39a94c2c5b0e8d7414ec758fee71721299a7bbea907d6f2391ac70504132ad144a88aa2ccf45ff08c93390e70ef68949e42ed7eb21"} build_url:https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/JmcwY4yTQVav8tTdX8i0hQ/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/76f1e0a5dc224de541343eb8dd61871958c07536efb90c622e4c21add1ef20bc1776d9eca5dd1572b6c17cd29e4eeb6aab1eed469e6d958eb4b3fcbb19b1a826", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7aff996705141d53f054b4988b3b70c2af9579d47b8d80b78eb82f4703acad97d7aa8b15be443a486cac3123e1235988d77b9fd5344603307d518a6d4612277", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/517ceeb67cc45b64d04e3f39a94c2c5b0e8d7414ec758fee71721299a7bbea907d6f2391ac70504132ad144a88aa2ccf45ff08c93390e70ef68949e42ed7eb21"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 07:51:43.179084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:51:43.179446) ========= 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.aAR2lCo3ga/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.KFqMJGJ0qR/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 07:51:43.228364) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 07:51:43.228691) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 07:51:43.229036) ========= ========= Total master_lag: 0.31 =========