builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0208 starttime: 1480175998.88 results: success (0) buildid: 20161126062719 builduid: 30124f9638c94b50b6f27fa53bf20f66 revision: fe8d7e54713a87a4a356a77ff35b0830a8567e76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:58.875765) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:58.876123) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:58.876389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:58.900787) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:58.901044) ========= 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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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-11-26 07:59:58-- 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% 10.6M=0.001s 2016-11-26 07:59:59 (10.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.635762 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:59.559219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:59.559499) ========= 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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.019491 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 07:59:59.607008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 07:59:59.607311) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fe8d7e54713a87a4a356a77ff35b0830a8567e76 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fe8d7e54713a87a4a356a77ff35b0830a8567e76 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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-11-26 07:59:59,694 truncating revision to first 12 chars 2016-11-26 07:59:59,694 Setting DEBUG logging. 2016-11-26 07:59:59,694 attempt 1/10 2016-11-26 07:59:59,694 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fe8d7e54713a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-26 08:00:00,934 unpacking tar archive at: mozilla-aurora-fe8d7e54713a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.508672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 08:00:01.138527) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 08:00:01.141848) ========= script_repo_revision: fe8d7e54713a87a4a356a77ff35b0830a8567e76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 08:00:01.142229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 08:00:01.142497) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 08:00:01.165860) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-11-26 08:00:01.166236) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:01 INFO - MultiFileLogger online at 20161126 08:00:01 in /builds/slave/test 08:00:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:00:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:00:01 INFO - {'append_to_log': False, 08:00:01 INFO - 'base_work_dir': '/builds/slave/test', 08:00:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:00:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:00:01 INFO - 'branch': 'Mozilla-Aurora', 08:00:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:01 INFO - 'config_files': ('talos/mac_config.py',), 08:00:01 INFO - 'default_actions': ('clobber', 08:00:01 INFO - 'read-buildbot-config', 08:00:01 INFO - 'download-and-extract', 08:00:01 INFO - 'populate-webroot', 08:00:01 INFO - 'create-virtualenv', 08:00:01 INFO - 'install', 08:00:01 INFO - 'run-tests'), 08:00:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:01 INFO - 'download_minidump_stackwalk': True, 08:00:01 INFO - 'download_symbols': 'ondemand', 08:00:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:01 INFO - 'installer_path': 'installer.exe', 08:00:01 INFO - 'log_level': 'info', 08:00:01 INFO - 'log_name': 'talos', 08:00:01 INFO - 'log_to_console': True, 08:00:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:00:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:00:01 INFO - 'opt_config_files': (), 08:00:01 INFO - 'pip_index': False, 08:00:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:01 INFO - 'cmd': ('bash', 08:00:01 INFO - '-c', 08:00:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:01 INFO - 'enabled': True, 08:00:01 INFO - 'halt_on_failure': False, 08:00:01 INFO - 'name': 'check_screen_resolution'},), 08:00:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:01 INFO - 'cmd': ('bash', 08:00:01 INFO - '-c', 08:00:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:01 INFO - 'enabled': True, 08:00:01 INFO - 'halt_on_failure': False, 08:00:01 INFO - 'name': 'check_screen_resolution'},), 08:00:01 INFO - 'run_cmd_checks_enabled': True, 08:00:01 INFO - 'sps_profile': False, 08:00:01 INFO - 'sps_profile_interval': 0, 08:00:01 INFO - 'suite': 'dromaeojs-e10s', 08:00:01 INFO - 'system_bits': '32', 08:00:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:01 INFO - 'title': 't-yosemite-r7-0208', 08:00:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:01 INFO - 'use_talos_json': True, 08:00:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:00:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:01 INFO - 'work_dir': 'build'} 08:00:01 INFO - [mozharness: 2016-11-26 16:00:01.375706Z] Running clobber step. 08:00:01 INFO - Running pre-action listener: _resource_record_pre_action 08:00:01 INFO - Running main action method: clobber 08:00:01 INFO - rmtree: /builds/slave/test/build 08:00:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:00:02 INFO - Running post-action listener: _resource_record_post_action 08:00:02 INFO - [mozharness: 2016-11-26 16:00:02.886993Z] Finished clobber step (success) 08:00:02 INFO - [mozharness: 2016-11-26 16:00:02.887202Z] Running read-buildbot-config step. 08:00:02 INFO - Running pre-action listener: _resource_record_pre_action 08:00:02 INFO - Running main action method: read_buildbot_config 08:00:02 INFO - Using buildbot properties: 08:00:02 INFO - { 08:00:02 INFO - "project": "", 08:00:02 INFO - "product": "firefox", 08:00:02 INFO - "script_repo_revision": "production", 08:00:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:00:02 INFO - "repository": "", 08:00:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:00:02 INFO - "buildid": "20161126062719", 08:00:02 INFO - "pgo_build": "False", 08:00:02 INFO - "basedir": "/builds/slave/test", 08:00:02 INFO - "buildnumber": 461, 08:00:02 INFO - "slavename": "t-yosemite-r7-0208", 08:00:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:00:02 INFO - "platform": "yosemite_r7", 08:00:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:00:02 INFO - "branch": "mozilla-aurora", 08:00:02 INFO - "repo_path": "releases/mozilla-aurora", 08:00:02 INFO - "revision": "fe8d7e54713a87a4a356a77ff35b0830a8567e76", 08:00:02 INFO - "stage_platform": "macosx64", 08:00:02 INFO - "builduid": "30124f9638c94b50b6f27fa53bf20f66", 08:00:02 INFO - "slavebuilddir": "test" 08:00:02 INFO - } 08:00:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:00:02 INFO - Running post-action listener: _resource_record_post_action 08:00:02 INFO - [mozharness: 2016-11-26 16:00:02.891879Z] Finished read-buildbot-config step (success) 08:00:02 INFO - [mozharness: 2016-11-26 16:00:02.891993Z] Running download-and-extract step. 08:00:02 INFO - Running pre-action listener: _resource_record_pre_action 08:00:02 INFO - Running main action method: download_and_extract 08:00:02 INFO - mkdir: /builds/slave/test/build/tests 08:00:02 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')]} 08:00:02 INFO - trying https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:00:02 INFO - Downloading https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:00:04 INFO - Downloaded 1199 bytes. 08:00:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:00:04 INFO - Using the following test package requirements: 08:00:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:00:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:00:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:00:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'jsshell-mac.zip'], 08:00:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:00:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:00:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:00:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:00:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:00:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:00:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:00:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:00:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:00:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:00:04 INFO - Fetch https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:00:04 INFO - Http code: 200 08:00:04 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 08:00:04 INFO - x-amz-cf-id: FZKINSVlnwABJryppvtSn4zQ4QEZ4d-l-4Xa_hWFO0T1kVyk6_OQKA== 08:00:04 INFO - x-amz-version-id: bYc0cc5dkxxXRRyyjCOxN7GYQwh3XM6h 08:00:05 INFO - Expected file size: 16278950 08:00:05 INFO - Obtained file size: 16278950 08:00:11 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:00:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:00:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:00:11 INFO - Fetch https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:00:12 INFO - Http code: 200 08:00:12 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:00:12 INFO - x-amz-cf-id: kUVrVi6-9bgPIkYv6dHoEBGrUBTkGXbSTuMgaATb3OvVI4xHLz2fuQ== 08:00:12 INFO - x-amz-version-id: bYc0cc5dkxxXRRyyjCOxN7GYQwh3XM6h 08:00:12 INFO - Expected file size: 16278950 08:00:12 INFO - Obtained file size: 16278950 08:00:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:00:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:00:18 INFO - Fetch https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:00:19 INFO - Http code: 200 08:00:19 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 08:00:19 INFO - x-amz-cf-id: YEIxirV8R3zfLjeKrW-IBvAuigeeO2IY3SCxEZR7YVDPB-KOabvgNw== 08:00:19 INFO - x-amz-version-id: eMYw7STdnFN3R8qaurWwtcOOc.FfA4Hp 08:00:19 INFO - Expected file size: 14409038 08:00:19 INFO - Obtained file size: 14409038 08:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:19 INFO - trying https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:00:19 INFO - Downloading https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:00:23 INFO - Downloaded 109738860 bytes. 08:00:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:00:23 INFO - mkdir: /builds/slave/test/properties 08:00:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:00:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:00:23 INFO - Contents: 08:00:23 INFO - build_url:https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:00:24 INFO - Running post-action listener: _resource_record_post_action 08:00:24 INFO - Running post-action listener: set_extra_try_arguments 08:00:24 INFO - [mozharness: 2016-11-26 16:00:24.401207Z] Finished download-and-extract step (success) 08:00:24 INFO - [mozharness: 2016-11-26 16:00:24.401344Z] Running populate-webroot step. 08:00:24 INFO - Running pre-action listener: _resource_record_pre_action 08:00:24 INFO - Running main action method: populate_webroot 08:00:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:24 INFO - '--tppagecycles', 08:00:24 INFO - '7'], 08:00:24 INFO - 'tests': ['tsvgm']}}, 08:00:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:00:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:00:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:00:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['damp', 'tps']}, 08:00:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'tests': ['damp', 'tps']}, 08:00:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['dromaeo_dom']}, 08:00:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['basic_compositor_video']}, 08:00:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:00:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['a11yr', 08:00:24 INFO - 'ts_paint', 08:00:24 INFO - 'tpaint', 08:00:24 INFO - 'sessionrestore', 08:00:24 INFO - 'sessionrestore_no_auto_restore', 08:00:24 INFO - 'tabpaint']}, 08:00:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['tsvgx', 08:00:24 INFO - 'tsvgr_opacity', 08:00:24 INFO - 'tart', 08:00:24 INFO - 'tscrollx', 08:00:24 INFO - 'cart']}, 08:00:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:00:24 INFO - 'tsvgr_opacity', 08:00:24 INFO - 'tart', 08:00:24 INFO - 'tscrollx', 08:00:24 INFO - 'cart']}, 08:00:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'talos_options': ['--disable-e10s'], 08:00:24 INFO - 'tests': ['tp5o']}, 08:00:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'tests': ['tp5o']}, 08:00:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'talos_options': ['--disable-e10s', 08:00:24 INFO - '--xperf_path', 08:00:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:24 INFO - 'tests': ['tp5n']}, 08:00:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:24 INFO - 'talos_options': ['--xperf_path', 08:00:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:24 INFO - 'tests': ['tp5n']}}, 08:00:24 INFO - 'talos.zip': {'path': '', 08:00:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:24 INFO - Running post-action listener: _resource_record_post_action 08:00:24 INFO - [mozharness: 2016-11-26 16:00:24.420403Z] Finished populate-webroot step (success) 08:00:24 INFO - [mozharness: 2016-11-26 16:00:24.420530Z] Running create-virtualenv step. 08:00:24 INFO - Running pre-action listener: _resource_record_pre_action 08:00:24 INFO - Running main action method: create_virtualenv 08:00:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:00:24 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 08:00:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:00:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:24 INFO - Using real prefix '/tools/python27' 08:00:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:00:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:00:28 INFO - Installing pip.................done. 08:00:28 INFO - Return code: 0 08:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:00:28 INFO - Reading from file tmpfile_stdout 08:00:28 INFO - Output received: 08:00:28 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) 08:00:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:28 INFO - 'HOME': '/Users/cltbld', 08:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:28 INFO - 'LOGNAME': 'cltbld', 08:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:28 INFO - 'MOZ_NO_REMOTE': '1', 08:00:28 INFO - 'NO_EM_RESTART': '1', 08:00:28 INFO - 'PAGER': '/bin/cat', 08:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:28 INFO - 'PWD': '/builds/slave/test', 08:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:28 INFO - 'SHELL': '/bin/bash', 08:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:28 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:28 INFO - 'USER': 'cltbld', 08:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:28 INFO - 'XPC_FLAGS': '0x0', 08:00:28 INFO - 'XPC_SERVICE_NAME': '0', 08:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:28 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 08:00:28 INFO - Cleaning up... 08:00:28 INFO - Return code: 0 08:00:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:28 INFO - 'HOME': '/Users/cltbld', 08:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:28 INFO - 'LOGNAME': 'cltbld', 08:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:28 INFO - 'MOZ_NO_REMOTE': '1', 08:00:28 INFO - 'NO_EM_RESTART': '1', 08:00:28 INFO - 'PAGER': '/bin/cat', 08:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:28 INFO - 'PWD': '/builds/slave/test', 08:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:28 INFO - 'SHELL': '/bin/bash', 08:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:28 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:28 INFO - 'USER': 'cltbld', 08:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:28 INFO - 'XPC_FLAGS': '0x0', 08:00:28 INFO - 'XPC_SERVICE_NAME': '0', 08:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:28 INFO - Downloading/unpacking psutil>=3.1.1 08:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:32 INFO - Installing collected packages: psutil 08:00:32 INFO - Running setup.py install for psutil 08:00:32 INFO - building 'psutil._psutil_osx' extension 08:00:32 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 08:00:33 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 08:00:33 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 08:00:33 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 08:00:33 INFO - building 'psutil._psutil_posix' extension 08:00:33 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 08:00:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:00:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:00:33 INFO - ^ 08:00:33 INFO - 1 warning generated. 08:00:33 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 08:00:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:33 INFO - Successfully installed psutil 08:00:33 INFO - Cleaning up... 08:00:33 INFO - Return code: 0 08:00:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:33 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')]} 08:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:33 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')]} 08:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:33 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:33 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 08:00:33 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 08:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:33 INFO - 'HOME': '/Users/cltbld', 08:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:33 INFO - 'LOGNAME': 'cltbld', 08:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:33 INFO - 'MOZ_NO_REMOTE': '1', 08:00:33 INFO - 'NO_EM_RESTART': '1', 08:00:33 INFO - 'PAGER': '/bin/cat', 08:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:33 INFO - 'PWD': '/builds/slave/test', 08:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:33 INFO - 'SHELL': '/bin/bash', 08:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:33 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:33 INFO - 'USER': 'cltbld', 08:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:33 INFO - 'XPC_FLAGS': '0x0', 08:00:33 INFO - 'XPC_SERVICE_NAME': '0', 08:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:36 INFO - Installing collected packages: mozsystemmonitor 08:00:36 INFO - Running setup.py install for mozsystemmonitor 08:00:36 INFO - Successfully installed mozsystemmonitor 08:00:36 INFO - Cleaning up... 08:00:36 INFO - Return code: 0 08:00:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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', '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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:00: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 jsonschema==2.5.1 08:00:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:36 INFO - 'HOME': '/Users/cltbld', 08:00:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:36 INFO - 'LOGNAME': 'cltbld', 08:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:36 INFO - 'MOZ_NO_REMOTE': '1', 08:00:36 INFO - 'NO_EM_RESTART': '1', 08:00:36 INFO - 'PAGER': '/bin/cat', 08:00:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:36 INFO - 'PWD': '/builds/slave/test', 08:00:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:36 INFO - 'SHELL': '/bin/bash', 08:00:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:36 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:36 INFO - 'USER': 'cltbld', 08:00:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:36 INFO - 'XPC_FLAGS': '0x0', 08:00:36 INFO - 'XPC_SERVICE_NAME': '0', 08:00:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00: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 08:00:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:40 INFO - warning: no files found matching '*.txt' 08:00:40 INFO - no previously-included directories found matching 'build' 08:00:40 INFO - no previously-included directories found matching 'dist' 08:00:40 INFO - no previously-included directories found matching '.git*' 08:00:40 INFO - Installing collected packages: jsonschema, functools32 08:00:40 INFO - Running setup.py install for functools32 08:00:40 INFO - warning: no files found matching '*.txt' 08:00:40 INFO - no previously-included directories found matching 'build' 08:00:40 INFO - no previously-included directories found matching 'dist' 08:00:40 INFO - no previously-included directories found matching '.git*' 08:00:40 INFO - Successfully installed jsonschema functools32 08:00:40 INFO - Cleaning up... 08:00:40 INFO - Return code: 0 08:00:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:40 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:40 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 08:00:40 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 08:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:40 INFO - 'HOME': '/Users/cltbld', 08:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:40 INFO - 'LOGNAME': 'cltbld', 08:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:40 INFO - 'MOZ_NO_REMOTE': '1', 08:00:40 INFO - 'NO_EM_RESTART': '1', 08:00:40 INFO - 'PAGER': '/bin/cat', 08:00:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:40 INFO - 'PWD': '/builds/slave/test', 08:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:40 INFO - 'SHELL': '/bin/bash', 08:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:40 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:40 INFO - 'USER': 'cltbld', 08:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:40 INFO - 'XPC_FLAGS': '0x0', 08:00:40 INFO - 'XPC_SERVICE_NAME': '0', 08:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:00:40 INFO - Cleaning up... 08:00:40 INFO - Return code: 0 08:00:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:40 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:40 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 08:00:40 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 08:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:40 INFO - 'HOME': '/Users/cltbld', 08:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:40 INFO - 'LOGNAME': 'cltbld', 08:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:40 INFO - 'MOZ_NO_REMOTE': '1', 08:00:40 INFO - 'NO_EM_RESTART': '1', 08:00:40 INFO - 'PAGER': '/bin/cat', 08:00:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:40 INFO - 'PWD': '/builds/slave/test', 08:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:40 INFO - 'SHELL': '/bin/bash', 08:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:40 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:40 INFO - 'USER': 'cltbld', 08:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:40 INFO - 'XPC_FLAGS': '0x0', 08:00:40 INFO - 'XPC_SERVICE_NAME': '0', 08:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:40 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 08:00:40 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 08:00:40 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 08:00:40 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 08:00:40 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 08:00:40 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 08:00:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:44 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 08:00:44 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 08:00:44 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 08:00:44 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 08:00:44 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 08:00:44 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 08:00:44 INFO - Downloading docopt-0.6.1.tar.gz 08:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:44 INFO - Running setup.py install for blobuploader 08:00:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:44 INFO - Running setup.py install for requests 08:00:45 INFO - Running setup.py install for docopt 08:00:45 INFO - Successfully installed blobuploader requests docopt 08:00:45 INFO - Cleaning up... 08:00:45 INFO - Return code: 0 08:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:45 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:45 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 08:00:45 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 08:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:45 INFO - 'HOME': '/Users/cltbld', 08:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:45 INFO - 'LOGNAME': 'cltbld', 08:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:45 INFO - 'MOZ_NO_REMOTE': '1', 08:00:45 INFO - 'NO_EM_RESTART': '1', 08:00:45 INFO - 'PAGER': '/bin/cat', 08:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:45 INFO - 'PWD': '/builds/slave/test', 08:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:45 INFO - 'SHELL': '/bin/bash', 08:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:45 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:45 INFO - 'USER': 'cltbld', 08:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:45 INFO - 'XPC_FLAGS': '0x0', 08:00:45 INFO - 'XPC_SERVICE_NAME': '0', 08:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:45 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-xRfvO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-6MQXy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-ZoVH6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-G4TBRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-rqSwyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-EwfUA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-rhFlyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-34RYBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-hBFC7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-5qrGtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:46 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-NFA070-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-UooEQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-zFUwas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-lxphZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-YdgqkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-3sHQPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:47 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-lOTSL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:47 INFO - Running setup.py install for manifestparser 08:00:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:47 INFO - Running setup.py install for mozcrash 08:00:47 INFO - Running setup.py install for mozdebug 08:00:48 INFO - Running setup.py install for mozdevice 08:00:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Running setup.py install for mozfile 08:00:48 INFO - Running setup.py install for mozhttpd 08:00:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Running setup.py install for mozinfo 08:00:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Running setup.py install for mozInstall 08:00:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:48 INFO - Running setup.py install for mozleak 08:00:49 INFO - Running setup.py install for mozlog 08:00:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Running setup.py install for moznetwork 08:00:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Running setup.py install for mozprocess 08:00:49 INFO - Running setup.py install for mozprofile 08:00:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Running setup.py install for mozrunner 08:00:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:49 INFO - Running setup.py install for mozscreenshot 08:00:49 INFO - Running setup.py install for moztest 08:00:50 INFO - Running setup.py install for mozversion 08:00:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:50 INFO - Cleaning up... 08:00:50 INFO - Return code: 0 08:00:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00: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')]} 08:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00: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')]} 08:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:50 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:50 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 08:00:50 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 08:00:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:50 INFO - 'HOME': '/Users/cltbld', 08:00:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:50 INFO - 'LOGNAME': 'cltbld', 08:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:50 INFO - 'MOZ_NO_REMOTE': '1', 08:00:50 INFO - 'NO_EM_RESTART': '1', 08:00:50 INFO - 'PAGER': '/bin/cat', 08:00:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:50 INFO - 'PWD': '/builds/slave/test', 08:00:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:50 INFO - 'SHELL': '/bin/bash', 08:00:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:50 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:50 INFO - 'USER': 'cltbld', 08:00:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:50 INFO - 'XPC_FLAGS': '0x0', 08:00:50 INFO - 'XPC_SERVICE_NAME': '0', 08:00:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:50 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-JV3X5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:50 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)) 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:50 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-0iySYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:50 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-hNd7o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:50 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)) 08:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:50 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-O3Krw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-vxZmM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-M1aARk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-OfLZpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-x4jLaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-vbPjD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-OGpSse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-mrpdHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-5xtXlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-TMlkGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:51 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)) 08:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:51 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-Kd_pFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-nEupk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:52 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)) 08:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-RvzmSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:52 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)) 08:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:52 INFO - Running setup.py (path:/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/pip-cQhKoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:52 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)) 08:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:52 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)) 08:00:52 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)) 08:00:52 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)) 08:00:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:52 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 08:00:52 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 08:00:52 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 08:00:52 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 08:00:52 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 08:00:52 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 08:00:54 INFO - Downloading blessings-1.6.tar.gz 08:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:54 INFO - Installing collected packages: blessings 08:00:54 INFO - Running setup.py install for blessings 08:00:55 INFO - Successfully installed blessings 08:00:55 INFO - Cleaning up... 08:00:55 INFO - Return code: 0 08:00:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:55 INFO - Reading from file tmpfile_stdout 08:00:55 INFO - Current package versions: 08:00:55 INFO - blessings == 1.6 08:00:55 INFO - blobuploader == 1.2.4 08:00:55 INFO - docopt == 0.6.1 08:00:55 INFO - functools32 == 3.2.3-2 08:00:55 INFO - jsonschema == 2.5.1 08:00:55 INFO - manifestparser == 1.1 08:00:55 INFO - mozInstall == 1.12 08:00:55 INFO - mozcrash == 1.0 08:00:55 INFO - mozdebug == 0.1 08:00:55 INFO - mozdevice == 0.48 08:00:55 INFO - mozfile == 1.2 08:00:55 INFO - mozhttpd == 0.7 08:00:55 INFO - mozinfo == 0.9 08:00:55 INFO - mozleak == 0.1 08:00:55 INFO - mozlog == 3.4 08:00:55 INFO - moznetwork == 0.27 08:00:55 INFO - mozprocess == 0.23 08:00:55 INFO - mozprofile == 0.28 08:00:55 INFO - mozrunner == 6.13 08:00:55 INFO - mozscreenshot == 0.1 08:00:55 INFO - mozsystemmonitor == 0.3 08:00:55 INFO - moztest == 0.7 08:00:55 INFO - mozversion == 1.4 08:00:55 INFO - psutil == 3.1.1 08:00:55 INFO - requests == 1.2.3 08:00:55 INFO - wsgiref == 0.1.2 08:00:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:55 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:55 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 08:00:55 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 08:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:55 INFO - 'HOME': '/Users/cltbld', 08:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:55 INFO - 'LOGNAME': 'cltbld', 08:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:55 INFO - 'MOZ_NO_REMOTE': '1', 08:00:55 INFO - 'NO_EM_RESTART': '1', 08:00:55 INFO - 'PAGER': '/bin/cat', 08:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:55 INFO - 'PWD': '/builds/slave/test', 08:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:55 INFO - 'SHELL': '/bin/bash', 08:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:55 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:55 INFO - 'USER': 'cltbld', 08:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:55 INFO - 'XPC_FLAGS': '0x0', 08:00:55 INFO - 'XPC_SERVICE_NAME': '0', 08:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 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)) 08:00:55 INFO - Cleaning up... 08:00:55 INFO - Return code: 0 08:00:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:55 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 0x10ebe4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac34e1930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, '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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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 08:00:55 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 08:00:55 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 08:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:55 INFO - 'HOME': '/Users/cltbld', 08:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:55 INFO - 'LOGNAME': 'cltbld', 08:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:55 INFO - 'MOZ_NO_REMOTE': '1', 08:00:55 INFO - 'NO_EM_RESTART': '1', 08:00:55 INFO - 'PAGER': '/bin/cat', 08:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:55 INFO - 'PWD': '/builds/slave/test', 08:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:55 INFO - 'SHELL': '/bin/bash', 08:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:00:55 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:55 INFO - 'USER': 'cltbld', 08:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:55 INFO - 'XPC_FLAGS': '0x0', 08:00:55 INFO - 'XPC_SERVICE_NAME': '0', 08:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:00:56 INFO - Cleaning up... 08:00:56 INFO - Return code: 0 08:00:56 INFO - Running post-action listener: _resource_record_post_action 08:00:56 INFO - Running post-action listener: _start_resource_monitoring 08:00:56 INFO - Starting resource monitoring. 08:00:56 INFO - [mozharness: 2016-11-26 16:00:56.086184Z] Finished create-virtualenv step (success) 08:00:56 INFO - [mozharness: 2016-11-26 16:00:56.087272Z] Running install step. 08:00:56 INFO - Running pre-action listener: _resource_record_pre_action 08:00:56 INFO - Running main action method: install 08:00:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:56 INFO - Reading from file tmpfile_stdout 08:00:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:00:56 INFO - Reading from file tmpfile_stdout 08:00:56 INFO - Output received: 08:00:56 INFO - Usage: mozinstall [options] installer 08:00:56 INFO - Options: 08:00:56 INFO - -h, --help show this help message and exit 08:00:56 INFO - -d DEST, --destination=DEST 08:00:56 INFO - Directory to install application into. [default: 08:00:56 INFO - "/builds/slave/test"] 08:00:56 INFO - --app=APP Application being installed. [default: firefox] 08:00:56 INFO - mkdir: /builds/slave/test/build/application 08:00:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:01:27 INFO - Reading from file tmpfile_stdout 08:01:27 INFO - Output received: 08:01:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:01:27 INFO - Running post-action listener: _resource_record_post_action 08:01:27 INFO - [mozharness: 2016-11-26 16:01:27.512341Z] Finished install step (success) 08:01:27 INFO - [mozharness: 2016-11-26 16:01:27.512488Z] Running run-tests step. 08:01:27 INFO - Running pre-action listener: _resource_record_pre_action 08:01:27 INFO - Running main action method: run_tests 08:01:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:01:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:01:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:01:27 INFO - 2016-11-26 08:01:27.526 screenresolution[2013:26639] starting screenresolution argv=screenresolution get 08:01:27 INFO - 2016-11-26 08:01:27.529 screenresolution[2013:26639] Display 0: 1600x1200x32@60 08:01:27 INFO - 2016-11-26 08:01:27.535 screenresolution[2014:26641] starting screenresolution argv=screenresolution list 08:01:27 INFO - Available Modes on Display 0 08:01:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:01:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:01:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:01:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:01:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:01:27 INFO - Intel Iris: 08:01:27 INFO - Chipset Model: Intel Iris 08:01:27 INFO - Type: GPU 08:01:27 INFO - Bus: Built-In 08:01:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:01:27 INFO - Vendor: Intel (0x8086) 08:01:27 INFO - Device ID: 0x0a2e 08:01:27 INFO - Revision ID: 0x0009 08:01:27 INFO - Displays: 08:01:27 INFO - Display: 08:01:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:01:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:01:27 INFO - Display Serial Number: 1600x1200 60 08:01:27 INFO - Main Display: Yes 08:01:27 INFO - Mirror: Off 08:01:27 INFO - Online: Yes 08:01:27 INFO - Rotation: Supported 08:01:27 INFO - Return code: 0 08:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:01:27 INFO - Python 2.7.3 08:01:27 INFO - Return code: 0 08:01:27 INFO - grabbing minidump binary from tooltool 08:01: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')]} 08:01: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 0x10e9f8d30>, '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 0x10e85edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:01: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 08:01: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 08:01: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 08:01:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:01:27 INFO - Return code: 0 08:01:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:01:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:01:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:01:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:01:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0208 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sDEvU4cE7F/Render', 08:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:27 INFO - 'HOME': '/Users/cltbld', 08:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:27 INFO - 'LOGNAME': 'cltbld', 08:01:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:01:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:27 INFO - 'MOZ_NO_REMOTE': '1', 08:01:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:01:27 INFO - 'NO_EM_RESTART': '1', 08:01:27 INFO - 'PAGER': '/bin/cat', 08:01:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:27 INFO - 'PWD': '/builds/slave/test', 08:01:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:27 INFO - 'SHELL': '/bin/bash', 08:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BWIpS0IbKf/Listeners', 08:01:27 INFO - 'TMPDIR': '/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/', 08:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:27 INFO - 'USER': 'cltbld', 08:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:27 INFO - 'XPC_FLAGS': '0x0', 08:01:27 INFO - 'XPC_SERVICE_NAME': '0', 08:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0208', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:01:28 INFO - mozversion application_buildid: 20161126062719 08:01:28 INFO - mozversion application_changeset: fe8d7e54713a87a4a356a77ff35b0830a8567e76 08:01:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:01:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:28 INFO - mozversion application_name: Firefox 08:01:28 INFO - mozversion application_remotingname: firefox-dev 08:01:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:28 INFO - mozversion application_vendor: Mozilla 08:01:28 INFO - mozversion application_version: 52.0a2 08:01:28 INFO - mozversion platform_buildid: 20161126062719 08:01:28 INFO - mozversion platform_changeset: fe8d7e54713a87a4a356a77ff35b0830a8567e76 08:01:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:01:28 INFO - mozversion platform_version: 52.0a2 08:01:28 INFO - using testdate: 1480176088 08:01:28 INFO - actual date: 1480176088 08:01:28 INFO - starting webserver on 'localhost:53481' 08:01:28 INFO - SUITE-START | Running 2 tests 08:01:28 INFO - TEST-START | dromaeo_css 08:01:28 INFO - operating with platform_type : mac_ 08:01:28 INFO - Initialising browser for dromaeo_css test... 08:01:28 INFO - TEST-INFO | started process 2024 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpv_Ra4S/profile http://localhost:53481/getInfo.html) 08:01:31 INFO - PROCESS | 2024 | __metrics Screen width/height:1600/1200 08:01:31 INFO - PROCESS | 2024 | colorDepth:24 08:01:31 INFO - PROCESS | 2024 | Browser inner width/height: 1024/689 08:01:31 INFO - PROCESS | 2024 | __metrics 08:01:31 ERROR - PROCESS | 2024 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:01:31 INFO - TEST-INFO | 2024: exit 0 08:01:31 INFO - Browser initialized. 08:01:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:01:31 INFO - TEST-INFO | started process 2027 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpv_Ra4S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:44 INFO - PROCESS | 2027 | RSS: Main: 230010880 08:01:44 INFO - PROCESS | 2027 | 08:02:33 INFO - PROCESS | 2027 | 1480176153908 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] 08:04:48 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53481/tests/dromaeo/cssquery-ext.html) 08:04:49 INFO - PROCESS | 2027 | RSS: Main: 234418176 08:04:49 INFO - PROCESS | 2027 | 08:07:51 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-ext.html (next: http://localhost:53481/tests/dromaeo/cssquery-jquery.html) 08:07:52 INFO - PROCESS | 2027 | RSS: Main: 243699712 08:07:52 INFO - PROCESS | 2027 | 08:10:54 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53481/tests/dromaeo/cssquery-mootools.html) 08:10:54 INFO - PROCESS | 2027 | RSS: Main: 246636544 08:10:54 INFO - PROCESS | 2027 | 08:13:56 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53481/tests/dromaeo/cssquery-prototype.html) 08:13:57 INFO - PROCESS | 2027 | RSS: Main: 245813248 08:13:57 INFO - PROCESS | 2027 | 08:17:00 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53481/tests/dromaeo/cssquery-yui.html) 08:17:00 INFO - PROCESS | 2027 | RSS: Main: 249257984 08:17:00 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:53481/tests/dromaeo/cssquery-yui.html (next: http://localhost:53481/tests/dromaeo/cssquery-dojo.html) 08:20:03 INFO - PROCESS | 2027 | RSS: Main: 250667008 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | __start_tp_report 08:20:03 INFO - PROCESS | 2027 | _x_x_mozilla_page_load 08:20:03 INFO - PROCESS | 2027 | _x_x_mozilla_page_load_details 08:20:03 INFO - PROCESS | 2027 | |i|pagename|runs| 08:20:03 INFO - PROCESS | 2027 | |0;dojo.html;3330.4338262495376;3362.9495557566624;3375;3376.898693039208;3400.302937897731;5628.38087810341;5711.057675483551;5716.428357164282;5736.913946290809;5743.712814359275;5703.9714801426035;5741.971290143543;5756.683382413966;5782.971085144568;5829.650220986744;5197.480251974791;5219.556337711299;5246.7901283948595;5255.842324730245;5293.735313234323;5269.112494455262;5287.788488460457;5289.709066001368;5294.49702278283;5304.973475132619;5144.56271216947;5274.868128296785;5287.233351164079;5294.57643388528;5323.467653234665;5155.690658560479;5213.478652134794;5260.079486089919;5274.868128296785;5283.894322113575;5045.117104506733;5207.244949482341;5226.7909283628605;5235.345581802275;5247.39654939683;5712.771489140429;5720.056190728525;5774.0761478163295;5842.766289348421;5932.9406705932815;5663.320401551799;5998.940010599926;6196.2874269459135;6220.688965551704;6222.657753823538;5098.286239926413;6212.689365531705;6260.467860231885;6274.968625156891;6284.905726414107;5859.355470898197;5886.087656413263;5888.146218798251;5911.142884281755;5931.466168044896;6253.37466253371;6263.812085637438;6270.184875966186;6277.340879207709;6304.968475157687;6012.278526576752;6067.362926892612;6081.8479538011015;6120.632762034292;6126.426068710516;6240.032794916795;6279.340669229757;6286.497080233662;6298.905516417258;6324.3043265240785;5110.671225481402;5235.816746413857;6202.0696895465135;6254.093156492283;6271.62370257786;6071.028635418312;6122.357152498924;6126.212334160686;6193.566450348432;6264;3931.8427262909736;4870.902581948341;4900.142475066849;4941.678790878654;4997.325361076281;4424.734515929054;4430.534793846664;4433.113377324548;4438.933415998763;4464.2187617166865;5709.8287051388525;5900.43945825146;5923.466887980103;5926.762929482772;5964.880702385928;5335.9466405336225;6108.3280839107665;6138.143176733788;6217;6226.1905952226825;5094.796208151706;5296.841094767163;5891.764329426861;5893.499052580493;5902.911456328158;5258.132408152612;5325.068113080196;5341.332333458317;5341.4925582069645;5373.462653734595;6173;6240.375962403723;6310.116583678197;6311.558190926591;6323.525735569759;6162.414570615784;6194;6256.374362563889;6295.905561416674;6315.684215789101;6256.0303153011355;6293.842653933688;6299.401556852092;6311.116443697794;6328;6213.627182369072;6226.62640241587;6231.40801623845;6237.220347456568;6243.313235544178;6024.789132380346;6210.5031597473;6215.440610345091;6240.4071613196675;6258.186435763504;6161.630302181704;6170.167027451416;6187.690615469299;6193.81418557444;6211.627302361872;6183.072539119168;6194.38056194377;6199.783007594712;6207.286162091236;6207.906881396873;4415.80128894207;4746.596539294199;5865.589408741518;5926.318473375444;5961.672108033927;6136.723847426966;6141.877162456815;6161.229846269216;6182.1345011697495;6193.287771906288;5965.373635768182;6025.548083893813;6034.939650603438;6037.335893051849;6067.787627433018 08:20:03 INFO - PROCESS | 2027 | |1;ext.html;472.1430603454729;519.509063934582;520.0197627472215;533.0830970730342;537.1995726367713;38236.617633823655;39867.80066099673;40088.59911400885;41932;42000;40818;41204;41246.38130428046;41306.586934130646;41350.793246033805;9274.999999999984;9348.813023739522;9417.576209070574;9431.95284023579;9446.338756287063;9327.347085704003;9335.439873607571;9404.52977351133;9404.96545380011;9415.764605884839;9331.973482916874;9358.391704539183;9409.294302927274;9468;9503.572339244716;9266.953665231664;9363.625454981793;9442.716718498456;9443.433393996382;9485.33602647815;9288.72133835986;9297.302702297287;9347.906520934772;9375.812483750355;9394.906050939471;38719.8064009681;38939.61060389388;39071.0464476775;39100.02249943746;39129;3108.1918701137643;3606.495090687306;3816.637419445176;3836.5971572984995;3916.255911376858;25237.990480380762;29281.707182928323;29887.850560747276;29958.55062174069;29975.40049199004;3029.5152775556026;3102.0848849589424;3117.0492999635103;3121.78147529673;3134.81191128533;519146;521095;523871;524805;525060;59320.70339648274;59865.40134598685;59973.40026599678;60100.09849852256;60160.69919650461;53036.20445693318;60174.39825601688;62395.68802156051;62698.37301627017;62964;16286.615637670931;16483;16530.09084500364;16534.50396488083;16541.172941353125;12671.922886554747;12693.111482196342;12785.61643150707;12811.935940320425;12816.295103769196;16741.58146046334;18244.817551824577;18268.63462730738;18314.26742930268;18409.987450690074;7089;7143.857122857514;7752.5348479091435;7807;7817.570033648204;779.7699678594828;839.701905823436;910.2763303173992;993.6621548673486;1099.8680158380885;29214;29372.97194598179;29733.810647574286;30247.69752302493;30326.54510182349;1049.464772965793;1065.674969134406;1084.9186311026547;1097.3251450358132;1129.745807193375;14510.637234069232;15328.923355383151;15347;15362;15484.612884677974;556801;673723;679692;680355;684808;55835.441645583836;56317.71841140768;56326.15510767342;56392;56466.870662586516;1311.7966715159166;1348.5010546098006;1359.2252416122913;1362.7683293840269;1371.245814801855;23802.880985595653;23906;24589.38526536851;29330;30058.24854378658;46472.7676361616;48169.759151204016;48720;48840.511594883596;48882.511174889216;603201;604534;604690;607896;608221;25552.872235638704;25853.224403267934;25991;26018;26593.33516662099;43495.130097398505;43541.564584355016;44038.339424909296;44068;44801.77599112114;33672.831635842645;34990.825045874604;35550.64449355577;36120.096997575274;36467.72362967284;35730.28539429249;36022;36053;36638.63361366352;36781.816090919376;35248.82375588106;38138.61861381351;39023.414648779726;39073;39246.41130383104;29299.24204547734;34050.829745851115;34333.96998090061;34624.82687586546;34677.82661086678;25301.493970120864;25605.743942560337;25620;25623.487530248916;26064.869675651502 08:20:03 INFO - PROCESS | 2027 | |2;jquery.html;368.62584476756086;371.2408125383592;380.5604526771579;386.6018001458499;400.2974779262394;18646.160922758474;19873;20591.176352945866;20764.27325043624;20856.957152142408;15524.534263972071;17532.737008944878;20071.799282007174;20238.494037649067;20576.794232057677;5677.233573467591;5728.799492017791;5890.88218235635;6021.217241758565;6035.155078289043;5796.594238403313;5934.703264836741;5937;5941.257342832146;5946.22699049123;5870.559708021894;5878.206442130322;6171.537134714892;6508.967455162718;6537.836554086139;6090.421409966046;6529.379708927644;6533.803985880431;6591.571547849375;6596.208454985408;5706.172604972297;5783.913241301384;5819.970900145515;5836.9124463133085;5848.678322692293;12772.680682982907;12807.487700491969;20824;24403.63394549083;24591.754082459127;1860.4604664647236;2188.9890550547148;2232.631615783394;2250.3473992542145;2257.7290725112925;8413.789655258606;8549.230569248735;11359.261647992855;12313.076519261002;14864.182469964147;1840.7975122736489;1882.4070417818343;1887.4998125496754;1915.4349466907295;1993.4717299915535;91585;108343;109392;111254.66236012932;112595;11421.828672569918;11531.71170720722;11666;11694.356810375511;11774.175807693378;14565.490207842675;14568.27158642085;14570;14573.635659108395;14586.34361453737;6808.3191680832515;8205.753827385188;8474.915250847413;8561.52911589856;8667.349948753868;8388.035375931846;8388.79028024292;8439.831203376021;8558.657653693906;8566.27186689126;4473.2842745160615;4501.3022981437925;4532.546745325507;4571.26859702446;4605.355250264965;1903.8952857592687;1961.1272983522197;1972.3294080012865;1984.2459865251112;2019.6364654362355;842.9789255268668;926.8007378413673;928.8420968435382;940.6049459227141;959.1511512311655;20004.89997550032;21055.052522636517;21103.68344474834;21159.365219043455;21177.258795942067;893.5800173918248;913.5158366065996;941.9434833909987;956.4978386347084;1004.0561871840447;9784.755381115387;9913.405195688281;10535.84105748367;11179.66461006171;11262.774744505065;590967;599296;599539;600437;602600;125277;125491;129072.70927290794;131325.6501418119;132786;932.230909499652;945.5839430650492;964.1659964130977;966.1401352796017;970.4419958523877;18195.45413637593;18533.443996680122;18689.626207475503;18736;18741.250349985847;33755.493667594514;49762.75593110201;50260;52164;56017.719911400185;75785;76101.09747256363;76884;77178;78279;7623.69505219785;7882.172371900868;7887.68449262046;7985.041794984639;8053.154418785943;10999.23005389615;12068.155229133876;12191.476065491814;12250.693732656755;12370.75258494843;9907.801843963225;9918.586229127968;9965.10314071737;10329.380237185793;10490.580376784843;10325.122364599094;10336.121429678562;10642.467876606293;10659;10824.891751082703;10409.542664027194;10455.895441045797;10529.157667386438;10680.732981675521;10899.182561307774;7777.883331749915;7834.334081603128;7954.403419743427;8015.559144247122;8072.636731347221;8327.458715183493;9616.278779091735;9843.507824608598;10010.949945250228;10449.895501044892 08:20:03 INFO - PROCESS | 2027 | |3;mootools.html;1205.9035277177827;1315.546136582879;1538.8999715018524;1614.1929035482258;1666.633340665054;7658.272464115914;7930.088039875416;8197;8674.609642566083;8682.913170868282;8250.05124410693;8555.871661925075;8558.443701159436;8576.528290943996;8616.91383086169;2331.743508214095;2357.316378250314;2358.2689366296418;2359.7640235976437;2372.205311220743;2344.402177444749;2372.0867466025534;2380.3811009137576;2397.0891061396733;2397.280815755275;2334.7898689117974;2345.0971376020184;2355.9516015373165;2356.387339291788;2366.8698221597806;2310.0528783198974;2325.2094287942095;2325.7790509901615;2351.753065928087;2352.717673879128;2346.296111166643;2355.528894221163;2367.3963139399516;2374.976250237493;2376.097083108425;8520.318374530085;8544.829103417958;8557.44376615518;8561.400701950866;8580.099089595566;5097.439281679012;5169.224616307546;5211.973940130313;5244.79020839166;5264.052470555299;3452.102453362119;3481.686648201674;3497.842597083137;3557.6442355764475;3562.7684200526883;921.8110287391136;955.8996305387973;970.5680971967478;1039.5114296280813;1093.5953697132034;10922.290051146729;11183.720406989809;11235.775284494346;11290;11312.773744525064;4597.494275629678;4654.115083391232;4724.385829842167;4730.739809310521;4774.331593576902;9490.050994900455;9552.331336806357;9582.041795820502;9582.520873956275;9590.856137157949;5214.947850521522;5242.606804489602;5246.97376513115;5259.737013149327;5260.92108618371;4280.229558679468;4303.763293018914;4770.642201834877;4800.255960326155;4808.2547205183255;3556.5554305711785;3622.565292164957;3633.091727068233;3671.375866102769;3696.4640127181947;1288.4717265920926;1293.5666551705096;1299.1166007115253;1300.5513097981182;1320.570814485296;787.6573690444675;862.7239283429242;878.8443197195667;901.544719916438;904.7285814255697;5565.998120338297;5566.081596536607;5657.377688454267;5847.649141051465;5873.882522349529;855.8416692911832;917.554985831871;995.2237254941158;1011.2466212671607;1024.9077583017565;3767.868124615625;3859.112404147062;3934.5081864766903;3947.348687466536;4003.979980100081;10034.996500350053;10375.792484150275;10402.635907743193;10418.374897506174;10439.37363758177;10318.968103189622;10319.742006449898;10320.380777153396;10325.432101234353;10391.58433662645;6408.070829729663;6502.577332473374;7399.445041622008;7885.802854928673;8101.473404228706;5301.893962120659;5771.913421298768;5802.303723553032;5817.7963771267805;5883.94116058834;7799.298063174115;9426.905730942603;9431.151196392357;9648.951755241178;9883.863355714186;9673.322867399214;9751.853722194315;9763.218942484442;9871.753206169904;9876.901230987598;6649.102371179829;6854.691538880862;7100.502964792415;7224.963875180591;7353.338199562065;7893.881591776013;8158.143394943446;8159.877601836096;8166.510009399455;8189.181081891763;7106.893396598952;7142.071530700976;7296.598687072262;7304.050473438419;7438.776836694908;6920.550164239308;7087.397571206299;7102.14774227096;7140.357367836919;7245.891311630435;6732.899006514808;6894.345037221456;6894.620795856276;6899.517033807585;6905.067815844796;4196.28663127263;4781.521847815191;4848.272759086165;4889.364382630235;4897.338859254098;4264.957350426456;4393.538678438846;4855.393075865517;4982.402111746614;5045.217991211368 08:20:03 INFO - PROCESS | 2027 | |4;prototype.html;279.8038385900277;284.27793404751924;303.41743851804534;310.00642939379264;318.1235695658461;4535.81856725731;4592.357070010201;5388.353397592295;5479.232907392963;5559.972200138999;4552.772361381926;5115.363083813172;5236.92144617831;5343.412224655285;5402.37872644646;3414.265932824442;3925.8625948091894;4092.897677558055;4170.47777561191;4175.770332631704;3277.7074002773024;3434.828258587073;4108.342665173573;4177.456930599018;4181.080162364275;4104.768569429174;4146.046409325857;4150.792460376984;4156.983862826527;4159.625633692967;3674.963250367502;4090.834112278016;4105.363668631367;4117.300058989979;4147.3778933159965;3592.7305452091196;3598.3702852001056;3604.7296452765886;3612.9703034635004;3626.549380247922;4397.890052748675;5120.897582048338;5170.948290517084;5251.369835619712;5315.973420132913;4035.59644035597;4733.763311834395;4768.904621907543;4774.212254977924;4811.398575178103;5026.396832380138;5670.5463562914865;5681.619436435314;5688.687122208277;5771.163181338682;3529.4176460883923;3746.981265093684;3896.9673036645313;3953.2686453006;3997.900052498711;6080.635161890301;6092.66490343031;6094.847628809271;6115.663638499881;6117.96941015292;3988.521377434726;4019.2765302245875;4025.584649210509;4027.637512623878;4032.5564187939303;3470.976062061698;3727.9440808387894;3916.686665066788;4057.594240575919;4062.370332598452;4301.677374196947;4314.525402205755;4711.740854253049;4712.293156026623;4760.833370832004;5234.92147617786;5764.042923086143;5812.44781745742;5838.416158384128;5905.202797622352;1185.9531312893184;1200.9279443233434;1228.6744012836605;1235.925844449336;1284.2102107204003;751.5265382808905;772.2702046565942;811.8822770698216;869.5478351257311;876.1720174435225;4157.812898419578;4418.399004811069;5159.716215608102;5164.431912489623;5239.161734122522;5331.360236771612;5553.666779993213;5554;5572.721363931868;5672.602917795714;4558.358990763389;4750.862112049592;5099.898002039939;5121.974390128025;5140.3831540215415;2642.656454660882;2800.971990280112;2847.800653954245;2874.195225336867;2949.277427030391;4935.728534930541;5544.473275038865;5675.914861277084;5713.6857472838565;5809.622374545641;4488.7905147819465;4595.793189306422;5456.481634244819;5635.506590753454;5769.451787150872;4893.241547560134;5229.509589766898;5386.434424385461;5741.942580574224;5875.823725288248;4897.779599917941;5298.569386265764;5327.813526526553;5594.944050559442;5630.324361076697;5147.691138531551;5276.577873770012;5500.862478438071;5526.806561770319;5529.419410961952;4947.48051454592;5116.411612664442;5698.800541981177;5788.813293274784;5807.925533776265;3130.7338876195777;3210.502372132323;3750.5124333837516;3762.175344957748;3768.2651882882574;4889.559939605452;5529.382965224083;5543.473370029841;5599.8880022400135;5605.383407825218;4790.215729573737;4956.323585263917;5382.273393091882;5688.914666280092;5714.9142762857755;5091.363579552556;5758.971205144115;5804.678128748535;5823.912641310469;5846.988800767693;4502.504724480239;4524.149785635029;5335.973320133375;5673.539141123829;5720.513756330759;4663.836765713184;5057.7724002419245;5255.553277971415;5661.735305877517;5748.878968601082;4984.551390374883;5141.845744627667;5573.210217052281;5677.517411019944;5738.810668212145 08:20:03 INFO - PROCESS | 2027 | |5;yui.html;673.3703986772368;678.4436761855278;719.6257945868149;750.7184805697864;775.6083177995112;14116.435342586297;14891.627709307246;14922.850771492282;14940.551783446486;15000.324985375657;11386.829197562043;13438.731225375486;13465.46138154475;13511.527096551623;13656.112352697068;641.3554377850277;665.0755449924585;780.2587541835251;803.7990502374406;815.3029160068145;797.5533701127358;805.0379796143611;807.1081454992237;807.858624740671;810.8662070758347;840.5755093677715;869.4696235296464;870.8650159225331;871.8866547348836;873.9737807865743;838.7022606974527;843.3084870406269;858.6994551906845;864.9135086491395;865.7965378136133;629.3926361061598;640.6028262477247;644.6196743921109;645.1355184053344;656.3174298729316;13489.797653035212;13804.309784510735;13885.305734713224;13922.58232253024;14087.788683169761;13357.53248636293;13566;13605.931970340183;13734.793978090336;13890.791638125436;10946.781064378747;10998.945005275002;11169.273997190237;11200.383978881158;11217.439128043565;3514.7012503937194;3663.3222853772136;3680.650338217865;3685.8341374638208;3761.097336639187;81412;85513.57243213807;85547.14452855516;87091;87678;10234.744131396774;10373.066424021874;10421.374717516974;10445.84331235032;10470.319429237075;21022.159113635284;21125.78874211238;21232.256871009442;21282.680759788305;21357.25249616256;6517.869642607121;7665.8850117248285;7682.539047657158;7738.651760670783;7858.332041776513;4163.146554956226;4255.97872010638;4320.503142138631;4326.41593384889;4334.631556317749;3317.3862835375535;3402.9149271268416;3444.7071998880374;3480.4257297545623;3483.6516348365467;498.87503679203166;598.8621618923992;599.1851082527759;618.5051958433236;626.946709529686;535.8821059367011;537.1378936806369;559.3260121553518;640.2668944059026;654.7871941618993;7979.9202007979175;8247.381446391544;8833.160849719266;8951.776205594912;9278.48968306736;588.8781870536635;626.0796628955519;650.9837254068686;653.2683394598081;668.2682462703332;3767.962320376816;4059.9594004060173;4260.147970405931;4462.419885414875;4477.865664030084;66209;109910;110003.79992400107;112764;113369.4331528337;95171;96309.55535666831;97112;99720.50139749255;101794.49102754438;81469.59265203636;83069;83906.16093838983;84489.57755211307;86604.56697716471;8126.106128325998;8322.64983912394;8484.660613575388;8574.442661227;8592.484450932963;52825.20762188647;54445.72777136089;55046.72476637751;55580.44419555915;57144.57138571569;74664;74833.25833708401;77144.61427692826;78354.6082269585;79485;11175.0721430601;11250.04374628133;11662.76674466489;11789.76420471603;11881.643550693492;11705.063594912557;11769.572758627646;11988.940055299669;12059.75880482403;12504.187227830162;11273.605423810128;11802.23285486475;11810.232334898548;11919.3444360561;12363.32001739913;11157.776844462904;11159.330440173615;11705.648830535098;11714.1800073994;11727.413629318671;10707.946460267649;11089.500972456097;11105.666829995112;11330.263532870336;11738.530458781554;9568.282378821477;9664.855027174739;9925.255605829447;9995;10039.899601003897;9633;9856.507174641383;9876.555555000186;9927.950360248153;9927.957564455628 08:20:03 INFO - PROCESS | 2027 | __end_tp_report 08:20:03 INFO - PROCESS | 2027 | __start_cc_report 08:20:03 INFO - PROCESS | 2027 | _x_x_mozilla_cycle_collect,370 08:20:03 INFO - PROCESS | 2027 | __end_cc_report 08:20:03 INFO - PROCESS | 2027 | __startTimestamp1480177203532__endTimestamp 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | ------- Summary: start ------- 08:20:03 INFO - PROCESS | 2027 | Number of tests: 6 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#0] dojo.html Cycles:165 Average:5716.60 Median:5932.94 stddev:663.48 (11.2%) stddev-sans-first:638.56 08:20:03 INFO - PROCESS | 2027 | Values: 3330.4 3362.9 3375.0 3376.9 3400.3 5628.4 5711.1 5716.4 5736.9 5743.7 5704.0 5742.0 5756.7 5783.0 5829.7 5197.5 5219.6 5246.8 5255.8 5293.7 5269.1 5287.8 5289.7 5294.5 5305.0 5144.6 5274.9 5287.2 5294.6 5323.5 5155.7 5213.5 5260.1 5274.9 5283.9 5045.1 5207.2 5226.8 5235.3 5247.4 5712.8 5720.1 5774.1 5842.8 5932.9 5663.3 5998.9 6196.3 6220.7 6222.7 5098.3 6212.7 6260.5 6275.0 6284.9 5859.4 5886.1 5888.1 5911.1 5931.5 6253.4 6263.8 6270.2 6277.3 6305.0 6012.3 6067.4 6081.8 6120.6 6126.4 6240.0 6279.3 6286.5 6298.9 6324.3 5110.7 5235.8 6202.1 6254.1 6271.6 6071.0 6122.4 6126.2 6193.6 6264.0 3931.8 4870.9 4900.1 4941.7 4997.3 4424.7 4430.5 4433.1 4438.9 4464.2 5709.8 5900.4 5923.5 5926.8 5964.9 5335.9 6108.3 6138.1 6217.0 6226.2 5094.8 5296.8 5891.8 5893.5 5902.9 5258.1 5325.1 5341.3 5341.5 5373.5 6173.0 6240.4 6310.1 6311.6 6323.5 6162.4 6194.0 6256.4 6295.9 6315.7 6256.0 6293.8 6299.4 6311.1 6328.0 6213.6 6226.6 6231.4 6237.2 6243.3 6024.8 6210.5 6215.4 6240.4 6258.2 6161.6 6170.2 6187.7 6193.8 6211.6 6183.1 6194.4 6199.8 6207.3 6207.9 4415.8 4746.6 5865.6 5926.3 5961.7 6136.7 6141.9 6161.2 6182.1 6193.3 5965.4 6025.5 6034.9 6037.3 6067.8 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#1] ext.html Cycles:180 Average:71668.86 Median:21581.33 stddev:160025.52 (741.5%) stddev-sans-first:160385.16 08:20:03 INFO - PROCESS | 2027 | Values: 472.1 519.5 520.0 533.1 537.2 38236.6 39867.8 40088.6 41932.0 42000.0 40818.0 41204.0 41246.4 41306.6 41350.8 9275.0 9348.8 9417.6 9432.0 9446.3 9327.3 9335.4 9404.5 9405.0 9415.8 9332.0 9358.4 9409.3 9468.0 9503.6 9267.0 9363.6 9442.7 9443.4 9485.3 9288.7 9297.3 9347.9 9375.8 9394.9 38719.8 38939.6 39071.0 39100.0 39129.0 3108.2 3606.5 3816.6 3836.6 3916.3 25238.0 29281.7 29887.9 29958.6 29975.4 3029.5 3102.1 3117.0 3121.8 3134.8 519146.0 521095.0 523871.0 524805.0 525060.0 59320.7 59865.4 59973.4 60100.1 60160.7 53036.2 60174.4 62395.7 62698.4 62964.0 16286.6 16483.0 16530.1 16534.5 16541.2 12671.9 12693.1 12785.6 12811.9 12816.3 16741.6 18244.8 18268.6 18314.3 18410.0 7089.0 7143.9 7752.5 7807.0 7817.6 779.8 839.7 910.3 993.7 1099.9 29214.0 29373.0 29733.8 30247.7 30326.5 1049.5 1065.7 1084.9 1097.3 1129.7 14510.6 15328.9 15347.0 15362.0 15484.6 556801.0 673723.0 679692.0 680355.0 684808.0 55835.4 56317.7 56326.2 56392.0 56466.9 1311.8 1348.5 1359.2 1362.8 1371.2 23802.9 23906.0 24589.4 29330.0 30058.2 46472.8 48169.8 48720.0 48840.5 48882.5 603201.0 604534.0 604690.0 607896.0 608221.0 25552.9 25853.2 25991.0 26018.0 26593.3 43495.1 43541.6 44038.3 44068.0 44801.8 33672.8 34990.8 35550.6 36120.1 36467.7 35730.3 36022.0 36053.0 36638.6 36781.8 35248.8 38138.6 39023.4 39073.0 39246.4 29299.2 34050.8 34334.0 34624.8 34677.8 25301.5 25605.7 25620.0 25623.5 26064.9 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#2] jquery.html Cycles:180 Average:34274.65 Median:2436.79 stddev:99707.94 (4091.8%) stddev-sans-first:99955.14 08:20:03 INFO - PROCESS | 2027 | Values: 368.6 371.2 380.6 386.6 400.3 18646.2 19873.0 20591.2 20764.3 20857.0 15524.5 17532.7 20071.8 20238.5 20576.8 5677.2 5728.8 5890.9 6021.2 6035.2 5796.6 5934.7 5937.0 5941.3 5946.2 5870.6 5878.2 6171.5 6509.0 6537.8 6090.4 6529.4 6533.8 6591.6 6596.2 5706.2 5783.9 5820.0 5836.9 5848.7 12772.7 12807.5 20824.0 24403.6 24591.8 1860.5 2189.0 2232.6 2250.3 2257.7 8413.8 8549.2 11359.3 12313.1 14864.2 1840.8 1882.4 1887.5 1915.4 1993.5 91585.0 108343.0 109392.0 111254.7 112595.0 11421.8 11531.7 11666.0 11694.4 11774.2 14565.5 14568.3 14570.0 14573.6 14586.3 6808.3 8205.8 8474.9 8561.5 8667.3 8388.0 8388.8 8439.8 8558.7 8566.3 4473.3 4501.3 4532.5 4571.3 4605.4 1903.9 1961.1 1972.3 1984.2 2019.6 843.0 926.8 928.8 940.6 959.2 20004.9 21055.1 21103.7 21159.4 21177.3 893.6 913.5 941.9 956.5 1004.1 9784.8 9913.4 10535.8 11179.7 11262.8 590967.0 599296.0 599539.0 600437.0 602600.0 125277.0 125491.0 129072.7 131325.7 132786.0 932.2 945.6 964.2 966.1 970.4 18195.5 18533.4 18689.6 18736.0 18741.3 33755.5 49762.8 50260.0 52164.0 56017.7 75785.0 76101.1 76884.0 77178.0 78279.0 7623.7 7882.2 7887.7 7985.0 8053.2 10999.2 12068.2 12191.5 12250.7 12370.8 9907.8 9918.6 9965.1 10329.4 10490.6 10325.1 10336.1 10642.5 10659.0 10824.9 10409.5 10455.9 10529.2 10680.7 10899.2 7777.9 7834.3 7954.4 8015.6 8072.6 8327.5 9616.3 9843.5 10010.9 10449.9 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#3] mootools.html Cycles:180 Average:5510.37 Median:5190.60 stddev:3053.88 (58.8%) stddev-sans-first:3045.31 08:20:03 INFO - PROCESS | 2027 | Values: 1205.9 1315.5 1538.9 1614.2 1666.6 7658.3 7930.1 8197.0 8674.6 8682.9 8250.1 8555.9 8558.4 8576.5 8616.9 2331.7 2357.3 2358.3 2359.8 2372.2 2344.4 2372.1 2380.4 2397.1 2397.3 2334.8 2345.1 2356.0 2356.4 2366.9 2310.1 2325.2 2325.8 2351.8 2352.7 2346.3 2355.5 2367.4 2375.0 2376.1 8520.3 8544.8 8557.4 8561.4 8580.1 5097.4 5169.2 5212.0 5244.8 5264.1 3452.1 3481.7 3497.8 3557.6 3562.8 921.8 955.9 970.6 1039.5 1093.6 10922.3 11183.7 11235.8 11290.0 11312.8 4597.5 4654.1 4724.4 4730.7 4774.3 9490.1 9552.3 9582.0 9582.5 9590.9 5214.9 5242.6 5247.0 5259.7 5260.9 4280.2 4303.8 4770.6 4800.3 4808.3 3556.6 3622.6 3633.1 3671.4 3696.5 1288.5 1293.6 1299.1 1300.6 1320.6 787.7 862.7 878.8 901.5 904.7 5566.0 5566.1 5657.4 5847.6 5873.9 855.8 917.6 995.2 1011.2 1024.9 3767.9 3859.1 3934.5 3947.3 4004.0 10035.0 10375.8 10402.6 10418.4 10439.4 10319.0 10319.7 10320.4 10325.4 10391.6 6408.1 6502.6 7399.4 7885.8 8101.5 5301.9 5771.9 5802.3 5817.8 5883.9 7799.3 9426.9 9431.2 9649.0 9883.9 9673.3 9751.9 9763.2 9871.8 9876.9 6649.1 6854.7 7100.5 7225.0 7353.3 7893.9 8158.1 8159.9 8166.5 8189.2 7106.9 7142.1 7296.6 7304.1 7438.8 6920.6 7087.4 7102.1 7140.4 7245.9 6732.9 6894.3 6894.6 6899.5 6905.1 4196.3 4781.5 4848.3 4889.4 4897.3 4265.0 4393.5 4855.4 4982.4 5045.2 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#4] prototype.html Cycles:180 Average:4492.56 Median:5005.47 stddev:1387.54 (27.7%) stddev-sans-first:1354.93 08:20:03 INFO - PROCESS | 2027 | Values: 279.8 284.3 303.4 310.0 318.1 4535.8 4592.4 5388.4 5479.2 5560.0 4552.8 5115.4 5236.9 5343.4 5402.4 3414.3 3925.9 4092.9 4170.5 4175.8 3277.7 3434.8 4108.3 4177.5 4181.1 4104.8 4146.0 4150.8 4157.0 4159.6 3675.0 4090.8 4105.4 4117.3 4147.4 3592.7 3598.4 3604.7 3613.0 3626.5 4397.9 5120.9 5170.9 5251.4 5316.0 4035.6 4733.8 4768.9 4774.2 4811.4 5026.4 5670.5 5681.6 5688.7 5771.2 3529.4 3747.0 3897.0 3953.3 3997.9 6080.6 6092.7 6094.8 6115.7 6118.0 3988.5 4019.3 4025.6 4027.6 4032.6 3471.0 3727.9 3916.7 4057.6 4062.4 4301.7 4314.5 4711.7 4712.3 4760.8 5234.9 5764.0 5812.4 5838.4 5905.2 1186.0 1200.9 1228.7 1235.9 1284.2 751.5 772.3 811.9 869.5 876.2 4157.8 4418.4 5159.7 5164.4 5239.2 5331.4 5553.7 5554.0 5572.7 5672.6 4558.4 4750.9 5099.9 5122.0 5140.4 2642.7 2801.0 2847.8 2874.2 2949.3 4935.7 5544.5 5675.9 5713.7 5809.6 4488.8 4595.8 5456.5 5635.5 5769.5 4893.2 5229.5 5386.4 5741.9 5875.8 4897.8 5298.6 5327.8 5594.9 5630.3 5147.7 5276.6 5500.9 5526.8 5529.4 4947.5 5116.4 5698.8 5788.8 5807.9 3130.7 3210.5 3750.5 3762.2 3768.3 4889.6 5529.4 5543.5 5599.9 5605.4 4790.2 4956.3 5382.3 5688.9 5714.9 5091.4 5759.0 5804.7 5823.9 5847.0 4502.5 4524.1 5336.0 5673.5 5720.5 4663.8 5057.8 5255.6 5661.7 5748.9 4984.6 5141.8 5573.2 5677.5 5738.8 08:20:03 INFO - PROCESS | 2027 | 08:20:03 INFO - PROCESS | 2027 | [#5] yui.html Cycles:180 Average:20114.28 Median:54746.23 stddev:29783.33 (54.4%) stddev-sans-first:29831.11 08:20:03 INFO - PROCESS | 2027 | Values: 673.4 678.4 719.6 750.7 775.6 14116.4 14891.6 14922.9 14940.6 15000.3 11386.8 13438.7 13465.5 13511.5 13656.1 641.4 665.1 780.3 803.8 815.3 797.6 805.0 807.1 807.9 810.9 840.6 869.5 870.9 871.9 874.0 838.7 843.3 858.7 864.9 865.8 629.4 640.6 644.6 645.1 656.3 13489.8 13804.3 13885.3 13922.6 14087.8 13357.5 13566.0 13605.9 13734.8 13890.8 10946.8 10998.9 11169.3 11200.4 11217.4 3514.7 3663.3 3680.7 3685.8 3761.1 81412.0 85513.6 85547.1 87091.0 87678.0 10234.7 10373.1 10421.4 10445.8 10470.3 21022.2 21125.8 21232.3 21282.7 21357.3 6517.9 7665.9 7682.5 7738.7 7858.3 4163.1 4256.0 4320.5 4326.4 4334.6 3317.4 3402.9 3444.7 3480.4 3483.7 498.9 598.9 599.2 618.5 626.9 535.9 537.1 559.3 640.3 654.8 7979.9 8247.4 8833.2 8951.8 9278.5 588.9 626.1 651.0 653.3 668.3 3768.0 4060.0 4260.1 4462.4 4477.9 66209.0 109910.0 110003.8 112764.0 113369.4 95171.0 96309.6 97112.0 99720.5 101794.5 81469.6 83069.0 83906.2 84489.6 86604.6 8126.1 8322.6 8484.7 8574.4 8592.5 52825.2 54445.7 55046.7 55580.4 57144.6 74664.0 74833.3 77144.6 78354.6 79485.0 11175.1 11250.0 11662.8 11789.8 11881.6 11705.1 11769.6 11988.9 12059.8 12504.2 11273.6 11802.2 11810.2 11919.3 12363.3 11157.8 11159.3 11705.6 11714.2 11727.4 10707.9 11089.5 11105.7 11330.3 11738.5 9568.3 9664.9 9925.3 9995.0 10039.9 9633.0 9856.5 9876.6 9928.0 9928.0 08:20:03 INFO - PROCESS | 2027 | -------- Summary: end -------- 08:20:03 INFO - PROCESS | 2027 | 08:20:04 INFO - TEST-INFO | 2027: exit 0 08:20:04 INFO - TEST-OK | dromaeo_css | took 1116054ms 08:20:04 INFO - TEST-START | kraken 08:20:04 INFO - operating with platform_type : mac_ 08:20:04 INFO - Initialising browser for kraken test... 08:20:04 INFO - TEST-INFO | started process 2031 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpkzjNOu/profile http://localhost:53481/getInfo.html) 08:20:07 INFO - PROCESS | 2031 | __metrics Screen width/height:1600/1200 08:20:07 INFO - PROCESS | 2031 | colorDepth:24 08:20:07 INFO - PROCESS | 2031 | Browser inner width/height: 1024/689 08:20:07 INFO - PROCESS | 2031 | __metrics 08:20:07 ERROR - PROCESS | 2031 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:20:07 INFO - TEST-INFO | 2031: exit 0 08:20:07 INFO - Browser initialized. 08:20:07 INFO - Running cycle 1/1 for kraken test... 08:20:07 INFO - TEST-INFO | started process 2033 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/tmpkzjNOu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:20:20 INFO - PROCESS | 2033 | RSS: Main: 229531648 08:20:20 INFO - PROCESS | 2033 | 08:20:23 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53481/tests/kraken/driver.html?testName=audio-beat-detection) 08:20:23 INFO - PROCESS | 2033 | RSS: Main: 241704960 08:20:23 INFO - PROCESS | 2033 | 08:20:26 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53481/tests/kraken/driver.html?testName=audio-dft) 08:20:27 INFO - PROCESS | 2033 | RSS: Main: 249237504 08:20:27 INFO - PROCESS | 2033 | 08:20:30 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53481/tests/kraken/driver.html?testName=audio-fft) 08:20:31 INFO - PROCESS | 2033 | RSS: Main: 253157376 08:20:31 INFO - PROCESS | 2033 | 08:20:32 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53481/tests/kraken/driver.html?testName=audio-oscillator) 08:20:33 INFO - PROCESS | 2033 | RSS: Main: 254418944 08:20:33 INFO - PROCESS | 2033 | 08:20:35 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53481/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:20:36 INFO - PROCESS | 2033 | RSS: Main: 254455808 08:20:36 INFO - PROCESS | 2033 | 08:20:38 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53481/tests/kraken/driver.html?testName=imaging-darkroom) 08:20:39 INFO - PROCESS | 2033 | RSS: Main: 258617344 08:20:39 INFO - PROCESS | 2033 | 08:20:43 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53481/tests/kraken/driver.html?testName=imaging-desaturate) 08:20:43 INFO - PROCESS | 2033 | RSS: Main: 258170880 08:20:43 INFO - PROCESS | 2033 | 08:20:46 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53481/tests/kraken/driver.html?testName=json-parse-financial) 08:20:47 INFO - PROCESS | 2033 | RSS: Main: 258244608 08:20:47 INFO - PROCESS | 2033 | 08:20:48 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53481/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:20:49 INFO - PROCESS | 2033 | RSS: Main: 257187840 08:20:49 INFO - PROCESS | 2033 | 08:20:51 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:20:52 INFO - PROCESS | 2033 | RSS: Main: 257220608 08:20:52 INFO - PROCESS | 2033 | 08:20:54 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:20:54 INFO - PROCESS | 2033 | RSS: Main: 257228800 08:20:54 INFO - PROCESS | 2033 | 08:20:57 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:20:58 INFO - PROCESS | 2033 | RSS: Main: 257703936 08:20:58 INFO - PROCESS | 2033 | 08:21:01 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:21:02 INFO - PROCESS | 2033 | RSS: Main: 257683456 08:21:02 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:53481/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53481/tests/kraken/driver.html?testName=ai-astar) 08:21:04 INFO - PROCESS | 2033 | RSS: Main: 259497984 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | __start_tp_report 08:21:04 INFO - PROCESS | 2033 | _x_x_mozilla_page_load 08:21:04 INFO - PROCESS | 2033 | _x_x_mozilla_page_load_details 08:21:04 INFO - PROCESS | 2033 | |i|pagename|runs| 08:21:04 INFO - PROCESS | 2033 | |0;ai-astar;106;117;119;119;118;117;119;118;119;120 08:21:04 INFO - PROCESS | 2033 | |1;audio-beat-detection;146;115;114;147;115;115;117;114;142;145 08:21:04 INFO - PROCESS | 2033 | |2;audio-dft;195;204;200;191;188;196;196;197;198;216 08:21:04 INFO - PROCESS | 2033 | |3;audio-fft;82;57;58;86;59;64;70;63;76;58 08:21:04 INFO - PROCESS | 2033 | |4;audio-oscillator;92;72;91;73;69;71;110;62;77;73 08:21:04 INFO - PROCESS | 2033 | |5;imaging-gaussian-blur;103;88;94;133;90;86;90;103;91;88 08:21:04 INFO - PROCESS | 2033 | |6;imaging-darkroom;153;121;102;160;169;116;109;175;105;166 08:21:04 INFO - PROCESS | 2033 | |7;imaging-desaturate;89;87;121;140;103;72;140;146;93;97 08:21:04 INFO - PROCESS | 2033 | |8;json-parse-financial;57;57;55;57;60;58;61;57;61;61 08:21:04 INFO - PROCESS | 2033 | |9;json-stringify-tinderbox;66;71;77;80;69;69;69;71;73;70 08:21:04 INFO - PROCESS | 2033 | |10;stanford-crypto-aes;89;101;106;78;96;106;105;83;89;81 08:21:04 INFO - PROCESS | 2033 | |11;stanford-crypto-ccm;167;153;152;158;161;153;158;152;208;153 08:21:04 INFO - PROCESS | 2033 | |12;stanford-crypto-pbkdf2;171;172;167;169;202;194;169;170;177;228 08:21:04 INFO - PROCESS | 2033 | |13;stanford-crypto-sha256-iterative;70;68;65;66;69;67;68;68;66;68 08:21:04 INFO - PROCESS | 2033 | __end_tp_report 08:21:04 INFO - PROCESS | 2033 | __start_cc_report 08:21:04 INFO - PROCESS | 2033 | _x_x_mozilla_cycle_collect,970 08:21:04 INFO - PROCESS | 2033 | __end_cc_report 08:21:04 INFO - PROCESS | 2033 | __startTimestamp1480177264274__endTimestamp 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | ------- Summary: start ------- 08:21:04 INFO - PROCESS | 2033 | Number of tests: 14 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#0] ai-astar Cycles:10 Average:117.20 Median:119.00 stddev:4.05 (3.4%) stddev-sans-first:1.01 08:21:04 INFO - PROCESS | 2033 | Values: 106.0 117.0 119.0 119.0 118.0 117.0 119.0 118.0 119.0 120.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#1] audio-beat-detection Cycles:10 Average:127.00 Median:129.50 stddev:15.56 (12.0%) stddev-sans-first:14.91 08:21:04 INFO - PROCESS | 2033 | Values: 146.0 115.0 114.0 147.0 115.0 115.0 117.0 114.0 142.0 145.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#2] audio-dft Cycles:10 Average:198.10 Median:197.50 stddev:7.68 (3.9%) stddev-sans-first:8.06 08:21:04 INFO - PROCESS | 2033 | Values: 195.0 204.0 200.0 191.0 188.0 196.0 196.0 197.0 198.0 216.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.00 stddev:10.68 (15.9%) stddev-sans-first:9.91 08:21:04 INFO - PROCESS | 2033 | Values: 82.0 57.0 58.0 86.0 59.0 64.0 70.0 63.0 76.0 58.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:73.00 stddev:14.34 (19.7%) stddev-sans-first:14.42 08:21:04 INFO - PROCESS | 2033 | Values: 92.0 72.0 91.0 73.0 69.0 71.0 110.0 62.0 77.0 73.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:89.00 stddev:14.11 (15.9%) stddev-sans-first:14.78 08:21:04 INFO - PROCESS | 2033 | Values: 103.0 88.0 94.0 133.0 90.0 86.0 90.0 103.0 91.0 88.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#6] imaging-darkroom Cycles:10 Average:137.60 Median:156.50 stddev:29.48 (18.8%) stddev-sans-first:30.73 08:21:04 INFO - PROCESS | 2033 | Values: 153.0 121.0 102.0 160.0 169.0 116.0 109.0 175.0 105.0 166.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#7] imaging-desaturate Cycles:10 Average:108.80 Median:79.50 stddev:26.08 (32.8%) stddev-sans-first:26.66 08:21:04 INFO - PROCESS | 2033 | Values: 89.0 87.0 121.0 140.0 103.0 72.0 140.0 146.0 93.0 97.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:59.00 stddev:2.17 (3.7%) stddev-sans-first:2.24 08:21:04 INFO - PROCESS | 2033 | Values: 57.0 57.0 55.0 57.0 60.0 58.0 61.0 57.0 61.0 61.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:4.17 (5.9%) stddev-sans-first:3.92 08:21:04 INFO - PROCESS | 2033 | Values: 66.0 71.0 77.0 80.0 69.0 69.0 69.0 71.0 73.0 70.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:82.00 stddev:10.82 (13.2%) stddev-sans-first:11.36 08:21:04 INFO - PROCESS | 2033 | Values: 89.0 101.0 106.0 78.0 96.0 106.0 105.0 83.0 89.0 81.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#11] stanford-crypto-ccm Cycles:10 Average:161.50 Median:158.00 stddev:17.04 (10.8%) stddev-sans-first:17.96 08:21:04 INFO - PROCESS | 2033 | Values: 167.0 153.0 152.0 158.0 161.0 153.0 158.0 152.0 208.0 153.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:174.50 stddev:20.04 (11.5%) stddev-sans-first:20.86 08:21:04 INFO - PROCESS | 2033 | Values: 171.0 172.0 167.0 169.0 202.0 194.0 169.0 170.0 177.0 228.0 08:21:04 INFO - PROCESS | 2033 | 08:21:04 INFO - PROCESS | 2033 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.30 08:21:04 INFO - PROCESS | 2033 | Values: 70.0 68.0 65.0 66.0 69.0 67.0 68.0 68.0 66.0 68.0 08:21:04 INFO - PROCESS | 2033 | -------- Summary: end -------- 08:21:04 INFO - PROCESS | 2033 | 08:21:05 INFO - TEST-INFO | 2033: exit 0 08:21:05 INFO - TEST-OK | kraken | took 61120ms 08:21:05 INFO - SUITE-END | took 1177s 08:21:05 INFO - Completed test suite (00:19:37) 08:21:05 INFO - javascript benchmark 08:21:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7134.815525551276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.4338262495376, 3362.9495557566624, 3375.0, 3376.898693039208, 3400.302937897731, 5628.38087810341, 5711.057675483551, 5716.428357164282, 5736.913946290809, 5743.712814359275, 5703.9714801426035, 5741.971290143543, 5756.683382413966, 5782.971085144568, 5829.650220986744, 5197.480251974791, 5219.556337711299, 5246.7901283948595, 5255.842324730245, 5293.735313234323, 5269.112494455262, 5287.788488460457, 5289.709066001368, 5294.49702278283, 5304.973475132619, 5144.56271216947, 5274.868128296785, 5287.233351164079, 5294.57643388528, 5323.467653234665, 5155.690658560479, 5213.478652134794, 5260.079486089919, 5274.868128296785, 5283.894322113575, 5045.117104506733, 5207.244949482341, 5226.7909283628605, 5235.345581802275, 5247.39654939683, 5712.771489140429, 5720.056190728525, 5774.0761478163295, 5842.766289348421, 5932.9406705932815, 5663.320401551799, 5998.940010599926, 6196.2874269459135, 6220.688965551704, 6222.657753823538, 5098.286239926413, 6212.689365531705, 6260.467860231885, 6274.968625156891, 6284.905726414107, 5859.355470898197, 5886.087656413263, 5888.146218798251, 5911.142884281755, 5931.466168044896, 6253.37466253371, 6263.812085637438, 6270.184875966186, 6277.340879207709, 6304.968475157687, 6012.278526576752, 6067.362926892612, 6081.8479538011015, 6120.632762034292, 6126.426068710516, 6240.032794916795, 6279.340669229757, 6286.497080233662, 6298.905516417258, 6324.3043265240785, 5110.671225481402, 5235.816746413857, 6202.0696895465135, 6254.093156492283, 6271.62370257786, 6071.028635418312, 6122.357152498924, 6126.212334160686, 6193.566450348432, 6264.0, 3931.8427262909736, 4870.902581948341, 4900.142475066849, 4941.678790878654, 4997.325361076281, 4424.734515929054, 4430.534793846664, 4433.113377324548, 4438.933415998763, 4464.2187617166865, 5709.8287051388525, 5900.43945825146, 5923.466887980103, 5926.762929482772, 5964.880702385928, 5335.9466405336225, 6108.3280839107665, 6138.143176733788, 6217.0, 6226.1905952226825, 5094.796208151706, 5296.841094767163, 5891.764329426861, 5893.499052580493, 5902.911456328158, 5258.132408152612, 5325.068113080196, 5341.332333458317, 5341.4925582069645, 5373.462653734595, 6173.0, 6240.375962403723, 6310.116583678197, 6311.558190926591, 6323.525735569759, 6162.414570615784, 6194.0, 6256.374362563889, 6295.905561416674, 6315.684215789101, 6256.0303153011355, 6293.842653933688, 6299.401556852092, 6311.116443697794, 6328.0, 6213.627182369072, 6226.62640241587, 6231.40801623845, 6237.220347456568, 6243.313235544178, 6024.789132380346, 6210.5031597473, 6215.440610345091, 6240.4071613196675, 6258.186435763504, 6161.630302181704, 6170.167027451416, 6187.690615469299, 6193.81418557444, 6211.627302361872, 6183.072539119168, 6194.38056194377, 6199.783007594712, 6207.286162091236, 6207.906881396873, 4415.80128894207, 4746.596539294199, 5865.589408741518, 5926.318473375444, 5961.672108033927, 6136.723847426966, 6141.877162456815, 6161.229846269216, 6182.1345011697495, 6193.287771906288, 5965.373635768182, 6025.548083893813, 6034.939650603438, 6037.335893051849, 6067.787627433018], "value": 5675.59566281263, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.1430603454729, 519.509063934582, 520.0197627472215, 533.0830970730342, 537.1995726367713, 38236.617633823655, 39867.80066099673, 40088.59911400885, 41932.0, 42000.0, 40818.0, 41204.0, 41246.38130428046, 41306.586934130646, 41350.793246033805, 9274.999999999984, 9348.813023739522, 9417.576209070574, 9431.95284023579, 9446.338756287063, 9327.347085704003, 9335.439873607571, 9404.52977351133, 9404.96545380011, 9415.764605884839, 9331.973482916874, 9358.391704539183, 9409.294302927274, 9468.0, 9503.572339244716, 9266.953665231664, 9363.625454981793, 9442.716718498456, 9443.433393996382, 9485.33602647815, 9288.72133835986, 9297.302702297287, 9347.906520934772, 9375.812483750355, 9394.906050939471, 38719.8064009681, 38939.61060389388, 39071.0464476775, 39100.02249943746, 39129.0, 3108.1918701137643, 3606.495090687306, 3816.637419445176, 3836.5971572984995, 3916.255911376858, 25237.990480380762, 29281.707182928323, 29887.850560747276, 29958.55062174069, 29975.40049199004, 3029.5152775556026, 3102.0848849589424, 3117.0492999635103, 3121.78147529673, 3134.81191128533, 519146.0, 521095.0, 523871.0, 524805.0, 525060.0, 59320.70339648274, 59865.40134598685, 59973.40026599678, 60100.09849852256, 60160.69919650461, 53036.20445693318, 60174.39825601688, 62395.68802156051, 62698.37301627017, 62964.0, 16286.615637670931, 16483.0, 16530.09084500364, 16534.50396488083, 16541.172941353125, 12671.922886554747, 12693.111482196342, 12785.61643150707, 12811.935940320425, 12816.295103769196, 16741.58146046334, 18244.817551824577, 18268.63462730738, 18314.26742930268, 18409.987450690074, 7089.0, 7143.857122857514, 7752.5348479091435, 7807.0, 7817.570033648204, 779.7699678594828, 839.701905823436, 910.2763303173992, 993.6621548673486, 1099.8680158380885, 29214.0, 29372.97194598179, 29733.810647574286, 30247.69752302493, 30326.54510182349, 1049.464772965793, 1065.674969134406, 1084.9186311026547, 1097.3251450358132, 1129.745807193375, 14510.637234069232, 15328.923355383151, 15347.0, 15362.0, 15484.612884677974, 556801.0, 673723.0, 679692.0, 680355.0, 684808.0, 55835.441645583836, 56317.71841140768, 56326.15510767342, 56392.0, 56466.870662586516, 1311.7966715159166, 1348.5010546098006, 1359.2252416122913, 1362.7683293840269, 1371.245814801855, 23802.880985595653, 23906.0, 24589.38526536851, 29330.0, 30058.24854378658, 46472.7676361616, 48169.759151204016, 48720.0, 48840.511594883596, 48882.511174889216, 603201.0, 604534.0, 604690.0, 607896.0, 608221.0, 25552.872235638704, 25853.224403267934, 25991.0, 26018.0, 26593.33516662099, 43495.130097398505, 43541.564584355016, 44038.339424909296, 44068.0, 44801.77599112114, 33672.831635842645, 34990.825045874604, 35550.64449355577, 36120.096997575274, 36467.72362967284, 35730.28539429249, 36022.0, 36053.0, 36638.63361366352, 36781.816090919376, 35248.82375588106, 38138.61861381351, 39023.414648779726, 39073.0, 39246.41130383104, 29299.24204547734, 34050.829745851115, 34333.96998090061, 34624.82687586546, 34677.82661086678, 25301.493970120864, 25605.743942560337, 25620.0, 25623.487530248916, 26064.869675651502], "value": 19642.22124303127, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.62584476756086, 371.2408125383592, 380.5604526771579, 386.6018001458499, 400.2974779262394, 18646.160922758474, 19873.0, 20591.176352945866, 20764.27325043624, 20856.957152142408, 15524.534263972071, 17532.737008944878, 20071.799282007174, 20238.494037649067, 20576.794232057677, 5677.233573467591, 5728.799492017791, 5890.88218235635, 6021.217241758565, 6035.155078289043, 5796.594238403313, 5934.703264836741, 5937.0, 5941.257342832146, 5946.22699049123, 5870.559708021894, 5878.206442130322, 6171.537134714892, 6508.967455162718, 6537.836554086139, 6090.421409966046, 6529.379708927644, 6533.803985880431, 6591.571547849375, 6596.208454985408, 5706.172604972297, 5783.913241301384, 5819.970900145515, 5836.9124463133085, 5848.678322692293, 12772.680682982907, 12807.487700491969, 20824.0, 24403.63394549083, 24591.754082459127, 1860.4604664647236, 2188.9890550547148, 2232.631615783394, 2250.3473992542145, 2257.7290725112925, 8413.789655258606, 8549.230569248735, 11359.261647992855, 12313.076519261002, 14864.182469964147, 1840.7975122736489, 1882.4070417818343, 1887.4998125496754, 1915.4349466907295, 1993.4717299915535, 91585.0, 108343.0, 109392.0, 111254.66236012932, 112595.0, 11421.828672569918, 11531.71170720722, 11666.0, 11694.356810375511, 11774.175807693378, 14565.490207842675, 14568.27158642085, 14570.0, 14573.635659108395, 14586.34361453737, 6808.3191680832515, 8205.753827385188, 8474.915250847413, 8561.52911589856, 8667.349948753868, 8388.035375931846, 8388.79028024292, 8439.831203376021, 8558.657653693906, 8566.27186689126, 4473.2842745160615, 4501.3022981437925, 4532.546745325507, 4571.26859702446, 4605.355250264965, 1903.8952857592687, 1961.1272983522197, 1972.3294080012865, 1984.2459865251112, 2019.6364654362355, 842.9789255268668, 926.8007378413673, 928.8420968435382, 940.6049459227141, 959.1511512311655, 20004.89997550032, 21055.052522636517, 21103.68344474834, 21159.365219043455, 21177.258795942067, 893.5800173918248, 913.5158366065996, 941.9434833909987, 956.4978386347084, 1004.0561871840447, 9784.755381115387, 9913.405195688281, 10535.84105748367, 11179.66461006171, 11262.774744505065, 590967.0, 599296.0, 599539.0, 600437.0, 602600.0, 125277.0, 125491.0, 129072.70927290794, 131325.6501418119, 132786.0, 932.230909499652, 945.5839430650492, 964.1659964130977, 966.1401352796017, 970.4419958523877, 18195.45413637593, 18533.443996680122, 18689.626207475503, 18736.0, 18741.250349985847, 33755.493667594514, 49762.75593110201, 50260.0, 52164.0, 56017.719911400185, 75785.0, 76101.09747256363, 76884.0, 77178.0, 78279.0, 7623.69505219785, 7882.172371900868, 7887.68449262046, 7985.041794984639, 8053.154418785943, 10999.23005389615, 12068.155229133876, 12191.476065491814, 12250.693732656755, 12370.75258494843, 9907.801843963225, 9918.586229127968, 9965.10314071737, 10329.380237185793, 10490.580376784843, 10325.122364599094, 10336.121429678562, 10642.467876606293, 10659.0, 10824.891751082703, 10409.542664027194, 10455.895441045797, 10529.157667386438, 10680.732981675521, 10899.182561307774, 7777.883331749915, 7834.334081603128, 7954.403419743427, 8015.559144247122, 8072.636731347221, 8327.458715183493, 9616.278779091735, 9843.507824608598, 10010.949945250228, 10449.895501044892], "value": 9353.095666783462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.9035277177827, 1315.546136582879, 1538.8999715018524, 1614.1929035482258, 1666.633340665054, 7658.272464115914, 7930.088039875416, 8197.0, 8674.609642566083, 8682.913170868282, 8250.05124410693, 8555.871661925075, 8558.443701159436, 8576.528290943996, 8616.91383086169, 2331.743508214095, 2357.316378250314, 2358.2689366296418, 2359.7640235976437, 2372.205311220743, 2344.402177444749, 2372.0867466025534, 2380.3811009137576, 2397.0891061396733, 2397.280815755275, 2334.7898689117974, 2345.0971376020184, 2355.9516015373165, 2356.387339291788, 2366.8698221597806, 2310.0528783198974, 2325.2094287942095, 2325.7790509901615, 2351.753065928087, 2352.717673879128, 2346.296111166643, 2355.528894221163, 2367.3963139399516, 2374.976250237493, 2376.097083108425, 8520.318374530085, 8544.829103417958, 8557.44376615518, 8561.400701950866, 8580.099089595566, 5097.439281679012, 5169.224616307546, 5211.973940130313, 5244.79020839166, 5264.052470555299, 3452.102453362119, 3481.686648201674, 3497.842597083137, 3557.6442355764475, 3562.7684200526883, 921.8110287391136, 955.8996305387973, 970.5680971967478, 1039.5114296280813, 1093.5953697132034, 10922.290051146729, 11183.720406989809, 11235.775284494346, 11290.0, 11312.773744525064, 4597.494275629678, 4654.115083391232, 4724.385829842167, 4730.739809310521, 4774.331593576902, 9490.050994900455, 9552.331336806357, 9582.041795820502, 9582.520873956275, 9590.856137157949, 5214.947850521522, 5242.606804489602, 5246.97376513115, 5259.737013149327, 5260.92108618371, 4280.229558679468, 4303.763293018914, 4770.642201834877, 4800.255960326155, 4808.2547205183255, 3556.5554305711785, 3622.565292164957, 3633.091727068233, 3671.375866102769, 3696.4640127181947, 1288.4717265920926, 1293.5666551705096, 1299.1166007115253, 1300.5513097981182, 1320.570814485296, 787.6573690444675, 862.7239283429242, 878.8443197195667, 901.544719916438, 904.7285814255697, 5565.998120338297, 5566.081596536607, 5657.377688454267, 5847.649141051465, 5873.882522349529, 855.8416692911832, 917.554985831871, 995.2237254941158, 1011.2466212671607, 1024.9077583017565, 3767.868124615625, 3859.112404147062, 3934.5081864766903, 3947.348687466536, 4003.979980100081, 10034.996500350053, 10375.792484150275, 10402.635907743193, 10418.374897506174, 10439.37363758177, 10318.968103189622, 10319.742006449898, 10320.380777153396, 10325.432101234353, 10391.58433662645, 6408.070829729663, 6502.577332473374, 7399.445041622008, 7885.802854928673, 8101.473404228706, 5301.893962120659, 5771.913421298768, 5802.303723553032, 5817.7963771267805, 5883.94116058834, 7799.298063174115, 9426.905730942603, 9431.151196392357, 9648.951755241178, 9883.863355714186, 9673.322867399214, 9751.853722194315, 9763.218942484442, 9871.753206169904, 9876.901230987598, 6649.102371179829, 6854.691538880862, 7100.502964792415, 7224.963875180591, 7353.338199562065, 7893.881591776013, 8158.143394943446, 8159.877601836096, 8166.510009399455, 8189.181081891763, 7106.893396598952, 7142.071530700976, 7296.598687072262, 7304.050473438419, 7438.776836694908, 6920.550164239308, 7087.397571206299, 7102.14774227096, 7140.357367836919, 7245.891311630435, 6732.899006514808, 6894.345037221456, 6894.620795856276, 6899.517033807585, 6905.067815844796, 4196.28663127263, 4781.521847815191, 4848.272759086165, 4889.364382630235, 4897.338859254098, 4264.957350426456, 4393.538678438846, 4855.393075865517, 4982.402111746614, 5045.217991211368], "value": 4451.769390658158, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.8038385900277, 284.27793404751924, 303.41743851804534, 310.00642939379264, 318.1235695658461, 4535.81856725731, 4592.357070010201, 5388.353397592295, 5479.232907392963, 5559.972200138999, 4552.772361381926, 5115.363083813172, 5236.92144617831, 5343.412224655285, 5402.37872644646, 3414.265932824442, 3925.8625948091894, 4092.897677558055, 4170.47777561191, 4175.770332631704, 3277.7074002773024, 3434.828258587073, 4108.342665173573, 4177.456930599018, 4181.080162364275, 4104.768569429174, 4146.046409325857, 4150.792460376984, 4156.983862826527, 4159.625633692967, 3674.963250367502, 4090.834112278016, 4105.363668631367, 4117.300058989979, 4147.3778933159965, 3592.7305452091196, 3598.3702852001056, 3604.7296452765886, 3612.9703034635004, 3626.549380247922, 4397.890052748675, 5120.897582048338, 5170.948290517084, 5251.369835619712, 5315.973420132913, 4035.59644035597, 4733.763311834395, 4768.904621907543, 4774.212254977924, 4811.398575178103, 5026.396832380138, 5670.5463562914865, 5681.619436435314, 5688.687122208277, 5771.163181338682, 3529.4176460883923, 3746.981265093684, 3896.9673036645313, 3953.2686453006, 3997.900052498711, 6080.635161890301, 6092.66490343031, 6094.847628809271, 6115.663638499881, 6117.96941015292, 3988.521377434726, 4019.2765302245875, 4025.584649210509, 4027.637512623878, 4032.5564187939303, 3470.976062061698, 3727.9440808387894, 3916.686665066788, 4057.594240575919, 4062.370332598452, 4301.677374196947, 4314.525402205755, 4711.740854253049, 4712.293156026623, 4760.833370832004, 5234.92147617786, 5764.042923086143, 5812.44781745742, 5838.416158384128, 5905.202797622352, 1185.9531312893184, 1200.9279443233434, 1228.6744012836605, 1235.925844449336, 1284.2102107204003, 751.5265382808905, 772.2702046565942, 811.8822770698216, 869.5478351257311, 876.1720174435225, 4157.812898419578, 4418.399004811069, 5159.716215608102, 5164.431912489623, 5239.161734122522, 5331.360236771612, 5553.666779993213, 5554.0, 5572.721363931868, 5672.602917795714, 4558.358990763389, 4750.862112049592, 5099.898002039939, 5121.974390128025, 5140.3831540215415, 2642.656454660882, 2800.971990280112, 2847.800653954245, 2874.195225336867, 2949.277427030391, 4935.728534930541, 5544.473275038865, 5675.914861277084, 5713.6857472838565, 5809.622374545641, 4488.7905147819465, 4595.793189306422, 5456.481634244819, 5635.506590753454, 5769.451787150872, 4893.241547560134, 5229.509589766898, 5386.434424385461, 5741.942580574224, 5875.823725288248, 4897.779599917941, 5298.569386265764, 5327.813526526553, 5594.944050559442, 5630.324361076697, 5147.691138531551, 5276.577873770012, 5500.862478438071, 5526.806561770319, 5529.419410961952, 4947.48051454592, 5116.411612664442, 5698.800541981177, 5788.813293274784, 5807.925533776265, 3130.7338876195777, 3210.502372132323, 3750.5124333837516, 3762.175344957748, 3768.2651882882574, 4889.559939605452, 5529.382965224083, 5543.473370029841, 5599.8880022400135, 5605.383407825218, 4790.215729573737, 4956.323585263917, 5382.273393091882, 5688.914666280092, 5714.9142762857755, 5091.363579552556, 5758.971205144115, 5804.678128748535, 5823.912641310469, 5846.988800767693, 4502.504724480239, 4524.149785635029, 5335.973320133375, 5673.539141123829, 5720.513756330759, 4663.836765713184, 5057.7724002419245, 5255.553277971415, 5661.735305877517, 5748.878968601082, 4984.551390374883, 5141.845744627667, 5573.210217052281, 5677.517411019944, 5738.810668212145], "value": 4041.5009784202184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.3703986772368, 678.4436761855278, 719.6257945868149, 750.7184805697864, 775.6083177995112, 14116.435342586297, 14891.627709307246, 14922.850771492282, 14940.551783446486, 15000.324985375657, 11386.829197562043, 13438.731225375486, 13465.46138154475, 13511.527096551623, 13656.112352697068, 641.3554377850277, 665.0755449924585, 780.2587541835251, 803.7990502374406, 815.3029160068145, 797.5533701127358, 805.0379796143611, 807.1081454992237, 807.858624740671, 810.8662070758347, 840.5755093677715, 869.4696235296464, 870.8650159225331, 871.8866547348836, 873.9737807865743, 838.7022606974527, 843.3084870406269, 858.6994551906845, 864.9135086491395, 865.7965378136133, 629.3926361061598, 640.6028262477247, 644.6196743921109, 645.1355184053344, 656.3174298729316, 13489.797653035212, 13804.309784510735, 13885.305734713224, 13922.58232253024, 14087.788683169761, 13357.53248636293, 13566.0, 13605.931970340183, 13734.793978090336, 13890.791638125436, 10946.781064378747, 10998.945005275002, 11169.273997190237, 11200.383978881158, 11217.439128043565, 3514.7012503937194, 3663.3222853772136, 3680.650338217865, 3685.8341374638208, 3761.097336639187, 81412.0, 85513.57243213807, 85547.14452855516, 87091.0, 87678.0, 10234.744131396774, 10373.066424021874, 10421.374717516974, 10445.84331235032, 10470.319429237075, 21022.159113635284, 21125.78874211238, 21232.256871009442, 21282.680759788305, 21357.25249616256, 6517.869642607121, 7665.8850117248285, 7682.539047657158, 7738.651760670783, 7858.332041776513, 4163.146554956226, 4255.97872010638, 4320.503142138631, 4326.41593384889, 4334.631556317749, 3317.3862835375535, 3402.9149271268416, 3444.7071998880374, 3480.4257297545623, 3483.6516348365467, 498.87503679203166, 598.8621618923992, 599.1851082527759, 618.5051958433236, 626.946709529686, 535.8821059367011, 537.1378936806369, 559.3260121553518, 640.2668944059026, 654.7871941618993, 7979.9202007979175, 8247.381446391544, 8833.160849719266, 8951.776205594912, 9278.48968306736, 588.8781870536635, 626.0796628955519, 650.9837254068686, 653.2683394598081, 668.2682462703332, 3767.962320376816, 4059.9594004060173, 4260.147970405931, 4462.419885414875, 4477.865664030084, 66209.0, 109910.0, 110003.79992400107, 112764.0, 113369.4331528337, 95171.0, 96309.55535666831, 97112.0, 99720.50139749255, 101794.49102754438, 81469.59265203636, 83069.0, 83906.16093838983, 84489.57755211307, 86604.56697716471, 8126.106128325998, 8322.64983912394, 8484.660613575388, 8574.442661227, 8592.484450932963, 52825.20762188647, 54445.72777136089, 55046.72476637751, 55580.44419555915, 57144.57138571569, 74664.0, 74833.25833708401, 77144.61427692826, 78354.6082269585, 79485.0, 11175.0721430601, 11250.04374628133, 11662.76674466489, 11789.76420471603, 11881.643550693492, 11705.063594912557, 11769.572758627646, 11988.940055299669, 12059.75880482403, 12504.187227830162, 11273.605423810128, 11802.23285486475, 11810.232334898548, 11919.3444360561, 12363.32001739913, 11157.776844462904, 11159.330440173615, 11705.648830535098, 11714.1800073994, 11727.413629318671, 10707.946460267649, 11089.500972456097, 11105.666829995112, 11330.263532870336, 11738.530458781554, 9568.282378821477, 9664.855027174739, 9925.255605829447, 9995.0, 10039.899601003897, 9633.0, 9856.507174641383, 9876.555555000186, 9927.950360248153, 9927.957564455628], "value": 7031.0001500145845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 119.0, 119.0, 118.0, 117.0, 119.0, 118.0, 119.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 114.0, 147.0, 115.0, 115.0, 117.0, 114.0, 142.0, 145.0], "value": 127.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 200.0, 191.0, 188.0, 196.0, 196.0, 197.0, 198.0, 216.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 58.0, 86.0, 59.0, 64.0, 70.0, 63.0, 76.0, 58.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 91.0, 73.0, 69.0, 71.0, 110.0, 62.0, 77.0, 73.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 88.0, 94.0, 133.0, 90.0, 86.0, 90.0, 103.0, 91.0, 88.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 102.0, 160.0, 169.0, 116.0, 109.0, 175.0, 105.0, 166.0], "value": 137.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 121.0, 140.0, 103.0, 72.0, 140.0, 146.0, 93.0, 97.0], "value": 108.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 57.0, 60.0, 58.0, 61.0, 57.0, 61.0, 61.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 77.0, 80.0, 69.0, 69.0, 69.0, 71.0, 73.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 101.0, 106.0, 78.0, 96.0, 106.0, 105.0, 83.0, 89.0, 81.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 153.0, 152.0, 158.0, 161.0, 153.0, 158.0, 152.0, 208.0, 153.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 167.0, 169.0, 202.0, 194.0, 169.0, 170.0, 177.0, 228.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 65.0, 66.0, 69.0, 67.0, 68.0, 68.0, 66.0, 68.0], "value": 67.5, "unit": "score"}]}]} 08:21:05 INFO - Return code: 0 08:21:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:21:05 ERROR - # TBPL SUCCESS # 08:21:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:05 INFO - 2016-11-26 08:21:05.609 screenresolution[2038:29689] starting screenresolution argv=screenresolution get 08:21:05 INFO - 2016-11-26 08:21:05.612 screenresolution[2038:29689] Display 0: 1600x1200x32@60 08:21:05 INFO - 2016-11-26 08:21:05.618 screenresolution[2039:29692] starting screenresolution argv=screenresolution list 08:21:05 INFO - Available Modes on Display 0 08:21:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:05 INFO - Intel Iris: 08:21:05 INFO - Chipset Model: Intel Iris 08:21:05 INFO - Type: GPU 08:21:05 INFO - Bus: Built-In 08:21:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:05 INFO - Vendor: Intel (0x8086) 08:21:05 INFO - Device ID: 0x0a2e 08:21:05 INFO - Revision ID: 0x0009 08:21:05 INFO - Displays: 08:21:05 INFO - Display: 08:21:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:05 INFO - Display Serial Number: 1600x1200 60 08:21:05 INFO - Main Display: Yes 08:21:05 INFO - Mirror: Off 08:21:05 INFO - Online: Yes 08:21:05 INFO - Rotation: Supported 08:21:05 INFO - Return code: 0 08:21:05 INFO - Running post-action listener: _resource_record_post_action 08:21:05 INFO - [mozharness: 2016-11-26 16:21:05.860985Z] Finished run-tests step (success) 08:21:05 INFO - Running post-run listener: _resource_record_post_run 08:21:05 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 626688; Write bytes: 660200448; Read time: 6; Write time: 1933 08:21:05 INFO - TinderboxPrint: CPU usage
54.2% 08:21:05 INFO - TinderboxPrint: I/O read bytes / time
626,688 / 6 08:21:05 INFO - TinderboxPrint: I/O write bytes / time
660,200,448 / 1,933 08:21:05 INFO - TinderboxPrint: CPU idle
2,216.0 (45.8%) 08:21:05 INFO - TinderboxPrint: CPU system
972.8 (20.1%) 08:21:05 INFO - TinderboxPrint: CPU user
1,648.9 (34.1%) 08:21:05 INFO - TinderboxPrint: Swap in / out
348,565,504 / 0 08:21:05 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345354752; Write bytes: 344950784; Read time: 26830; Write time: 715 08:21:05 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 626688; Write bytes: 308188160; Read time: 6; Write time: 1191 08:21:06 INFO - Running post-run listener: _upload_blobber_files 08:21:06 INFO - Blob upload gear active. 08:21:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:21:06 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 08:21:06 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:21:06 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:21:06 INFO - (blobuploader) - INFO - Open directory for files ... 08:21:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:21:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:21:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:07 INFO - (blobuploader) - INFO - Done attempting. 08:21:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:21:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:08 INFO - (blobuploader) - INFO - Done attempting. 08:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:21:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:10 INFO - (blobuploader) - INFO - Done attempting. 08:21:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:21:10 INFO - Return code: 0 08:21:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:21:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:21:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e13f765b9199cd892d8e5cd7a3d5fda273a1d11d80c115f6c3a98b6c3070caba436538d8f07c295ca9796574bab05c1e88fa142ead8e3457923b07607590edb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/010b9d5d1453fc53d08937ec5b6a79dd063b22420d3d43d6e087e136261cca704af100649e31a622484a2908ec599442d20cd0fbf34d041067cd011c17fd12d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d10a6d5d7bb99fc3f2f566121768fc9d6b33288a63439e2d09ece55dbfe8eb3ce127866f03ef8f95eda420fba2c8b0ecf197e7d001ff80f5425d7c70acf3e54a"} 08:21:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:21:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:21:10 INFO - Contents: 08:21:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e13f765b9199cd892d8e5cd7a3d5fda273a1d11d80c115f6c3a98b6c3070caba436538d8f07c295ca9796574bab05c1e88fa142ead8e3457923b07607590edb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/010b9d5d1453fc53d08937ec5b6a79dd063b22420d3d43d6e087e136261cca704af100649e31a622484a2908ec599442d20cd0fbf34d041067cd011c17fd12d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d10a6d5d7bb99fc3f2f566121768fc9d6b33288a63439e2d09ece55dbfe8eb3ce127866f03ef8f95eda420fba2c8b0ecf197e7d001ff80f5425d7c70acf3e54a"} 08:21:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:21:10 INFO - Copying logs to upload dir... 08:21:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:21:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.647146 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-11-26 08:21:10.851991) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 08:21:10.855131) ========= 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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e13f765b9199cd892d8e5cd7a3d5fda273a1d11d80c115f6c3a98b6c3070caba436538d8f07c295ca9796574bab05c1e88fa142ead8e3457923b07607590edb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/010b9d5d1453fc53d08937ec5b6a79dd063b22420d3d43d6e087e136261cca704af100649e31a622484a2908ec599442d20cd0fbf34d041067cd011c17fd12d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d10a6d5d7bb99fc3f2f566121768fc9d6b33288a63439e2d09ece55dbfe8eb3ce127866f03ef8f95eda420fba2c8b0ecf197e7d001ff80f5425d7c70acf3e54a"} build_url:https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/B-Xwb77wQjW8erSXsEBYKQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e13f765b9199cd892d8e5cd7a3d5fda273a1d11d80c115f6c3a98b6c3070caba436538d8f07c295ca9796574bab05c1e88fa142ead8e3457923b07607590edb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/010b9d5d1453fc53d08937ec5b6a79dd063b22420d3d43d6e087e136261cca704af100649e31a622484a2908ec599442d20cd0fbf34d041067cd011c17fd12d6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d10a6d5d7bb99fc3f2f566121768fc9d6b33288a63439e2d09ece55dbfe8eb3ce127866f03ef8f95eda420fba2c8b0ecf197e7d001ff80f5425d7c70acf3e54a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 08:21:10.889770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 08:21:10.890098) ========= 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.sDEvU4cE7F/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.BWIpS0IbKf/Listeners TMPDIR=/var/folders/wq/f5xgyhbd75545ndpjmyvls7c00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 08:21:10.938494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 08:21:10.938813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 08:21:10.939140) ========= ========= Total master_lag: 0.18 =========