builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0320 starttime: 1476263813.87 results: success (0) buildid: 20161012003647 builduid: 977a5037b8e9429385332f2b2e8c4f24 revision: ae10e05963870f953b47eb29ba485001fd4c07d3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:53.871325) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:53.871707) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:53.871970) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:53.895548) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:53.895812) ========= 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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-10-12 02:16:53-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2016-10-12 02:16:54 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:54.054099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:54.057430) ========= 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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.027085 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:54.105779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-12 02:16:54.106095) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev ae10e05963870f953b47eb29ba485001fd4c07d3 --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 ae10e05963870f953b47eb29ba485001fd4c07d3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-10-12 02:16:54,194 truncating revision to first 12 chars 2016-10-12 02:16:54,194 Setting DEBUG logging. 2016-10-12 02:16:54,194 attempt 1/10 2016-10-12 02:16:54,194 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/ae10e0596387?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 02:16:55,968 unpacking tar archive at: autoland-ae10e0596387/testing/mozharness/ program finished with exit code 0 elapsedTime=2.055703 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-12 02:16:56.204022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:56.204339) ========= script_repo_revision: ae10e05963870f953b47eb29ba485001fd4c07d3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:56.204717) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:56.204987) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 02:16:56.224722) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 02:16:56.225003) ========= /tools/buildbot/bin/python -u 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', '-u', '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:16:56 INFO - MultiFileLogger online at 20161012 02:16:56 in /builds/slave/test 02:16:56 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 02:16:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:16:56 INFO - {'append_to_log': False, 02:16:56 INFO - 'base_work_dir': '/builds/slave/test', 02:16:56 INFO - 'blob_upload_branch': 'Autoland', 02:16:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:16:56 INFO - 'branch': 'Autoland', 02:16:56 INFO - 'buildbot_json_path': 'buildprops.json', 02:16:56 INFO - 'config_files': ('talos/mac_config.py',), 02:16:56 INFO - 'default_actions': ('clobber', 02:16:56 INFO - 'read-buildbot-config', 02:16:56 INFO - 'download-and-extract', 02:16:56 INFO - 'populate-webroot', 02:16:56 INFO - 'create-virtualenv', 02:16:56 INFO - 'install', 02:16:56 INFO - 'run-tests'), 02:16:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:16:56 INFO - 'download_minidump_stackwalk': True, 02:16:56 INFO - 'download_symbols': 'ondemand', 02:16:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:16:56 INFO - 'tooltool.py': '/tools/tooltool.py', 02:16:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:16:56 INFO - '/tools/misc-python/virtualenv.py')}, 02:16:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:16:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:16:56 INFO - 'installer_path': 'installer.exe', 02:16:56 INFO - 'log_level': 'info', 02:16:56 INFO - 'log_name': 'talos', 02:16:56 INFO - 'log_to_console': True, 02:16:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:16:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:16:56 INFO - 'opt_config_files': (), 02:16:56 INFO - 'pip_index': False, 02:16:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:56 INFO - 'cmd': ('bash', 02:16:56 INFO - '-c', 02:16:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:56 INFO - 'enabled': True, 02:16:56 INFO - 'halt_on_failure': False, 02:16:56 INFO - 'name': 'check_screen_resolution'},), 02:16:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:56 INFO - 'cmd': ('bash', 02:16:56 INFO - '-c', 02:16:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:56 INFO - 'enabled': True, 02:16:56 INFO - 'halt_on_failure': False, 02:16:56 INFO - 'name': 'check_screen_resolution'},), 02:16:56 INFO - 'run_cmd_checks_enabled': True, 02:16:56 INFO - 'sps_profile': False, 02:16:56 INFO - 'sps_profile_interval': 0, 02:16:56 INFO - 'suite': 'dromaeojs', 02:16:56 INFO - 'system_bits': '32', 02:16:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:16:56 INFO - 'title': 't-yosemite-r7-0320', 02:16:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:16:56 INFO - 'use_talos_json': True, 02:16:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:16:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:16:56 INFO - 'work_dir': 'build'} 02:16:56 INFO - [mozharness: 2016-10-12 09:16:56.418135Z] Running clobber step. 02:16:56 INFO - Running pre-action listener: _resource_record_pre_action 02:16:56 INFO - Running main action method: clobber 02:16:56 INFO - rmtree: /builds/slave/test/build 02:16:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:16:58 INFO - Running post-action listener: _resource_record_post_action 02:16:58 INFO - [mozharness: 2016-10-12 09:16:58.530204Z] Finished clobber step (success) 02:16:58 INFO - [mozharness: 2016-10-12 09:16:58.530415Z] Running read-buildbot-config step. 02:16:58 INFO - Running pre-action listener: _resource_record_pre_action 02:16:58 INFO - Running main action method: read_buildbot_config 02:16:58 INFO - Using buildbot properties: 02:16:58 INFO - { 02:16:58 INFO - "project": "", 02:16:58 INFO - "product": "firefox", 02:16:58 INFO - "script_repo_revision": "production", 02:16:58 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 02:16:58 INFO - "repository": "", 02:16:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 02:16:58 INFO - "buildid": "20161012003647", 02:16:58 INFO - "pgo_build": "False", 02:16:58 INFO - "basedir": "/builds/slave/test", 02:16:58 INFO - "buildnumber": 370, 02:16:58 INFO - "slavename": "t-yosemite-r7-0320", 02:16:58 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:16:58 INFO - "platform": "yosemite_r7", 02:16:58 INFO - "scheduler": "tests-autoland-macosx64-talos", 02:16:58 INFO - "branch": "autoland", 02:16:58 INFO - "repo_path": "integration/autoland", 02:16:58 INFO - "revision": "ae10e05963870f953b47eb29ba485001fd4c07d3", 02:16:58 INFO - "stage_platform": "macosx64", 02:16:58 INFO - "builduid": "977a5037b8e9429385332f2b2e8c4f24", 02:16:58 INFO - "slavebuilddir": "test" 02:16:58 INFO - } 02:16:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 02:16:58 INFO - Running post-action listener: _resource_record_post_action 02:16:58 INFO - [mozharness: 2016-10-12 09:16:58.534485Z] Finished read-buildbot-config step (success) 02:16:58 INFO - [mozharness: 2016-10-12 09:16:58.534602Z] Running download-and-extract step. 02:16:58 INFO - Running pre-action listener: _resource_record_pre_action 02:16:58 INFO - Running main action method: download_and_extract 02:16:58 INFO - mkdir: /builds/slave/test/build/tests 02:16:58 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')]} 02:16:58 INFO - trying https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 02:16:58 INFO - Downloading https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 02:16:59 INFO - Downloaded 1199 bytes. 02:16:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:16:59 INFO - Using the following test package requirements: 02:16:59 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:16:59 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 02:16:59 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 02:16:59 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'jsshell-mac.zip'], 02:16:59 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 02:16:59 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:16:59 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 02:16:59 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 02:16:59 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 02:16:59 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:16:59 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 02:16:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:16:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:16:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:16:59 INFO - Fetch https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:17:00 INFO - Http code: 200 02:17:00 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:17:00 INFO - x-amz-cf-id: 2AJK4nGXLQ0Iht4Xw6P1FVAg8NW0o-ZLWKmBk9uARINgbrVqcQs02A== 02:17:00 INFO - x-amz-version-id: RoacCuLi5du.AsgVIyeZ0HqzLYCGgd.w 02:17:00 INFO - Expected file size: 16508459 02:17:00 INFO - Obtained file size: 16508459 02:17:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:17:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:17:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:17:07 INFO - Fetch https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:17:07 INFO - Http code: 200 02:17:07 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 02:17:07 INFO - x-amz-cf-id: MBqzMd9QObiYRiEjUeyaUxRO8ozisqrLDRmsk22WUoeeFKT1yi0FbA== 02:17:07 INFO - x-amz-version-id: RoacCuLi5du.AsgVIyeZ0HqzLYCGgd.w 02:17:08 INFO - Expected file size: 16508459 02:17:08 INFO - Obtained file size: 16508459 02:17:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 02:17:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:17:14 INFO - Fetch https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 02:17:15 INFO - Http code: 200 02:17:15 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 02:17:15 INFO - x-amz-cf-id: luQOAi_0u7C93WxZrJpm44LSIxF-czeUy6MkGBYFvUoamVqRdHXOpw== 02:17:15 INFO - x-amz-version-id: ZFSPKtaxlwr2R4hGwg2TN84xKeS2fzxD 02:17:16 INFO - Expected file size: 14409006 02:17:16 INFO - Obtained file size: 14409006 02:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:16 INFO - trying https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:17:16 INFO - Downloading https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 02:17:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 02:17:19 INFO - Downloaded 111696913 bytes. 02:17:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:17:19 INFO - mkdir: /builds/slave/test/properties 02:17:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:17:19 INFO - Writing to file /builds/slave/test/properties/build_url 02:17:19 INFO - Contents: 02:17:19 INFO - build_url:https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:17:19 INFO - Running post-action listener: _resource_record_post_action 02:17:19 INFO - Running post-action listener: set_extra_try_arguments 02:17:19 INFO - [mozharness: 2016-10-12 09:17:19.947883Z] Finished download-and-extract step (success) 02:17:19 INFO - [mozharness: 2016-10-12 09:17:19.948032Z] Running populate-webroot step. 02:17:19 INFO - Running pre-action listener: _resource_record_pre_action 02:17:19 INFO - Running main action method: populate_webroot 02:17:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:17:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:17:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:17:19 INFO - '--tppagecycles', 02:17:19 INFO - '7'], 02:17:19 INFO - 'tests': ['tsvgm']}}, 02:17:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:17:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:17:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:17:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:17:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:17:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:17:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['damp', 'tps']}, 02:17:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'tests': ['damp', 'tps']}, 02:17:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['dromaeo_dom']}, 02:17:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:17:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['basic_compositor_video']}, 02:17:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:17:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'other-e10s': {'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['a11yr', 02:17:19 INFO - 'ts_paint', 02:17:19 INFO - 'tpaint', 02:17:19 INFO - 'sessionrestore', 02:17:19 INFO - 'sessionrestore_no_auto_restore', 02:17:19 INFO - 'tabpaint']}, 02:17:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['tsvgx', 02:17:19 INFO - 'tsvgr_opacity', 02:17:19 INFO - 'tart', 02:17:19 INFO - 'tscrollx', 02:17:19 INFO - 'cart']}, 02:17:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:17:19 INFO - 'tsvgr_opacity', 02:17:19 INFO - 'tart', 02:17:19 INFO - 'tscrollx', 02:17:19 INFO - 'cart']}, 02:17:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'talos_options': ['--disable-e10s'], 02:17:19 INFO - 'tests': ['tp5o']}, 02:17:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'tests': ['tp5o']}, 02:17:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'talos_options': ['--disable-e10s', 02:17:19 INFO - '--xperf_path', 02:17:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:17:19 INFO - 'tests': ['tp5n']}, 02:17:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:17:19 INFO - 'talos_options': ['--xperf_path', 02:17:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:17:19 INFO - 'tests': ['tp5n']}}, 02:17:19 INFO - 'talos.zip': {'path': '', 02:17:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:17:19 INFO - Running post-action listener: _resource_record_post_action 02:17:19 INFO - [mozharness: 2016-10-12 09:17:19.967031Z] Finished populate-webroot step (success) 02:17:19 INFO - [mozharness: 2016-10-12 09:17:19.967137Z] Running create-virtualenv step. 02:17:19 INFO - Running pre-action listener: _resource_record_pre_action 02:17:19 INFO - Running main action method: create_virtualenv 02:17:19 INFO - Creating virtualenv /builds/slave/test/build/venv 02:17:19 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 02:17:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:17:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:17:20 INFO - Using real prefix '/tools/python27' 02:17:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:17:20 INFO - Installing distribute.............................................................................................................................................................................................done. 02:17:23 INFO - Installing pip.................done. 02:17:23 INFO - Return code: 0 02:17:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:17:24 INFO - Reading from file tmpfile_stdout 02:17:24 INFO - Output received: 02:17:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:17:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:24 INFO - 'HOME': '/Users/cltbld', 02:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:24 INFO - 'LOGNAME': 'cltbld', 02:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:24 INFO - 'MOZ_NO_REMOTE': '1', 02:17:24 INFO - 'NO_EM_RESTART': '1', 02:17:24 INFO - 'PAGER': '/bin/cat', 02:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:24 INFO - 'PWD': '/builds/slave/test', 02:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:24 INFO - 'SHELL': '/bin/bash', 02:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:24 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:24 INFO - 'USER': 'cltbld', 02:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:24 INFO - 'XPC_FLAGS': '0x0', 02:17:24 INFO - 'XPC_SERVICE_NAME': '0', 02:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:17:24 INFO - Cleaning up... 02:17:24 INFO - Return code: 0 02:17:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:24 INFO - 'HOME': '/Users/cltbld', 02:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:24 INFO - 'LOGNAME': 'cltbld', 02:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:24 INFO - 'MOZ_NO_REMOTE': '1', 02:17:24 INFO - 'NO_EM_RESTART': '1', 02:17:24 INFO - 'PAGER': '/bin/cat', 02:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:24 INFO - 'PWD': '/builds/slave/test', 02:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:24 INFO - 'SHELL': '/bin/bash', 02:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:24 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:24 INFO - 'USER': 'cltbld', 02:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:24 INFO - 'XPC_FLAGS': '0x0', 02:17:24 INFO - 'XPC_SERVICE_NAME': '0', 02:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:24 INFO - Downloading/unpacking psutil>=3.1.1 02:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:17:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:17:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:17:28 INFO - Installing collected packages: psutil 02:17:28 INFO - Running setup.py install for psutil 02:17:28 INFO - building 'psutil._psutil_osx' extension 02:17:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:17:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:17:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:17:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:17:28 INFO - building 'psutil._psutil_posix' extension 02:17:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:17:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:17:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:17:28 INFO - ^ 02:17:28 INFO - 1 warning generated. 02:17:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:17:29 INFO - Successfully installed psutil 02:17:29 INFO - Cleaning up... 02:17:29 INFO - Return code: 0 02:17:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:29 INFO - 'HOME': '/Users/cltbld', 02:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:29 INFO - 'LOGNAME': 'cltbld', 02:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:29 INFO - 'MOZ_NO_REMOTE': '1', 02:17:29 INFO - 'NO_EM_RESTART': '1', 02:17:29 INFO - 'PAGER': '/bin/cat', 02:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:29 INFO - 'PWD': '/builds/slave/test', 02:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:29 INFO - 'SHELL': '/bin/bash', 02:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:29 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:29 INFO - 'USER': 'cltbld', 02:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:29 INFO - 'XPC_FLAGS': '0x0', 02:17:29 INFO - 'XPC_SERVICE_NAME': '0', 02:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:17:32 INFO - Installing collected packages: mozsystemmonitor 02:17:32 INFO - Running setup.py install for mozsystemmonitor 02:17:32 INFO - Successfully installed mozsystemmonitor 02:17:32 INFO - Cleaning up... 02:17:32 INFO - Return code: 0 02:17:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:32 INFO - 'HOME': '/Users/cltbld', 02:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:32 INFO - 'LOGNAME': 'cltbld', 02:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:32 INFO - 'MOZ_NO_REMOTE': '1', 02:17:32 INFO - 'NO_EM_RESTART': '1', 02:17:32 INFO - 'PAGER': '/bin/cat', 02:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:32 INFO - 'PWD': '/builds/slave/test', 02:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:32 INFO - 'SHELL': '/bin/bash', 02:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:32 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:32 INFO - 'USER': 'cltbld', 02:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:32 INFO - 'XPC_FLAGS': '0x0', 02:17:32 INFO - 'XPC_SERVICE_NAME': '0', 02:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:32 INFO - Downloading/unpacking jsonschema==2.5.1 02:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:17:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:35 INFO - Downloading functools32-3.2.3-2.tar.gz 02:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:17:35 INFO - warning: no files found matching '*.txt' 02:17:35 INFO - no previously-included directories found matching 'build' 02:17:35 INFO - no previously-included directories found matching 'dist' 02:17:35 INFO - no previously-included directories found matching '.git*' 02:17:35 INFO - Installing collected packages: jsonschema, functools32 02:17:35 INFO - Running setup.py install for functools32 02:17:35 INFO - warning: no files found matching '*.txt' 02:17:35 INFO - no previously-included directories found matching 'build' 02:17:35 INFO - no previously-included directories found matching 'dist' 02:17:35 INFO - no previously-included directories found matching '.git*' 02:17:35 INFO - Successfully installed jsonschema functools32 02:17:35 INFO - Cleaning up... 02:17:35 INFO - Return code: 0 02:17:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:17:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:35 INFO - 'HOME': '/Users/cltbld', 02:17:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:35 INFO - 'LOGNAME': 'cltbld', 02:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:35 INFO - 'MOZ_NO_REMOTE': '1', 02:17:35 INFO - 'NO_EM_RESTART': '1', 02:17:35 INFO - 'PAGER': '/bin/cat', 02:17:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:35 INFO - 'PWD': '/builds/slave/test', 02:17:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:35 INFO - 'SHELL': '/bin/bash', 02:17:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:35 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:35 INFO - 'USER': 'cltbld', 02:17:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:35 INFO - 'XPC_FLAGS': '0x0', 02:17:35 INFO - 'XPC_SERVICE_NAME': '0', 02:17:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:17:36 INFO - Cleaning up... 02:17:36 INFO - Return code: 0 02:17:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:17:36 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')]} 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 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')]} 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 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 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:36 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 02:17:36 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 02:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:36 INFO - 'HOME': '/Users/cltbld', 02:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:36 INFO - 'LOGNAME': 'cltbld', 02:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:36 INFO - 'MOZ_NO_REMOTE': '1', 02:17:36 INFO - 'NO_EM_RESTART': '1', 02:17:36 INFO - 'PAGER': '/bin/cat', 02:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:36 INFO - 'PWD': '/builds/slave/test', 02:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:36 INFO - 'SHELL': '/bin/bash', 02:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:36 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:36 INFO - 'USER': 'cltbld', 02:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:36 INFO - 'XPC_FLAGS': '0x0', 02:17:36 INFO - 'XPC_SERVICE_NAME': '0', 02:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:36 INFO - Downloading/unpacking blobuploader==1.2.4 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:38 INFO - Downloading blobuploader-1.2.4.tar.gz 02:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:17:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:17:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:40 INFO - Downloading docopt-0.6.1.tar.gz 02:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:17:40 INFO - Installing collected packages: blobuploader, requests, docopt 02:17:40 INFO - Running setup.py install for blobuploader 02:17:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:17:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:17:40 INFO - Running setup.py install for requests 02:17:40 INFO - Running setup.py install for docopt 02:17:40 INFO - Successfully installed blobuploader requests docopt 02:17:40 INFO - Cleaning up... 02:17:41 INFO - Return code: 0 02:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:41 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')]} 02:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:41 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')]} 02:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:41 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 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:41 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 02:17:41 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 02:17:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:41 INFO - 'HOME': '/Users/cltbld', 02:17:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:41 INFO - 'LOGNAME': 'cltbld', 02:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:41 INFO - 'MOZ_NO_REMOTE': '1', 02:17:41 INFO - 'NO_EM_RESTART': '1', 02:17:41 INFO - 'PAGER': '/bin/cat', 02:17:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:41 INFO - 'PWD': '/builds/slave/test', 02:17:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:41 INFO - 'SHELL': '/bin/bash', 02:17:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:41 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:41 INFO - 'USER': 'cltbld', 02:17:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:41 INFO - 'XPC_FLAGS': '0x0', 02:17:41 INFO - 'XPC_SERVICE_NAME': '0', 02:17:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-uctFmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zEU0Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-MG6Rd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-l3luex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-M9dQ4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-PMY8be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:41 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-xW3yET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-qbn1mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-9pQeMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-UqQI29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-3ZV9KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zKTShe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-GLGZzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wsWRwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wfTOQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-rCEaIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:42 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-vNQ5_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:17:43 INFO - Running setup.py install for manifestparser 02:17:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:17:43 INFO - Running setup.py install for mozcrash 02:17:43 INFO - Running setup.py install for mozdebug 02:17:43 INFO - Running setup.py install for mozdevice 02:17:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:17:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:17:43 INFO - Running setup.py install for mozfile 02:17:43 INFO - Running setup.py install for mozhttpd 02:17:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:17:43 INFO - Running setup.py install for mozinfo 02:17:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Running setup.py install for mozInstall 02:17:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Running setup.py install for mozleak 02:17:44 INFO - Running setup.py install for mozlog 02:17:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Running setup.py install for moznetwork 02:17:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Running setup.py install for mozprocess 02:17:44 INFO - Running setup.py install for mozprofile 02:17:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:17:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:17:45 INFO - Running setup.py install for mozrunner 02:17:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:17:45 INFO - Running setup.py install for mozscreenshot 02:17:45 INFO - Running setup.py install for moztest 02:17:45 INFO - Running setup.py install for mozversion 02:17:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:17:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:17:45 INFO - Cleaning up... 02:17:45 INFO - Return code: 0 02:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17: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')]} 02:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17: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')]} 02:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:45 INFO - 'HOME': '/Users/cltbld', 02:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:45 INFO - 'LOGNAME': 'cltbld', 02:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:45 INFO - 'MOZ_NO_REMOTE': '1', 02:17:45 INFO - 'NO_EM_RESTART': '1', 02:17:45 INFO - 'PAGER': '/bin/cat', 02:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:45 INFO - 'PWD': '/builds/slave/test', 02:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:45 INFO - 'SHELL': '/bin/bash', 02:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:45 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:45 INFO - 'USER': 'cltbld', 02:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:45 INFO - 'XPC_FLAGS': '0x0', 02:17:45 INFO - 'XPC_SERVICE_NAME': '0', 02:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:45 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-bEjueX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:46 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)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-lHnIzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:46 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)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-x27JTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:46 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)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-cjVbmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-9Je1u9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-eKokVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-csPH45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-7jMaZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-26Pnsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-sHBpVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 02:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:46 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-ojx2P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:47 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)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-aaUlII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:47 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)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-eagmoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-dDRHF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-q6DntM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-XDtMuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:47 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-IGIuM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:17:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:50 INFO - Downloading blessings-1.6.tar.gz 02:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:17:50 INFO - Installing collected packages: blessings 02:17:50 INFO - Running setup.py install for blessings 02:17:50 INFO - Successfully installed blessings 02:17:50 INFO - Cleaning up... 02:17:50 INFO - Return code: 0 02:17:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:50 INFO - Reading from file tmpfile_stdout 02:17:50 INFO - Current package versions: 02:17:50 INFO - blessings == 1.6 02:17:50 INFO - blobuploader == 1.2.4 02:17:50 INFO - docopt == 0.6.1 02:17:50 INFO - functools32 == 3.2.3-2 02:17:50 INFO - jsonschema == 2.5.1 02:17:50 INFO - manifestparser == 1.1 02:17:50 INFO - mozInstall == 1.12 02:17:50 INFO - mozcrash == 0.17 02:17:50 INFO - mozdebug == 0.1 02:17:50 INFO - mozdevice == 0.48 02:17:50 INFO - mozfile == 1.2 02:17:50 INFO - mozhttpd == 0.7 02:17:50 INFO - mozinfo == 0.9 02:17:50 INFO - mozleak == 0.1 02:17:50 INFO - mozlog == 3.4 02:17:50 INFO - moznetwork == 0.27 02:17:50 INFO - mozprocess == 0.23 02:17:50 INFO - mozprofile == 0.28 02:17:50 INFO - mozrunner == 6.12 02:17:50 INFO - mozscreenshot == 0.1 02:17:50 INFO - mozsystemmonitor == 0.3 02:17:50 INFO - moztest == 0.7 02:17:50 INFO - mozversion == 1.4 02:17:50 INFO - psutil == 3.1.1 02:17:50 INFO - requests == 1.2.3 02:17:50 INFO - wsgiref == 0.1.2 02:17:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:51 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')]} 02:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:51 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 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:51 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 02:17:51 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 02:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:51 INFO - 'HOME': '/Users/cltbld', 02:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:51 INFO - 'LOGNAME': 'cltbld', 02:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:51 INFO - 'MOZ_NO_REMOTE': '1', 02:17:51 INFO - 'NO_EM_RESTART': '1', 02:17:51 INFO - 'PAGER': '/bin/cat', 02:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:51 INFO - 'PWD': '/builds/slave/test', 02:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:51 INFO - 'SHELL': '/bin/bash', 02:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:51 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:51 INFO - 'USER': 'cltbld', 02:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:51 INFO - 'XPC_FLAGS': '0x0', 02:17:51 INFO - 'XPC_SERVICE_NAME': '0', 02:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 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)) 02:17:51 INFO - Cleaning up... 02:17:51 INFO - Return code: 0 02:17:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:17:51 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')]} 02:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:51 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')]} 02:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:52 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 0x105f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce12c943e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, '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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 02:17:52 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 02:17:52 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 02:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:52 INFO - 'HOME': '/Users/cltbld', 02:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:52 INFO - 'LOGNAME': 'cltbld', 02:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:52 INFO - 'MOZ_NO_REMOTE': '1', 02:17:52 INFO - 'NO_EM_RESTART': '1', 02:17:52 INFO - 'PAGER': '/bin/cat', 02:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:52 INFO - 'PWD': '/builds/slave/test', 02:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:52 INFO - 'SHELL': '/bin/bash', 02:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:17:52 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:52 INFO - 'USER': 'cltbld', 02:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:52 INFO - 'XPC_FLAGS': '0x0', 02:17:52 INFO - 'XPC_SERVICE_NAME': '0', 02:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:17:52 INFO - Cleaning up... 02:17:52 INFO - Return code: 0 02:17:52 INFO - Running post-action listener: _resource_record_post_action 02:17:52 INFO - Running post-action listener: _start_resource_monitoring 02:17:52 INFO - Starting resource monitoring. 02:17:52 INFO - [mozharness: 2016-10-12 09:17:52.494643Z] Finished create-virtualenv step (success) 02:17:52 INFO - [mozharness: 2016-10-12 09:17:52.495615Z] Running install step. 02:17:52 INFO - Running pre-action listener: _resource_record_pre_action 02:17:52 INFO - Running main action method: install 02:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:17:52 INFO - Reading from file tmpfile_stdout 02:17:52 INFO - Detecting whether we're running mozinstall >=1.0... 02:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:17:52 INFO - Reading from file tmpfile_stdout 02:17:52 INFO - Output received: 02:17:52 INFO - Usage: mozinstall [options] installer 02:17:52 INFO - Options: 02:17:52 INFO - -h, --help show this help message and exit 02:17:52 INFO - -d DEST, --destination=DEST 02:17:52 INFO - Directory to install application into. [default: 02:17:52 INFO - "/builds/slave/test"] 02:17:52 INFO - --app=APP Application being installed. [default: firefox] 02:17:52 INFO - mkdir: /builds/slave/test/build/application 02:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:18:27 INFO - Reading from file tmpfile_stdout 02:18:27 INFO - Output received: 02:18:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:18:27 INFO - Running post-action listener: _resource_record_post_action 02:18:27 INFO - [mozharness: 2016-10-12 09:18:27.041574Z] Finished install step (success) 02:18:27 INFO - [mozharness: 2016-10-12 09:18:27.041727Z] Running run-tests step. 02:18:27 INFO - Running pre-action listener: _resource_record_pre_action 02:18:27 INFO - Running main action method: run_tests 02:18:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:18:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:18:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:18:27 INFO - 2016-10-12 02:18:27.056 screenresolution[1640:7533] starting screenresolution argv=screenresolution get 02:18:27 INFO - 2016-10-12 02:18:27.058 screenresolution[1640:7533] Display 0: 1600x1200x32@60 02:18:27 INFO - 2016-10-12 02:18:27.064 screenresolution[1641:7535] starting screenresolution argv=screenresolution list 02:18:27 INFO - Available Modes on Display 0 02:18:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:18:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:18:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:18:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:18:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:18:27 INFO - Intel Iris: 02:18:27 INFO - Chipset Model: Intel Iris 02:18:27 INFO - Type: GPU 02:18:27 INFO - Bus: Built-In 02:18:27 INFO - VRAM (Dynamic, Max): 1536 MB 02:18:27 INFO - Vendor: Intel (0x8086) 02:18:27 INFO - Device ID: 0x0a2e 02:18:27 INFO - Revision ID: 0x0009 02:18:27 INFO - Displays: 02:18:27 INFO - Display: 02:18:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:18:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:18:27 INFO - Display Serial Number: 1600x1200 60 02:18:27 INFO - Main Display: Yes 02:18:27 INFO - Mirror: Off 02:18:27 INFO - Online: Yes 02:18:27 INFO - Rotation: Supported 02:18:27 INFO - Return code: 0 02:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:18:27 INFO - Python 2.7.3 02:18:27 INFO - Return code: 0 02:18:27 INFO - grabbing minidump binary from tooltool 02:18:27 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')]} 02:18:27 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 0x105d49d30>, '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 0x105b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:18:27 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 02:18:27 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 02:18:27 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 02:18:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:18:27 INFO - Return code: 0 02:18:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:18:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:18:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:18:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:18:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:18:27 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/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 02:18:27 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/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 02:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wifJJkbR2Y/Render', 02:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:27 INFO - 'HOME': '/Users/cltbld', 02:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:27 INFO - 'LOGNAME': 'cltbld', 02:18:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:18:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:27 INFO - 'MOZ_NO_REMOTE': '1', 02:18:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:18:27 INFO - 'NO_EM_RESTART': '1', 02:18:27 INFO - 'PAGER': '/bin/cat', 02:18:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:27 INFO - 'PWD': '/builds/slave/test', 02:18:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:27 INFO - 'SHELL': '/bin/bash', 02:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8HguaQ6vhP/Listeners', 02:18:27 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 02:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:27 INFO - 'USER': 'cltbld', 02:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:27 INFO - 'XPC_FLAGS': '0x0', 02:18:27 INFO - 'XPC_SERVICE_NAME': '0', 02:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:27 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/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 02:18:27 INFO - mozversion application_buildid: 20161012003647 02:18:27 INFO - mozversion application_changeset: ae10e05963870f953b47eb29ba485001fd4c07d3 02:18:27 INFO - mozversion application_display_name: Nightly 02:18:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:18:27 INFO - mozversion application_name: Firefox 02:18:27 INFO - mozversion application_remotingname: firefox 02:18:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:18:27 INFO - mozversion application_vendor: Mozilla 02:18:27 INFO - mozversion application_version: 52.0a1 02:18:27 INFO - mozversion platform_buildid: 20161012003647 02:18:27 INFO - mozversion platform_changeset: ae10e05963870f953b47eb29ba485001fd4c07d3 02:18:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:18:27 INFO - mozversion platform_version: 52.0a1 02:18:27 INFO - using testdate: 1476263907 02:18:27 INFO - actual date: 1476263907 02:18:27 INFO - starting webserver on 'localhost:49267' 02:18:27 INFO - SUITE-START | Running 2 tests 02:18:27 INFO - TEST-START | dromaeo_css 02:18:27 INFO - operating with platform_type : mac_ 02:18:27 INFO - Initialising browser for dromaeo_css test... 02:18:27 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpz6bwVp/profile http://localhost:49267/getInfo.html) 02:18:30 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 02:18:30 INFO - PROCESS | 1651 | colorDepth:24 02:18:30 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 02:18:30 INFO - PROCESS | 1651 | __metrics 02:18:30 INFO - PROCESS | 1651 | 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" 02:18:30 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:18:31 INFO - TEST-INFO | 1651: exit 0 02:18:31 INFO - Browser initialized. 02:18:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:18:31 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpz6bwVp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:18:43 INFO - PROCESS | 1653 | RSS: Main: 235073536 02:18:43 INFO - PROCESS | 1653 | 02:19:34 INFO - PROCESS | 1653 | 1476263974666 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] 02:21:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 02:21:49 INFO - PROCESS | 1653 | RSS: Main: 374120448 02:21:49 INFO - PROCESS | 1653 | 02:24:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 02:24:52 INFO - PROCESS | 1653 | RSS: Main: 366993408 02:24:52 INFO - PROCESS | 1653 | 02:27:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 02:27:55 INFO - PROCESS | 1653 | RSS: Main: 340254720 02:27:55 INFO - PROCESS | 1653 | 02:30:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 02:30:58 INFO - PROCESS | 1653 | RSS: Main: 339136512 02:30:58 INFO - PROCESS | 1653 | 02:34:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 02:34:02 INFO - PROCESS | 1653 | RSS: Main: 330997760 02:34:02 INFO - PROCESS | 1653 | 02:37:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 02:37:06 INFO - PROCESS | 1653 | RSS: Main: 462467072 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | __start_tp_report 02:37:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:37:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:37:06 INFO - PROCESS | 1653 | |i|pagename|runs| 02:37:06 INFO - PROCESS | 1653 | |0;dojo.html;3299.4390953537895;3299.538064670946;3331.1672081979505;3375.510550970108;3380.814055226963;5526.143447765593;5612.989661860864;5627.296587926509;5652.943470565293;5656.604037717357;5627.099664053752;5628.409017053212;5707.885842283152;5712.514436272912;5722.427757224286;5065.544101030907;5100.15847385181;5131.358580177478;5182.689038657673;5213.869653258661;5265.473452654742;5286.682799032051;5307.097793375136;5310.946890531084;5354.464553544653;5077.136886729228;5229.346331708536;5243.842684719464;5246.265522826808;5341.225522299264;5120.1039818031695;5173.689578625294;5186.7147306898105;5186.740662966874;5190.818321358772;5090.720010399426;5127.641065125443;5140.1004824155625;5157.664751791159;5169.974150129263;5641.5204707599905;5641.633293835928;5647.152927060933;5650.74571644277;5659.773609055633;6116.052011938156;6128.108695869037;6146.617011172495;6177.938220617782;6192.659403732793;6188.535859810532;6191.919743194101;6249.468795152372;6258.374162583751;6268.15379923709;5588.161775733673;5726.627769194989;5734.197212390269;5754.568407369422;5789.971050144764;6209.782657606962;6277.905831412532;6286.9056964145575;6307.716152773136;6333.145025421509;6071.453569178707;6073.362296958756;6084.8783024340155;6087.726052327655;6116.082587611894;6149.292831324366;6193.138386366771;6227.346128656516;6229.0967809667345;6246.312905580383;5668.291463567054;5955.987482128048;6009.429104235091;6034.336223015464;6158.938410615837;6122.7550897964475;6132.816015519542;6134.938650613437;6142.355052719489;6144.17053697754;4869.440014398389;4898.657094003385;4899.608031357483;4903.239997800275;4908.509149085063;4342;4347.760873152007;4405.559444055633;4440.555944405598;4447.955520444819;5510.476504732044;5683.886322273532;5684.403137670568;5689.231953686282;5693.231413759089;6113.354685976816;6135.7839197735275;6146.7399183167345;6156.107364432221;6161.6611086389785;5641.097424412156;5654.6324488908085;5695.971520142372;5729.4843464088435;5743.798967036134;5346.4653534647005;5360.276362691065;5371.167469042305;5373.650712703662;5374.785008599612;6156.984097624021;6274.152989346562;6285.402886725754;6297.055441683784;6307.117003619405;6096.980278560502;6142.928535732134;6158.692065396623;6191.349908259568;6247.531435142291;5092.592592592509;5345.251664766858;6175.6912154393;6288.717007734755;6289.779857704958;6135.263768347827;6147.262328520606;6158.815235542941;6161.938380616316;6175.44421002112;4588.701734387254;6022.156898034191;6100.694965251809;6100.793122958854;6103.542234332532;6059.969700151471;6076.483498902643;6080.513558915364;6089.086637004308;6120.846978825565;6077.848053798691;6079.209702738615;6087.117367981707;6089.147519347206;6099.268087829311;5735;5787.768489260382;5792.681402522901;5840.532757379485;5860.531157507475;5918.467337939606;5936.050231962865;6001.159837622824;6040.426159514839;6078.817635470943;5821.854453638693;5855.121731740102;5868.471837534642;5870.823875283749;5921.792737254175 02:37:06 INFO - PROCESS | 1653 | |1;ext.html;478.1345764166857;484.36306257271707;494.43634256947064;515.3969855269335;525.8001959255482;29894.850525747366;32876.5068523972;40249.19501609974;40399.99999999996;42842.78578607103;41686.58313416865;42054;42076.579234207646;42093.15813683724;42256.36615450763;9348.39235449697;9532.189763870045;9534.998825123366;9540.522973851314;9554.570044348022;9357.391769534992;9462.243020558373;9479;9494;9495.715128546155;9409.670661526814;9484.525773711286;9488.193503552206;9488.952555237214;9498.050194980517;9379.343445958786;9428;9443.575039123256;9448.763780905463;9449.385789923634;9362.485063321514;9421.528923553864;9448.338616296862;9449.433034017911;9459.90540094597;39280.214395711926;39482;39495.8025209875;39577;39596.802015990026;3315.088350703552;3324.6010478742464;3503.5445392098864;3588;4020.618041286102;30861.845690771403;31230.37539249225;31765.841170794;32033.03900882977;32094.197645058826;3154.8895788647287;3222.3555288942307;3227.2415982244156;3256.674332566748;3360.41192791263;499253.9999999964;503585;504537;505615;508446;59501;59764.40235597675;59792.80414391688;59826.700866496256;59902.40097599055;34206.82896585551;34930.65069349274;34985.300293993976;36192;36337.63662363395;16041.278142483616;16215.27031283595;16230.837691623168;16238.837611623732;16265.024098554124;12284.631461056182;12395.876041239471;12400.379981000913;12400.441980110916;12436.00511959041;19028.80971190298;19097.140628671743;19102.522436939187;19105.426837194907;19111.044447777556;6183.350748171467;7515.158786097441;7526.3065810192265;7597.962010189914;7609.162992070867;856.9785755356041;983.695054533097;1008.8991100889853;1031.0771859185986;1059.4279089291697;24133.276001719976;24998.250052498453;28027.719722802918;29567.704322956924;30498.84750576233;1015.3552494166257;1019.6227395863579;1035.5236591168148;1038.0294424712918;1058.7300238439302;13994.613243852946;15251.847481525043;15410.381909899523;15572.221388930735;15810.762838557432;669479;681841;683537;684352;685791;42477.57522424736;42674.57325426706;42766.57233427626;42773.71678849639;42831.78584107122;1211.200607598998;1237.2576454127452;1310.4889093253448;1342.4026308292912;1348.2315080404467;29413;29823.518925664306;30669.23326916845;30703.9253626122;30977;49929;50524.49475505345;50560.24159637682;50572.4828255153;50678.74660626673;403479.98260008515;471939;472462;473324;475509.24490756035;20107.09518071661;22508.887455562617;23961;24687.135950241656;26648.60027099634;39967;45636.77181614071;47254.52745472639;47951.5204847947;49482.752586236835;37659.62340376561;38530.6146938527;38533;38569.42145867758;38832.61167388403;38195.85412437631;38559.6144038556;38740.031499212746;39299.803500982314;39643.405348920365;37332.440013400366;37853.81073094617;38099.238015240095;38306.80846595749;38317.42523862088;31185.8440707795;33755.15612109619;33986.15034624154;34166.31667366589;34342.65657343494;24839.62740558857;24902.50194996126;25406.618900716134;25578.74421255838;25771.226863194133 02:37:06 INFO - PROCESS | 1653 | |2;jquery.html;368.195492848127;374.8313259033435;383.71796729403883;391.2605176216952;405.92287465381577;18255.543611409707;20040.29858954937;20321.796782032176;20730;20772;18751.906240468812;19832.20671173155;20304;20430.182792688276;20936.057877395517;4644.302634519402;5831.970840145804;6042.063480160582;6094.664793436359;6149.631022138686;5967.612105213169;5975.551833612475;6015.037593984964;6017.939820601804;6029.638221706711;5739.225204597367;5962;6035.69821508925;6085.117657939596;6130.203073600425;5898.469137777621;6106.267247930255;6149.5080393568405;6155.045967874987;6182.1345011698395;5930.466258036798;5995.070764031539;6002.699865006776;6003.699815009276;6018.729157187937;13148.211107333591;13241.668958276025;13315.201087934658;13370.264635445046;13563.118397304144;2086.8017538333834;2226;2453.251758213744;2728.140635699748;2835.2203144135324;10239.692809215736;10269.537870795832;10466.581336746522;12864.09951303409;14154.150750954976;1794.3809385761892;1800.4148651688122;1826.7669323206915;1838.9816101838944;1868.2900497810745;122535;123962.76037239692;127111;128559.71440285571;133035;10697.411642359744;11112.722181945515;11365.829512557319;11387.259828111146;11650.94174529139;12043.698907527381;14647.92676036613;14659.92670036643;14660.56018319452;14674.266286685837;7925;8235.794105147419;8258.876116858251;8303.335733141455;8381.329493640495;8033.063798738576;8057.758267251992;8100.959495202486;8168.264856162974;8245.79385515367;4430.021879686296;4533.048059907391;4562.201614717411;4598.264277715616;4664.463586687574;1853.1382906948065;1899.6200759848086;1904.2668572599648;1909.4462605844149;1909.5608010157741;857.5840717252128;902.1970446302796;905.5472263868066;908.9590968406437;1001.1290177545436;18852.434426967215;19920.203191872162;20377;20457.590848183252;20560.347403438394;920.581135583308;932.6689025396022;940.4122423485321;948.151404492975;980.3039841712322;10414.843777343345;10867.021968022917;10959.83560246597;10968.561257549609;10990.780184396268;498306;562112;562959;564912;583732;103409;123994;129939.70060299276;130525;144708;949.7625593601599;979.3780949097232;995.7510622344414;999.1157825324494;1001.4341896828414;17927.64144717072;18848.905755471682;18979;19036.809631903503;19121.426357209308;55557.44442555523;56529.15206271991;57022.85954280974;57138.714306429865;57795.71102144462;75510;76763.18262712542;78471;82631.58684206782;84568.73146902678;7786.4938778979185;7935.880961785693;8080.676772929017;8099.8380032397845;8279.130691277329;10201;11365.659030229106;11599.942000289944;11947.581834635746;12374.81437778452;10541.947290263499;10659.14726821868;10864.511097000495;10973.396463194282;10996.780064398827;10032.598696052368;10692.83960740574;10938.6718398445;10968.506417211085;11115.555377784798;9088.773015642804;10722.678319650424;11052.723681907695;11073.833892491459;11488.483018264365;5325.680459172462;7932.84134317322;8011.559364235023;8238.340932725248;8294.751157465285;8696.782580435287;10386.117180039479;10730;10793.352398856094;10888.29226100301 02:37:06 INFO - PROCESS | 1653 | |3;mootools.html;1309.7295623245452;1515.6968606278745;1635.0761819571942;1638.9918050409747;1864.0865975671916;7991.520508769478;8469.364797640179;8957.95521022394;8970.058143894887;9003.099690031002;8046.718364857231;8215.342772578195;8545.487270763759;8769.079246679103;8783.472991620507;2326.639370897514;2355.34050465869;2355.8704271265074;2359.433735903388;2362.6337917622795;2301.608726516488;2341.988290058547;2354.1878052072007;2356.6582845487396;2362.586547354215;1706.2833609883878;2127.617028934791;2135.540858715376;2264.7169103862016;2334.486412989148;1945.5816999345138;2314.155333303349;2327.837051406402;2335.089315167086;2345.0854166874933;2291.232437133562;2331.94170145746;2337.357226762637;2338.6141286687675;2338.6258198688297;8646.740597782076;8855.07021762712;8870.86693699595;8872.600732967032;8888.555572221363;5210.947890521122;5374.892502149935;5392.002479541258;5393.5954803389905;5394.622376433651;3473.6526347365316;3564.8395822188063;3704.129529560551;3707.814609269553;3721.5534135903595;1082.5345101606306;1082.5669732107142;1085.5766251162054;1108.927919685226;1109.7724966381872;11838.822417663741;11966.64100076999;11998.320235166997;12000.57997970067;12006.87993120075;4545.181867263925;4552.88617784558;4764.618830493552;4770.594499467584;4781.904361912742;10163.44101074448;10170.847437288447;10179.84730229047;10183.796324073624;10184.898151018395;5368.570514358842;5371.006363822706;5372.033034053829;5379.78480860769;5382.91925621116;4703.270992996091;4877.219644856877;4892.143874821892;4910.288008238786;4917.0903382874285;3148.622165340174;3699.315626609086;3734.9439758403646;3792.943105853414;3811.6760075393904;1122.2424863217295;1123.713453069462;1132.5073592987078;1138.4592318648577;1139.8632164140356;626.5833220908136;757.1950946493803;782.2763943352377;868.2229404682773;911.5260064766285;5420.430854760193;5751.424857514216;5799.94200057994;5842.737076831553;5943.405659434108;469.71112765648877;470.8493282149679;474.99762501187274;481.80727708916015;630.5050535329744;3768.660820526166;3901.7268791184347;3920.9019774505864;3976.8409263629133;3977.105151340983;9003.729888103368;10712.303700259457;10723.785524289471;10979.231453798158;10982.505787239594;10700.732481688021;10725.570977160982;10741.677749667522;10948.95984881435;10985.395803230898;7114;8037.91488149104;8053.476524025919;8228.958855205805;8378.785076163922;5047.520485553867;5762.481376676119;5796.246487956539;5877.647341159373;5911.733971971357;9882;10048.84926726085;10104.04011618895;10136.543855526666;10284.845727314247;7672.923270767221;9841.360311579725;10212.131968782736;10251.897481025393;10374.948125259627;7046.084009078992;7052.823679408056;7091.007258983644;7132.286771322826;7141;8313.251807337369;8330.333573314241;8417.494950303002;8427.452215605967;8443.8733419;7441.479096463412;7494.362979146834;7534;7539.962300188464;7703.922960770545;7363.595002274927;7382.040334756447;7399.741009064657;7439.256074392518;7458.1796002438605;6826.965865170642;7006.75476358325;7010.614416207158;7015;7024.438044956289;3827.3876179811095;4971.428285747185;5038.546530812097;5115.872103197301;5165.527824569979;4256.978715106405;5091.312672789126;5128.230765385222;5143.871403214949;5186.910748742655 02:37:06 INFO - PROCESS | 1653 | |4;prototype.html;302.4706763164462;303.2237472071497;310.41022058089635;310.9828959407233;319.11286623187544;3863.0728625129977;4904.0437114762635;5002.374703162105;5194.584433245341;5230.5031022052935;3950.5259368875704;4499.887502812431;4832.269767581314;4942.578136713012;5186.766595503202;3961.2671655743634;3965.9206815863668;3979.781112038837;3989.18221764539;3998.280309544281;3414.4195486767308;3663.358912190369;3964.1962906877447;3976.4631774710333;4003.4595329630565;3266.395716792389;3591.5151454553693;3677.595464498903;3954.7429417087797;3964.6828253739777;3275.5905511811025;3954.8813535593977;3972.781497017663;3979.9851037985272;3994.7216890595023;3085.9074227773203;3460.3944309745943;3464.41105012146;3471.7396195285455;3473.114355839245;4764.213904705719;5032.773525191338;5060.1903695408555;5076.949230507722;5080.916824102137;4445.732386695776;4485.596296333346;4492.9550704493195;4506.1663592235545;4521.547845215485;4493.932591011137;5363.6245462817615;5435;5445.428230035829;5453.23654688344;3168.8574014169417;3787.1478917243676;3787.5833658297565;3789.3747531657245;3801.581825999138;4881.6338774591695;5222.895542089175;5587.74855131516;5597.944020559742;5675.701340956878;3566.8038257895546;3819.579846216914;3840.231953609289;3840.4461033412467;3890.046938500029;3613.4941108244866;3925.430812532167;3928.7642741435607;3943.2310699413338;3961.841526338972;3966.8413263469715;3981.7610943343493;3988.561258261589;4137.131202447459;4469.4636643602325;4787.593054590329;5465.398806131322;5480.342358916876;5547.16792481123;5587.552995760329;1285.4022879361141;1307.9084464087423;1309.5154792726753;1309.6922223277522;1313.914595551286;818.2594751749676;840.920112589303;842.8651415773447;862.6204470033165;888.7291173621783;4101.323281658493;4703.412073490814;4715.599174070174;5096.763555142511;5099;4970.378702662168;5540.335159780852;5566.777328906879;5621.4659607337235;5642.379338272786;4725.362076119749;4844.434543699372;4939.728314942641;4970.229614409773;5080.364954380702;2587.7929765618705;2669.759721625063;2796.5944937984286;2906.6512018557487;2919.9270018249713;4786;5473.28847249863;5657.2928383952;5693.345265294462;5724.942750572441;4902.313676085351;5253.349897950171;5407.486288802557;5633.239512665819;5635.464630860061;5004.699718016858;5163.483651634807;5611.204414587456;5657.783576531066;5675.489205971482;4109.8972525687095;4754.5245475452175;4947.579455746302;5037.445880953165;5527.53015993645;4795.690973228461;5364.544013758718;5411.864703382289;5442.809501667422;5450.373207081237;4698.154332220233;4830.565249127595;5595.034631645071;5644.717764111861;5694.515966142924;3349.4808304711833;3408.8295585220144;3722.3858063419234;3730.421784623388;3742.2515496900182;4653.86038418848;4996.951249987487;5450.563954883521;5467.58993075513;5483.862903427286;4696.829639992923;5063.974680126576;5293.523582877559;5296.285001524745;5300.655457395257;4802.543758343091;5026.296318515484;5070.721110338966;5165.553644979412;5319.388270348804;5069.518395752398;5351.652142610874;5381.623286369917;5403.702796346239;5423.230854556045;4753.5008824072975;4928.6057115431395;4959.305697202322;5110.693358398508;5237.847673511821;5337.279467271868;5398;5398.64908780928;5399.406065332889;5409.6213265071065 02:37:06 INFO - PROCESS | 1653 | |5;yui.html;705.3105737316263;733.2410954661926;769.4114002787868;780.7891869195314;805.6575955219031;14528;14999.0250633709;15023.699526009474;15038.699226015488;15043.699126017487;11755.941220293907;14202.431902723878;14226.502072427467;14236.501722439716;14240.857591424083;590.8079874040933;692.9411000065;696.2132789947377;820.7086484298078;829.5686243153557;847.4872702015253;858.3862538285125;859.5401460218784;861.2248975921689;863.6761214544546;901.256463417683;901.5852707754408;907.6142639378271;908.736466424736;909.0682050897816;701.8526109517007;896.8968568614628;901.4050726520466;903.2367649336301;904.5251243097393;541.4314969282277;650.4373716735038;670.282797406775;678.7793966960758;680.0717021265986;14889.957702960797;14956.700865982619;14967.401303947829;14969.251537423086;14976.625584360369;13374.264415457146;13775;13885.652858678513;13901.79147312791;14050.128649626065;12516.937415312865;12544.24734515932;12612.369381530887;12723.10938234324;12954.80567791484;4409.845655402078;4467.307567327037;4553.590176884097;4658.627309815207;4662.393888794469;97474;99934.50032749862;116001;117705;119909;10389.584416623251;11169.273997190157;11471.139664525193;11502.309861408343;12017.579384721494;23392.883035584713;23752.524949501258;23776.7622323778;23778.286651400485;23791.286261412184;8179.304759095425;8199.180081991872;8333.95833020831;8371.121032291643;8384;3880.2239552089695;4259.467566554181;4353.194658988097;4457.8216871324785;4587;3410.6759857813468;3573.928521429557;3581.3553560358864;3581.8746343877838;3582.3014512169875;640.6572483721187;660.3132741948416;662.1193812229723;666.7999600119945;667.2293501006345;594.8661551150957;609.2810483629271;614.0942110387143;614.5452365249772;618.1037495631352;8101.716439924574;8818.823623527494;8820.657913088535;8867.778305542413;8996.415233009833;499.3957311652941;513.6327525819057;538.7737150396842;539.0674133748558;563.833669067626;3800.56293526248;4339.327404252283;4343.391925130484;4401.856862696435;4434.071179037129;152561.23719381553;158254;162960.37039629451;163909;164134;85633.57183214044;117303;131506;136618;140343;102546;116601.4169929145;118216.81783182058;118720.59830726079;121338.39330803466;7398.741044063432;7771.844563108819;8061.516309021477;8338.87491687613;8465.576721163794;65754.67122664547;65997.67001164964;66329.33670663231;66788;67568.66215668891;64170;67825.66087169532;84345.45681789776;90659;94115;13253.66865828362;13272.933635331761;13280.468781249028;13303.800442993557;13350.599482015556;14073.788893166406;14799.630009249424;14969.176695281863;15337.846621533641;15371.308291126703;13677.31613419345;14014.439422422989;14028.22844743549;14100.858991409954;14123.929380353033;13787.931060344634;13815.930920345334;13922.443102275796;13948.372323245681;14182.43270269219;13287.534936277183;13473.797893031417;13476.258805765778;13488.797668035184;13505.594832155051;11103.72240693989;11523.769524609628;11528.942355288169;11898.286102833858;11937.94031029874;10887.618933337611;11382.54469821198;11433.615607887981;11515.539378425105;11698.181127321006 02:37:06 INFO - PROCESS | 1653 | __end_tp_report 02:37:06 INFO - PROCESS | 1653 | __start_cc_report 02:37:06 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1741 02:37:06 INFO - PROCESS | 1653 | __end_cc_report 02:37:06 INFO - PROCESS | 1653 | __startTimestamp1476265026112__endTimestamp 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:37:06 INFO - PROCESS | 1653 | Number of tests: 6 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5673.79 Median:5821.85 stddev:627.22 (10.8%) stddev-sans-first:600.85 02:37:06 INFO - PROCESS | 1653 | Values: 3299.4 3299.5 3331.2 3375.5 3380.8 5526.1 5613.0 5627.3 5652.9 5656.6 5627.1 5628.4 5707.9 5712.5 5722.4 5065.5 5100.2 5131.4 5182.7 5213.9 5265.5 5286.7 5307.1 5310.9 5354.5 5077.1 5229.3 5243.8 5246.3 5341.2 5120.1 5173.7 5186.7 5186.7 5190.8 5090.7 5127.6 5140.1 5157.7 5170.0 5641.5 5641.6 5647.2 5650.7 5659.8 6116.1 6128.1 6146.6 6177.9 6192.7 6188.5 6191.9 6249.5 6258.4 6268.2 5588.2 5726.6 5734.2 5754.6 5790.0 6209.8 6277.9 6286.9 6307.7 6333.1 6071.5 6073.4 6084.9 6087.7 6116.1 6149.3 6193.1 6227.3 6229.1 6246.3 5668.3 5956.0 6009.4 6034.3 6158.9 6122.8 6132.8 6134.9 6142.4 6144.2 4869.4 4898.7 4899.6 4903.2 4908.5 4342.0 4347.8 4405.6 4440.6 4448.0 5510.5 5683.9 5684.4 5689.2 5693.2 6113.4 6135.8 6146.7 6156.1 6161.7 5641.1 5654.6 5696.0 5729.5 5743.8 5346.5 5360.3 5371.2 5373.7 5374.8 6157.0 6274.2 6285.4 6297.1 6307.1 6097.0 6142.9 6158.7 6191.3 6247.5 5092.6 5345.3 6175.7 6288.7 6289.8 6135.3 6147.3 6158.8 6161.9 6175.4 4588.7 6022.2 6100.7 6100.8 6103.5 6060.0 6076.5 6080.5 6089.1 6120.8 6077.8 6079.2 6087.1 6089.1 6099.3 5735.0 5787.8 5792.7 5840.5 5860.5 5918.5 5936.1 6001.2 6040.4 6078.8 5821.9 5855.1 5868.5 5870.8 5921.8 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:67022.04 Median:38786.32 stddev:149038.88 (384.3%) stddev-sans-first:149373.23 02:37:06 INFO - PROCESS | 1653 | Values: 478.1 484.4 494.4 515.4 525.8 29894.9 32876.5 40249.2 40400.0 42842.8 41686.6 42054.0 42076.6 42093.2 42256.4 9348.4 9532.2 9535.0 9540.5 9554.6 9357.4 9462.2 9479.0 9494.0 9495.7 9409.7 9484.5 9488.2 9489.0 9498.1 9379.3 9428.0 9443.6 9448.8 9449.4 9362.5 9421.5 9448.3 9449.4 9459.9 39280.2 39482.0 39495.8 39577.0 39596.8 3315.1 3324.6 3503.5 3588.0 4020.6 30861.8 31230.4 31765.8 32033.0 32094.2 3154.9 3222.4 3227.2 3256.7 3360.4 499254.0 503585.0 504537.0 505615.0 508446.0 59501.0 59764.4 59792.8 59826.7 59902.4 34206.8 34930.7 34985.3 36192.0 36337.6 16041.3 16215.3 16230.8 16238.8 16265.0 12284.6 12395.9 12400.4 12400.4 12436.0 19028.8 19097.1 19102.5 19105.4 19111.0 6183.4 7515.2 7526.3 7598.0 7609.2 857.0 983.7 1008.9 1031.1 1059.4 24133.3 24998.3 28027.7 29567.7 30498.8 1015.4 1019.6 1035.5 1038.0 1058.7 13994.6 15251.8 15410.4 15572.2 15810.8 669479.0 681841.0 683537.0 684352.0 685791.0 42477.6 42674.6 42766.6 42773.7 42831.8 1211.2 1237.3 1310.5 1342.4 1348.2 29413.0 29823.5 30669.2 30703.9 30977.0 49929.0 50524.5 50560.2 50572.5 50678.7 403480.0 471939.0 472462.0 473324.0 475509.2 20107.1 22508.9 23961.0 24687.1 26648.6 39967.0 45636.8 47254.5 47951.5 49482.8 37659.6 38530.6 38533.0 38569.4 38832.6 38195.9 38559.6 38740.0 39299.8 39643.4 37332.4 37853.8 38099.2 38306.8 38317.4 31185.8 33755.2 33986.2 34166.3 34342.7 24839.6 24902.5 25406.6 25578.7 25771.2 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33727.22 Median:11581.03 stddev:93437.41 (806.8%) stddev-sans-first:93665.95 02:37:06 INFO - PROCESS | 1653 | Values: 368.2 374.8 383.7 391.3 405.9 18255.5 20040.3 20321.8 20730.0 20772.0 18751.9 19832.2 20304.0 20430.2 20936.1 4644.3 5832.0 6042.1 6094.7 6149.6 5967.6 5975.6 6015.0 6017.9 6029.6 5739.2 5962.0 6035.7 6085.1 6130.2 5898.5 6106.3 6149.5 6155.0 6182.1 5930.5 5995.1 6002.7 6003.7 6018.7 13148.2 13241.7 13315.2 13370.3 13563.1 2086.8 2226.0 2453.3 2728.1 2835.2 10239.7 10269.5 10466.6 12864.1 14154.2 1794.4 1800.4 1826.8 1839.0 1868.3 122535.0 123962.8 127111.0 128559.7 133035.0 10697.4 11112.7 11365.8 11387.3 11650.9 12043.7 14647.9 14659.9 14660.6 14674.3 7925.0 8235.8 8258.9 8303.3 8381.3 8033.1 8057.8 8101.0 8168.3 8245.8 4430.0 4533.0 4562.2 4598.3 4664.5 1853.1 1899.6 1904.3 1909.4 1909.6 857.6 902.2 905.5 909.0 1001.1 18852.4 19920.2 20377.0 20457.6 20560.3 920.6 932.7 940.4 948.2 980.3 10414.8 10867.0 10959.8 10968.6 10990.8 498306.0 562112.0 562959.0 564912.0 583732.0 103409.0 123994.0 129939.7 130525.0 144708.0 949.8 979.4 995.8 999.1 1001.4 17927.6 18848.9 18979.0 19036.8 19121.4 55557.4 56529.2 57022.9 57138.7 57795.7 75510.0 76763.2 78471.0 82631.6 84568.7 7786.5 7935.9 8080.7 8099.8 8279.1 10201.0 11365.7 11599.9 11947.6 12374.8 10541.9 10659.1 10864.5 10973.4 10996.8 10032.6 10692.8 10938.7 10968.5 11115.6 9088.8 10722.7 11052.7 11073.8 11488.5 5325.7 7932.8 8011.6 8238.3 8294.8 8696.8 10386.1 10730.0 10793.4 10888.3 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5658.87 Median:4776.25 stddev:3250.89 (68.1%) stddev-sans-first:3243.58 02:37:06 INFO - PROCESS | 1653 | Values: 1309.7 1515.7 1635.1 1639.0 1864.1 7991.5 8469.4 8958.0 8970.1 9003.1 8046.7 8215.3 8545.5 8769.1 8783.5 2326.6 2355.3 2355.9 2359.4 2362.6 2301.6 2342.0 2354.2 2356.7 2362.6 1706.3 2127.6 2135.5 2264.7 2334.5 1945.6 2314.2 2327.8 2335.1 2345.1 2291.2 2331.9 2337.4 2338.6 2338.6 8646.7 8855.1 8870.9 8872.6 8888.6 5210.9 5374.9 5392.0 5393.6 5394.6 3473.7 3564.8 3704.1 3707.8 3721.6 1082.5 1082.6 1085.6 1108.9 1109.8 11838.8 11966.6 11998.3 12000.6 12006.9 4545.2 4552.9 4764.6 4770.6 4781.9 10163.4 10170.8 10179.8 10183.8 10184.9 5368.6 5371.0 5372.0 5379.8 5382.9 4703.3 4877.2 4892.1 4910.3 4917.1 3148.6 3699.3 3734.9 3792.9 3811.7 1122.2 1123.7 1132.5 1138.5 1139.9 626.6 757.2 782.3 868.2 911.5 5420.4 5751.4 5799.9 5842.7 5943.4 469.7 470.8 475.0 481.8 630.5 3768.7 3901.7 3920.9 3976.8 3977.1 9003.7 10712.3 10723.8 10979.2 10982.5 10700.7 10725.6 10741.7 10949.0 10985.4 7114.0 8037.9 8053.5 8229.0 8378.8 5047.5 5762.5 5796.2 5877.6 5911.7 9882.0 10048.8 10104.0 10136.5 10284.8 7672.9 9841.4 10212.1 10251.9 10374.9 7046.1 7052.8 7091.0 7132.3 7141.0 8313.3 8330.3 8417.5 8427.5 8443.9 7441.5 7494.4 7534.0 7540.0 7703.9 7363.6 7382.0 7399.7 7439.3 7458.2 6827.0 7006.8 7010.6 7015.0 7024.4 3827.4 4971.4 5038.5 5115.9 5165.5 4257.0 5091.3 5128.2 5143.9 5186.9 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4332.36 Median:4838.35 stddev:1311.91 (27.1%) stddev-sans-first:1280.25 02:37:06 INFO - PROCESS | 1653 | Values: 302.5 303.2 310.4 311.0 319.1 3863.1 4904.0 5002.4 5194.6 5230.5 3950.5 4499.9 4832.3 4942.6 5186.8 3961.3 3965.9 3979.8 3989.2 3998.3 3414.4 3663.4 3964.2 3976.5 4003.5 3266.4 3591.5 3677.6 3954.7 3964.7 3275.6 3954.9 3972.8 3980.0 3994.7 3085.9 3460.4 3464.4 3471.7 3473.1 4764.2 5032.8 5060.2 5076.9 5080.9 4445.7 4485.6 4493.0 4506.2 4521.5 4493.9 5363.6 5435.0 5445.4 5453.2 3168.9 3787.1 3787.6 3789.4 3801.6 4881.6 5222.9 5587.7 5597.9 5675.7 3566.8 3819.6 3840.2 3840.4 3890.0 3613.5 3925.4 3928.8 3943.2 3961.8 3966.8 3981.8 3988.6 4137.1 4469.5 4787.6 5465.4 5480.3 5547.2 5587.6 1285.4 1307.9 1309.5 1309.7 1313.9 818.3 840.9 842.9 862.6 888.7 4101.3 4703.4 4715.6 5096.8 5099.0 4970.4 5540.3 5566.8 5621.5 5642.4 4725.4 4844.4 4939.7 4970.2 5080.4 2587.8 2669.8 2796.6 2906.7 2919.9 4786.0 5473.3 5657.3 5693.3 5724.9 4902.3 5253.3 5407.5 5633.2 5635.5 5004.7 5163.5 5611.2 5657.8 5675.5 4109.9 4754.5 4947.6 5037.4 5527.5 4795.7 5364.5 5411.9 5442.8 5450.4 4698.2 4830.6 5595.0 5644.7 5694.5 3349.5 3408.8 3722.4 3730.4 3742.3 4653.9 4997.0 5450.6 5467.6 5483.9 4696.8 5064.0 5293.5 5296.3 5300.7 4802.5 5026.3 5070.7 5165.6 5319.4 5069.5 5351.7 5381.6 5403.7 5423.2 4753.5 4928.6 4959.3 5110.7 5237.8 5337.3 5398.0 5398.6 5399.4 5409.6 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25025.77 Median:3582.09 stddev:40373.15 (1127.1%) stddev-sans-first:40445.11 02:37:06 INFO - PROCESS | 1653 | Values: 705.3 733.2 769.4 780.8 805.7 14528.0 14999.0 15023.7 15038.7 15043.7 11755.9 14202.4 14226.5 14236.5 14240.9 590.8 692.9 696.2 820.7 829.6 847.5 858.4 859.5 861.2 863.7 901.3 901.6 907.6 908.7 909.1 701.9 896.9 901.4 903.2 904.5 541.4 650.4 670.3 678.8 680.1 14890.0 14956.7 14967.4 14969.3 14976.6 13374.3 13775.0 13885.7 13901.8 14050.1 12516.9 12544.2 12612.4 12723.1 12954.8 4409.8 4467.3 4553.6 4658.6 4662.4 97474.0 99934.5 116001.0 117705.0 119909.0 10389.6 11169.3 11471.1 11502.3 12017.6 23392.9 23752.5 23776.8 23778.3 23791.3 8179.3 8199.2 8334.0 8371.1 8384.0 3880.2 4259.5 4353.2 4457.8 4587.0 3410.7 3573.9 3581.4 3581.9 3582.3 640.7 660.3 662.1 666.8 667.2 594.9 609.3 614.1 614.5 618.1 8101.7 8818.8 8820.7 8867.8 8996.4 499.4 513.6 538.8 539.1 563.8 3800.6 4339.3 4343.4 4401.9 4434.1 152561.2 158254.0 162960.4 163909.0 164134.0 85633.6 117303.0 131506.0 136618.0 140343.0 102546.0 116601.4 118216.8 118720.6 121338.4 7398.7 7771.8 8061.5 8338.9 8465.6 65754.7 65997.7 66329.3 66788.0 67568.7 64170.0 67825.7 84345.5 90659.0 94115.0 13253.7 13272.9 13280.5 13303.8 13350.6 14073.8 14799.6 14969.2 15337.8 15371.3 13677.3 14014.4 14028.2 14100.9 14123.9 13787.9 13815.9 13922.4 13948.4 14182.4 13287.5 13473.8 13476.3 13488.8 13505.6 11103.7 11523.8 11528.9 11898.3 11937.9 10887.6 11382.5 11433.6 11515.5 11698.2 02:37:06 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:37:06 INFO - PROCESS | 1653 | 02:37:06 INFO - TEST-INFO | 1653: exit 0 02:37:06 INFO - TEST-OK | dromaeo_css | took 1119124ms 02:37:06 INFO - TEST-START | kraken 02:37:06 INFO - operating with platform_type : mac_ 02:37:06 INFO - Initialising browser for kraken test... 02:37:06 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpTq2Q20/profile http://localhost:49267/getInfo.html) 02:37:08 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 02:37:08 INFO - PROCESS | 1663 | colorDepth:24 02:37:08 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 02:37:08 INFO - PROCESS | 1663 | __metrics 02:37:09 INFO - PROCESS | 1663 | 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" 02:37:09 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:37:09 INFO - TEST-INFO | 1663: exit 0 02:37:09 INFO - Browser initialized. 02:37:09 INFO - Running cycle 1/1 for kraken test... 02:37:09 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpTq2Q20/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:21 INFO - PROCESS | 1664 | RSS: Main: 234663936 02:37:21 INFO - PROCESS | 1664 | 02:37:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 02:37:25 INFO - PROCESS | 1664 | RSS: Main: 293478400 02:37:25 INFO - PROCESS | 1664 | 02:37:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 02:37:29 INFO - PROCESS | 1664 | RSS: Main: 293535744 02:37:29 INFO - PROCESS | 1664 | 02:37:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 02:37:33 INFO - PROCESS | 1664 | RSS: Main: 381317120 02:37:33 INFO - PROCESS | 1664 | 02:37:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 02:37:35 INFO - PROCESS | 1664 | RSS: Main: 283959296 02:37:35 INFO - PROCESS | 1664 | 02:37:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:37:38 INFO - PROCESS | 1664 | RSS: Main: 287825920 02:37:38 INFO - PROCESS | 1664 | 02:37:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 02:37:41 INFO - PROCESS | 1664 | RSS: Main: 325967872 02:37:41 INFO - PROCESS | 1664 | 02:37:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 02:37:45 INFO - PROCESS | 1664 | RSS: Main: 327090176 02:37:45 INFO - PROCESS | 1664 | 02:37:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 02:37:48 INFO - PROCESS | 1664 | RSS: Main: 327204864 02:37:48 INFO - PROCESS | 1664 | 02:37:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:37:50 INFO - PROCESS | 1664 | RSS: Main: 300654592 02:37:50 INFO - PROCESS | 1664 | 02:37:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:37:53 INFO - PROCESS | 1664 | RSS: Main: 306417664 02:37:53 INFO - PROCESS | 1664 | 02:37:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:37:56 INFO - PROCESS | 1664 | RSS: Main: 316948480 02:37:56 INFO - PROCESS | 1664 | 02:37:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:38:00 INFO - PROCESS | 1664 | RSS: Main: 326160384 02:38:00 INFO - PROCESS | 1664 | 02:38:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:38:03 INFO - PROCESS | 1664 | RSS: Main: 318971904 02:38:03 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 02:38:05 INFO - PROCESS | 1664 | RSS: Main: 363376640 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | __start_tp_report 02:38:05 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 02:38:05 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 02:38:05 INFO - PROCESS | 1664 | |i|pagename|runs| 02:38:05 INFO - PROCESS | 1664 | |0;ai-astar;113;113;113;112;112;113;111;113;112;112 02:38:05 INFO - PROCESS | 1664 | |1;audio-beat-detection;149;114;119;181;147;137;145;142;154;113 02:38:05 INFO - PROCESS | 1664 | |2;audio-dft;182;181;182;186;182;184;185;182;182;182 02:38:05 INFO - PROCESS | 1664 | |3;audio-fft;77;58;58;87;62;61;74;83;95;61 02:38:05 INFO - PROCESS | 1664 | |4;audio-oscillator;89;74;90;70;68;70;93;68;69;110 02:38:05 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;86;86;87;84;87;85;88;84;89;124 02:38:05 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;93;92;93;92;92;93;92;93;92 02:38:05 INFO - PROCESS | 1664 | |7;imaging-desaturate;86;86;81;84;85;83;86;85;85;86 02:38:05 INFO - PROCESS | 1664 | |8;json-parse-financial;57;54;54;53;53;54;55;53;53;54 02:38:05 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;77;77;73;71;70;71;71;70;71 02:38:05 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;89;88;90;86;86;86;89;90;92;92 02:38:05 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;145;147;145;154;155;153;152;141;183;155 02:38:05 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;171;172;172;179;170;172;171;170;171;168 02:38:05 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;69;69;69;68;69;69;76;77;90;85 02:38:05 INFO - PROCESS | 1664 | __end_tp_report 02:38:05 INFO - PROCESS | 1664 | __start_cc_report 02:38:05 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2071 02:38:05 INFO - PROCESS | 1664 | __end_cc_report 02:38:05 INFO - PROCESS | 1664 | __startTimestamp1476265085621__endTimestamp 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | ------- Summary: start ------- 02:38:05 INFO - PROCESS | 1664 | Number of tests: 14 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 02:38:05 INFO - PROCESS | 1664 | Values: 113.0 113.0 113.0 112.0 112.0 113.0 111.0 113.0 112.0 112.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:146.00 stddev:20.79 (14.2%) stddev-sans-first:21.80 02:38:05 INFO - PROCESS | 1664 | Values: 149.0 114.0 119.0 181.0 147.0 137.0 145.0 142.0 154.0 113.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:182.80 Median:182.00 stddev:1.62 (0.9%) stddev-sans-first:1.69 02:38:05 INFO - PROCESS | 1664 | Values: 182.0 181.0 182.0 186.0 182.0 184.0 185.0 182.0 182.0 182.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:71.60 Median:75.50 stddev:13.48 (17.9%) stddev-sans-first:14.16 02:38:05 INFO - PROCESS | 1664 | Values: 77.0 58.0 58.0 87.0 62.0 61.0 74.0 83.0 95.0 61.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:72.00 stddev:14.51 (20.2%) stddev-sans-first:15.03 02:38:05 INFO - PROCESS | 1664 | Values: 89.0 74.0 90.0 70.0 68.0 70.0 93.0 68.0 69.0 110.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:86.50 stddev:12.06 (13.9%) stddev-sans-first:12.70 02:38:05 INFO - PROCESS | 1664 | Values: 86.0 86.0 87.0 84.0 87.0 85.0 88.0 84.0 89.0 124.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 02:38:05 INFO - PROCESS | 1664 | Values: 93.0 93.0 92.0 93.0 92.0 92.0 93.0 92.0 93.0 92.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.50 stddev:1.64 (1.9%) stddev-sans-first:1.67 02:38:05 INFO - PROCESS | 1664 | Values: 86.0 86.0 81.0 84.0 85.0 83.0 86.0 85.0 85.0 86.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.25 (2.3%) stddev-sans-first:0.71 02:38:05 INFO - PROCESS | 1664 | Values: 57.0 54.0 54.0 53.0 53.0 54.0 55.0 53.0 53.0 54.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.00 stddev:3.50 (4.9%) stddev-sans-first:2.78 02:38:05 INFO - PROCESS | 1664 | Values: 65.0 77.0 77.0 73.0 71.0 70.0 71.0 71.0 70.0 71.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:89.50 stddev:2.30 (2.6%) stddev-sans-first:2.44 02:38:05 INFO - PROCESS | 1664 | Values: 89.0 88.0 90.0 86.0 86.0 86.0 89.0 90.0 92.0 92.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:153.50 stddev:11.63 (7.6%) stddev-sans-first:11.97 02:38:05 INFO - PROCESS | 1664 | Values: 145.0 147.0 145.0 154.0 155.0 153.0 152.0 141.0 183.0 155.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:171.50 stddev:2.88 (1.7%) stddev-sans-first:3.04 02:38:05 INFO - PROCESS | 1664 | Values: 171.0 172.0 172.0 179.0 170.0 172.0 171.0 170.0 171.0 168.0 02:38:05 INFO - PROCESS | 1664 | 02:38:05 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:72.50 stddev:7.82 (10.8%) stddev-sans-first:8.08 02:38:05 INFO - PROCESS | 1664 | Values: 69.0 69.0 69.0 68.0 69.0 69.0 76.0 77.0 90.0 85.0 02:38:05 INFO - PROCESS | 1664 | -------- Summary: end -------- 02:38:05 INFO - PROCESS | 1664 | 02:38:06 INFO - TEST-INFO | 1664: exit 0 02:38:06 INFO - TEST-OK | kraken | took 59817ms 02:38:06 INFO - SUITE-END | took 1178s 02:38:06 INFO - Completed test suite (00:19:39) 02:38:06 INFO - javascript benchmark 02:38:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.4390953537895, 3299.538064670946, 3331.1672081979505, 3375.510550970108, 3380.814055226963, 5526.143447765593, 5612.989661860864, 5627.296587926509, 5652.943470565293, 5656.604037717357, 5627.099664053752, 5628.409017053212, 5707.885842283152, 5712.514436272912, 5722.427757224286, 5065.544101030907, 5100.15847385181, 5131.358580177478, 5182.689038657673, 5213.869653258661, 5265.473452654742, 5286.682799032051, 5307.097793375136, 5310.946890531084, 5354.464553544653, 5077.136886729228, 5229.346331708536, 5243.842684719464, 5246.265522826808, 5341.225522299264, 5120.1039818031695, 5173.689578625294, 5186.7147306898105, 5186.740662966874, 5190.818321358772, 5090.720010399426, 5127.641065125443, 5140.1004824155625, 5157.664751791159, 5169.974150129263, 5641.5204707599905, 5641.633293835928, 5647.152927060933, 5650.74571644277, 5659.773609055633, 6116.052011938156, 6128.108695869037, 6146.617011172495, 6177.938220617782, 6192.659403732793, 6188.535859810532, 6191.919743194101, 6249.468795152372, 6258.374162583751, 6268.15379923709, 5588.161775733673, 5726.627769194989, 5734.197212390269, 5754.568407369422, 5789.971050144764, 6209.782657606962, 6277.905831412532, 6286.9056964145575, 6307.716152773136, 6333.145025421509, 6071.453569178707, 6073.362296958756, 6084.8783024340155, 6087.726052327655, 6116.082587611894, 6149.292831324366, 6193.138386366771, 6227.346128656516, 6229.0967809667345, 6246.312905580383, 5668.291463567054, 5955.987482128048, 6009.429104235091, 6034.336223015464, 6158.938410615837, 6122.7550897964475, 6132.816015519542, 6134.938650613437, 6142.355052719489, 6144.17053697754, 4869.440014398389, 4898.657094003385, 4899.608031357483, 4903.239997800275, 4908.509149085063, 4342.0, 4347.760873152007, 4405.559444055633, 4440.555944405598, 4447.955520444819, 5510.476504732044, 5683.886322273532, 5684.403137670568, 5689.231953686282, 5693.231413759089, 6113.354685976816, 6135.7839197735275, 6146.7399183167345, 6156.107364432221, 6161.6611086389785, 5641.097424412156, 5654.6324488908085, 5695.971520142372, 5729.4843464088435, 5743.798967036134, 5346.4653534647005, 5360.276362691065, 5371.167469042305, 5373.650712703662, 5374.785008599612, 6156.984097624021, 6274.152989346562, 6285.402886725754, 6297.055441683784, 6307.117003619405, 6096.980278560502, 6142.928535732134, 6158.692065396623, 6191.349908259568, 6247.531435142291, 5092.592592592509, 5345.251664766858, 6175.6912154393, 6288.717007734755, 6289.779857704958, 6135.263768347827, 6147.262328520606, 6158.815235542941, 6161.938380616316, 6175.44421002112, 4588.701734387254, 6022.156898034191, 6100.694965251809, 6100.793122958854, 6103.542234332532, 6059.969700151471, 6076.483498902643, 6080.513558915364, 6089.086637004308, 6120.846978825565, 6077.848053798691, 6079.209702738615, 6087.117367981707, 6089.147519347206, 6099.268087829311, 5735.0, 5787.768489260382, 5792.681402522901, 5840.532757379485, 5860.531157507475, 5918.467337939606, 5936.050231962865, 6001.159837622824, 6040.426159514839, 6078.817635470943, 5821.854453638693, 5855.121731740102, 5868.471837534642, 5870.823875283749, 5921.792737254175], "value": 5634.782849248608, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.1345764166857, 484.36306257271707, 494.43634256947064, 515.3969855269335, 525.8001959255482, 29894.850525747366, 32876.5068523972, 40249.19501609974, 40399.99999999996, 42842.78578607103, 41686.58313416865, 42054.0, 42076.579234207646, 42093.15813683724, 42256.36615450763, 9348.39235449697, 9532.189763870045, 9534.998825123366, 9540.522973851314, 9554.570044348022, 9357.391769534992, 9462.243020558373, 9479.0, 9494.0, 9495.715128546155, 9409.670661526814, 9484.525773711286, 9488.193503552206, 9488.952555237214, 9498.050194980517, 9379.343445958786, 9428.0, 9443.575039123256, 9448.763780905463, 9449.385789923634, 9362.485063321514, 9421.528923553864, 9448.338616296862, 9449.433034017911, 9459.90540094597, 39280.214395711926, 39482.0, 39495.8025209875, 39577.0, 39596.802015990026, 3315.088350703552, 3324.6010478742464, 3503.5445392098864, 3588.0, 4020.618041286102, 30861.845690771403, 31230.37539249225, 31765.841170794, 32033.03900882977, 32094.197645058826, 3154.8895788647287, 3222.3555288942307, 3227.2415982244156, 3256.674332566748, 3360.41192791263, 499253.9999999964, 503585.0, 504537.0, 505615.0, 508446.0, 59501.0, 59764.40235597675, 59792.80414391688, 59826.700866496256, 59902.40097599055, 34206.82896585551, 34930.65069349274, 34985.300293993976, 36192.0, 36337.63662363395, 16041.278142483616, 16215.27031283595, 16230.837691623168, 16238.837611623732, 16265.024098554124, 12284.631461056182, 12395.876041239471, 12400.379981000913, 12400.441980110916, 12436.00511959041, 19028.80971190298, 19097.140628671743, 19102.522436939187, 19105.426837194907, 19111.044447777556, 6183.350748171467, 7515.158786097441, 7526.3065810192265, 7597.962010189914, 7609.162992070867, 856.9785755356041, 983.695054533097, 1008.8991100889853, 1031.0771859185986, 1059.4279089291697, 24133.276001719976, 24998.250052498453, 28027.719722802918, 29567.704322956924, 30498.84750576233, 1015.3552494166257, 1019.6227395863579, 1035.5236591168148, 1038.0294424712918, 1058.7300238439302, 13994.613243852946, 15251.847481525043, 15410.381909899523, 15572.221388930735, 15810.762838557432, 669479.0, 681841.0, 683537.0, 684352.0, 685791.0, 42477.57522424736, 42674.57325426706, 42766.57233427626, 42773.71678849639, 42831.78584107122, 1211.200607598998, 1237.2576454127452, 1310.4889093253448, 1342.4026308292912, 1348.2315080404467, 29413.0, 29823.518925664306, 30669.23326916845, 30703.9253626122, 30977.0, 49929.0, 50524.49475505345, 50560.24159637682, 50572.4828255153, 50678.74660626673, 403479.98260008515, 471939.0, 472462.0, 473324.0, 475509.24490756035, 20107.09518071661, 22508.887455562617, 23961.0, 24687.135950241656, 26648.60027099634, 39967.0, 45636.77181614071, 47254.52745472639, 47951.5204847947, 49482.752586236835, 37659.62340376561, 38530.6146938527, 38533.0, 38569.42145867758, 38832.61167388403, 38195.85412437631, 38559.6144038556, 38740.031499212746, 39299.803500982314, 39643.405348920365, 37332.440013400366, 37853.81073094617, 38099.238015240095, 38306.80846595749, 38317.42523862088, 31185.8440707795, 33755.15612109619, 33986.15034624154, 34166.31667366589, 34342.65657343494, 24839.62740558857, 24902.50194996126, 25406.618900716134, 25578.74421255838, 25771.226863194133], "value": 19168.646946880526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.195492848127, 374.8313259033435, 383.71796729403883, 391.2605176216952, 405.92287465381577, 18255.543611409707, 20040.29858954937, 20321.796782032176, 20730.0, 20772.0, 18751.906240468812, 19832.20671173155, 20304.0, 20430.182792688276, 20936.057877395517, 4644.302634519402, 5831.970840145804, 6042.063480160582, 6094.664793436359, 6149.631022138686, 5967.612105213169, 5975.551833612475, 6015.037593984964, 6017.939820601804, 6029.638221706711, 5739.225204597367, 5962.0, 6035.69821508925, 6085.117657939596, 6130.203073600425, 5898.469137777621, 6106.267247930255, 6149.5080393568405, 6155.045967874987, 6182.1345011698395, 5930.466258036798, 5995.070764031539, 6002.699865006776, 6003.699815009276, 6018.729157187937, 13148.211107333591, 13241.668958276025, 13315.201087934658, 13370.264635445046, 13563.118397304144, 2086.8017538333834, 2226.0, 2453.251758213744, 2728.140635699748, 2835.2203144135324, 10239.692809215736, 10269.537870795832, 10466.581336746522, 12864.09951303409, 14154.150750954976, 1794.3809385761892, 1800.4148651688122, 1826.7669323206915, 1838.9816101838944, 1868.2900497810745, 122535.0, 123962.76037239692, 127111.0, 128559.71440285571, 133035.0, 10697.411642359744, 11112.722181945515, 11365.829512557319, 11387.259828111146, 11650.94174529139, 12043.698907527381, 14647.92676036613, 14659.92670036643, 14660.56018319452, 14674.266286685837, 7925.0, 8235.794105147419, 8258.876116858251, 8303.335733141455, 8381.329493640495, 8033.063798738576, 8057.758267251992, 8100.959495202486, 8168.264856162974, 8245.79385515367, 4430.021879686296, 4533.048059907391, 4562.201614717411, 4598.264277715616, 4664.463586687574, 1853.1382906948065, 1899.6200759848086, 1904.2668572599648, 1909.4462605844149, 1909.5608010157741, 857.5840717252128, 902.1970446302796, 905.5472263868066, 908.9590968406437, 1001.1290177545436, 18852.434426967215, 19920.203191872162, 20377.0, 20457.590848183252, 20560.347403438394, 920.581135583308, 932.6689025396022, 940.4122423485321, 948.151404492975, 980.3039841712322, 10414.843777343345, 10867.021968022917, 10959.83560246597, 10968.561257549609, 10990.780184396268, 498306.0, 562112.0, 562959.0, 564912.0, 583732.0, 103409.0, 123994.0, 129939.70060299276, 130525.0, 144708.0, 949.7625593601599, 979.3780949097232, 995.7510622344414, 999.1157825324494, 1001.4341896828414, 17927.64144717072, 18848.905755471682, 18979.0, 19036.809631903503, 19121.426357209308, 55557.44442555523, 56529.15206271991, 57022.85954280974, 57138.714306429865, 57795.71102144462, 75510.0, 76763.18262712542, 78471.0, 82631.58684206782, 84568.73146902678, 7786.4938778979185, 7935.880961785693, 8080.676772929017, 8099.8380032397845, 8279.130691277329, 10201.0, 11365.659030229106, 11599.942000289944, 11947.581834635746, 12374.81437778452, 10541.947290263499, 10659.14726821868, 10864.511097000495, 10973.396463194282, 10996.780064398827, 10032.598696052368, 10692.83960740574, 10938.6718398445, 10968.506417211085, 11115.555377784798, 9088.773015642804, 10722.678319650424, 11052.723681907695, 11073.833892491459, 11488.483018264365, 5325.680459172462, 7932.84134317322, 8011.559364235023, 8238.340932725248, 8294.751157465285, 8696.782580435287, 10386.117180039479, 10730.0, 10793.352398856094, 10888.29226100301], "value": 9363.185749126493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.7295623245452, 1515.6968606278745, 1635.0761819571942, 1638.9918050409747, 1864.0865975671916, 7991.520508769478, 8469.364797640179, 8957.95521022394, 8970.058143894887, 9003.099690031002, 8046.718364857231, 8215.342772578195, 8545.487270763759, 8769.079246679103, 8783.472991620507, 2326.639370897514, 2355.34050465869, 2355.8704271265074, 2359.433735903388, 2362.6337917622795, 2301.608726516488, 2341.988290058547, 2354.1878052072007, 2356.6582845487396, 2362.586547354215, 1706.2833609883878, 2127.617028934791, 2135.540858715376, 2264.7169103862016, 2334.486412989148, 1945.5816999345138, 2314.155333303349, 2327.837051406402, 2335.089315167086, 2345.0854166874933, 2291.232437133562, 2331.94170145746, 2337.357226762637, 2338.6141286687675, 2338.6258198688297, 8646.740597782076, 8855.07021762712, 8870.86693699595, 8872.600732967032, 8888.555572221363, 5210.947890521122, 5374.892502149935, 5392.002479541258, 5393.5954803389905, 5394.622376433651, 3473.6526347365316, 3564.8395822188063, 3704.129529560551, 3707.814609269553, 3721.5534135903595, 1082.5345101606306, 1082.5669732107142, 1085.5766251162054, 1108.927919685226, 1109.7724966381872, 11838.822417663741, 11966.64100076999, 11998.320235166997, 12000.57997970067, 12006.87993120075, 4545.181867263925, 4552.88617784558, 4764.618830493552, 4770.594499467584, 4781.904361912742, 10163.44101074448, 10170.847437288447, 10179.84730229047, 10183.796324073624, 10184.898151018395, 5368.570514358842, 5371.006363822706, 5372.033034053829, 5379.78480860769, 5382.91925621116, 4703.270992996091, 4877.219644856877, 4892.143874821892, 4910.288008238786, 4917.0903382874285, 3148.622165340174, 3699.315626609086, 3734.9439758403646, 3792.943105853414, 3811.6760075393904, 1122.2424863217295, 1123.713453069462, 1132.5073592987078, 1138.4592318648577, 1139.8632164140356, 626.5833220908136, 757.1950946493803, 782.2763943352377, 868.2229404682773, 911.5260064766285, 5420.430854760193, 5751.424857514216, 5799.94200057994, 5842.737076831553, 5943.405659434108, 469.71112765648877, 470.8493282149679, 474.99762501187274, 481.80727708916015, 630.5050535329744, 3768.660820526166, 3901.7268791184347, 3920.9019774505864, 3976.8409263629133, 3977.105151340983, 9003.729888103368, 10712.303700259457, 10723.785524289471, 10979.231453798158, 10982.505787239594, 10700.732481688021, 10725.570977160982, 10741.677749667522, 10948.95984881435, 10985.395803230898, 7114.0, 8037.91488149104, 8053.476524025919, 8228.958855205805, 8378.785076163922, 5047.520485553867, 5762.481376676119, 5796.246487956539, 5877.647341159373, 5911.733971971357, 9882.0, 10048.84926726085, 10104.04011618895, 10136.543855526666, 10284.845727314247, 7672.923270767221, 9841.360311579725, 10212.131968782736, 10251.897481025393, 10374.948125259627, 7046.084009078992, 7052.823679408056, 7091.007258983644, 7132.286771322826, 7141.0, 8313.251807337369, 8330.333573314241, 8417.494950303002, 8427.452215605967, 8443.8733419, 7441.479096463412, 7494.362979146834, 7534.0, 7539.962300188464, 7703.922960770545, 7363.595002274927, 7382.040334756447, 7399.741009064657, 7439.256074392518, 7458.1796002438605, 6826.965865170642, 7006.75476358325, 7010.614416207158, 7015.0, 7024.438044956289, 3827.3876179811095, 4971.428285747185, 5038.546530812097, 5115.872103197301, 5165.527824569979, 4256.978715106405, 5091.312672789126, 5128.230765385222, 5143.871403214949, 5186.910748742655], "value": 4442.321724449094, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.4706763164462, 303.2237472071497, 310.41022058089635, 310.9828959407233, 319.11286623187544, 3863.0728625129977, 4904.0437114762635, 5002.374703162105, 5194.584433245341, 5230.5031022052935, 3950.5259368875704, 4499.887502812431, 4832.269767581314, 4942.578136713012, 5186.766595503202, 3961.2671655743634, 3965.9206815863668, 3979.781112038837, 3989.18221764539, 3998.280309544281, 3414.4195486767308, 3663.358912190369, 3964.1962906877447, 3976.4631774710333, 4003.4595329630565, 3266.395716792389, 3591.5151454553693, 3677.595464498903, 3954.7429417087797, 3964.6828253739777, 3275.5905511811025, 3954.8813535593977, 3972.781497017663, 3979.9851037985272, 3994.7216890595023, 3085.9074227773203, 3460.3944309745943, 3464.41105012146, 3471.7396195285455, 3473.114355839245, 4764.213904705719, 5032.773525191338, 5060.1903695408555, 5076.949230507722, 5080.916824102137, 4445.732386695776, 4485.596296333346, 4492.9550704493195, 4506.1663592235545, 4521.547845215485, 4493.932591011137, 5363.6245462817615, 5435.0, 5445.428230035829, 5453.23654688344, 3168.8574014169417, 3787.1478917243676, 3787.5833658297565, 3789.3747531657245, 3801.581825999138, 4881.6338774591695, 5222.895542089175, 5587.74855131516, 5597.944020559742, 5675.701340956878, 3566.8038257895546, 3819.579846216914, 3840.231953609289, 3840.4461033412467, 3890.046938500029, 3613.4941108244866, 3925.430812532167, 3928.7642741435607, 3943.2310699413338, 3961.841526338972, 3966.8413263469715, 3981.7610943343493, 3988.561258261589, 4137.131202447459, 4469.4636643602325, 4787.593054590329, 5465.398806131322, 5480.342358916876, 5547.16792481123, 5587.552995760329, 1285.4022879361141, 1307.9084464087423, 1309.5154792726753, 1309.6922223277522, 1313.914595551286, 818.2594751749676, 840.920112589303, 842.8651415773447, 862.6204470033165, 888.7291173621783, 4101.323281658493, 4703.412073490814, 4715.599174070174, 5096.763555142511, 5099.0, 4970.378702662168, 5540.335159780852, 5566.777328906879, 5621.4659607337235, 5642.379338272786, 4725.362076119749, 4844.434543699372, 4939.728314942641, 4970.229614409773, 5080.364954380702, 2587.7929765618705, 2669.759721625063, 2796.5944937984286, 2906.6512018557487, 2919.9270018249713, 4786.0, 5473.28847249863, 5657.2928383952, 5693.345265294462, 5724.942750572441, 4902.313676085351, 5253.349897950171, 5407.486288802557, 5633.239512665819, 5635.464630860061, 5004.699718016858, 5163.483651634807, 5611.204414587456, 5657.783576531066, 5675.489205971482, 4109.8972525687095, 4754.5245475452175, 4947.579455746302, 5037.445880953165, 5527.53015993645, 4795.690973228461, 5364.544013758718, 5411.864703382289, 5442.809501667422, 5450.373207081237, 4698.154332220233, 4830.565249127595, 5595.034631645071, 5644.717764111861, 5694.515966142924, 3349.4808304711833, 3408.8295585220144, 3722.3858063419234, 3730.421784623388, 3742.2515496900182, 4653.86038418848, 4996.951249987487, 5450.563954883521, 5467.58993075513, 5483.862903427286, 4696.829639992923, 5063.974680126576, 5293.523582877559, 5296.285001524745, 5300.655457395257, 4802.543758343091, 5026.296318515484, 5070.721110338966, 5165.553644979412, 5319.388270348804, 5069.518395752398, 5351.652142610874, 5381.623286369917, 5403.702796346239, 5423.230854556045, 4753.5008824072975, 4928.6057115431395, 4959.305697202322, 5110.693358398508, 5237.847673511821, 5337.279467271868, 5398.0, 5398.64908780928, 5399.406065332889, 5409.6213265071065], "value": 3920.8615800853618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.3105737316263, 733.2410954661926, 769.4114002787868, 780.7891869195314, 805.6575955219031, 14528.0, 14999.0250633709, 15023.699526009474, 15038.699226015488, 15043.699126017487, 11755.941220293907, 14202.431902723878, 14226.502072427467, 14236.501722439716, 14240.857591424083, 590.8079874040933, 692.9411000065, 696.2132789947377, 820.7086484298078, 829.5686243153557, 847.4872702015253, 858.3862538285125, 859.5401460218784, 861.2248975921689, 863.6761214544546, 901.256463417683, 901.5852707754408, 907.6142639378271, 908.736466424736, 909.0682050897816, 701.8526109517007, 896.8968568614628, 901.4050726520466, 903.2367649336301, 904.5251243097393, 541.4314969282277, 650.4373716735038, 670.282797406775, 678.7793966960758, 680.0717021265986, 14889.957702960797, 14956.700865982619, 14967.401303947829, 14969.251537423086, 14976.625584360369, 13374.264415457146, 13775.0, 13885.652858678513, 13901.79147312791, 14050.128649626065, 12516.937415312865, 12544.24734515932, 12612.369381530887, 12723.10938234324, 12954.80567791484, 4409.845655402078, 4467.307567327037, 4553.590176884097, 4658.627309815207, 4662.393888794469, 97474.0, 99934.50032749862, 116001.0, 117705.0, 119909.0, 10389.584416623251, 11169.273997190157, 11471.139664525193, 11502.309861408343, 12017.579384721494, 23392.883035584713, 23752.524949501258, 23776.7622323778, 23778.286651400485, 23791.286261412184, 8179.304759095425, 8199.180081991872, 8333.95833020831, 8371.121032291643, 8384.0, 3880.2239552089695, 4259.467566554181, 4353.194658988097, 4457.8216871324785, 4587.0, 3410.6759857813468, 3573.928521429557, 3581.3553560358864, 3581.8746343877838, 3582.3014512169875, 640.6572483721187, 660.3132741948416, 662.1193812229723, 666.7999600119945, 667.2293501006345, 594.8661551150957, 609.2810483629271, 614.0942110387143, 614.5452365249772, 618.1037495631352, 8101.716439924574, 8818.823623527494, 8820.657913088535, 8867.778305542413, 8996.415233009833, 499.3957311652941, 513.6327525819057, 538.7737150396842, 539.0674133748558, 563.833669067626, 3800.56293526248, 4339.327404252283, 4343.391925130484, 4401.856862696435, 4434.071179037129, 152561.23719381553, 158254.0, 162960.37039629451, 163909.0, 164134.0, 85633.57183214044, 117303.0, 131506.0, 136618.0, 140343.0, 102546.0, 116601.4169929145, 118216.81783182058, 118720.59830726079, 121338.39330803466, 7398.741044063432, 7771.844563108819, 8061.516309021477, 8338.87491687613, 8465.576721163794, 65754.67122664547, 65997.67001164964, 66329.33670663231, 66788.0, 67568.66215668891, 64170.0, 67825.66087169532, 84345.45681789776, 90659.0, 94115.0, 13253.66865828362, 13272.933635331761, 13280.468781249028, 13303.800442993557, 13350.599482015556, 14073.788893166406, 14799.630009249424, 14969.176695281863, 15337.846621533641, 15371.308291126703, 13677.31613419345, 14014.439422422989, 14028.22844743549, 14100.858991409954, 14123.929380353033, 13787.931060344634, 13815.930920345334, 13922.443102275796, 13948.372323245681, 14182.43270269219, 13287.534936277183, 13473.797893031417, 13476.258805765778, 13488.797668035184, 13505.594832155051, 11103.72240693989, 11523.769524609628, 11528.942355288169, 11898.286102833858, 11937.94031029874, 10887.618933337611, 11382.54469821198, 11433.615607887981, 11515.539378425105, 11698.181127321006], "value": 7773.563955493244, "unit": "score"}], "name": "dromaeo_css", "value": 7179.462325506753, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 112.0, 113.0, 111.0, 113.0, 112.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 119.0, 181.0, 147.0, 137.0, 145.0, 142.0, 154.0, 113.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 181.0, 182.0, 186.0, 182.0, 184.0, 185.0, 182.0, 182.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 58.0, 87.0, 62.0, 61.0, 74.0, 83.0, 95.0, 61.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 74.0, 90.0, 70.0, 68.0, 70.0, 93.0, 68.0, 69.0, 110.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 87.0, 85.0, 88.0, 84.0, 89.0, 124.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 92.0, 92.0, 93.0, 92.0, 93.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 81.0, 84.0, 85.0, 83.0, 86.0, 85.0, 85.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 53.0, 53.0, 54.0, 55.0, 53.0, 53.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 77.0, 73.0, 71.0, 70.0, 71.0, 71.0, 70.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 86.0, 86.0, 86.0, 89.0, 90.0, 92.0, 92.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 154.0, 155.0, 153.0, 152.0, 141.0, 183.0, 155.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 179.0, 170.0, 172.0, 171.0, 170.0, 171.0, 168.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 68.0, 69.0, 69.0, 76.0, 77.0, 90.0, 85.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1467.3, "alertThreshold": 2.0}]} 02:38:06 INFO - Return code: 0 02:38:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:38:06 INFO - # TBPL SUCCESS # 02:38:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:06 INFO - 2016-10-12 02:38:06.924 screenresolution[1668:10044] starting screenresolution argv=screenresolution get 02:38:06 INFO - 2016-10-12 02:38:06.926 screenresolution[1668:10044] Display 0: 1600x1200x32@60 02:38:06 INFO - 2016-10-12 02:38:06.932 screenresolution[1669:10047] starting screenresolution argv=screenresolution list 02:38:06 INFO - Available Modes on Display 0 02:38:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:07 INFO - Intel Iris: 02:38:07 INFO - Chipset Model: Intel Iris 02:38:07 INFO - Type: GPU 02:38:07 INFO - Bus: Built-In 02:38:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:07 INFO - Vendor: Intel (0x8086) 02:38:07 INFO - Device ID: 0x0a2e 02:38:07 INFO - Revision ID: 0x0009 02:38:07 INFO - Displays: 02:38:07 INFO - Display: 02:38:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:07 INFO - Display Serial Number: 1600x1200 60 02:38:07 INFO - Main Display: Yes 02:38:07 INFO - Mirror: Off 02:38:07 INFO - Online: Yes 02:38:07 INFO - Rotation: Supported 02:38:07 INFO - Return code: 0 02:38:07 INFO - Running post-action listener: _resource_record_post_action 02:38:07 INFO - [mozharness: 2016-10-12 09:38:07.175370Z] Finished run-tests step (success) 02:38:07 INFO - Running post-run listener: _resource_record_post_run 02:38:07 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 37385216; Write bytes: 708511744; Read time: 240; Write time: 1990 02:38:07 INFO - TinderboxPrint: CPU usage
53.6% 02:38:07 INFO - TinderboxPrint: I/O read bytes / time
37,385,216 / 240 02:38:07 INFO - TinderboxPrint: I/O write bytes / time
708,511,744 / 1,990 02:38:07 INFO - TinderboxPrint: CPU idle
2,256.0 (46.4%) 02:38:07 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 02:38:07 INFO - TinderboxPrint: CPU user
1,630.2 (33.6%) 02:38:07 INFO - TinderboxPrint: Swap in / out
444,682,240 / 0 02:38:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397525504; Write bytes: 392398336; Read time: 29408; Write time: 813 02:38:07 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35386368; Write bytes: 297140736; Read time: 222; Write time: 1128 02:38:07 INFO - Running post-run listener: _upload_blobber_files 02:38:07 INFO - Blob upload gear active. 02:38:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:38:07 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 02:38:07 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'] 02:38:07 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 02:38:08 INFO - (blobuploader) - INFO - Open directory for files ... 02:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:08 INFO - (blobuploader) - INFO - Done attempting. 02:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:09 INFO - (blobuploader) - INFO - Done attempting. 02:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:38:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:12 INFO - (blobuploader) - INFO - Done attempting. 02:38:12 INFO - (blobuploader) - INFO - Iteration through files over. 02:38:12 INFO - Return code: 0 02:38:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:38:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:38:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d64536a5f4fab6890d11208d1a66f4c7b30652320f49d4498e222905baefeb2ce907578bb4d3a849d0d81e89dfe735963c56470153fd378f41f49180c6f0b6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f921f5c6041baed084be8771bb01aa9936ba342f8c2a152bb4c48be072a9d8b1b1689628deb6aea6ac8ff30f0302ee55852ced80e9c4a7b112d055115e18d1a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7585a92acde8fd9815f4bb11ffaf811d1f42a11adf8cf34ddd61aa2202e9f49ea7a3cbed5b632d4739d0065548b8b4fae4031584788b845f886414c9fad20907"} 02:38:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:38:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:38:12 INFO - Contents: 02:38:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d64536a5f4fab6890d11208d1a66f4c7b30652320f49d4498e222905baefeb2ce907578bb4d3a849d0d81e89dfe735963c56470153fd378f41f49180c6f0b6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f921f5c6041baed084be8771bb01aa9936ba342f8c2a152bb4c48be072a9d8b1b1689628deb6aea6ac8ff30f0302ee55852ced80e9c4a7b112d055115e18d1a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7585a92acde8fd9815f4bb11ffaf811d1f42a11adf8cf34ddd61aa2202e9f49ea7a3cbed5b632d4739d0065548b8b4fae4031584788b845f886414c9fad20907"} 02:38:12 INFO - Running post-run listener: copy_logs_to_upload_dir 02:38:12 INFO - Copying logs to upload dir... 02:38:12 INFO - mkdir: /builds/slave/test/build/upload/logs 02:38:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.843377 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 02:38:12.121676) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 02:38:12.124712) ========= 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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/8d64536a5f4fab6890d11208d1a66f4c7b30652320f49d4498e222905baefeb2ce907578bb4d3a849d0d81e89dfe735963c56470153fd378f41f49180c6f0b6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f921f5c6041baed084be8771bb01aa9936ba342f8c2a152bb4c48be072a9d8b1b1689628deb6aea6ac8ff30f0302ee55852ced80e9c4a7b112d055115e18d1a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7585a92acde8fd9815f4bb11ffaf811d1f42a11adf8cf34ddd61aa2202e9f49ea7a3cbed5b632d4739d0065548b8b4fae4031584788b845f886414c9fad20907"} build_url:https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010892 build_url: 'https://queue.taskcluster.net/v1/task/OifKeINrQsGWP4i3RV_TuA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d64536a5f4fab6890d11208d1a66f4c7b30652320f49d4498e222905baefeb2ce907578bb4d3a849d0d81e89dfe735963c56470153fd378f41f49180c6f0b6b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f921f5c6041baed084be8771bb01aa9936ba342f8c2a152bb4c48be072a9d8b1b1689628deb6aea6ac8ff30f0302ee55852ced80e9c4a7b112d055115e18d1a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7585a92acde8fd9815f4bb11ffaf811d1f42a11adf8cf34ddd61aa2202e9f49ea7a3cbed5b632d4739d0065548b8b4fae4031584788b845f886414c9fad20907"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 02:38:12.160427) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:38:12.160791) ========= 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.wifJJkbR2Y/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.8HguaQ6vhP/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.004964 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 02:38:12.209191) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 02:38:12.212626) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 02:38:12.213012) ========= ========= Total master_lag: 0.21 =========