builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1473654547.02 results: success (0) buildid: 20160911193416 builduid: f8a9e6840a4e4b6d83aff6e3acb4b123 revision: cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.021761) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.022307) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.022789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.048678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.049139) ========= 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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-11 21:29:07-- 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% 20.6M=0.001s 2016-09-11 21:29:07 (20.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.640819 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.715229) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.715518) ========= 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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.028218 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:07.780806) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-11 21:29:07.781169) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-11 21:29:07,862 truncating revision to first 12 chars 2016-09-11 21:29:07,862 Setting DEBUG logging. 2016-09-11 21:29:07,863 attempt 1/10 2016-09-11 21:29:07,863 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cfdb7af3af2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-11 21:29:09,725 unpacking tar archive at: autoland-cfdb7af3af2e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.144480 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-11 21:29:09.951959) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:09.952323) ========= script_repo_revision: cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:09.952712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:09.952978) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-11 21:29:09.972250) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-11 21:29:09.972755) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:10 INFO - MultiFileLogger online at 20160911 21:29:10 in /builds/slave/test 21:29:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:29:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:29:10 INFO - {'append_to_log': False, 21:29:10 INFO - 'base_work_dir': '/builds/slave/test', 21:29:10 INFO - 'blob_upload_branch': 'Autoland', 21:29:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:29:10 INFO - 'branch': 'Autoland', 21:29:10 INFO - 'buildbot_json_path': 'buildprops.json', 21:29:10 INFO - 'config_files': ('talos/mac_config.py',), 21:29:10 INFO - 'default_actions': ('clobber', 21:29:10 INFO - 'read-buildbot-config', 21:29:10 INFO - 'download-and-extract', 21:29:10 INFO - 'populate-webroot', 21:29:10 INFO - 'create-virtualenv', 21:29:10 INFO - 'install', 21:29:10 INFO - 'run-tests'), 21:29:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:29:10 INFO - 'download_minidump_stackwalk': True, 21:29:10 INFO - 'download_symbols': 'ondemand', 21:29:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:29:10 INFO - 'tooltool.py': '/tools/tooltool.py', 21:29:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:29:10 INFO - '/tools/misc-python/virtualenv.py')}, 21:29:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:29:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:29:10 INFO - 'installer_path': 'installer.exe', 21:29:10 INFO - 'log_level': 'info', 21:29:10 INFO - 'log_name': 'talos', 21:29:10 INFO - 'log_to_console': True, 21:29:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:29:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:29:10 INFO - 'opt_config_files': (), 21:29:10 INFO - 'pip_index': False, 21:29:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:29:10 INFO - 'cmd': ('bash', 21:29:10 INFO - '-c', 21:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:29:10 INFO - 'enabled': True, 21:29:10 INFO - 'halt_on_failure': False, 21:29:10 INFO - 'name': 'check_screen_resolution'},), 21:29:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:29:10 INFO - 'cmd': ('bash', 21:29:10 INFO - '-c', 21:29:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:29:10 INFO - 'enabled': True, 21:29:10 INFO - 'halt_on_failure': False, 21:29:10 INFO - 'name': 'check_screen_resolution'},), 21:29:10 INFO - 'run_cmd_checks_enabled': True, 21:29:10 INFO - 'sps_profile': False, 21:29:10 INFO - 'sps_profile_interval': 0, 21:29:10 INFO - 'suite': 'dromaeojs', 21:29:10 INFO - 'system_bits': '32', 21:29:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:29:10 INFO - 'title': 't-yosemite-r7-0074', 21:29:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:29:10 INFO - 'use_talos_json': True, 21:29:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:29:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:29:10 INFO - 'work_dir': 'build'} 21:29:10 INFO - [mozharness: 2016-09-12 04:29:10.189062Z] Running clobber step. 21:29:10 INFO - Running pre-action listener: _resource_record_pre_action 21:29:10 INFO - Running main action method: clobber 21:29:10 INFO - rmtree: /builds/slave/test/build 21:29:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:29:12 INFO - Running post-action listener: _resource_record_post_action 21:29:12 INFO - [mozharness: 2016-09-12 04:29:12.253643Z] Finished clobber step (success) 21:29:12 INFO - [mozharness: 2016-09-12 04:29:12.253815Z] Running read-buildbot-config step. 21:29:12 INFO - Running pre-action listener: _resource_record_pre_action 21:29:12 INFO - Running main action method: read_buildbot_config 21:29:12 INFO - Using buildbot properties: 21:29:12 INFO - { 21:29:12 INFO - "project": "", 21:29:12 INFO - "product": "firefox", 21:29:12 INFO - "script_repo_revision": "production", 21:29:12 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 21:29:12 INFO - "repository": "", 21:29:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 21:29:12 INFO - "buildid": "20160911193416", 21:29:12 INFO - "pgo_build": "False", 21:29:12 INFO - "basedir": "/builds/slave/test", 21:29:12 INFO - "buildnumber": 193, 21:29:12 INFO - "slavename": "t-yosemite-r7-0074", 21:29:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:29:12 INFO - "platform": "yosemite_r7", 21:29:12 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:29:12 INFO - "branch": "autoland", 21:29:12 INFO - "repo_path": "integration/autoland", 21:29:12 INFO - "revision": "cfdb7af3af2e92e95f71ca2f1672bf5433beeb89", 21:29:12 INFO - "stage_platform": "macosx64", 21:29:12 INFO - "builduid": "f8a9e6840a4e4b6d83aff6e3acb4b123", 21:29:12 INFO - "slavebuilddir": "test" 21:29:12 INFO - } 21:29:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:29:12 INFO - Running post-action listener: _resource_record_post_action 21:29:12 INFO - [mozharness: 2016-09-12 04:29:12.257171Z] Finished read-buildbot-config step (success) 21:29:12 INFO - [mozharness: 2016-09-12 04:29:12.257297Z] Running download-and-extract step. 21:29:12 INFO - Running pre-action listener: _resource_record_pre_action 21:29:12 INFO - Running main action method: download_and_extract 21:29:12 INFO - mkdir: /builds/slave/test/build/tests 21:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:12 INFO - trying https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/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 21:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/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 21:29:13 INFO - Downloaded 1199 bytes. 21:29:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:29:13 INFO - Using the following test package requirements: 21:29:13 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:29:13 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:29:13 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:29:13 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'jsshell-mac.zip'], 21:29:13 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:29:13 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:29:13 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:29:13 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:29:13 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:29:13 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:29:13 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:29:13 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:29:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:13 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:29:20 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 21:29:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:29:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:29:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:29:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:29:30 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')]} 21:29:30 INFO - trying https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/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 21:29:33 INFO - Downloaded 111649537 bytes. 21:29:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:33 INFO - mkdir: /builds/slave/test/properties 21:29:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:29:33 INFO - Writing to file /builds/slave/test/properties/build_url 21:29:33 INFO - Contents: 21:29:33 INFO - build_url:https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:29:33 INFO - Running post-action listener: _resource_record_post_action 21:29:33 INFO - Running post-action listener: set_extra_try_arguments 21:29:33 INFO - [mozharness: 2016-09-12 04:29:33.895973Z] Finished download-and-extract step (success) 21:29:33 INFO - [mozharness: 2016-09-12 04:29:33.896103Z] Running populate-webroot step. 21:29:33 INFO - Running pre-action listener: _resource_record_pre_action 21:29:33 INFO - Running main action method: populate_webroot 21:29:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:29:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:29:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:29:33 INFO - '--tppagecycles', 21:29:33 INFO - '7'], 21:29:33 INFO - 'tests': ['tsvgm']}}, 21:29:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:29:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:29:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:29:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:29:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['damp', 'tps']}, 21:29:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'tests': ['damp', 'tps']}, 21:29:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['dromaeo_dom']}, 21:29:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:29:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['basic_compositor_video']}, 21:29:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:29:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'other-e10s': {'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['a11yr', 21:29:33 INFO - 'ts_paint', 21:29:33 INFO - 'tpaint', 21:29:33 INFO - 'sessionrestore', 21:29:33 INFO - 'sessionrestore_no_auto_restore', 21:29:33 INFO - 'tabpaint']}, 21:29:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['tsvgx', 21:29:33 INFO - 'tsvgr_opacity', 21:29:33 INFO - 'tart', 21:29:33 INFO - 'tscrollx', 21:29:33 INFO - 'cart']}, 21:29:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:29:33 INFO - 'tsvgr_opacity', 21:29:33 INFO - 'tart', 21:29:33 INFO - 'tscrollx', 21:29:33 INFO - 'cart']}, 21:29:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'talos_options': ['--disable-e10s'], 21:29:33 INFO - 'tests': ['tp5o']}, 21:29:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'tests': ['tp5o']}, 21:29:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'talos_options': ['--disable-e10s', 21:29:33 INFO - '--xperf_path', 21:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:33 INFO - 'tests': ['tp5n']}, 21:29:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:33 INFO - 'talos_options': ['--xperf_path', 21:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:33 INFO - 'tests': ['tp5n']}}, 21:29:33 INFO - 'talos.zip': {'path': '', 21:29:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:29:33 INFO - Running post-action listener: _resource_record_post_action 21:29:33 INFO - [mozharness: 2016-09-12 04:29:33.915012Z] Finished populate-webroot step (success) 21:29:33 INFO - [mozharness: 2016-09-12 04:29:33.915092Z] Running create-virtualenv step. 21:29:33 INFO - Running pre-action listener: _resource_record_pre_action 21:29:33 INFO - Running main action method: create_virtualenv 21:29:33 INFO - Creating virtualenv /builds/slave/test/build/venv 21:29:33 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 21:29:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:29:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:29:34 INFO - Using real prefix '/tools/python27' 21:29:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:29:34 INFO - Installing distribute.............................................................................................................................................................................................done. 21:29:37 INFO - Installing pip.................done. 21:29:37 INFO - Return code: 0 21:29:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:37 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:37 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 21:29:37 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 21:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:37 INFO - 'HOME': '/Users/cltbld', 21:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:37 INFO - 'LOGNAME': 'cltbld', 21:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:37 INFO - 'MOZ_NO_REMOTE': '1', 21:29:37 INFO - 'NO_EM_RESTART': '1', 21:29:37 INFO - 'PAGER': '/bin/cat', 21:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:37 INFO - 'PWD': '/builds/slave/test', 21:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:37 INFO - 'SHELL': '/bin/bash', 21:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:37 INFO - 'USER': 'cltbld', 21:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:37 INFO - 'XPC_FLAGS': '0x0', 21:29:37 INFO - 'XPC_SERVICE_NAME': '0', 21:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:37 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 21:29:37 INFO - Cleaning up... 21:29:37 INFO - Return code: 0 21:29:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:37 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:37 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 21:29:37 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 21:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:37 INFO - 'HOME': '/Users/cltbld', 21:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:37 INFO - 'LOGNAME': 'cltbld', 21:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:37 INFO - 'MOZ_NO_REMOTE': '1', 21:29:37 INFO - 'NO_EM_RESTART': '1', 21:29:37 INFO - 'PAGER': '/bin/cat', 21:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:37 INFO - 'PWD': '/builds/slave/test', 21:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:37 INFO - 'SHELL': '/bin/bash', 21:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:37 INFO - 'USER': 'cltbld', 21:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:37 INFO - 'XPC_FLAGS': '0x0', 21:29:37 INFO - 'XPC_SERVICE_NAME': '0', 21:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:38 INFO - Downloading/unpacking psutil>=3.1.1 21:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:29:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:41 INFO - Installing collected packages: psutil 21:29:41 INFO - Running setup.py install for psutil 21:29:41 INFO - building 'psutil._psutil_osx' extension 21:29:41 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 21:29:42 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 21:29:42 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 21:29:42 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 21:29:42 INFO - building 'psutil._psutil_posix' extension 21:29:42 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 21:29:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:29:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:29:42 INFO - ^ 21:29:42 INFO - 1 warning generated. 21:29:42 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 21:29:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:42 INFO - Successfully installed psutil 21:29:42 INFO - Cleaning up... 21:29:42 INFO - Return code: 0 21:29:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:29:42 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')]} 21:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:42 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')]} 21:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:42 INFO - 'HOME': '/Users/cltbld', 21:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:42 INFO - 'LOGNAME': 'cltbld', 21:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:42 INFO - 'MOZ_NO_REMOTE': '1', 21:29:42 INFO - 'NO_EM_RESTART': '1', 21:29:42 INFO - 'PAGER': '/bin/cat', 21:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:42 INFO - 'PWD': '/builds/slave/test', 21:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:42 INFO - 'SHELL': '/bin/bash', 21:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:42 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:42 INFO - 'USER': 'cltbld', 21:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:42 INFO - 'XPC_FLAGS': '0x0', 21:29:42 INFO - 'XPC_SERVICE_NAME': '0', 21:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:29:45 INFO - Installing collected packages: mozsystemmonitor 21:29:45 INFO - Running setup.py install for mozsystemmonitor 21:29:45 INFO - Successfully installed mozsystemmonitor 21:29:45 INFO - Cleaning up... 21:29:45 INFO - Return code: 0 21:29:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:45 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:45 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 21:29:45 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 21:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:45 INFO - 'HOME': '/Users/cltbld', 21:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:45 INFO - 'LOGNAME': 'cltbld', 21:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:45 INFO - 'MOZ_NO_REMOTE': '1', 21:29:45 INFO - 'NO_EM_RESTART': '1', 21:29:45 INFO - 'PAGER': '/bin/cat', 21:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:45 INFO - 'PWD': '/builds/slave/test', 21:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:45 INFO - 'SHELL': '/bin/bash', 21:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:45 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:45 INFO - 'USER': 'cltbld', 21:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:45 INFO - 'XPC_FLAGS': '0x0', 21:29:45 INFO - 'XPC_SERVICE_NAME': '0', 21:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:46 INFO - Downloading/unpacking jsonschema==2.5.1 21:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:29:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:29:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 INFO - Downloading functools32-3.2.3-2.tar.gz 21:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:29:49 INFO - warning: no files found matching '*.txt' 21:29:49 INFO - no previously-included directories found matching 'build' 21:29:49 INFO - no previously-included directories found matching 'dist' 21:29:49 INFO - no previously-included directories found matching '.git*' 21:29:49 INFO - Installing collected packages: jsonschema, functools32 21:29:49 INFO - Running setup.py install for functools32 21:29:49 INFO - warning: no files found matching '*.txt' 21:29:49 INFO - no previously-included directories found matching 'build' 21:29:49 INFO - no previously-included directories found matching 'dist' 21:29:49 INFO - no previously-included directories found matching '.git*' 21:29:49 INFO - Successfully installed jsonschema functools32 21:29:49 INFO - Cleaning up... 21:29:49 INFO - Return code: 0 21:29:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:49 INFO - 'HOME': '/Users/cltbld', 21:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:49 INFO - 'LOGNAME': 'cltbld', 21:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:49 INFO - 'MOZ_NO_REMOTE': '1', 21:29:49 INFO - 'NO_EM_RESTART': '1', 21:29:49 INFO - 'PAGER': '/bin/cat', 21:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:49 INFO - 'PWD': '/builds/slave/test', 21:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:49 INFO - 'SHELL': '/bin/bash', 21:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:49 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:49 INFO - 'USER': 'cltbld', 21:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:49 INFO - 'XPC_FLAGS': '0x0', 21:29:49 INFO - 'XPC_SERVICE_NAME': '0', 21:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:29:49 INFO - Cleaning up... 21:29:49 INFO - Return code: 0 21:29:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:29:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:49 INFO - 'HOME': '/Users/cltbld', 21:29:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:49 INFO - 'LOGNAME': 'cltbld', 21:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:49 INFO - 'MOZ_NO_REMOTE': '1', 21:29:49 INFO - 'NO_EM_RESTART': '1', 21:29:49 INFO - 'PAGER': '/bin/cat', 21:29:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:49 INFO - 'PWD': '/builds/slave/test', 21:29:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:49 INFO - 'SHELL': '/bin/bash', 21:29:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:49 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:49 INFO - 'USER': 'cltbld', 21:29:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:49 INFO - 'XPC_FLAGS': '0x0', 21:29:49 INFO - 'XPC_SERVICE_NAME': '0', 21:29:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:49 INFO - Downloading/unpacking blobuploader==1.2.4 21:29:49 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 21:29:49 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 21:29:49 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 21:29:49 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 21:29:49 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 21:29:49 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 21:29:53 INFO - Downloading blobuploader-1.2.4.tar.gz 21:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:29:53 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 21:29:53 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 21:29:53 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 21:29:53 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 21:29:53 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 21:29:53 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 21:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:29:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 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 21:29:54 INFO - Downloading docopt-0.6.1.tar.gz 21:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:29:54 INFO - Installing collected packages: blobuploader, requests, docopt 21:29:54 INFO - Running setup.py install for blobuploader 21:29:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:29:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:29:54 INFO - Running setup.py install for requests 21:29:55 INFO - Running setup.py install for docopt 21:29:55 INFO - Successfully installed blobuploader requests docopt 21:29:55 INFO - Cleaning up... 21:29:55 INFO - Return code: 0 21:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:55 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:29:55 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 21:29:55 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 21:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:29:55 INFO - 'HOME': '/Users/cltbld', 21:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:29:55 INFO - 'LOGNAME': 'cltbld', 21:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:55 INFO - 'MOZ_NO_REMOTE': '1', 21:29:55 INFO - 'NO_EM_RESTART': '1', 21:29:55 INFO - 'PAGER': '/bin/cat', 21:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:55 INFO - 'PWD': '/builds/slave/test', 21:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:29:55 INFO - 'SHELL': '/bin/bash', 21:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:29:55 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:29:55 INFO - 'USER': 'cltbld', 21:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:55 INFO - 'XPC_FLAGS': '0x0', 21:29:55 INFO - 'XPC_SERVICE_NAME': '0', 21:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:55 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-pzJyPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:55 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ByWq9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:55 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aPY75z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vEMk_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-SM7sbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-a4PK5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7Rn2NE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BYI9rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-qS3GYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-gXjgZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-JOegt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:56 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-MhU18C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TFG47z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hxMsN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-WC4ggM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Ckpxvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:57 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-poRrHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:29:57 INFO - Running setup.py install for manifestparser 21:29:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Running setup.py install for mozcrash 21:29:57 INFO - Running setup.py install for mozdebug 21:29:58 INFO - Running setup.py install for mozdevice 21:29:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Running setup.py install for mozfile 21:29:58 INFO - Running setup.py install for mozhttpd 21:29:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Running setup.py install for mozinfo 21:29:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Running setup.py install for mozInstall 21:29:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:29:58 INFO - Running setup.py install for mozleak 21:29:58 INFO - Running setup.py install for mozlog 21:29:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Running setup.py install for moznetwork 21:29:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Running setup.py install for mozprocess 21:29:59 INFO - Running setup.py install for mozprofile 21:29:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Running setup.py install for mozrunner 21:29:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:29:59 INFO - Running setup.py install for mozscreenshot 21:29:59 INFO - Running setup.py install for moztest 21:30:00 INFO - Running setup.py install for mozversion 21:30:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:30:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:30:00 INFO - Cleaning up... 21:30:00 INFO - Return code: 0 21:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:00 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')]} 21:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:00 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')]} 21:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:00 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:30:00 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 21:30:00 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 21:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:00 INFO - 'HOME': '/Users/cltbld', 21:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:00 INFO - 'LOGNAME': 'cltbld', 21:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:00 INFO - 'MOZ_NO_REMOTE': '1', 21:30:00 INFO - 'NO_EM_RESTART': '1', 21:30:00 INFO - 'PAGER': '/bin/cat', 21:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:00 INFO - 'PWD': '/builds/slave/test', 21:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:00 INFO - 'SHELL': '/bin/bash', 21:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:30:00 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:00 INFO - 'USER': 'cltbld', 21:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:00 INFO - 'XPC_FLAGS': '0x0', 21:30:00 INFO - 'XPC_SERVICE_NAME': '0', 21:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:30:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Hm1Vuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:30:00 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)) 21:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:30:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-DnIp1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:30:00 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)) 21:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:30:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QszbBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:30:00 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)) 21:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:30:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LsFKL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:30:00 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)) 21:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:30:00 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BmtPYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-JRnQFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TRH5sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QWqNbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-v3wDM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YDwKDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-H6Rt2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-245UEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TSajOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-rralQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:30:01 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)) 21:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YeOoGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:30:02 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)) 21:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:30:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7eUK3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:30:02 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)) 21:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:30:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7B3TgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:30:02 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)) 21:30:02 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)) 21:30:02 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)) 21:30:02 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)) 21:30:02 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)) 21:30:02 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)) 21:30:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:04 INFO - Downloading blessings-1.6.tar.gz 21:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:30:04 INFO - Installing collected packages: blessings 21:30:04 INFO - Running setup.py install for blessings 21:30:05 INFO - Successfully installed blessings 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:05 INFO - Reading from file tmpfile_stdout 21:30:05 INFO - Current package versions: 21:30:05 INFO - blessings == 1.6 21:30:05 INFO - blobuploader == 1.2.4 21:30:05 INFO - docopt == 0.6.1 21:30:05 INFO - functools32 == 3.2.3-2 21:30:05 INFO - jsonschema == 2.5.1 21:30:05 INFO - manifestparser == 1.1 21:30:05 INFO - mozInstall == 1.12 21:30:05 INFO - mozcrash == 0.17 21:30:05 INFO - mozdebug == 0.1 21:30:05 INFO - mozdevice == 0.48 21:30:05 INFO - mozfile == 1.2 21:30:05 INFO - mozhttpd == 0.7 21:30:05 INFO - mozinfo == 0.9 21:30:05 INFO - mozleak == 0.1 21:30:05 INFO - mozlog == 3.3 21:30:05 INFO - moznetwork == 0.27 21:30:05 INFO - mozprocess == 0.23 21:30:05 INFO - mozprofile == 0.28 21:30:05 INFO - mozrunner == 6.12 21:30:05 INFO - mozscreenshot == 0.1 21:30:05 INFO - mozsystemmonitor == 0.3 21:30:05 INFO - moztest == 0.7 21:30:05 INFO - mozversion == 1.4 21:30:05 INFO - psutil == 3.1.1 21:30:05 INFO - requests == 1.2.3 21:30:05 INFO - wsgiref == 0.1.2 21:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:05 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')]} 21:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 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')]} 21:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:30:05 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 21:30:05 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 21:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:05 INFO - 'HOME': '/Users/cltbld', 21:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:05 INFO - 'LOGNAME': 'cltbld', 21:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:05 INFO - 'MOZ_NO_REMOTE': '1', 21:30:05 INFO - 'NO_EM_RESTART': '1', 21:30:05 INFO - 'PAGER': '/bin/cat', 21:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:05 INFO - 'PWD': '/builds/slave/test', 21:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:05 INFO - 'SHELL': '/bin/bash', 21:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:30:05 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:05 INFO - 'USER': 'cltbld', 21:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:05 INFO - 'XPC_FLAGS': '0x0', 21:30:05 INFO - 'XPC_SERVICE_NAME': '0', 21:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 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)) 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:30:05 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')]} 21:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 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')]} 21:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:05 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 0x10434c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd0471100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, '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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 21:30:05 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 21:30:05 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 21:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:05 INFO - 'HOME': '/Users/cltbld', 21:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:05 INFO - 'LOGNAME': 'cltbld', 21:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:05 INFO - 'MOZ_NO_REMOTE': '1', 21:30:05 INFO - 'NO_EM_RESTART': '1', 21:30:05 INFO - 'PAGER': '/bin/cat', 21:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:05 INFO - 'PWD': '/builds/slave/test', 21:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:05 INFO - 'SHELL': '/bin/bash', 21:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:30:05 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:05 INFO - 'USER': 'cltbld', 21:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:05 INFO - 'XPC_FLAGS': '0x0', 21:30:05 INFO - 'XPC_SERVICE_NAME': '0', 21:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Running post-action listener: _resource_record_post_action 21:30:05 INFO - Running post-action listener: _start_resource_monitoring 21:30:05 INFO - Starting resource monitoring. 21:30:05 INFO - [mozharness: 2016-09-12 04:30:05.951380Z] Finished create-virtualenv step (success) 21:30:05 INFO - [mozharness: 2016-09-12 04:30:05.952366Z] Running install step. 21:30:05 INFO - Running pre-action listener: _resource_record_pre_action 21:30:05 INFO - Running main action method: install 21:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:06 INFO - Reading from file tmpfile_stdout 21:30:06 INFO - Detecting whether we're running mozinstall >=1.0... 21:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:30:06 INFO - Reading from file tmpfile_stdout 21:30:06 INFO - Output received: 21:30:06 INFO - Usage: mozinstall [options] installer 21:30:06 INFO - Options: 21:30:06 INFO - -h, --help show this help message and exit 21:30:06 INFO - -d DEST, --destination=DEST 21:30:06 INFO - Directory to install application into. [default: 21:30:06 INFO - "/builds/slave/test"] 21:30:06 INFO - --app=APP Application being installed. [default: firefox] 21:30:06 INFO - mkdir: /builds/slave/test/build/application 21:30:06 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'] 21:30:06 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 21:30:40 INFO - Reading from file tmpfile_stdout 21:30:40 INFO - Output received: 21:30:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:30:40 INFO - Running post-action listener: _resource_record_post_action 21:30:40 INFO - [mozharness: 2016-09-12 04:30:40.384962Z] Finished install step (success) 21:30:40 INFO - [mozharness: 2016-09-12 04:30:40.385114Z] Running run-tests step. 21:30:40 INFO - Running pre-action listener: _resource_record_pre_action 21:30:40 INFO - Running main action method: run_tests 21:30:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:30:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:30:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:30:40 INFO - 2016-09-11 21:30:40.399 screenresolution[1639:8256] starting screenresolution argv=screenresolution get 21:30:40 INFO - 2016-09-11 21:30:40.402 screenresolution[1639:8256] Display 0: 1600x1200x32@60 21:30:40 INFO - 2016-09-11 21:30:40.407 screenresolution[1640:8258] starting screenresolution argv=screenresolution list 21:30:40 INFO - Available Modes on Display 0 21:30:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:30:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:30:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:30:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:30:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:30:40 INFO - Intel Iris: 21:30:40 INFO - Chipset Model: Intel Iris 21:30:40 INFO - Type: GPU 21:30:40 INFO - Bus: Built-In 21:30:40 INFO - VRAM (Dynamic, Max): 1536 MB 21:30:40 INFO - Vendor: Intel (0x8086) 21:30:40 INFO - Device ID: 0x0a2e 21:30:40 INFO - Revision ID: 0x0009 21:30:40 INFO - Displays: 21:30:40 INFO - Display: 21:30:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:30:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:30:40 INFO - Display Serial Number: 1600x1200 60 21:30:40 INFO - Main Display: Yes 21:30:40 INFO - Mirror: Off 21:30:40 INFO - Online: Yes 21:30:40 INFO - Rotation: Supported 21:30:40 INFO - Return code: 0 21:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:30:40 INFO - Python 2.7.3 21:30:40 INFO - Return code: 0 21:30:40 INFO - grabbing minidump binary from tooltool 21:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:40 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 0x104160d30>, '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 0x103f86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104158b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:30:40 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 21:30:40 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 21:30:40 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 21:30:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:30:40 INFO - Return code: 0 21:30:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:30:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:30:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:30:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:30:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DXQPXEcBoM/Render', 21:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:30:40 INFO - 'HOME': '/Users/cltbld', 21:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:30:40 INFO - 'LOGNAME': 'cltbld', 21:30:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:30:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:40 INFO - 'MOZ_NO_REMOTE': '1', 21:30:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:30:40 INFO - 'NO_EM_RESTART': '1', 21:30:40 INFO - 'PAGER': '/bin/cat', 21:30:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:40 INFO - 'PWD': '/builds/slave/test', 21:30:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:30:40 INFO - 'SHELL': '/bin/bash', 21:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZCFnQEq32z/Listeners', 21:30:40 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 21:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:30:40 INFO - 'USER': 'cltbld', 21:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:40 INFO - 'XPC_FLAGS': '0x0', 21:30:40 INFO - 'XPC_SERVICE_NAME': '0', 21:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:30:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:30:40 INFO - mozversion application_buildid: 20160911193416 21:30:40 INFO - mozversion application_changeset: cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 21:30:40 INFO - mozversion application_display_name: Nightly 21:30:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:30:40 INFO - mozversion application_name: Firefox 21:30:40 INFO - mozversion application_remotingname: firefox 21:30:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:30:40 INFO - mozversion application_vendor: Mozilla 21:30:40 INFO - mozversion application_version: 51.0a1 21:30:40 INFO - mozversion platform_buildid: 20160911193416 21:30:40 INFO - mozversion platform_changeset: cfdb7af3af2e92e95f71ca2f1672bf5433beeb89 21:30:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:30:40 INFO - mozversion platform_version: 51.0a1 21:30:40 INFO - using testdate: 1473654640 21:30:40 INFO - actual date: 1473654640 21:30:40 INFO - starting webserver on 'localhost:49273' 21:30:40 INFO - SUITE-START | Running 2 tests 21:30:40 INFO - TEST-START | dromaeo_css 21:30:40 INFO - operating with platform_type : mac_ 21:30:40 INFO - Initialising browser for dromaeo_css test... 21:30:41 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpT32cWd/profile http://localhost:49273/getInfo.html) 21:30:43 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 21:30:43 INFO - PROCESS | 1650 | colorDepth:24 21:30:43 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 21:30:43 INFO - PROCESS | 1650 | __metrics 21:30:43 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:30:43 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:30:44 INFO - TEST-INFO | 1650: exit 0 21:30:44 INFO - Browser initialized. 21:30:44 INFO - Running cycle 1/1 for dromaeo_css test... 21:30:44 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpT32cWd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:55 INFO - PROCESS | 1653 | RSS: Main: 233246720 21:30:55 INFO - PROCESS | 1653 | 21:31:47 INFO - PROCESS | 1653 | 1473654707437 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] 21:33:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 21:34:01 INFO - PROCESS | 1653 | RSS: Main: 374276096 21:34:01 INFO - PROCESS | 1653 | 21:37:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 21:37:04 INFO - PROCESS | 1653 | RSS: Main: 372629504 21:37:04 INFO - PROCESS | 1653 | 21:40:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 21:40:07 INFO - PROCESS | 1653 | RSS: Main: 337342464 21:40:07 INFO - PROCESS | 1653 | 21:43:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 21:43:10 INFO - PROCESS | 1653 | RSS: Main: 343093248 21:43:10 INFO - PROCESS | 1653 | 21:46:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 21:46:14 INFO - PROCESS | 1653 | RSS: Main: 331067392 21:46:14 INFO - PROCESS | 1653 | 21:49:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 21:49:18 INFO - PROCESS | 1653 | RSS: Main: 465039360 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | __start_tp_report 21:49:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 21:49:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 21:49:18 INFO - PROCESS | 1653 | |i|pagename|runs| 21:49:18 INFO - PROCESS | 1653 | |0;dojo.html;2964.718351756583;3379.9831000845;3395.0663567518927;3428.1258279138815;3464;5639.464250896169;5740.942590574093;5753.165790960314;5822.359540450552;5852.151438041481;5718.799842005531;5756.338021127572;5761.164631128494;5769.7115144242825;5772.393898640646;5190.8442746717565;5233.345831771028;5239.659422137549;5249.396319423277;5290.418054014055;5038.697678139305;5335.386430560497;5378.542823859977;5383.757730902099;5437.34751829781;5320.255164276985;5329.387120481136;5376.677399356032;5396.082665946798;5398.2712333834825;4142.565030671796;4380.364847097153;5198.16829307309;5344.732763361836;5361.51746342831;5207.062728708831;5254.159334506498;5282.603804714662;5322.0952438085615;5336.439673834269;5635.21107045014;5687.800926967537;5696.743646535874;5704.258446401941;5823.475887170132;6155.753769849201;6169.907451388232;6171.290301615282;6175.2898416681755;6197.442230199259;5754.683492407916;6183.072539119124;6218.191635087455;6247.781327653556;6262.655553944532;5732.828015159552;5789.94210057894;5859.8535036624;5875.941240587625;5882.205902203191;6223.96888015566;6233.2208473940755;6251.906221406683;6280.371962803683;6367.140436041168;6212.378762123842;6232.283287421914;6233.002719564848;6237.157983672146;6248.656323902229;6128.908066379008;6251.6561589112125;6261.906071408933;6281.371862813682;6288.9056664150075;6101.368489499298;6139.9828168395225;6161.784337548164;6232;6239.688015599202;6280.7487700491465;6282.465990390777;6283.151774510474;6284.937150628527;6289.182406287154;4980.178270585386;4985.551300382983;4985.900281994413;4993.375828021497;5019.272205530177;4392.868213953587;4395.252807022814;4434.002349471408;4464.441944756905;4479.865604031885;5722.513586345124;5751.654900705971;5768.221290125779;5775.422457754191;5783.652980821164;6187.226596675416;6229.065640154013;6242.375762423812;6246.531510136758;6272.937270627235;5784.334801497882;5848.385919478478;5861.384554621789;5863.296404431496;5893.852653683606;5492.258545096441;5509.090999985037;5509.283793106871;5511.311086114236;5513.7794488220825;6228.906566401508;6259.530535209969;6261.780837670751;6262.5929314594405;6319.778807741707;6193.1329613855;6239.032949892774;6245.781397651061;6250.937490625218;6258.780942666985;6388.9041664374145;6401.839954001187;6415.390537898892;6416.679166041773;6427.646479443675;5360.686631775084;6093;6112.1995365628145;6115.449609535163;6221.844453888508;6213.627182369072;6222.844428889314;6236.65698386575;6243.032329988859;6268.655223962726;6190.473809726223;6190.721417536131;6192.256929168529;6204.193454850841;6215.129881816459;5888.4700376966275;6047.395260473917;6098.78654247117;6124;6135;6001.159837622649;6071.483923866344;6079.756809727738;6082.5438092142385;6084.574079814548;6220.502359811295;6224.408681175281;6235.532335074797;6241.063840423791;6243.656598887105;5848.64908105515;5920.348761636131;6105.023196288573;6105.267367915879;6115.1438798569125 21:49:18 INFO - PROCESS | 1653 | |1;ext.html;498.28744894800445;519.7894852584703;526.6129394894751;529.3303970477347;556.5797822643904;24032.759672403292;40996.97507562312;42116.157676846444;42118.57881421185;43432.56567434325;42344;42801.35797963033;42873.57126428735;42960;42972.57027429725;8642.611082501271;9643.035696430354;9823.95088024559;9825.606975720963;9845.901540984605;8658.523781192032;11923.463444145034;12146.028317734561;12161;12332.815007774845;12148;12245.020398368153;12258.57095001675;12267.386630668476;12280.324582147978;12150.27098374096;12226.083043771752;12229.510819567207;12240.571579994747;12261.448234829453;11917.986971107466;11960.282383057045;12002.099842511847;12030.157888947777;12043.819342709867;40165.59834401648;40361.39457908134;40453.79773101116;40624.39063414051;40707.38938915919;2754.5454999924987;3256.2022304535303;3715.8142092895246;4037.262451869333;4115.794210289474;30840.53739193914;31562.545260224477;31776;32017.359652806812;32485;3215.9839200803845;3255.7069863712145;3340.682635149657;3353.496975453677;3378.5101160331615;516521;519003;519309;520218.3770704097;520741;59568.702156488944;59923.101153482734;59984.10023849646;60043;60126;62974.68512657408;63051;63096.68451657712;63164;63165;16422.93250938685;16610.086445245626;16623.83376166247;16633.83366166323;16657.750133748003;11711.297322160528;11906.523739050514;11940.582079627346;12833.871661283454;12899.871001290056;14902.850971490363;15002.024868383738;15064.322105505278;16665.25006374716;17077.7438338425;5315.6013299002725;6217.725366299897;6389.073584330246;6508.479321654257;6550.7707230246715;728.6065524616648;928.8560273157672;1024.4672770159625;1028.7582418131733;1116;29811.552826707615;29957.700422995928;29964.700352996628;30738.692613073585;30931.381372372427;873.5239294584467;1026.743314171457;1032.4837581209395;1057.746140926172;1068.326954018963;15628.609284767972;15751.527454176394;15762.605934851721;15841.52475425739;16149.434769783002;654823;661852;662162;662246;683390;45395.31907021398;48513.7871553214;56906;57372.139417908766;57507.42492575021;1327.707904261061;1339.4776037345443;1377.7726675098697;1377.986220137826;1379.6688794689203;29100.8359665611;29565.556516651835;31719.52420713645;31769.523457147698;32638.83680581582;51998.740006299726;52422.73788631032;52563.47436525586;52581.47418525919;52700;571523;599538;602839;603465;603878;26276.474470510864;27095;27111.1866644001;40698.186036278516;41065.79467102645;48441.51558484511;48794.512054879;49589.00821983612;49829.50170498249;49876.50123498864;32206.19484512906;37939.86180414592;38596.421053683654;39657.2068558633;40989.59010409858;32439.513407298437;39203;39384.80307598443;40314.1937161261;40904.79547602243;36212.27575448424;37812.622773907075;39066.2186756269;39296;39483.802580986914;26822.463550729266;33416.49875251922;34331.485027724106;34741.47887781635;35104.94685159449;23295.87270014918;24440.022399103837;25364.112256071687;26159.422442734754;26322.86838565795 21:49:18 INFO - PROCESS | 1653 | |2;jquery.html;345.6508925984756;353.64812012048014;364.28236374342544;368.9354362986477;376.668531692111;13816.654583635402;14525.055871368353;15868.809839262058;19914;19980.800191998074;19022;20478.590428191463;20484.59581190044;20675.689864652042;20929.05819238134;5711.8572035699235;6023.789167379143;6029.186059881926;6032.9698351508405;6043.1841701370195;4637.489876113625;5927.792527261547;5964.19483369746;5966.403359664042;5995.61028533144;5636.351819540744;5937.851553711149;5941.287045554518;5941.613795103304;5954.821355359346;5793.71031448428;5904.409559044061;5905.645661260317;5906.084556893688;5915.053591425372;4975.950240497585;5011.323471331397;5142.588592912558;5143.8456846294675;5166.147585648364;19695.704564431544;24579.63130553043;24589.295177530457;24949.126780562594;25005.62491562628;2166.2309879992536;2170.9240176593744;2203.900824462887;2391.4235851408625;2987.0292155049697;13669.974751893647;14847.960642755012;14966.735626367425;15219.847801521953;15490.91563590549;1674.3051633572054;1829.1128802530761;1877.9624407511774;1889.763779527559;1930.0349825087455;91716;119255.21117183249;121610;124723;126857.36571317085;11320;11324.830127548094;11355.034822040054;11457.312561246352;11715.941420292844;14357.066790658573;14411.919106067087;14413.279336033156;14424.990250682351;14426;8287;8504.787380315542;8552.315814734931;8685.522296273766;8709.12908709124;8540.44487108336;8541.658333666708;8564.52895090776;8739.30085593151;8783.5169065702;3667.8166091695307;4466.843660471933;4470.351798989128;4537.115262523776;4552.9772351138035;1835.7062869940746;1895.687211610069;1896.0898768590878;1896.4026331705468;1897.8671492995359;812.4475356757462;845.2815107158866;917.9311551633655;926.666400095965;981.6858605246252;20459.977001149884;21144.577108457746;21241.468963276042;21287.148514059263;21308.467288317606;938.1565224334216;941.8916824565263;943.2925306020485;950.847864341702;981.2738573455591;10568.048875601078;10975.231733778559;11026;11068.335899846035;11114;577561.1121944364;579562;581193;592313;593970;93960;128906;141518;142783;144803;973.3819024919227;980.6959842448864;1022.1822541966397;1022.539857064324;1022.8619136416637;18232.45302640923;18700;18745;18925.810741892405;18936.715949260495;43714.56285437105;53414.73292633512;55324.89350213054;56680.433195667516;58800;77719.61140194263;82806;84089;86812;86872.1312786864;6998.6150761708595;7422.777316680508;7587.696492140252;8078.474899131537;8094.757157285291;10797.352158870493;12513.249205047727;12920.612381628565;13485.056046076681;13567;10544.209184311361;10686.786264274515;10693.786124277627;10748.570057197938;11716.765664686489;11295.491702873225;11308.886911130783;11455.656330310105;11721.355325457182;11785.410729463663;11080.670319561705;11282.379469129277;11760.70598235051;11871.228370155912;11948.984336331161;8158.469699469515;8260.752177434686;8366.83266334682;8387.07742148351;8500.107488713596;8277.875831862406;10843.403612801372;11177;11464.025557827677;11704.88295117072 21:49:18 INFO - PROCESS | 1653 | |3;mootools.html;1354.2957662015758;1501.7146742118996;1541.4373753579944;1744.8865823721458;1921.3563456242166;7749.573773442458;8253.298469630081;8447.619857106421;8808.691695790649;8808.999999999984;8662.610182541785;8746.387752857303;8747.606357713903;8756.562171891395;8761.167689069543;2341.2156927429287;2371.8221133414977;2375.9406014849596;2379.2029670060465;2381.714194296687;2303.2974942642486;2337.1118974789556;2358.516504116652;2359.1507057459303;2363.397333679909;2317.4669825940045;2344;2346.6480027995767;2348.342464110052;2350.2009316832273;2288.5079707862724;2312.8843557822042;2319.7564255753155;2321.3268152235833;2333.9533209335887;2306.3542208181734;2310.145246258878;2316.791488766002;2321.9419514512274;2324.8721320327377;8701.564921753887;8760.386772925898;8800.8679869802;8842.381033327669;8848.557572121432;4906.730129842857;5036.84889453317;5202.03762303975;5416.891662166774;5418.160185171305;3525.9118522037156;3702.315071711742;3765.6234376562397;3768.5854555998817;3773.792441415721;1055.250771951915;1085.2403317677656;1090.9727256818564;1111.1000089927104;1113.4544073404054;11723.589674361356;11749.471273792615;11821.995130413925;11828.70428239301;11837.112216583791;4403.603675669141;4464.17412778637;4633.374494443275;4636.258198688193;4662.370579971661;9239.676611318571;9241.815163696689;9247.503174142943;9249.583768730423;9269.582868770787;5276.736163191825;5278.049951008778;5282.022825777243;5286.920696189561;5290.470952904756;4353.4122893408985;4355.773072313237;4460.464744230713;4600.056988317387;4953.72754498506;3796.2787070457075;3848.7690738555775;3863.864764733221;3895.3572660511263;3906.0820707133803;1162.3258510064143;1171.262104873941;1173.0732721150366;1182.9763404731857;1184.224333061846;825.8183199696057;874.6588830356167;903.3631289940577;908.8727578139066;938.6198589571235;4886.560209581084;5826.155207494974;6103.542234332443;6119.877602447926;6161.137440758297;427.8973046468825;435.7494440696549;438.5395334898408;449.22732899412955;449.3529317782383;3742.73800833939;3992.201559688074;4052.4124002019544;4099.467069280974;4101.91796164075;10546.789064218672;10871.891281087088;10972.780544389068;10977.12183025356;10981.956714112146;10932.234743567873;10933.835992460119;10933.945330273298;10939.234253602172;10949.069329107115;8267.62795674196;8343.749687509384;8357.456765310235;8364.414490985573;8367.665293388196;5787.2187254720075;5866.6186697864505;5960.403959604005;5998.790042348497;6019.247594050744;9223.58493867791;9456;9892.604295828087;10181.490925453845;10183.43991080468;9172.357934944757;10266.743331416774;10355.326903751233;10380.532876020447;10407.531661075118;5861.853453663693;6852;6942.201646810479;7015.438764898898;7104.360607545345;8303.418760686694;8321.6671333146;8409.075001749925;8414.53720045379;8424.368172386972;5908.970455147869;6366;7453.627318633939;7563.546187228784;7609.124950630749;6111.358307377839;7350.96324518374;7361.2638736125955;7396.371308438843;7433.331000210007;7026.894596581157;7153.070100886852;7159.284071593007;7162.925561165866;7174;4914.189158788759;4989.29091442174;5028.673136246132;5076.543111120017;5247.921281180709;5032.6980381177245;5117.155669314521;5178.559822414986;5244.318238629106;5258.316418865523 21:49:18 INFO - PROCESS | 1653 | |4;prototype.html;301.52810851018154;302.8970150148949;310.6008778719345;314.10949956872264;316.99524507132384;5351.635332990087;5424.430434804343;5462.317210348707;5467.699276539789;5492.670439773617;5051.797928082881;5210.374755029402;5211.947880521203;5229;5416.187571864222;3417.709494692954;4037.6971727120585;4137.482814648169;4152.190322887031;4168.8332466701295;4112.403701463286;4118.97940510297;4122.649574786146;4134.813933373005;4244.49066112067;3812;4066.8983275418054;4083.9795801020955;4098.549159592442;4099.631033207011;3394.8981530554247;4049.6557792587664;4072.8778136655947;4098.303288440972;4102.804999325362;3070.201747545632;3490.586312543424;3497.300539892032;3501.159721666807;3531.205478767257;5019.196928491424;5275.525202731734;5278.495628745827;5297.543175626695;5326.147816349404;4406.317020861771;4609.239475486541;4624.774434774788;4649.511801260853;4672.556107169813;5626.971865140689;5656.773729050833;5656.822123482448;5675.233843431167;5693.829185124453;3407.9318413631836;3609.7112231021456;3710.5862606401906;3823.6558709715982;3857.7685338879755;4688.29675548667;5955.434233747787;5991.6405015698765;5998.580099393044;6002.0289956471825;3214.5660335854827;3840.7325582557687;3851.8651847185215;3852.536036306242;3865.6520913117956;3687.2256826066828;3941.7832019239218;3958.752992807314;3961.6632586229916;3976.9204615908097;4058.553559108496;4220.451341325608;4558.133954548625;4606.95393046072;4623.791929363187;5612.691301978431;5664.85837854057;5686.260786097863;5691.430856914359;5706.828795136153;1274.337344580813;1282.3716378974236;1309.646395473236;1346.2528296795176;1348.5549768576345;851.17010914358;875.1948207648978;884.2307192742355;886.9556522173865;896.852019416802;4478.865634030984;5222.3733152022005;5234.528892399626;5250.579953603779;5294.49702278283;5662.235598194274;5710.685912274865;5810.448007439287;5849.303701926394;5863.706814659336;4889.362063708698;5181.637285390062;5203.16749320107;5251.76367063483;5253.870417860178;2885.4851203118533;3023.9244018899262;3033.119465931137;3056.9847150764103;3060.3879224155257;4702.435707715028;5453.609329621001;5778.104393818965;6021.939780602225;6086.786962456293;5001.524855138756;5005.774740136702;5274.393444753826;5650.519705824969;6049.18336024631;6089.5432842537875;6096.969515152396;6193.318734939153;6217.533684973645;6233.875322493615;5654.604177707521;5785.1652690182345;5880.794172203952;5898.852528686817;5928.318243401892;5821.98115329824;5830.737616807339;5832.533397328119;5863.648181109147;5877.764889404376;5018.297438358706;5727.713614319351;6057.7879774207695;6158.846028849314;6169.5064394849205;3297.9505307419927;3314.801111933292;3866.7873266969464;3913.021744563859;3965;4937.407511098691;5173.63713586157;5384.542313903362;5892.145638882424;5948.226730525004;5246.790128394821;5360;5665.393918797399;5670.092785154356;5826.970865145817;4747.382840230748;5006.574441172541;5700.572457065819;5733.541316694738;5741.74162162712;4921.384826896638;5290.7883684653725;5500.339959204921;5518.944810551843;5684.175794509855;4721.929171062368;4796.664233503621;4809.663323567456;4917.950820491749;5330.680159190306;5294.829120060756;5510.531604813636;5641.294838145232;5644.339679178609;5664.433556644302 21:49:18 INFO - PROCESS | 1653 | |5;yui.html;699.8495323505447;711.5943911970176;724.7898109548231;801.4510060608487;813.4793732011514;15634.843651563495;16559.66880662386;16560;16588.751168732462;16594.83405165948;14778;14830.851691483082;14838.629034274121;14846.925765371157;14857.331420086095;680.8944613584903;698.34006863514;702.3994484715571;838.0404436919723;838.367032890167;849.6516428264382;851.3572252949015;863.5811631358818;864.7838040489878;865.4980111535294;924.1267002682467;924.8890133184027;926.3422969691527;927.285989787863;927.4296307770707;715.0954043135438;739.4528049243521;915.0620613870802;915.638322862469;915.7802127489422;657.9111819904299;670.7283550162192;673.2829536543566;678.2979616097316;679.4734081087148;14858.554243372717;14870;14896.553103406915;14899.553013409615;14903.03130296538;13332.866671333259;13471.656904596504;13718.519851805238;13851.16892986414;14293.213873236968;12235.510579576896;12621.242725436503;12670.429830657642;12725.42735576892;13071.150375225676;4473.9552604473865;4487.663425243087;4529.297958816389;4614.584687378118;4619.214733495314;88954.11045889523;121255;123350.14974775385;123456.9999999991;123714.38142809228;10816;10833.891661083446;10841.512131954081;10872.402017888933;10970.616028438966;21410.678839817414;21568.568628627654;21571.352859414244;21576.568468630852;21606.351809445743;7668.424868134912;7759.612019399007;7772.414032615774;7964.20357964199;8004.439689221698;4252.638525725348;4280.143971205709;4295.763732994716;4307.353896915488;4451.443569553806;3019.8641061152302;3439.9312013760086;3549.0772399175885;3554.964450355515;3558.733095017884;651.2770824384929;657.3886285754198;667.7095463473405;672.5897202706345;691.8201267670441;421.82915919052834;556.5686592890543;603.8399824046631;603.8912995660824;617.8918689229367;8277.379196560161;8902.688405905763;8985.415947963362;9018.215008532028;9118.680846170351;597.8505373656586;600.5525883216944;610.1701685707434;621.142822904398;642.1267076775581;3497.667721566447;4311.48262208537;4333.826646934087;4457.869697633195;4514.142312960527;105119.4744026275;105467;147961;167184;171341.14329428523;121892;135404;144019.6769500729;144189.5581044197;149893;113541.86458135313;113950.76787713925;115222;115858.1520570744;117497.9999999983;7847.979762630822;8452.661893524437;8458.915410845813;8635;8775.42959707617;45454;64621.67689161524;65512.67243663751;66440.66779666071;68971;77416.2258377409;84007;86438;98402.5079874596;107221.46389268004;10720.356778592997;13285.601431957057;13384.799228011783;13772.03595748288;13908.791368129283;14873.92563037178;14985;15209.695806083595;15218.619534511727;15635.92182039128;13927.576601671326;14026.649333766738;14047.929760351133;14148.929255353658;14246.857531424968;13799.103058301178;13846.584602461942;14059.718805624036;14199;14671.266436678337;13445.529406470727;13792.379342929391;13802.585922422344;13809.378577963815;14259.144451332953;11293.661190163979;11325.886741132812;11425.885741142481;11791.882081179421;11950.581729639422;9880.654177103766;10464.267501275142;10715.464226788474;11428.542858285908;11457.255278406878 21:49:18 INFO - PROCESS | 1653 | __end_tp_report 21:49:18 INFO - PROCESS | 1653 | __start_cc_report 21:49:18 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1207 21:49:18 INFO - PROCESS | 1653 | __end_cc_report 21:49:18 INFO - PROCESS | 1653 | __startTimestamp1473655758204__endTimestamp 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | ------- Summary: start ------- 21:49:18 INFO - PROCESS | 1653 | Number of tests: 6 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5771.64 Median:6071.48 stddev:653.93 (10.8%) stddev-sans-first:617.76 21:49:18 INFO - PROCESS | 1653 | Values: 2964.7 3380.0 3395.1 3428.1 3464.0 5639.5 5740.9 5753.2 5822.4 5852.2 5718.8 5756.3 5761.2 5769.7 5772.4 5190.8 5233.3 5239.7 5249.4 5290.4 5038.7 5335.4 5378.5 5383.8 5437.3 5320.3 5329.4 5376.7 5396.1 5398.3 4142.6 4380.4 5198.2 5344.7 5361.5 5207.1 5254.2 5282.6 5322.1 5336.4 5635.2 5687.8 5696.7 5704.3 5823.5 6155.8 6169.9 6171.3 6175.3 6197.4 5754.7 6183.1 6218.2 6247.8 6262.7 5732.8 5789.9 5859.9 5875.9 5882.2 6224.0 6233.2 6251.9 6280.4 6367.1 6212.4 6232.3 6233.0 6237.2 6248.7 6128.9 6251.7 6261.9 6281.4 6288.9 6101.4 6140.0 6161.8 6232.0 6239.7 6280.7 6282.5 6283.2 6284.9 6289.2 4980.2 4985.6 4985.9 4993.4 5019.3 4392.9 4395.3 4434.0 4464.4 4479.9 5722.5 5751.7 5768.2 5775.4 5783.7 6187.2 6229.1 6242.4 6246.5 6272.9 5784.3 5848.4 5861.4 5863.3 5893.9 5492.3 5509.1 5509.3 5511.3 5513.8 6228.9 6259.5 6261.8 6262.6 6319.8 6193.1 6239.0 6245.8 6250.9 6258.8 6388.9 6401.8 6415.4 6416.7 6427.6 5360.7 6093.0 6112.2 6115.4 6221.8 6213.6 6222.8 6236.7 6243.0 6268.7 6190.5 6190.7 6192.3 6204.2 6215.1 5888.5 6047.4 6098.8 6124.0 6135.0 6001.2 6071.5 6079.8 6082.5 6084.6 6220.5 6224.4 6235.5 6241.1 6243.7 5848.6 5920.3 6105.0 6105.3 6115.1 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:72582.03 Median:20764.22 stddev:159442.56 (767.9%) stddev-sans-first:159797.98 21:49:18 INFO - PROCESS | 1653 | Values: 498.3 519.8 526.6 529.3 556.6 24032.8 40997.0 42116.2 42118.6 43432.6 42344.0 42801.4 42873.6 42960.0 42972.6 8642.6 9643.0 9824.0 9825.6 9845.9 8658.5 11923.5 12146.0 12161.0 12332.8 12148.0 12245.0 12258.6 12267.4 12280.3 12150.3 12226.1 12229.5 12240.6 12261.4 11918.0 11960.3 12002.1 12030.2 12043.8 40165.6 40361.4 40453.8 40624.4 40707.4 2754.5 3256.2 3715.8 4037.3 4115.8 30840.5 31562.5 31776.0 32017.4 32485.0 3216.0 3255.7 3340.7 3353.5 3378.5 516521.0 519003.0 519309.0 520218.4 520741.0 59568.7 59923.1 59984.1 60043.0 60126.0 62974.7 63051.0 63096.7 63164.0 63165.0 16422.9 16610.1 16623.8 16633.8 16657.8 11711.3 11906.5 11940.6 12833.9 12899.9 14902.9 15002.0 15064.3 16665.3 17077.7 5315.6 6217.7 6389.1 6508.5 6550.8 728.6 928.9 1024.5 1028.8 1116.0 29811.6 29957.7 29964.7 30738.7 30931.4 873.5 1026.7 1032.5 1057.7 1068.3 15628.6 15751.5 15762.6 15841.5 16149.4 654823.0 661852.0 662162.0 662246.0 683390.0 45395.3 48513.8 56906.0 57372.1 57507.4 1327.7 1339.5 1377.8 1378.0 1379.7 29100.8 29565.6 31719.5 31769.5 32638.8 51998.7 52422.7 52563.5 52581.5 52700.0 571523.0 599538.0 602839.0 603465.0 603878.0 26276.5 27095.0 27111.2 40698.2 41065.8 48441.5 48794.5 49589.0 49829.5 49876.5 32206.2 37939.9 38596.4 39657.2 40989.6 32439.5 39203.0 39384.8 40314.2 40904.8 36212.3 37812.6 39066.2 39296.0 39483.8 26822.5 33416.5 34331.5 34741.5 35104.9 23295.9 24440.0 25364.1 26159.4 26322.9 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34844.14 Median:24584.46 stddev:97945.49 (398.4%) stddev-sans-first:98186.00 21:49:18 INFO - PROCESS | 1653 | Values: 345.7 353.6 364.3 368.9 376.7 13816.7 14525.1 15868.8 19914.0 19980.8 19022.0 20478.6 20484.6 20675.7 20929.1 5711.9 6023.8 6029.2 6033.0 6043.2 4637.5 5927.8 5964.2 5966.4 5995.6 5636.4 5937.9 5941.3 5941.6 5954.8 5793.7 5904.4 5905.6 5906.1 5915.1 4976.0 5011.3 5142.6 5143.8 5166.1 19695.7 24579.6 24589.3 24949.1 25005.6 2166.2 2170.9 2203.9 2391.4 2987.0 13670.0 14848.0 14966.7 15219.8 15490.9 1674.3 1829.1 1878.0 1889.8 1930.0 91716.0 119255.2 121610.0 124723.0 126857.4 11320.0 11324.8 11355.0 11457.3 11715.9 14357.1 14411.9 14413.3 14425.0 14426.0 8287.0 8504.8 8552.3 8685.5 8709.1 8540.4 8541.7 8564.5 8739.3 8783.5 3667.8 4466.8 4470.4 4537.1 4553.0 1835.7 1895.7 1896.1 1896.4 1897.9 812.4 845.3 917.9 926.7 981.7 20460.0 21144.6 21241.5 21287.1 21308.5 938.2 941.9 943.3 950.8 981.3 10568.0 10975.2 11026.0 11068.3 11114.0 577561.1 579562.0 581193.0 592313.0 593970.0 93960.0 128906.0 141518.0 142783.0 144803.0 973.4 980.7 1022.2 1022.5 1022.9 18232.5 18700.0 18745.0 18925.8 18936.7 43714.6 53414.7 55324.9 56680.4 58800.0 77719.6 82806.0 84089.0 86812.0 86872.1 6998.6 7422.8 7587.7 8078.5 8094.8 10797.4 12513.2 12920.6 13485.1 13567.0 10544.2 10686.8 10693.8 10748.6 11716.8 11295.5 11308.9 11455.7 11721.4 11785.4 11080.7 11282.4 11760.7 11871.2 11949.0 8158.5 8260.8 8366.8 8387.1 8500.1 8277.9 10843.4 11177.0 11464.0 11704.9 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5650.53 Median:4896.65 stddev:3210.16 (65.6%) stddev-sans-first:3202.93 21:49:18 INFO - PROCESS | 1653 | Values: 1354.3 1501.7 1541.4 1744.9 1921.4 7749.6 8253.3 8447.6 8808.7 8809.0 8662.6 8746.4 8747.6 8756.6 8761.2 2341.2 2371.8 2375.9 2379.2 2381.7 2303.3 2337.1 2358.5 2359.2 2363.4 2317.5 2344.0 2346.6 2348.3 2350.2 2288.5 2312.9 2319.8 2321.3 2334.0 2306.4 2310.1 2316.8 2321.9 2324.9 8701.6 8760.4 8800.9 8842.4 8848.6 4906.7 5036.8 5202.0 5416.9 5418.2 3525.9 3702.3 3765.6 3768.6 3773.8 1055.3 1085.2 1091.0 1111.1 1113.5 11723.6 11749.5 11822.0 11828.7 11837.1 4403.6 4464.2 4633.4 4636.3 4662.4 9239.7 9241.8 9247.5 9249.6 9269.6 5276.7 5278.0 5282.0 5286.9 5290.5 4353.4 4355.8 4460.5 4600.1 4953.7 3796.3 3848.8 3863.9 3895.4 3906.1 1162.3 1171.3 1173.1 1183.0 1184.2 825.8 874.7 903.4 908.9 938.6 4886.6 5826.2 6103.5 6119.9 6161.1 427.9 435.7 438.5 449.2 449.4 3742.7 3992.2 4052.4 4099.5 4101.9 10546.8 10871.9 10972.8 10977.1 10982.0 10932.2 10933.8 10933.9 10939.2 10949.1 8267.6 8343.7 8357.5 8364.4 8367.7 5787.2 5866.6 5960.4 5998.8 6019.2 9223.6 9456.0 9892.6 10181.5 10183.4 9172.4 10266.7 10355.3 10380.5 10407.5 5861.9 6852.0 6942.2 7015.4 7104.4 8303.4 8321.7 8409.1 8414.5 8424.4 5909.0 6366.0 7453.6 7563.5 7609.1 6111.4 7351.0 7361.3 7396.4 7433.3 7026.9 7153.1 7159.3 7162.9 7174.0 4914.2 4989.3 5028.7 5076.5 5247.9 5032.7 5117.2 5178.6 5244.3 5258.3 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4581.96 Median:5211.16 stddev:1426.90 (27.4%) stddev-sans-first:1394.27 21:49:18 INFO - PROCESS | 1653 | Values: 301.5 302.9 310.6 314.1 317.0 5351.6 5424.4 5462.3 5467.7 5492.7 5051.8 5210.4 5211.9 5229.0 5416.2 3417.7 4037.7 4137.5 4152.2 4168.8 4112.4 4119.0 4122.6 4134.8 4244.5 3812.0 4066.9 4084.0 4098.5 4099.6 3394.9 4049.7 4072.9 4098.3 4102.8 3070.2 3490.6 3497.3 3501.2 3531.2 5019.2 5275.5 5278.5 5297.5 5326.1 4406.3 4609.2 4624.8 4649.5 4672.6 5627.0 5656.8 5656.8 5675.2 5693.8 3407.9 3609.7 3710.6 3823.7 3857.8 4688.3 5955.4 5991.6 5998.6 6002.0 3214.6 3840.7 3851.9 3852.5 3865.7 3687.2 3941.8 3958.8 3961.7 3976.9 4058.6 4220.5 4558.1 4607.0 4623.8 5612.7 5664.9 5686.3 5691.4 5706.8 1274.3 1282.4 1309.6 1346.3 1348.6 851.2 875.2 884.2 887.0 896.9 4478.9 5222.4 5234.5 5250.6 5294.5 5662.2 5710.7 5810.4 5849.3 5863.7 4889.4 5181.6 5203.2 5251.8 5253.9 2885.5 3023.9 3033.1 3057.0 3060.4 4702.4 5453.6 5778.1 6021.9 6086.8 5001.5 5005.8 5274.4 5650.5 6049.2 6089.5 6097.0 6193.3 6217.5 6233.9 5654.6 5785.2 5880.8 5898.9 5928.3 5822.0 5830.7 5832.5 5863.6 5877.8 5018.3 5727.7 6057.8 6158.8 6169.5 3298.0 3314.8 3866.8 3913.0 3965.0 4937.4 5173.6 5384.5 5892.1 5948.2 5246.8 5360.0 5665.4 5670.1 5827.0 4747.4 5006.6 5700.6 5733.5 5741.7 4921.4 5290.8 5500.3 5518.9 5684.2 4721.9 4796.7 4809.7 4918.0 5330.7 5294.8 5510.5 5641.3 5644.3 5664.4 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25236.38 Median:3468.80 stddev:40660.23 (1172.2%) stddev-sans-first:40732.56 21:49:18 INFO - PROCESS | 1653 | Values: 699.8 711.6 724.8 801.5 813.5 15634.8 16559.7 16560.0 16588.8 16594.8 14778.0 14830.9 14838.6 14846.9 14857.3 680.9 698.3 702.4 838.0 838.4 849.7 851.4 863.6 864.8 865.5 924.1 924.9 926.3 927.3 927.4 715.1 739.5 915.1 915.6 915.8 657.9 670.7 673.3 678.3 679.5 14858.6 14870.0 14896.6 14899.6 14903.0 13332.9 13471.7 13718.5 13851.2 14293.2 12235.5 12621.2 12670.4 12725.4 13071.2 4474.0 4487.7 4529.3 4614.6 4619.2 88954.1 121255.0 123350.1 123457.0 123714.4 10816.0 10833.9 10841.5 10872.4 10970.6 21410.7 21568.6 21571.4 21576.6 21606.4 7668.4 7759.6 7772.4 7964.2 8004.4 4252.6 4280.1 4295.8 4307.4 4451.4 3019.9 3439.9 3549.1 3555.0 3558.7 651.3 657.4 667.7 672.6 691.8 421.8 556.6 603.8 603.9 617.9 8277.4 8902.7 8985.4 9018.2 9118.7 597.9 600.6 610.2 621.1 642.1 3497.7 4311.5 4333.8 4457.9 4514.1 105119.5 105467.0 147961.0 167184.0 171341.1 121892.0 135404.0 144019.7 144189.6 149893.0 113541.9 113950.8 115222.0 115858.2 117498.0 7848.0 8452.7 8458.9 8635.0 8775.4 45454.0 64621.7 65512.7 66440.7 68971.0 77416.2 84007.0 86438.0 98402.5 107221.5 10720.4 13285.6 13384.8 13772.0 13908.8 14873.9 14985.0 15209.7 15218.6 15635.9 13927.6 14026.6 14047.9 14148.9 14246.9 13799.1 13846.6 14059.7 14199.0 14671.3 13445.5 13792.4 13802.6 13809.4 14259.1 11293.7 11325.9 11425.9 11791.9 11950.6 9880.7 10464.3 10715.5 11428.5 11457.3 21:49:18 INFO - PROCESS | 1653 | -------- Summary: end -------- 21:49:18 INFO - PROCESS | 1653 | 21:49:18 INFO - TEST-INFO | 1653: exit 0 21:49:18 INFO - TEST-OK | dromaeo_css | took 1117864ms 21:49:18 INFO - TEST-START | kraken 21:49:18 INFO - operating with platform_type : mac_ 21:49:18 INFO - Initialising browser for kraken test... 21:49:18 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpAAusmT/profile http://localhost:49273/getInfo.html) 21:49:20 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 21:49:20 INFO - PROCESS | 1659 | colorDepth:24 21:49:20 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 21:49:20 INFO - PROCESS | 1659 | __metrics 21:49:21 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:49:21 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:49:21 INFO - TEST-INFO | 1659: exit 0 21:49:21 INFO - Browser initialized. 21:49:21 INFO - Running cycle 1/1 for kraken test... 21:49:21 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpAAusmT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:33 INFO - PROCESS | 1660 | RSS: Main: 232886272 21:49:33 INFO - PROCESS | 1660 | 21:49:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 21:49:37 INFO - PROCESS | 1660 | RSS: Main: 291463168 21:49:37 INFO - PROCESS | 1660 | 21:49:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 21:49:40 INFO - PROCESS | 1660 | RSS: Main: 288264192 21:49:40 INFO - PROCESS | 1660 | 21:49:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 21:49:44 INFO - PROCESS | 1660 | RSS: Main: 376057856 21:49:44 INFO - PROCESS | 1660 | 21:49:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 21:49:47 INFO - PROCESS | 1660 | RSS: Main: 280027136 21:49:47 INFO - PROCESS | 1660 | 21:49:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:49:50 INFO - PROCESS | 1660 | RSS: Main: 284774400 21:49:50 INFO - PROCESS | 1660 | 21:49:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 21:49:53 INFO - PROCESS | 1660 | RSS: Main: 322723840 21:49:53 INFO - PROCESS | 1660 | 21:49:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 21:49:57 INFO - PROCESS | 1660 | RSS: Main: 323440640 21:49:57 INFO - PROCESS | 1660 | 21:49:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 21:50:00 INFO - PROCESS | 1660 | RSS: Main: 321937408 21:50:00 INFO - PROCESS | 1660 | 21:50:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:50:02 INFO - PROCESS | 1660 | RSS: Main: 297885696 21:50:02 INFO - PROCESS | 1660 | 21:50:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:50:05 INFO - PROCESS | 1660 | RSS: Main: 304214016 21:50:05 INFO - PROCESS | 1660 | 21:50:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:50:08 INFO - PROCESS | 1660 | RSS: Main: 308244480 21:50:08 INFO - PROCESS | 1660 | 21:50:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:50:11 INFO - PROCESS | 1660 | RSS: Main: 311615488 21:50:11 INFO - PROCESS | 1660 | 21:50:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:50:15 INFO - PROCESS | 1660 | RSS: Main: 312885248 21:50:15 INFO - PROCESS | 1660 | 21:50:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 21:50:17 INFO - PROCESS | 1660 | RSS: Main: 357625856 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | __start_tp_report 21:50:17 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 21:50:17 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 21:50:17 INFO - PROCESS | 1660 | |i|pagename|runs| 21:50:17 INFO - PROCESS | 1660 | |0;ai-astar;113;113;115;112;112;113;113;113;111;123 21:50:17 INFO - PROCESS | 1660 | |1;audio-beat-detection;147;113;131;160;109;155;161;114;157;107 21:50:17 INFO - PROCESS | 1660 | |2;audio-dft;193;192;191;192;205;277;194;230;272;198 21:50:17 INFO - PROCESS | 1660 | |3;audio-fft;74;77;74;151;58;57;56;68;135;57 21:50:17 INFO - PROCESS | 1660 | |4;audio-oscillator;84;86;131;70;75;77;115;67;71;116 21:50:17 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;89;85;87;86;88;85;87;85;88;85 21:50:17 INFO - PROCESS | 1660 | |6;imaging-darkroom;97;98;98;97;97;97;96;96;96;97 21:50:17 INFO - PROCESS | 1660 | |7;imaging-desaturate;85;83;85;84;84;83;85;84;84;87 21:50:17 INFO - PROCESS | 1660 | |8;json-parse-financial;52;52;56;53;53;55;54;54;53;53 21:50:17 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;64;71;73;74;74;68;69;68;69 21:50:17 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;86;88;86;87;87;90;88;105;91 21:50:17 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;144;155;150;152;139;138;139;136;153;137 21:50:17 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;167;167;171;166;167;168;166;166;165;231 21:50:17 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;69;67;66;69;68;68;67;67;66;67 21:50:17 INFO - PROCESS | 1660 | __end_tp_report 21:50:17 INFO - PROCESS | 1660 | __start_cc_report 21:50:17 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2057 21:50:17 INFO - PROCESS | 1660 | __end_cc_report 21:50:17 INFO - PROCESS | 1660 | __startTimestamp1473655817195__endTimestamp 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | ------- Summary: start ------- 21:50:17 INFO - PROCESS | 1660 | Number of tests: 14 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:113.80 Median:113.00 stddev:3.39 (3.0%) stddev-sans-first:3.59 21:50:17 INFO - PROCESS | 1660 | Values: 113.0 113.0 115.0 112.0 112.0 113.0 113.0 113.0 111.0 123.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:151.00 stddev:22.92 (15.2%) stddev-sans-first:23.92 21:50:17 INFO - PROCESS | 1660 | Values: 147.0 113.0 131.0 160.0 109.0 155.0 161.0 114.0 157.0 107.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:214.40 Median:201.50 stddev:33.77 (16.8%) stddev-sans-first:34.92 21:50:17 INFO - PROCESS | 1660 | Values: 193.0 192.0 191.0 192.0 205.0 277.0 194.0 230.0 272.0 198.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:80.70 Median:63.00 stddev:34.00 (54.0%) stddev-sans-first:35.98 21:50:17 INFO - PROCESS | 1660 | Values: 74.0 77.0 74.0 151.0 58.0 57.0 56.0 68.0 135.0 57.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:73.00 stddev:22.88 (31.3%) stddev-sans-first:24.19 21:50:17 INFO - PROCESS | 1660 | Values: 84.0 86.0 131.0 70.0 75.0 77.0 115.0 67.0 71.0 116.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.30 21:50:17 INFO - PROCESS | 1660 | Values: 89.0 85.0 87.0 86.0 88.0 85.0 87.0 85.0 88.0 85.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:97.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 21:50:17 INFO - PROCESS | 1660 | Values: 97.0 98.0 98.0 97.0 97.0 97.0 96.0 96.0 96.0 97.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.22 21:50:17 INFO - PROCESS | 1660 | Values: 85.0 83.0 85.0 84.0 84.0 83.0 85.0 84.0 84.0 87.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.50 stddev:1.27 (2.4%) stddev-sans-first:1.22 21:50:17 INFO - PROCESS | 1660 | Values: 52.0 52.0 56.0 53.0 53.0 55.0 54.0 54.0 53.0 53.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:3.66 (5.2%) stddev-sans-first:3.32 21:50:17 INFO - PROCESS | 1660 | Values: 64.0 64.0 71.0 73.0 74.0 74.0 68.0 69.0 68.0 69.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:87.50 stddev:5.74 (6.6%) stddev-sans-first:5.95 21:50:17 INFO - PROCESS | 1660 | Values: 86.0 86.0 88.0 86.0 87.0 87.0 90.0 88.0 105.0 91.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:147.00 stddev:7.45 (5.1%) stddev-sans-first:7.91 21:50:17 INFO - PROCESS | 1660 | Values: 144.0 155.0 150.0 152.0 139.0 138.0 139.0 136.0 153.0 137.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:167.00 stddev:20.30 (12.2%) stddev-sans-first:21.40 21:50:17 INFO - PROCESS | 1660 | Values: 167.0 167.0 171.0 166.0 167.0 168.0 166.0 166.0 165.0 231.0 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.07 (1.6%) stddev-sans-first:0.97 21:50:17 INFO - PROCESS | 1660 | Values: 69.0 67.0 66.0 69.0 68.0 68.0 67.0 67.0 66.0 67.0 21:50:17 INFO - PROCESS | 1660 | -------- Summary: end -------- 21:50:17 INFO - PROCESS | 1660 | 21:50:17 INFO - TEST-INFO | 1660: exit 0 21:50:18 INFO - TEST-OK | kraken | took 59150ms 21:50:18 INFO - SUITE-END | took 1177s 21:50:18 INFO - Completed test suite (00:19:37) 21:50:18 INFO - javascript benchmark 21:50:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2964.718351756583, 3379.9831000845, 3395.0663567518927, 3428.1258279138815, 3464.0, 5639.464250896169, 5740.942590574093, 5753.165790960314, 5822.359540450552, 5852.151438041481, 5718.799842005531, 5756.338021127572, 5761.164631128494, 5769.7115144242825, 5772.393898640646, 5190.8442746717565, 5233.345831771028, 5239.659422137549, 5249.396319423277, 5290.418054014055, 5038.697678139305, 5335.386430560497, 5378.542823859977, 5383.757730902099, 5437.34751829781, 5320.255164276985, 5329.387120481136, 5376.677399356032, 5396.082665946798, 5398.2712333834825, 4142.565030671796, 4380.364847097153, 5198.16829307309, 5344.732763361836, 5361.51746342831, 5207.062728708831, 5254.159334506498, 5282.603804714662, 5322.0952438085615, 5336.439673834269, 5635.21107045014, 5687.800926967537, 5696.743646535874, 5704.258446401941, 5823.475887170132, 6155.753769849201, 6169.907451388232, 6171.290301615282, 6175.2898416681755, 6197.442230199259, 5754.683492407916, 6183.072539119124, 6218.191635087455, 6247.781327653556, 6262.655553944532, 5732.828015159552, 5789.94210057894, 5859.8535036624, 5875.941240587625, 5882.205902203191, 6223.96888015566, 6233.2208473940755, 6251.906221406683, 6280.371962803683, 6367.140436041168, 6212.378762123842, 6232.283287421914, 6233.002719564848, 6237.157983672146, 6248.656323902229, 6128.908066379008, 6251.6561589112125, 6261.906071408933, 6281.371862813682, 6288.9056664150075, 6101.368489499298, 6139.9828168395225, 6161.784337548164, 6232.0, 6239.688015599202, 6280.7487700491465, 6282.465990390777, 6283.151774510474, 6284.937150628527, 6289.182406287154, 4980.178270585386, 4985.551300382983, 4985.900281994413, 4993.375828021497, 5019.272205530177, 4392.868213953587, 4395.252807022814, 4434.002349471408, 4464.441944756905, 4479.865604031885, 5722.513586345124, 5751.654900705971, 5768.221290125779, 5775.422457754191, 5783.652980821164, 6187.226596675416, 6229.065640154013, 6242.375762423812, 6246.531510136758, 6272.937270627235, 5784.334801497882, 5848.385919478478, 5861.384554621789, 5863.296404431496, 5893.852653683606, 5492.258545096441, 5509.090999985037, 5509.283793106871, 5511.311086114236, 5513.7794488220825, 6228.906566401508, 6259.530535209969, 6261.780837670751, 6262.5929314594405, 6319.778807741707, 6193.1329613855, 6239.032949892774, 6245.781397651061, 6250.937490625218, 6258.780942666985, 6388.9041664374145, 6401.839954001187, 6415.390537898892, 6416.679166041773, 6427.646479443675, 5360.686631775084, 6093.0, 6112.1995365628145, 6115.449609535163, 6221.844453888508, 6213.627182369072, 6222.844428889314, 6236.65698386575, 6243.032329988859, 6268.655223962726, 6190.473809726223, 6190.721417536131, 6192.256929168529, 6204.193454850841, 6215.129881816459, 5888.4700376966275, 6047.395260473917, 6098.78654247117, 6124.0, 6135.0, 6001.159837622649, 6071.483923866344, 6079.756809727738, 6082.5438092142385, 6084.574079814548, 6220.502359811295, 6224.408681175281, 6235.532335074797, 6241.063840423791, 6243.656598887105, 5848.64908105515, 5920.348761636131, 6105.023196288573, 6105.267367915879, 6115.1438798569125], "value": 5728.764455968026, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.28744894800445, 519.7894852584703, 526.6129394894751, 529.3303970477347, 556.5797822643904, 24032.759672403292, 40996.97507562312, 42116.157676846444, 42118.57881421185, 43432.56567434325, 42344.0, 42801.35797963033, 42873.57126428735, 42960.0, 42972.57027429725, 8642.611082501271, 9643.035696430354, 9823.95088024559, 9825.606975720963, 9845.901540984605, 8658.523781192032, 11923.463444145034, 12146.028317734561, 12161.0, 12332.815007774845, 12148.0, 12245.020398368153, 12258.57095001675, 12267.386630668476, 12280.324582147978, 12150.27098374096, 12226.083043771752, 12229.510819567207, 12240.571579994747, 12261.448234829453, 11917.986971107466, 11960.282383057045, 12002.099842511847, 12030.157888947777, 12043.819342709867, 40165.59834401648, 40361.39457908134, 40453.79773101116, 40624.39063414051, 40707.38938915919, 2754.5454999924987, 3256.2022304535303, 3715.8142092895246, 4037.262451869333, 4115.794210289474, 30840.53739193914, 31562.545260224477, 31776.0, 32017.359652806812, 32485.0, 3215.9839200803845, 3255.7069863712145, 3340.682635149657, 3353.496975453677, 3378.5101160331615, 516521.0, 519003.0, 519309.0, 520218.3770704097, 520741.0, 59568.702156488944, 59923.101153482734, 59984.10023849646, 60043.0, 60126.0, 62974.68512657408, 63051.0, 63096.68451657712, 63164.0, 63165.0, 16422.93250938685, 16610.086445245626, 16623.83376166247, 16633.83366166323, 16657.750133748003, 11711.297322160528, 11906.523739050514, 11940.582079627346, 12833.871661283454, 12899.871001290056, 14902.850971490363, 15002.024868383738, 15064.322105505278, 16665.25006374716, 17077.7438338425, 5315.6013299002725, 6217.725366299897, 6389.073584330246, 6508.479321654257, 6550.7707230246715, 728.6065524616648, 928.8560273157672, 1024.4672770159625, 1028.7582418131733, 1116.0, 29811.552826707615, 29957.700422995928, 29964.700352996628, 30738.692613073585, 30931.381372372427, 873.5239294584467, 1026.743314171457, 1032.4837581209395, 1057.746140926172, 1068.326954018963, 15628.609284767972, 15751.527454176394, 15762.605934851721, 15841.52475425739, 16149.434769783002, 654823.0, 661852.0, 662162.0, 662246.0, 683390.0, 45395.31907021398, 48513.7871553214, 56906.0, 57372.139417908766, 57507.42492575021, 1327.707904261061, 1339.4776037345443, 1377.7726675098697, 1377.986220137826, 1379.6688794689203, 29100.8359665611, 29565.556516651835, 31719.52420713645, 31769.523457147698, 32638.83680581582, 51998.740006299726, 52422.73788631032, 52563.47436525586, 52581.47418525919, 52700.0, 571523.0, 599538.0, 602839.0, 603465.0, 603878.0, 26276.474470510864, 27095.0, 27111.1866644001, 40698.186036278516, 41065.79467102645, 48441.51558484511, 48794.512054879, 49589.00821983612, 49829.50170498249, 49876.50123498864, 32206.19484512906, 37939.86180414592, 38596.421053683654, 39657.2068558633, 40989.59010409858, 32439.513407298437, 39203.0, 39384.80307598443, 40314.1937161261, 40904.79547602243, 36212.27575448424, 37812.622773907075, 39066.2186756269, 39296.0, 39483.802580986914, 26822.463550729266, 33416.49875251922, 34331.485027724106, 34741.47887781635, 35104.94685159449, 23295.87270014918, 24440.022399103837, 25364.112256071687, 26159.422442734754, 26322.86838565795], "value": 20484.922683897872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.6508925984756, 353.64812012048014, 364.28236374342544, 368.9354362986477, 376.668531692111, 13816.654583635402, 14525.055871368353, 15868.809839262058, 19914.0, 19980.800191998074, 19022.0, 20478.590428191463, 20484.59581190044, 20675.689864652042, 20929.05819238134, 5711.8572035699235, 6023.789167379143, 6029.186059881926, 6032.9698351508405, 6043.1841701370195, 4637.489876113625, 5927.792527261547, 5964.19483369746, 5966.403359664042, 5995.61028533144, 5636.351819540744, 5937.851553711149, 5941.287045554518, 5941.613795103304, 5954.821355359346, 5793.71031448428, 5904.409559044061, 5905.645661260317, 5906.084556893688, 5915.053591425372, 4975.950240497585, 5011.323471331397, 5142.588592912558, 5143.8456846294675, 5166.147585648364, 19695.704564431544, 24579.63130553043, 24589.295177530457, 24949.126780562594, 25005.62491562628, 2166.2309879992536, 2170.9240176593744, 2203.900824462887, 2391.4235851408625, 2987.0292155049697, 13669.974751893647, 14847.960642755012, 14966.735626367425, 15219.847801521953, 15490.91563590549, 1674.3051633572054, 1829.1128802530761, 1877.9624407511774, 1889.763779527559, 1930.0349825087455, 91716.0, 119255.21117183249, 121610.0, 124723.0, 126857.36571317085, 11320.0, 11324.830127548094, 11355.034822040054, 11457.312561246352, 11715.941420292844, 14357.066790658573, 14411.919106067087, 14413.279336033156, 14424.990250682351, 14426.0, 8287.0, 8504.787380315542, 8552.315814734931, 8685.522296273766, 8709.12908709124, 8540.44487108336, 8541.658333666708, 8564.52895090776, 8739.30085593151, 8783.5169065702, 3667.8166091695307, 4466.843660471933, 4470.351798989128, 4537.115262523776, 4552.9772351138035, 1835.7062869940746, 1895.687211610069, 1896.0898768590878, 1896.4026331705468, 1897.8671492995359, 812.4475356757462, 845.2815107158866, 917.9311551633655, 926.666400095965, 981.6858605246252, 20459.977001149884, 21144.577108457746, 21241.468963276042, 21287.148514059263, 21308.467288317606, 938.1565224334216, 941.8916824565263, 943.2925306020485, 950.847864341702, 981.2738573455591, 10568.048875601078, 10975.231733778559, 11026.0, 11068.335899846035, 11114.0, 577561.1121944364, 579562.0, 581193.0, 592313.0, 593970.0, 93960.0, 128906.0, 141518.0, 142783.0, 144803.0, 973.3819024919227, 980.6959842448864, 1022.1822541966397, 1022.539857064324, 1022.8619136416637, 18232.45302640923, 18700.0, 18745.0, 18925.810741892405, 18936.715949260495, 43714.56285437105, 53414.73292633512, 55324.89350213054, 56680.433195667516, 58800.0, 77719.61140194263, 82806.0, 84089.0, 86812.0, 86872.1312786864, 6998.6150761708595, 7422.777316680508, 7587.696492140252, 8078.474899131537, 8094.757157285291, 10797.352158870493, 12513.249205047727, 12920.612381628565, 13485.056046076681, 13567.0, 10544.209184311361, 10686.786264274515, 10693.786124277627, 10748.570057197938, 11716.765664686489, 11295.491702873225, 11308.886911130783, 11455.656330310105, 11721.355325457182, 11785.410729463663, 11080.670319561705, 11282.379469129277, 11760.70598235051, 11871.228370155912, 11948.984336331161, 8158.469699469515, 8260.752177434686, 8366.83266334682, 8387.07742148351, 8500.107488713596, 8277.875831862406, 10843.403612801372, 11177.0, 11464.025557827677, 11704.88295117072], "value": 9578.120409453702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.2957662015758, 1501.7146742118996, 1541.4373753579944, 1744.8865823721458, 1921.3563456242166, 7749.573773442458, 8253.298469630081, 8447.619857106421, 8808.691695790649, 8808.999999999984, 8662.610182541785, 8746.387752857303, 8747.606357713903, 8756.562171891395, 8761.167689069543, 2341.2156927429287, 2371.8221133414977, 2375.9406014849596, 2379.2029670060465, 2381.714194296687, 2303.2974942642486, 2337.1118974789556, 2358.516504116652, 2359.1507057459303, 2363.397333679909, 2317.4669825940045, 2344.0, 2346.6480027995767, 2348.342464110052, 2350.2009316832273, 2288.5079707862724, 2312.8843557822042, 2319.7564255753155, 2321.3268152235833, 2333.9533209335887, 2306.3542208181734, 2310.145246258878, 2316.791488766002, 2321.9419514512274, 2324.8721320327377, 8701.564921753887, 8760.386772925898, 8800.8679869802, 8842.381033327669, 8848.557572121432, 4906.730129842857, 5036.84889453317, 5202.03762303975, 5416.891662166774, 5418.160185171305, 3525.9118522037156, 3702.315071711742, 3765.6234376562397, 3768.5854555998817, 3773.792441415721, 1055.250771951915, 1085.2403317677656, 1090.9727256818564, 1111.1000089927104, 1113.4544073404054, 11723.589674361356, 11749.471273792615, 11821.995130413925, 11828.70428239301, 11837.112216583791, 4403.603675669141, 4464.17412778637, 4633.374494443275, 4636.258198688193, 4662.370579971661, 9239.676611318571, 9241.815163696689, 9247.503174142943, 9249.583768730423, 9269.582868770787, 5276.736163191825, 5278.049951008778, 5282.022825777243, 5286.920696189561, 5290.470952904756, 4353.4122893408985, 4355.773072313237, 4460.464744230713, 4600.056988317387, 4953.72754498506, 3796.2787070457075, 3848.7690738555775, 3863.864764733221, 3895.3572660511263, 3906.0820707133803, 1162.3258510064143, 1171.262104873941, 1173.0732721150366, 1182.9763404731857, 1184.224333061846, 825.8183199696057, 874.6588830356167, 903.3631289940577, 908.8727578139066, 938.6198589571235, 4886.560209581084, 5826.155207494974, 6103.542234332443, 6119.877602447926, 6161.137440758297, 427.8973046468825, 435.7494440696549, 438.5395334898408, 449.22732899412955, 449.3529317782383, 3742.73800833939, 3992.201559688074, 4052.4124002019544, 4099.467069280974, 4101.91796164075, 10546.789064218672, 10871.891281087088, 10972.780544389068, 10977.12183025356, 10981.956714112146, 10932.234743567873, 10933.835992460119, 10933.945330273298, 10939.234253602172, 10949.069329107115, 8267.62795674196, 8343.749687509384, 8357.456765310235, 8364.414490985573, 8367.665293388196, 5787.2187254720075, 5866.6186697864505, 5960.403959604005, 5998.790042348497, 6019.247594050744, 9223.58493867791, 9456.0, 9892.604295828087, 10181.490925453845, 10183.43991080468, 9172.357934944757, 10266.743331416774, 10355.326903751233, 10380.532876020447, 10407.531661075118, 5861.853453663693, 6852.0, 6942.201646810479, 7015.438764898898, 7104.360607545345, 8303.418760686694, 8321.6671333146, 8409.075001749925, 8414.53720045379, 8424.368172386972, 5908.970455147869, 6366.0, 7453.627318633939, 7563.546187228784, 7609.124950630749, 6111.358307377839, 7350.96324518374, 7361.2638736125955, 7396.371308438843, 7433.331000210007, 7026.894596581157, 7153.070100886852, 7159.284071593007, 7162.925561165866, 7174.0, 4914.189158788759, 4989.29091442174, 5028.673136246132, 5076.543111120017, 5247.921281180709, 5032.6980381177245, 5117.155669314521, 5178.559822414986, 5244.318238629106, 5258.316418865523], "value": 4456.503282026375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.52810851018154, 302.8970150148949, 310.6008778719345, 314.10949956872264, 316.99524507132384, 5351.635332990087, 5424.430434804343, 5462.317210348707, 5467.699276539789, 5492.670439773617, 5051.797928082881, 5210.374755029402, 5211.947880521203, 5229.0, 5416.187571864222, 3417.709494692954, 4037.6971727120585, 4137.482814648169, 4152.190322887031, 4168.8332466701295, 4112.403701463286, 4118.97940510297, 4122.649574786146, 4134.813933373005, 4244.49066112067, 3812.0, 4066.8983275418054, 4083.9795801020955, 4098.549159592442, 4099.631033207011, 3394.8981530554247, 4049.6557792587664, 4072.8778136655947, 4098.303288440972, 4102.804999325362, 3070.201747545632, 3490.586312543424, 3497.300539892032, 3501.159721666807, 3531.205478767257, 5019.196928491424, 5275.525202731734, 5278.495628745827, 5297.543175626695, 5326.147816349404, 4406.317020861771, 4609.239475486541, 4624.774434774788, 4649.511801260853, 4672.556107169813, 5626.971865140689, 5656.773729050833, 5656.822123482448, 5675.233843431167, 5693.829185124453, 3407.9318413631836, 3609.7112231021456, 3710.5862606401906, 3823.6558709715982, 3857.7685338879755, 4688.29675548667, 5955.434233747787, 5991.6405015698765, 5998.580099393044, 6002.0289956471825, 3214.5660335854827, 3840.7325582557687, 3851.8651847185215, 3852.536036306242, 3865.6520913117956, 3687.2256826066828, 3941.7832019239218, 3958.752992807314, 3961.6632586229916, 3976.9204615908097, 4058.553559108496, 4220.451341325608, 4558.133954548625, 4606.95393046072, 4623.791929363187, 5612.691301978431, 5664.85837854057, 5686.260786097863, 5691.430856914359, 5706.828795136153, 1274.337344580813, 1282.3716378974236, 1309.646395473236, 1346.2528296795176, 1348.5549768576345, 851.17010914358, 875.1948207648978, 884.2307192742355, 886.9556522173865, 896.852019416802, 4478.865634030984, 5222.3733152022005, 5234.528892399626, 5250.579953603779, 5294.49702278283, 5662.235598194274, 5710.685912274865, 5810.448007439287, 5849.303701926394, 5863.706814659336, 4889.362063708698, 5181.637285390062, 5203.16749320107, 5251.76367063483, 5253.870417860178, 2885.4851203118533, 3023.9244018899262, 3033.119465931137, 3056.9847150764103, 3060.3879224155257, 4702.435707715028, 5453.609329621001, 5778.104393818965, 6021.939780602225, 6086.786962456293, 5001.524855138756, 5005.774740136702, 5274.393444753826, 5650.519705824969, 6049.18336024631, 6089.5432842537875, 6096.969515152396, 6193.318734939153, 6217.533684973645, 6233.875322493615, 5654.604177707521, 5785.1652690182345, 5880.794172203952, 5898.852528686817, 5928.318243401892, 5821.98115329824, 5830.737616807339, 5832.533397328119, 5863.648181109147, 5877.764889404376, 5018.297438358706, 5727.713614319351, 6057.7879774207695, 6158.846028849314, 6169.5064394849205, 3297.9505307419927, 3314.801111933292, 3866.7873266969464, 3913.021744563859, 3965.0, 4937.407511098691, 5173.63713586157, 5384.542313903362, 5892.145638882424, 5948.226730525004, 5246.790128394821, 5360.0, 5665.393918797399, 5670.092785154356, 5826.970865145817, 4747.382840230748, 5006.574441172541, 5700.572457065819, 5733.541316694738, 5741.74162162712, 4921.384826896638, 5290.7883684653725, 5500.339959204921, 5518.944810551843, 5684.175794509855, 4721.929171062368, 4796.664233503621, 4809.663323567456, 4917.950820491749, 5330.680159190306, 5294.829120060756, 5510.531604813636, 5641.294838145232, 5644.339679178609, 5664.433556644302], "value": 4124.809759974354, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.8495323505447, 711.5943911970176, 724.7898109548231, 801.4510060608487, 813.4793732011514, 15634.843651563495, 16559.66880662386, 16560.0, 16588.751168732462, 16594.83405165948, 14778.0, 14830.851691483082, 14838.629034274121, 14846.925765371157, 14857.331420086095, 680.8944613584903, 698.34006863514, 702.3994484715571, 838.0404436919723, 838.367032890167, 849.6516428264382, 851.3572252949015, 863.5811631358818, 864.7838040489878, 865.4980111535294, 924.1267002682467, 924.8890133184027, 926.3422969691527, 927.285989787863, 927.4296307770707, 715.0954043135438, 739.4528049243521, 915.0620613870802, 915.638322862469, 915.7802127489422, 657.9111819904299, 670.7283550162192, 673.2829536543566, 678.2979616097316, 679.4734081087148, 14858.554243372717, 14870.0, 14896.553103406915, 14899.553013409615, 14903.03130296538, 13332.866671333259, 13471.656904596504, 13718.519851805238, 13851.16892986414, 14293.213873236968, 12235.510579576896, 12621.242725436503, 12670.429830657642, 12725.42735576892, 13071.150375225676, 4473.9552604473865, 4487.663425243087, 4529.297958816389, 4614.584687378118, 4619.214733495314, 88954.11045889523, 121255.0, 123350.14974775385, 123456.9999999991, 123714.38142809228, 10816.0, 10833.891661083446, 10841.512131954081, 10872.402017888933, 10970.616028438966, 21410.678839817414, 21568.568628627654, 21571.352859414244, 21576.568468630852, 21606.351809445743, 7668.424868134912, 7759.612019399007, 7772.414032615774, 7964.20357964199, 8004.439689221698, 4252.638525725348, 4280.143971205709, 4295.763732994716, 4307.353896915488, 4451.443569553806, 3019.8641061152302, 3439.9312013760086, 3549.0772399175885, 3554.964450355515, 3558.733095017884, 651.2770824384929, 657.3886285754198, 667.7095463473405, 672.5897202706345, 691.8201267670441, 421.82915919052834, 556.5686592890543, 603.8399824046631, 603.8912995660824, 617.8918689229367, 8277.379196560161, 8902.688405905763, 8985.415947963362, 9018.215008532028, 9118.680846170351, 597.8505373656586, 600.5525883216944, 610.1701685707434, 621.142822904398, 642.1267076775581, 3497.667721566447, 4311.48262208537, 4333.826646934087, 4457.869697633195, 4514.142312960527, 105119.4744026275, 105467.0, 147961.0, 167184.0, 171341.14329428523, 121892.0, 135404.0, 144019.6769500729, 144189.5581044197, 149893.0, 113541.86458135313, 113950.76787713925, 115222.0, 115858.1520570744, 117497.9999999983, 7847.979762630822, 8452.661893524437, 8458.915410845813, 8635.0, 8775.42959707617, 45454.0, 64621.67689161524, 65512.67243663751, 66440.66779666071, 68971.0, 77416.2258377409, 84007.0, 86438.0, 98402.5079874596, 107221.46389268004, 10720.356778592997, 13285.601431957057, 13384.799228011783, 13772.03595748288, 13908.791368129283, 14873.92563037178, 14985.0, 15209.695806083595, 15218.619534511727, 15635.92182039128, 13927.576601671326, 14026.649333766738, 14047.929760351133, 14148.929255353658, 14246.857531424968, 13799.103058301178, 13846.584602461942, 14059.718805624036, 14199.0, 14671.266436678337, 13445.529406470727, 13792.379342929391, 13802.585922422344, 13809.378577963815, 14259.144451332953, 11293.661190163979, 11325.886741132812, 11425.885741142481, 11791.882081179421, 11950.581729639422, 9880.654177103766, 10464.267501275142, 10715.464226788474, 11428.542858285908, 11457.255278406878], "value": 7819.687769385931, "unit": "score"}], "name": "dromaeo_css", "value": 7380.205411331233, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 112.0, 112.0, 113.0, 113.0, 113.0, 111.0, 123.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 131.0, 160.0, 109.0, 155.0, 161.0, 114.0, 157.0, 107.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 192.0, 191.0, 192.0, 205.0, 277.0, 194.0, 230.0, 272.0, 198.0], "value": 214.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 151.0, 58.0, 57.0, 56.0, 68.0, 135.0, 57.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 131.0, 70.0, 75.0, 77.0, 115.0, 67.0, 71.0, 116.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 86.0, 88.0, 85.0, 87.0, 85.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 97.0, 97.0, 97.0, 96.0, 96.0, 96.0, 97.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 84.0, 84.0, 83.0, 85.0, 84.0, 84.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 56.0, 53.0, 53.0, 55.0, 54.0, 54.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 71.0, 73.0, 74.0, 74.0, 68.0, 69.0, 68.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 86.0, 87.0, 87.0, 90.0, 88.0, 105.0, 91.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 155.0, 150.0, 152.0, 139.0, 138.0, 139.0, 136.0, 153.0, 137.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 171.0, 166.0, 167.0, 168.0, 166.0, 166.0, 165.0, 231.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 69.0, 68.0, 68.0, 67.0, 67.0, 66.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1498.7000000000003, "alertThreshold": 2.0}]} 21:50:18 INFO - Return code: 0 21:50:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:50:18 INFO - # TBPL SUCCESS # 21:50:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:50:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:50:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:50:18 INFO - 2016-09-11 21:50:18.515 screenresolution[1664:10650] starting screenresolution argv=screenresolution get 21:50:18 INFO - 2016-09-11 21:50:18.518 screenresolution[1664:10650] Display 0: 1600x1200x32@60 21:50:18 INFO - 2016-09-11 21:50:18.524 screenresolution[1665:10653] starting screenresolution argv=screenresolution list 21:50:18 INFO - Available Modes on Display 0 21:50:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:50:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:50:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:50:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:50:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:50:18 INFO - Intel Iris: 21:50:18 INFO - Chipset Model: Intel Iris 21:50:18 INFO - Type: GPU 21:50:18 INFO - Bus: Built-In 21:50:18 INFO - VRAM (Dynamic, Max): 1536 MB 21:50:18 INFO - Vendor: Intel (0x8086) 21:50:18 INFO - Device ID: 0x0a2e 21:50:18 INFO - Revision ID: 0x0009 21:50:18 INFO - Displays: 21:50:18 INFO - Display: 21:50:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:50:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:50:18 INFO - Display Serial Number: 1600x1200 60 21:50:18 INFO - Main Display: Yes 21:50:18 INFO - Mirror: Off 21:50:18 INFO - Online: Yes 21:50:18 INFO - Rotation: Supported 21:50:18 INFO - Return code: 0 21:50:18 INFO - Running post-action listener: _resource_record_post_action 21:50:18 INFO - [mozharness: 2016-09-12 04:50:18.760671Z] Finished run-tests step (success) 21:50:18 INFO - Running post-run listener: _resource_record_post_run 21:50:18 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33086464; Write bytes: 707445248; Read time: 312; Write time: 2377 21:50:18 INFO - TinderboxPrint: CPU usage
53.4% 21:50:18 INFO - TinderboxPrint: I/O read bytes / time
33,086,464 / 312 21:50:18 INFO - TinderboxPrint: I/O write bytes / time
707,445,248 / 2,377 21:50:18 INFO - TinderboxPrint: CPU idle
2,260.8 (46.6%) 21:50:18 INFO - TinderboxPrint: CPU system
965.7 (19.9%) 21:50:18 INFO - TinderboxPrint: CPU user
1,623.2 (33.5%) 21:50:18 INFO - TinderboxPrint: Swap in / out
435,417,088 / 0 21:50:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398656000; Write bytes: 392553984; Read time: 29521; Write time: 725 21:50:18 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31034368; Write bytes: 298118144; Read time: 289; Write time: 1611 21:50:19 INFO - Running post-run listener: _upload_blobber_files 21:50:19 INFO - Blob upload gear active. 21:50:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:50:19 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 21:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:50:19 INFO - (blobuploader) - INFO - Open directory for files ... 21:50:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:50:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:50:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:20 INFO - (blobuploader) - INFO - Done attempting. 21:50:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:50:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:50:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:21 INFO - (blobuploader) - INFO - Done attempting. 21:50:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:50:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:24 INFO - (blobuploader) - INFO - Done attempting. 21:50:24 INFO - (blobuploader) - INFO - Iteration through files over. 21:50:24 INFO - Return code: 0 21:50:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:50:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:50:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c69909cb5be8296c8c0bb736db069c1dbb95f01a172669f7f4d2d3900afc435950751b3034a25d1fed3f7bedd94501607671a01b6798a99d847ae162971d5694", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88f4adeafb61fc1420ce38cc14af8f6ea39bb30fae946aac9dbd87ede1bc94c0fe28f39c5dd11069c3e7b412838f634e6b6ea6b4b3222d5edc2eaf1d95be4da9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08d2769a76b76237db47b7f17b1f67930d1efa39b09af1798478dd810f816019cb6b18954e090386bd6d06abc6756195fb290fe31173cfe6c4b2379150e1f188"} 21:50:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:50:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:50:24 INFO - Contents: 21:50:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c69909cb5be8296c8c0bb736db069c1dbb95f01a172669f7f4d2d3900afc435950751b3034a25d1fed3f7bedd94501607671a01b6798a99d847ae162971d5694", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88f4adeafb61fc1420ce38cc14af8f6ea39bb30fae946aac9dbd87ede1bc94c0fe28f39c5dd11069c3e7b412838f634e6b6ea6b4b3222d5edc2eaf1d95be4da9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08d2769a76b76237db47b7f17b1f67930d1efa39b09af1798478dd810f816019cb6b18954e090386bd6d06abc6756195fb290fe31173cfe6c4b2379150e1f188"} 21:50:24 INFO - Running post-run listener: copy_logs_to_upload_dir 21:50:24 INFO - Copying logs to upload dir... 21:50:24 INFO - mkdir: /builds/slave/test/build/upload/logs 21:50:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.632084 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-11 21:50:24.657009) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-11 21:50:24.660725) ========= 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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c69909cb5be8296c8c0bb736db069c1dbb95f01a172669f7f4d2d3900afc435950751b3034a25d1fed3f7bedd94501607671a01b6798a99d847ae162971d5694", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88f4adeafb61fc1420ce38cc14af8f6ea39bb30fae946aac9dbd87ede1bc94c0fe28f39c5dd11069c3e7b412838f634e6b6ea6b4b3222d5edc2eaf1d95be4da9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08d2769a76b76237db47b7f17b1f67930d1efa39b09af1798478dd810f816019cb6b18954e090386bd6d06abc6756195fb290fe31173cfe6c4b2379150e1f188"} build_url:https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/WtbtBNq9Qlu3zL7dRGqxNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c69909cb5be8296c8c0bb736db069c1dbb95f01a172669f7f4d2d3900afc435950751b3034a25d1fed3f7bedd94501607671a01b6798a99d847ae162971d5694", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/88f4adeafb61fc1420ce38cc14af8f6ea39bb30fae946aac9dbd87ede1bc94c0fe28f39c5dd11069c3e7b412838f634e6b6ea6b4b3222d5edc2eaf1d95be4da9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/08d2769a76b76237db47b7f17b1f67930d1efa39b09af1798478dd810f816019cb6b18954e090386bd6d06abc6756195fb290fe31173cfe6c4b2379150e1f188"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-11 21:50:24.696893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:50:24.697459) ========= 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.DXQPXEcBoM/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.ZCFnQEq32z/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005208 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-11 21:50:24.747453) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-11 21:50:24.747809) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-11 21:50:24.748155) ========= ========= Total master_lag: 0.21 =========