builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0108 starttime: 1476359772.09 results: success (0) buildid: 20161013023624 builduid: 5f5eb7ca508e4712987082af272b41e6 revision: 00fdaac3efafd2621ed9849c52527051f9edda53 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.089166) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.089605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.089974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.115361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.115724) ========= 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.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-13 04:56:12-- 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.2M=0.001s 2016-10-13 04:56:12 (10.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.239848) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.240158) ========= 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.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.029376 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:12.289345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 04:56:12.289685) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 00fdaac3efafd2621ed9849c52527051f9edda53 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 00fdaac3efafd2621ed9849c52527051f9edda53 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-13 04:56:12,364 truncating revision to first 12 chars 2016-10-13 04:56:12,364 Setting DEBUG logging. 2016-10-13 04:56:12,364 attempt 1/10 2016-10-13 04:56:12,364 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/00fdaac3efaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 04:56:13,635 unpacking tar archive at: mozilla-central-00fdaac3efaf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.553767 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 04:56:13.865552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:13.865982) ========= script_repo_revision: 00fdaac3efafd2621ed9849c52527051f9edda53 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:13.866394) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:13.866677) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 04:56:13.888842) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-13 04:56:13.889266) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:14 INFO - MultiFileLogger online at 20161013 04:56:14 in /builds/slave/test 04:56:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:56:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:56:14 INFO - {'append_to_log': False, 04:56:14 INFO - 'base_work_dir': '/builds/slave/test', 04:56:14 INFO - 'blob_upload_branch': 'Firefox', 04:56:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:56:14 INFO - 'branch': 'Firefox', 04:56:14 INFO - 'buildbot_json_path': 'buildprops.json', 04:56:14 INFO - 'config_files': ('talos/mac_config.py',), 04:56:14 INFO - 'default_actions': ('clobber', 04:56:14 INFO - 'read-buildbot-config', 04:56:14 INFO - 'download-and-extract', 04:56:14 INFO - 'populate-webroot', 04:56:14 INFO - 'create-virtualenv', 04:56:14 INFO - 'install', 04:56:14 INFO - 'run-tests'), 04:56:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:56:14 INFO - 'download_minidump_stackwalk': True, 04:56:14 INFO - 'download_symbols': 'ondemand', 04:56:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:56:14 INFO - 'tooltool.py': '/tools/tooltool.py', 04:56:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:56:14 INFO - '/tools/misc-python/virtualenv.py')}, 04:56:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:56:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:56:14 INFO - 'installer_path': 'installer.exe', 04:56:14 INFO - 'log_level': 'info', 04:56:14 INFO - 'log_name': 'talos', 04:56:14 INFO - 'log_to_console': True, 04:56:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:56:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:56:14 INFO - 'opt_config_files': (), 04:56:14 INFO - 'pip_index': False, 04:56:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:56:14 INFO - 'cmd': ('bash', 04:56:14 INFO - '-c', 04:56:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:56:14 INFO - 'enabled': True, 04:56:14 INFO - 'halt_on_failure': False, 04:56:14 INFO - 'name': 'check_screen_resolution'},), 04:56:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:56:14 INFO - 'cmd': ('bash', 04:56:14 INFO - '-c', 04:56:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:56:14 INFO - 'enabled': True, 04:56:14 INFO - 'halt_on_failure': False, 04:56:14 INFO - 'name': 'check_screen_resolution'},), 04:56:14 INFO - 'run_cmd_checks_enabled': True, 04:56:14 INFO - 'sps_profile': False, 04:56:14 INFO - 'sps_profile_interval': 0, 04:56:14 INFO - 'suite': 'dromaeojs', 04:56:14 INFO - 'system_bits': '32', 04:56:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:56:14 INFO - 'title': 't-yosemite-r7-0108', 04:56:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:56:14 INFO - 'use_talos_json': True, 04:56:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:56:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:56:14 INFO - 'work_dir': 'build'} 04:56:14 INFO - [mozharness: 2016-10-13 11:56:14.113198Z] Running clobber step. 04:56:14 INFO - Running pre-action listener: _resource_record_pre_action 04:56:14 INFO - Running main action method: clobber 04:56:14 INFO - rmtree: /builds/slave/test/build 04:56:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:56:16 INFO - Running post-action listener: _resource_record_post_action 04:56:16 INFO - [mozharness: 2016-10-13 11:56:16.489254Z] Finished clobber step (success) 04:56:16 INFO - [mozharness: 2016-10-13 11:56:16.489392Z] Running read-buildbot-config step. 04:56:16 INFO - Running pre-action listener: _resource_record_pre_action 04:56:16 INFO - Running main action method: read_buildbot_config 04:56:16 INFO - Using buildbot properties: 04:56:16 INFO - { 04:56:16 INFO - "project": "", 04:56:16 INFO - "product": "firefox", 04:56:16 INFO - "script_repo_revision": "production", 04:56:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:56:16 INFO - "repository": "", 04:56:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:56:16 INFO - "buildid": "20161013023624", 04:56:16 INFO - "pgo_build": "False", 04:56:16 INFO - "basedir": "/builds/slave/test", 04:56:16 INFO - "buildnumber": 46, 04:56:16 INFO - "slavename": "t-yosemite-r7-0108", 04:56:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:56:16 INFO - "platform": "yosemite_r7", 04:56:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:56:16 INFO - "branch": "mozilla-central", 04:56:16 INFO - "repo_path": "mozilla-central", 04:56:16 INFO - "revision": "00fdaac3efafd2621ed9849c52527051f9edda53", 04:56:16 INFO - "stage_platform": "macosx64", 04:56:16 INFO - "builduid": "5f5eb7ca508e4712987082af272b41e6", 04:56:16 INFO - "slavebuilddir": "test" 04:56:16 INFO - } 04:56:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:56:16 INFO - Running post-action listener: _resource_record_post_action 04:56:16 INFO - [mozharness: 2016-10-13 11:56:16.492885Z] Finished read-buildbot-config step (success) 04:56:16 INFO - [mozharness: 2016-10-13 11:56:16.493026Z] Running download-and-extract step. 04:56:16 INFO - Running pre-action listener: _resource_record_pre_action 04:56:16 INFO - Running main action method: download_and_extract 04:56:16 INFO - mkdir: /builds/slave/test/build/tests 04:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:16 INFO - trying https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:56:16 INFO - Downloading https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:56:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 04:56:17 INFO - Downloaded 1199 bytes. 04:56:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:56:17 INFO - Using the following test package requirements: 04:56:17 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:56:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:56:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:56:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'jsshell-mac.zip'], 04:56:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:56:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:56:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:56:17 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:56:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:56:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:56:17 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:56:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:56:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:56:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:56:17 INFO - Fetch https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:56:17 INFO - Http code: 200 04:56:17 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 04:56:17 INFO - x-amz-cf-id: ZCIXkEwnRtH-Ywv7NaRh0iHsBE3H1Pdyu5oLODiLigatV46o1viFhQ== 04:56:17 INFO - x-amz-version-id: ECwwaruVlyEsY219XxA0qvXnJXzH4pjh 04:56:17 INFO - Expected file size: 16511104 04:56:17 INFO - Obtained file size: 16511104 04:56:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:56:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:56:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:56:24 INFO - Fetch https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:56:25 INFO - Http code: 200 04:56:25 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 04:56:25 INFO - x-amz-cf-id: 81o6ad5r-ub-1NsIiYj_5Iz6nGg4Ivk19fYgj1K7zDJs0QHYXYC2zA== 04:56:25 INFO - x-amz-version-id: ECwwaruVlyEsY219XxA0qvXnJXzH4pjh 04:56:25 INFO - Expected file size: 16511104 04:56:25 INFO - Obtained file size: 16511104 04:56:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:56:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:56:32 INFO - Fetch https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:56:32 INFO - Http code: 200 04:56:32 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 04:56:32 INFO - x-amz-cf-id: _hKN6E3MklEJGVd7J_T0d8aNMhG9sfrClpPAZsYKs9gT5WCzrjxz6w== 04:56:32 INFO - x-amz-version-id: .TCSwr7WVuCTABL6ZkgzwIF.vqmeCvGA 04:56:33 INFO - Expected file size: 14409006 04:56:33 INFO - Obtained file size: 14409006 04:56: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')]} 04:56:33 INFO - trying https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:56:33 INFO - Downloading https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:56:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 04:56:36 INFO - Downloaded 111666507 bytes. 04:56:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:56:36 INFO - mkdir: /builds/slave/test/properties 04:56:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:56:36 INFO - Writing to file /builds/slave/test/properties/build_url 04:56:36 INFO - Contents: 04:56:36 INFO - build_url:https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:56:37 INFO - Running post-action listener: _resource_record_post_action 04:56:37 INFO - Running post-action listener: set_extra_try_arguments 04:56:37 INFO - [mozharness: 2016-10-13 11:56:37.356244Z] Finished download-and-extract step (success) 04:56:37 INFO - [mozharness: 2016-10-13 11:56:37.356341Z] Running populate-webroot step. 04:56:37 INFO - Running pre-action listener: _resource_record_pre_action 04:56:37 INFO - Running main action method: populate_webroot 04:56:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:56:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:56:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:56:37 INFO - '--tppagecycles', 04:56:37 INFO - '7'], 04:56:37 INFO - 'tests': ['tsvgm']}}, 04:56:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:56:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:56:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:56:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:56:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['damp', 'tps']}, 04:56:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'tests': ['damp', 'tps']}, 04:56:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['dromaeo_dom']}, 04:56:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:56:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['basic_compositor_video']}, 04:56:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:56:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'other-e10s': {'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['a11yr', 04:56:37 INFO - 'ts_paint', 04:56:37 INFO - 'tpaint', 04:56:37 INFO - 'sessionrestore', 04:56:37 INFO - 'sessionrestore_no_auto_restore', 04:56:37 INFO - 'tabpaint']}, 04:56:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['tsvgx', 04:56:37 INFO - 'tsvgr_opacity', 04:56:37 INFO - 'tart', 04:56:37 INFO - 'tscrollx', 04:56:37 INFO - 'cart']}, 04:56:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:56:37 INFO - 'tsvgr_opacity', 04:56:37 INFO - 'tart', 04:56:37 INFO - 'tscrollx', 04:56:37 INFO - 'cart']}, 04:56:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'talos_options': ['--disable-e10s'], 04:56:37 INFO - 'tests': ['tp5o']}, 04:56:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'tests': ['tp5o']}, 04:56:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'talos_options': ['--disable-e10s', 04:56:37 INFO - '--xperf_path', 04:56:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:37 INFO - 'tests': ['tp5n']}, 04:56:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:56:37 INFO - 'talos_options': ['--xperf_path', 04:56:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:37 INFO - 'tests': ['tp5n']}}, 04:56:37 INFO - 'talos.zip': {'path': '', 04:56:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:56:37 INFO - Running post-action listener: _resource_record_post_action 04:56:37 INFO - [mozharness: 2016-10-13 11:56:37.375373Z] Finished populate-webroot step (success) 04:56:37 INFO - [mozharness: 2016-10-13 11:56:37.375487Z] Running create-virtualenv step. 04:56:37 INFO - Running pre-action listener: _resource_record_pre_action 04:56:37 INFO - Running main action method: create_virtualenv 04:56:37 INFO - Creating virtualenv /builds/slave/test/build/venv 04:56:37 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 04:56:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:56:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:56:37 INFO - Using real prefix '/tools/python27' 04:56:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:56:38 INFO - Installing distribute.............................................................................................................................................................................................done. 04:56:41 INFO - Installing pip.................done. 04:56:41 INFO - Return code: 0 04:56:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:56:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:56:41 INFO - Reading from file tmpfile_stdout 04:56:41 INFO - Output received: 04:56:41 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) 04:56:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:41 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:41 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 04:56:41 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 04:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:41 INFO - 'HOME': '/Users/cltbld', 04:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:41 INFO - 'LOGNAME': 'cltbld', 04:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:41 INFO - 'MOZ_NO_REMOTE': '1', 04:56:41 INFO - 'NO_EM_RESTART': '1', 04:56:41 INFO - 'PAGER': '/bin/cat', 04:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:41 INFO - 'PWD': '/builds/slave/test', 04:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:41 INFO - 'SHELL': '/bin/bash', 04:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:41 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:41 INFO - 'USER': 'cltbld', 04:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:41 INFO - 'XPC_FLAGS': '0x0', 04:56:41 INFO - 'XPC_SERVICE_NAME': '0', 04:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:41 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 04:56:41 INFO - Cleaning up... 04:56:41 INFO - Return code: 0 04:56:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:41 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:41 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 04:56:41 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 04:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:41 INFO - 'HOME': '/Users/cltbld', 04:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:41 INFO - 'LOGNAME': 'cltbld', 04:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:41 INFO - 'MOZ_NO_REMOTE': '1', 04:56:41 INFO - 'NO_EM_RESTART': '1', 04:56:41 INFO - 'PAGER': '/bin/cat', 04:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:41 INFO - 'PWD': '/builds/slave/test', 04:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:41 INFO - 'SHELL': '/bin/bash', 04:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:41 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:41 INFO - 'USER': 'cltbld', 04:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:41 INFO - 'XPC_FLAGS': '0x0', 04:56:41 INFO - 'XPC_SERVICE_NAME': '0', 04:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:41 INFO - Downloading/unpacking psutil>=3.1.1 04:56:41 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 04:56:41 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 04:56:41 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 04:56:41 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 04:56:41 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 04:56:41 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 04:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:56:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:45 INFO - Installing collected packages: psutil 04:56:45 INFO - Running setup.py install for psutil 04:56:45 INFO - building 'psutil._psutil_osx' extension 04:56:45 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 04:56:45 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 04:56:45 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 04:56:46 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 04:56:46 INFO - building 'psutil._psutil_posix' extension 04:56:46 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 04:56:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:56:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:56:46 INFO - ^ 04:56:46 INFO - 1 warning generated. 04:56:46 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 04:56:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:46 INFO - Successfully installed psutil 04:56:46 INFO - Cleaning up... 04:56:46 INFO - Return code: 0 04:56:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:46 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:46 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 04:56:46 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 04:56:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:46 INFO - 'HOME': '/Users/cltbld', 04:56:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:46 INFO - 'LOGNAME': 'cltbld', 04:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:46 INFO - 'MOZ_NO_REMOTE': '1', 04:56:46 INFO - 'NO_EM_RESTART': '1', 04:56:46 INFO - 'PAGER': '/bin/cat', 04:56:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:46 INFO - 'PWD': '/builds/slave/test', 04:56:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:46 INFO - 'SHELL': '/bin/bash', 04:56:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:46 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:46 INFO - 'USER': 'cltbld', 04:56:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:46 INFO - 'XPC_FLAGS': '0x0', 04:56:46 INFO - 'XPC_SERVICE_NAME': '0', 04:56:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:56:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:56:49 INFO - Installing collected packages: mozsystemmonitor 04:56:49 INFO - Running setup.py install for mozsystemmonitor 04:56:49 INFO - Successfully installed mozsystemmonitor 04:56:49 INFO - Cleaning up... 04:56:49 INFO - Return code: 0 04:56:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:56:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:49 INFO - 'HOME': '/Users/cltbld', 04:56:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:49 INFO - 'LOGNAME': 'cltbld', 04:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:49 INFO - 'MOZ_NO_REMOTE': '1', 04:56:49 INFO - 'NO_EM_RESTART': '1', 04:56:49 INFO - 'PAGER': '/bin/cat', 04:56:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:49 INFO - 'PWD': '/builds/slave/test', 04:56:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:49 INFO - 'SHELL': '/bin/bash', 04:56:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:49 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:49 INFO - 'USER': 'cltbld', 04:56:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:49 INFO - 'XPC_FLAGS': '0x0', 04:56:49 INFO - 'XPC_SERVICE_NAME': '0', 04:56:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:49 INFO - Downloading/unpacking jsonschema==2.5.1 04:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:52 INFO - Downloading functools32-3.2.3-2.tar.gz 04:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:56:52 INFO - warning: no files found matching '*.txt' 04:56:52 INFO - no previously-included directories found matching 'build' 04:56:52 INFO - no previously-included directories found matching 'dist' 04:56:52 INFO - no previously-included directories found matching '.git*' 04:56:52 INFO - Installing collected packages: jsonschema, functools32 04:56:52 INFO - Running setup.py install for functools32 04:56:53 INFO - warning: no files found matching '*.txt' 04:56:53 INFO - no previously-included directories found matching 'build' 04:56:53 INFO - no previously-included directories found matching 'dist' 04:56:53 INFO - no previously-included directories found matching '.git*' 04:56:53 INFO - Successfully installed jsonschema functools32 04:56:53 INFO - Cleaning up... 04:56:53 INFO - Return code: 0 04:56:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:56:53 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')]} 04:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:53 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')]} 04:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:53 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:53 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 04:56:53 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 04:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:53 INFO - 'HOME': '/Users/cltbld', 04:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:53 INFO - 'LOGNAME': 'cltbld', 04:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:53 INFO - 'MOZ_NO_REMOTE': '1', 04:56:53 INFO - 'NO_EM_RESTART': '1', 04:56:53 INFO - 'PAGER': '/bin/cat', 04:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:53 INFO - 'PWD': '/builds/slave/test', 04:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:53 INFO - 'SHELL': '/bin/bash', 04:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:53 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:53 INFO - 'USER': 'cltbld', 04:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:53 INFO - 'XPC_FLAGS': '0x0', 04:56:53 INFO - 'XPC_SERVICE_NAME': '0', 04:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:56:53 INFO - Cleaning up... 04:56:53 INFO - Return code: 0 04:56:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:56:53 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')]} 04:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:53 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')]} 04:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:53 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:53 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 04:56:53 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 04:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:53 INFO - 'HOME': '/Users/cltbld', 04:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:53 INFO - 'LOGNAME': 'cltbld', 04:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:53 INFO - 'MOZ_NO_REMOTE': '1', 04:56:53 INFO - 'NO_EM_RESTART': '1', 04:56:53 INFO - 'PAGER': '/bin/cat', 04:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:53 INFO - 'PWD': '/builds/slave/test', 04:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:53 INFO - 'SHELL': '/bin/bash', 04:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:53 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:53 INFO - 'USER': 'cltbld', 04:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:53 INFO - 'XPC_FLAGS': '0x0', 04:56:53 INFO - 'XPC_SERVICE_NAME': '0', 04:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:53 INFO - Downloading/unpacking blobuploader==1.2.4 04:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:56 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:56 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 04:56:57 INFO - Downloading docopt-0.6.1.tar.gz 04:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:57 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:57 INFO - Running setup.py install for blobuploader 04:56:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:57 INFO - Running setup.py install for requests 04:56:58 INFO - Running setup.py install for docopt 04:56:58 INFO - Successfully installed blobuploader requests docopt 04:56:58 INFO - Cleaning up... 04:56:58 INFO - Return code: 0 04:56:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:58 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:56:58 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 04:56:58 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 04:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:58 INFO - 'HOME': '/Users/cltbld', 04:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:58 INFO - 'LOGNAME': 'cltbld', 04:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:58 INFO - 'MOZ_NO_REMOTE': '1', 04:56:58 INFO - 'NO_EM_RESTART': '1', 04:56:58 INFO - 'PAGER': '/bin/cat', 04:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:58 INFO - 'PWD': '/builds/slave/test', 04:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:58 INFO - 'SHELL': '/bin/bash', 04:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:56:58 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:58 INFO - 'USER': 'cltbld', 04:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:58 INFO - 'XPC_FLAGS': '0x0', 04:56:58 INFO - 'XPC_SERVICE_NAME': '0', 04:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-15RxV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-eTfhMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Jpjf5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-rbW7ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-bEK82U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-hCY6Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-3LqnJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-334TYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-OnUQ1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0kpFy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-3wOqAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gTAcJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-f7BCqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:59 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0jvndl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uhsvsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:57:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-1dSIPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:57:00 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-I_7Ovd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:57:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:57:00 INFO - Running setup.py install for manifestparser 04:57:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:57:00 INFO - Running setup.py install for mozcrash 04:57:00 INFO - Running setup.py install for mozdebug 04:57:00 INFO - Running setup.py install for mozdevice 04:57:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:57:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:57:00 INFO - Running setup.py install for mozfile 04:57:01 INFO - Running setup.py install for mozhttpd 04:57:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Running setup.py install for mozinfo 04:57:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Running setup.py install for mozInstall 04:57:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Running setup.py install for mozleak 04:57:01 INFO - Running setup.py install for mozlog 04:57:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Running setup.py install for moznetwork 04:57:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:57:01 INFO - Running setup.py install for mozprocess 04:57:02 INFO - Running setup.py install for mozprofile 04:57:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:57:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:57:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:57:02 INFO - Running setup.py install for mozrunner 04:57:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:57:02 INFO - Running setup.py install for mozscreenshot 04:57:02 INFO - Running setup.py install for moztest 04:57:02 INFO - Running setup.py install for mozversion 04:57:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:57:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:57:02 INFO - Cleaning up... 04:57:02 INFO - Return code: 0 04:57:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:57: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')]} 04:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57: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')]} 04:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:02 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:57:02 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 04:57:02 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 04:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:02 INFO - 'HOME': '/Users/cltbld', 04:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:02 INFO - 'LOGNAME': 'cltbld', 04:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:02 INFO - 'MOZ_NO_REMOTE': '1', 04:57:02 INFO - 'NO_EM_RESTART': '1', 04:57:02 INFO - 'PAGER': '/bin/cat', 04:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:02 INFO - 'PWD': '/builds/slave/test', 04:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:02 INFO - 'SHELL': '/bin/bash', 04:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:57:02 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:02 INFO - 'USER': 'cltbld', 04:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:02 INFO - 'XPC_FLAGS': '0x0', 04:57:02 INFO - 'XPC_SERVICE_NAME': '0', 04:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-OiCfzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-j8YS5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:57:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-G80gP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-rM36Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-8soaCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-kYuhBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-AcRLMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:57:03 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)) 04:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:57:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uuIy0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-_bObCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-a5UXdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ILEggV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2ALsUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0MhoHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-qKwhLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-YOEK6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pBLG9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:57:04 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)) 04:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:57:04 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-IPzFL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:57:04 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)) 04:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:57:04 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)) 04:57:04 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)) 04:57:04 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)) 04:57:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:57:07 INFO - Downloading blessings-1.6.tar.gz 04:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:57:07 INFO - Installing collected packages: blessings 04:57:07 INFO - Running setup.py install for blessings 04:57:07 INFO - Successfully installed blessings 04:57:07 INFO - Cleaning up... 04:57:07 INFO - Return code: 0 04:57:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:57:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:57:08 INFO - Reading from file tmpfile_stdout 04:57:08 INFO - Current package versions: 04:57:08 INFO - blessings == 1.6 04:57:08 INFO - blobuploader == 1.2.4 04:57:08 INFO - docopt == 0.6.1 04:57:08 INFO - functools32 == 3.2.3-2 04:57:08 INFO - jsonschema == 2.5.1 04:57:08 INFO - manifestparser == 1.1 04:57:08 INFO - mozInstall == 1.12 04:57:08 INFO - mozcrash == 0.17 04:57:08 INFO - mozdebug == 0.1 04:57:08 INFO - mozdevice == 0.48 04:57:08 INFO - mozfile == 1.2 04:57:08 INFO - mozhttpd == 0.7 04:57:08 INFO - mozinfo == 0.9 04:57:08 INFO - mozleak == 0.1 04:57:08 INFO - mozlog == 3.4 04:57:08 INFO - moznetwork == 0.27 04:57:08 INFO - mozprocess == 0.23 04:57:08 INFO - mozprofile == 0.28 04:57:08 INFO - mozrunner == 6.12 04:57:08 INFO - mozscreenshot == 0.1 04:57:08 INFO - mozsystemmonitor == 0.3 04:57:08 INFO - moztest == 0.7 04:57:08 INFO - mozversion == 1.4 04:57:08 INFO - psutil == 3.1.1 04:57:08 INFO - requests == 1.2.3 04:57:08 INFO - wsgiref == 0.1.2 04:57:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:57:08 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')]} 04:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:08 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')]} 04:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:08 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:57:08 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 04:57:08 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 04:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:08 INFO - 'HOME': '/Users/cltbld', 04:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:08 INFO - 'LOGNAME': 'cltbld', 04:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:08 INFO - 'MOZ_NO_REMOTE': '1', 04:57:08 INFO - 'NO_EM_RESTART': '1', 04:57:08 INFO - 'PAGER': '/bin/cat', 04:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:08 INFO - 'PWD': '/builds/slave/test', 04:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:08 INFO - 'SHELL': '/bin/bash', 04:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:57:08 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:08 INFO - 'USER': 'cltbld', 04:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:08 INFO - 'XPC_FLAGS': '0x0', 04:57:08 INFO - 'XPC_SERVICE_NAME': '0', 04:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 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)) 04:57:08 INFO - Cleaning up... 04:57:08 INFO - Return code: 0 04:57:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:57:08 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')]} 04:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:08 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')]} 04:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:57:08 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 0x10d4c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d43a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81eb59b000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, '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.axtTVV7ftW/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.xHh78ZaaWb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:57:08 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 04:57:08 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 04:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:08 INFO - 'HOME': '/Users/cltbld', 04:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:08 INFO - 'LOGNAME': 'cltbld', 04:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:08 INFO - 'MOZ_NO_REMOTE': '1', 04:57:08 INFO - 'NO_EM_RESTART': '1', 04:57:08 INFO - 'PAGER': '/bin/cat', 04:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:08 INFO - 'PWD': '/builds/slave/test', 04:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:08 INFO - 'SHELL': '/bin/bash', 04:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:57:08 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:08 INFO - 'USER': 'cltbld', 04:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:08 INFO - 'XPC_FLAGS': '0x0', 04:57:08 INFO - 'XPC_SERVICE_NAME': '0', 04:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:57:08 INFO - Cleaning up... 04:57:08 INFO - Return code: 0 04:57:08 INFO - Running post-action listener: _resource_record_post_action 04:57:08 INFO - Running post-action listener: _start_resource_monitoring 04:57:08 INFO - Starting resource monitoring. 04:57:08 INFO - [mozharness: 2016-10-13 11:57:08.709534Z] Finished create-virtualenv step (success) 04:57:08 INFO - [mozharness: 2016-10-13 11:57:08.710474Z] Running install step. 04:57:08 INFO - Running pre-action listener: _resource_record_pre_action 04:57:08 INFO - Running main action method: install 04:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:57:08 INFO - Reading from file tmpfile_stdout 04:57:08 INFO - Detecting whether we're running mozinstall >=1.0... 04:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:57:09 INFO - Reading from file tmpfile_stdout 04:57:09 INFO - Output received: 04:57:09 INFO - Usage: mozinstall [options] installer 04:57:09 INFO - Options: 04:57:09 INFO - -h, --help show this help message and exit 04:57:09 INFO - -d DEST, --destination=DEST 04:57:09 INFO - Directory to install application into. [default: 04:57:09 INFO - "/builds/slave/test"] 04:57:09 INFO - --app=APP Application being installed. [default: firefox] 04:57:09 INFO - mkdir: /builds/slave/test/build/application 04:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:57:43 INFO - Reading from file tmpfile_stdout 04:57:43 INFO - Output received: 04:57:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:57:43 INFO - Running post-action listener: _resource_record_post_action 04:57:43 INFO - [mozharness: 2016-10-13 11:57:43.438195Z] Finished install step (success) 04:57:43 INFO - [mozharness: 2016-10-13 11:57:43.438326Z] Running run-tests step. 04:57:43 INFO - Running pre-action listener: _resource_record_pre_action 04:57:43 INFO - Running main action method: run_tests 04:57:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:43 INFO - 2016-10-13 04:57:43.452 screenresolution[1642:7594] starting screenresolution argv=screenresolution get 04:57:43 INFO - 2016-10-13 04:57:43.455 screenresolution[1642:7594] Display 0: 1600x1200x32@60 04:57:43 INFO - 2016-10-13 04:57:43.461 screenresolution[1643:7596] starting screenresolution argv=screenresolution list 04:57:43 INFO - Available Modes on Display 0 04:57:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:43 INFO - Intel Iris: 04:57:43 INFO - Chipset Model: Intel Iris 04:57:43 INFO - Type: GPU 04:57:43 INFO - Bus: Built-In 04:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:43 INFO - Vendor: Intel (0x8086) 04:57:43 INFO - Device ID: 0x0a2e 04:57:43 INFO - Revision ID: 0x0009 04:57:43 INFO - Displays: 04:57:43 INFO - Display: 04:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:43 INFO - Display Serial Number: 1600x1200 60 04:57:43 INFO - Main Display: Yes 04:57:43 INFO - Mirror: Off 04:57:43 INFO - Online: Yes 04:57:43 INFO - Rotation: Supported 04:57:43 INFO - Return code: 0 04:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:57:43 INFO - Python 2.7.3 04:57:43 INFO - Return code: 0 04:57:43 INFO - grabbing minidump binary from tooltool 04:57:43 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')]} 04:57:43 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 0x10d2d8d30>, '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 0x10d0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:57:43 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 04:57:43 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 04:57:43 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 04:57:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:57:43 INFO - Return code: 0 04:57:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:57:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:57:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:57:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:57:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.axtTVV7ftW/Render', 04:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:43 INFO - 'HOME': '/Users/cltbld', 04:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:43 INFO - 'LOGNAME': 'cltbld', 04:57:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:57:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:43 INFO - 'MOZ_NO_REMOTE': '1', 04:57:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:57:43 INFO - 'NO_EM_RESTART': '1', 04:57:43 INFO - 'PAGER': '/bin/cat', 04:57:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:43 INFO - 'PWD': '/builds/slave/test', 04:57:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:43 INFO - 'SHELL': '/bin/bash', 04:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xHh78ZaaWb/Listeners', 04:57:43 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:43 INFO - 'USER': 'cltbld', 04:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:43 INFO - 'XPC_FLAGS': '0x0', 04:57:43 INFO - 'XPC_SERVICE_NAME': '0', 04:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:57:44 INFO - mozversion application_buildid: 20161013023624 04:57:44 INFO - mozversion application_changeset: 00fdaac3efafd2621ed9849c52527051f9edda53 04:57:44 INFO - mozversion application_display_name: Nightly 04:57:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:57:44 INFO - mozversion application_name: Firefox 04:57:44 INFO - mozversion application_remotingname: firefox 04:57:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:57:44 INFO - mozversion application_vendor: Mozilla 04:57:44 INFO - mozversion application_version: 52.0a1 04:57:44 INFO - mozversion platform_buildid: 20161013023624 04:57:44 INFO - mozversion platform_changeset: 00fdaac3efafd2621ed9849c52527051f9edda53 04:57:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:57:44 INFO - mozversion platform_version: 52.0a1 04:57:44 INFO - using testdate: 1476359864 04:57:44 INFO - actual date: 1476359864 04:57:44 INFO - starting webserver on 'localhost:49267' 04:57:44 INFO - SUITE-START | Running 2 tests 04:57:44 INFO - TEST-START | dromaeo_css 04:57:44 INFO - operating with platform_type : mac_ 04:57:44 INFO - Initialising browser for dromaeo_css test... 04:57:44 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpgrrlTl/profile http://localhost:49267/getInfo.html) 04:57:46 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:57:46 INFO - PROCESS | 1653 | colorDepth:24 04:57:46 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:57:46 INFO - PROCESS | 1653 | __metrics 04:57:46 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:57:46 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:57:47 INFO - TEST-INFO | 1653: exit 0 04:57:47 INFO - Browser initialized. 04:57:47 INFO - Running cycle 1/1 for dromaeo_css test... 04:57:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpgrrlTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:59 INFO - PROCESS | 1655 | RSS: Main: 235147264 04:57:59 INFO - PROCESS | 1655 | 04:58:50 INFO - PROCESS | 1655 | 1476359930256 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] 05:01:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 05:01:07 INFO - PROCESS | 1655 | RSS: Main: 457007104 05:01:07 INFO - PROCESS | 1655 | 05:04:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 05:04:11 INFO - PROCESS | 1655 | RSS: Main: 384045056 05:04:11 INFO - PROCESS | 1655 | 05:07:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 05:07:14 INFO - PROCESS | 1655 | RSS: Main: 356806656 05:07:14 INFO - PROCESS | 1655 | 05:10:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 05:10:17 INFO - PROCESS | 1655 | RSS: Main: 336060416 05:10:17 INFO - PROCESS | 1655 | 05:13:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 05:13:21 INFO - PROCESS | 1655 | RSS: Main: 332406784 05:13:21 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 05:16:24 INFO - PROCESS | 1655 | RSS: Main: 380575744 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | __start_tp_report 05:16:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:16:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:16:24 INFO - PROCESS | 1655 | |i|pagename|runs| 05:16:24 INFO - PROCESS | 1655 | |0;dojo.html;3293.1108600677817;3302.669733026697;3315.3535060663166;3343;3367.444371678673;5389.73051347433;5460.481254280842;5497.202905578689;5504.0918248489;5558.360788509328;5450.236966824647;5481.588880833933;5541.251930989315;5576.302962129734;5613.326400831896;4953.900921981558;4967.801287948478;4973.552380285773;4977.50224977503;5007;4433.113377324532;5060.848174554751;5158.793648254065;5162.483751624845;5207.453217412174;4995.5753760930365;5092.618053645972;5124.282600435942;5142.485751424865;5227.660202086852;5053.292539044537;5106.336176297094;5107.668001579886;5117.2068329409;5127.820526281562;5008.023435430093;5054.3176671149295;5080.79676812927;5085.084684756743;5096.159133742928;5509.862253443656;5531.280933478622;5532.197831314437;5536.363318218376;5542.196381524656;5984.371640977722;5990.341062483123;5991.101334799815;5993.700314984233;6003;4824.541668541483;5976.641401515923;6019.63882167067;6056.394360563952;6090.299615544225;5523.6685798852195;5581.413951535031;5592.496675299202;5596.468335508161;5608.439156084359;6139.2550544168025;6188.597741146811;6191.876162476815;6237.345078766664;6254.312025677172;5882.117682347682;5907.586468947218;5913.2608423947;5915.704214789243;5970.76116955317;5887.882242355129;5948.315943666534;5954.4045595440975;5957.970210148921;6083.330833608299;5907.763689452459;5996.310424301261;6000.069989151689;6024;6028.457438830438;5974.9402505974385;5986.491148252448;5990.430909063632;5992.700364981733;6054.303755068136;4750.168720473903;4771.832985845548;4774.618030557547;4782.0914026334885;4808.903821923542;4208.936865946952;4235;4251.936220956688;4261.232978063917;4270.188664153801;5578.8326350208745;5582.274304340491;5586.776528938797;5591.049521581341;5596.3284405871555;5989.55078369124;6001.1898393717165;6002.819915402545;6002.819915402545;6011.849703757441;5484.698341591251;5568.749406276646;5577.525910297589;5587.832365029056;5594.1888426177975;4624.686175616352;5169.7932082717025;5223.9477605223465;5224.320838291073;5236.240745092016;6271.6550589718;6285.685715714196;6299.6535190564955;6301.52738544611;6303.716332765129;6102.755889764538;6111.174991376108;6124.693765311627;6182.814515564541;6201.937980620136;5484.643498172606;6272.968635156795;6333.050042493663;6335.873282534416;6346.873062538816;5787.7974270902005;5827.475527202572;5827.650340979555;5893.882122357443;5902.793402231073;5917.201177840936;6104.095385180791;6104.3075792339105;6114.0236562776045;6117.979653188475;5850;5854.853628659147;5861.7655293787775;5868.09044598088;5907.645541267538;5700.914486282626;5757.453041961007;5857.47282744555;5859.267591551056;5859.384764599656;5632.9436705632415;5683.488486036276;5683.573731970201;5694.487496125369;5697.971510142423;4587.38070360497;5759.769609215585;5765.135229715576;5818.098194779646;5829.621074630136;5691.658500489983;5695.857603559778;5714.20001199824;5714.228579141929;5724.599278050663 05:16:24 INFO - PROCESS | 1655 | |1;ext.html;467.904079663669;478.428278207542;508.7049511283456;526.6050462153385;559.5104283751717;38127;39056.60943390565;39349.60650393495;39473.60526394735;41909.16181676365;34737.65262347376;38994.415083773696;41018.58981410185;41225.58774412255;41354.58645413545;9455.385399948998;9639.084286992724;9642.75893102671;9643.035696430336;9649.276304277173;9448.763780905463;9544.093311135432;9557.808843823119;9569.66506172284;9580.185684216849;9400.153986141246;9558.713238602819;9586.90413095867;9596.616135354578;9633.566489507988;9507;9550.474723890218;9556.139947404698;9580.0898914603;9583.952080239624;9441.716748497556;9524.619015239452;9541.61833526665;9547.188488978445;9562;38948.221035579416;39271;39316;39350.40974385387;39417.60582394168;3117.9220519486967;3137.7803553751246;3169.7781155319135;3181.3955348483714;3257.820819854907;29729.608938547484;30039.399212015855;30458.086257412313;30889.69110308913;31128.53307200394;3185.5540224368606;3194.3132226571165;3229.9664107485614;3318;3327.667233276677;438472.7283634034;475657;490316;492352.53823731013;492740;26456.735432645426;54652;54943;55013.449865501636;55082;32909.67090329066;33910.66089339124;34082;34097.82951085278;34122.829385852914;14216.360263788154;14416.91873109521;14419.495317663832;14422.567322980327;14424.206668633326;12174.14780965324;12250.693732656755;12266.877331226751;12271.877281227253;12303.138780285293;7523.548587084792;7562.281583249583;7612.048493938258;7633.122190948112;7640.617969101522;3167.4456970030615;3214.967850321467;3225.70968612826;3231.321422501254;3253.4469140246215;859.58739804894;887.4985633117268;1000.7848312612678;1030.253066526762;1085.2511766880828;28414.573781393297;29220.707792922225;29283.707162928524;30039.249018774706;30251.848740756155;828.9461185022955;999.5306898858667;1016.2124353625999;1029.264076185516;1074.9731256718644;14464.493742718954;14769.261536923326;14818.369499472697;15315;15322.463713769965;636080;637283.8135809292;641129;642053;643124.7843760845;54155.72922135364;54564.45435545673;54738.72630636821;54811;54846.72576637171;1114.8829372915727;1204.0909113619161;1233.8396008518835;1330.6274243211917;1352.3914238592477;29333.26666833346;30393.32836694003;30669.8466507666;30773.21435684903;31049.8447507761;43663.781681091394;43913.56086439094;43949;44063.898402540195;44108.779456102515;538984;541335;542260.0417970704;542960;770465;39202.491713452175;39732.60267397289;39828.60171398249;40158.397624036246;40159.17691934711;44332.556674432846;44560;44973;44998;45023.324650130926;27791.16626501208;31682.366352673278;32467.512987305683;32906;34219.65780342264;28349.858250708614;36266;36830.44754328633;37243;37283.81358093192;36131.916042518766;36260.09349766171;36750.8162459186;36773.81613091917;36813;31174.68825311718;33045.843395481046;33195;33294;33345.83327083349;23928.88035559811;24162.03351865976;24324.27027189187;24780.50438991174;24792.752072479045 05:16:24 INFO - PROCESS | 1655 | |2;jquery.html;332.5062282510473;345.44383542496576;351.3359750072363;352.13726370392527;374.40843467321633;13266.469341226351;14804.40782368704;15152.77270840937;15869.285882135302;20133.697994530095;19875.503112422197;20087.899560502177;20242.89878550605;20861.26985555506;21042.053107610194;5539.750711218005;5877.7355019024035;5879.294484661825;5891.617044892089;5899.764009439617;4642.675012749109;5694.743736531866;5722.742476588543;5782.653040817543;5840.912386314209;5752.993226185401;5793.362730099686;5797.681127537984;5804.303483581976;5809.883802323951;5701.287339082594;5777.826665200029;5969.5821292509545;6023.789167379121;6078.8784224315705;5641.802536911188;5849.532037436995;5859.6777177255235;5863.413658634145;5866.384029676908;12673.429695663717;13024.479020839155;13060.216387016715;13061.673458163526;13062.412191451407;1955.9413217603494;1991.8705284156483;2133.626615342309;2153.1064608187585;2788.9721102789117;9363.859542106873;9838.852417213748;10118.696439106838;10362;10421;1759.568905618119;1765.5586103474132;1873.7095750158746;1881.8964956927364;1953.658109830774;96690.51654741752;116390.41804790922;124656.9999999991;127285;129687;11089.889101109047;11412.942935285271;11446.828297575543;11473;11623.535058597561;14421.206833624252;14459.13245205291;14467.914906382064;14482.203478808557;14492.637684057772;8122.187781221949;8406.41155119148;8445.619947102396;8564.057953625097;8589.570521474026;8306.293965012921;8311.875321870177;8332.6666933322;8483.490990540588;8518.957405212934;4378.824847006084;4508.301213311942;4521.570450807168;4571.6799824012;4575.839845605387;1717.2615775216527;1834.293796888181;1842.926282948667;1894.2801735340483;1894.4790182699592;743.1045590064068;814.8736945773414;903.915301638037;959.5106495687111;1002.8997100289913;16208.027518348938;18612.813871861377;19551.90224048899;21487.57024859494;21571.78428215698;873.7597160780836;914.337105598449;927.1562877781187;945.9810803783886;963.7253382786013;10499.475026248658;10989.06592939593;11002.944985275022;11034.337939723642;11078.224524283383;567871;581938;589238;589529;590753;124134.37932810278;128205;129782;130858.34570827275;136883.31558342345;946.7633091727068;950.6530116507425;962.4033099478369;971.1793534463367;971.6453494474466;12858.485660573473;15664.76502852481;18363.35728249505;19726.704099438808;19878.304259350854;53151;54246.915061699336;54481.45518544764;55247.723761380934;55635.44364556303;74018;75487.86768198371;75509;75974.24025759671;76431;6864.4165245954655;7154.892676609751;7163.785086447415;7259.709611615476;7415.666295016629;9505.477198754135;10139.64511242103;12574.559890403792;12942.611721648365;13048.607362085737;6903.551269167488;9962.900370996198;10508.842367364648;10928.6721398355;11007.284526505751;9922.503874806087;10510;10925.016748492673;10946.671599852018;11103;10009.69970900874;10509.421981791074;11132.554697811996;11269.323840569592;11346.773064538498;7803.928771774816;7821.296083352526;7960.656524259709;8208.343332533503;8221;10688.89311106879;10736.73158171052;10810.29733067348;11073;11109.666709998713 05:16:24 INFO - PROCESS | 1655 | |3;mootools.html;1323.146570462052;1500.1299246437063;1631.2577777111414;1836.9357072502464;1879.2389082421616;8456.746297611066;8968.910310896888;8995.820083598324;9002.504862232574;9004.639814407432;7787.104482984459;8111.107778144399;8117.553534555597;8496.2778163856;8736.0827113153;2308.261356365968;2342.917997870075;2343.4492894169857;2344.3318654183563;2349.0955981946986;2286.7941885230325;2315.5021670340875;2328.1036800831635;2328.382978510696;2329.5108027314286;1984.8709833860826;2297.2648752399155;2310.9306720798318;2317.73346065203;2323.9302820915314;2276.715410573678;2287.5653625811124;2296.3111066680012;2298.6552017197555;2300.2754132448226;2303.8156947444163;2305.4236440889777;2312.2716344351475;2315.1549684365255;2316.779905908936;7405.333519983174;8943.016268210491;8977.730668079968;8993.010768815426;8993.415427997159;5119.795208191705;5296;5305.814296499604;5306.442823503515;5312.60155488336;3341.3484370547753;3432.916578098117;3552.0409489437707;3562.4300111982216;3568.553930758655;999.3254553176649;1033.1992705653179;1040.583766493401;1056.5668076088843;1057.3972835483778;11607.129465290138;11746.471408786625;11777.823332650018;11792.646220613395;11799.646010619696;4458.152950939311;4594.724316541018;4675.111728771522;4707.670463067552;4713.505081966344;10016.499175041365;10022.098011178883;10025.598976040877;10045.447500387549;10053.346532475365;5260.395054568697;5268.15709486488;5275.419703832576;5278.392984806796;5278.5249327560705;4193.727407718488;4271.466066741657;4366.083122544238;4798.49615790344;4838.7338696372035;3794.6395092466173;3808.1622043150464;3893.4354518594646;3921.1961547882615;3924.4309575111447;1209.1294268126935;1212.3271584270815;1216.5194748074462;1225.473046589975;1227.4476485581524;832.7895961069988;852.058475384691;884.9292056635454;888.657866721317;929.0616477357917;4894.559489645949;5571.693556854331;5717.542596592262;5998.490128339053;6036;698.6995591895433;889.3863234368265;927.5223260021095;931.092185119503;959.8608201810838;3396;3760.7179461540495;3945.309570825094;3953.545342285674;4102.384642303678;10486.790264194673;10755.354678719303;10865.728356790985;10919.508622112024;10919.727006824734;10138.543765530569;10698.03717665414;10870.72823179411;10874.728131796768;10897.67306980792;8043.436959412902;8244.876326854983;8248.381371397289;8253.876191857127;8256.174382561696;5469.261649677402;5793.884122317614;5819.82540523785;5863.677497737665;6098.329183789869;9993.650222242186;10012.449315287438;10075.899241007495;10095.343802652804;10145.847812282675;9740.464274464972;10144.289899707244;10199.38803671782;10320.019598138166;10326.535305911402;7002.299770022957;7252.854942901218;7284.672189751368;7446.664900079401;7676.884846727416;8205.302549283379;8346.624401901807;8472.322214222724;8478.618462169341;8496.617652205541;7287.125544934642;7337.963310183415;7357.0803649543805;7429.814254643461;7518.097828260644;7125;7234.96382518084;7324.926750732638;7374.078240219973;7424.777256682308;7018.75434359795;7026.789196324118;7113.82215444618;7113.82215444618;7127.10911136108;4164.208800327928;4350.73895566267;4944.208926571731;4955.677880937727;4983.07813054586;4878.853634390974;4911.189653707242;4935.383077115361;4965.677230979974;5178.32681751385 05:16:24 INFO - PROCESS | 1655 | |4;prototype.html;284.782141661629;284.9857507124644;293.0826513014265;297.2449977058272;300.9262730630996;4402.877266297094;5060.84817455476;5149.485051494848;5150.742462876855;5193.766280517376;4165.750054996694;4835.782389792459;4976.726280054596;4991.8768277137715;5007.798128449164;3450.8792192273277;3616.62025487324;3755.342815007363;3848.3073046851555;3892.9805350973206;3271.8854840080603;3916.8824935251987;3931.9803400982955;3932.9577661919616;3933.3706606942897;3190.585223920887;3640.2173532690467;3851.326017946862;3879.7196925014678;3880.9715425412287;3863.478430411915;3874.2638898609175;3874.8450061997487;3887.241987812378;3889.727719059667;2722.891084356623;3297.851596678131;3317.552130462405;3320.704925079221;3332.750043746729;4915.754212289407;4946.3817022872145;4957.603391728653;4984.775685094143;5046.7224302663335;3618.3125206211;4067.617010216511;4291.678124140702;4301.741895486281;4392.89017774555;4483.2963473880045;5284.524392804666;5303.257543943851;5332.466753324676;5360.91958620621;3631.89104326868;3671.632836716307;3671.834767435472;3676.6874815640704;3684.9631503684886;4662.7202367858035;5369.973150134263;5596.985085369264;5671.631343962629;5709.600327977043;3686.5023221864703;3692.2615476904725;3697.815109244527;3702.426123950792;3703.4444833274683;3426.5031570422148;3648.868850656305;3752.587215406303;3769.3780526212868;3769.962300377016;3657.9149884566073;4355.891102722394;4370.125974805052;4389.780510974502;4394.472663280427;4301.526832048472;5082.796688132507;5379.731013449312;5396.406395296514;5470;1063.3726101600093;1095.2059756676347;1111.7554138089608;1220.8463002462765;1264.3437097402432;797.7287722174373;821.4373154389262;824.1428913929566;837.4975014991079;850.8638617821119;4342.413774140514;5112.897742045213;5164.3802743671;5179.585633149339;5182.274481572583;4826.686265392738;5460.918086228709;5531.197976293415;5536.141898005815;5574.665520068809;4807.302941073524;5019.573336266385;5059.026979478006;5078.187490001582;5094.465081166498;2522.965584110506;2528.7471252874934;2878.337982264091;2904.7966642335255;2909.9272518187213;4885.389326334208;5398.571906839185;5741.048043665174;5746.827595172152;5759.913601295984;5243.580513558983;5361.303030606075;5578.242853501134;5626.943730562723;5643.492085712305;4760.4763476016915;4824.541668541483;5716.942830571724;5745.339285982082;5753.913691294634;4564.6576506762785;5116.386033675983;5483.970930755646;5487.560995120461;5526.696031718294;4902.3872015998;5175.419753518088;5451.400345961862;5532.30846144232;5601.243832082617;4797.95202047989;5540.556755459635;5831.708414579339;5844.503217226414;5864.912026319694;3471.8437670304393;3595.4966304716836;3715.4241092629604;3715.628437156263;3738.7382883197915;5179.507946745053;5522.266478093369;5548.583856210881;5581.7767289309595;5589.441055894378;4369.672274579483;5447.404712889474;5453.945460545344;5463.836084917459;5467.261919640798;4700.876045110846;5159.845204643867;5292.259083728358;5339.786408543615;5391.649542779707;3997.040710229582;4552.931706024346;4830.154722923545;5127.333446652061;5364.785408583769;5359;5364.785408583613;5382.3895410726545;5384.946150538444;5387.895481426376;4322.546132656152;4774;4830.227163653799;4843.522725568735;5170.043541944752 05:16:24 INFO - PROCESS | 1655 | |5;yui.html;668.2916108924538;690.4441924250978;697.7243988624493;699.8705239530684;736.3924762071292;14110.012299139064;14381.209033503153;14598.416063357454;14789.334479948402;14794.704105917875;13066.803997940038;13125.606231813037;13145.145565538234;13147.211167329968;13157.802632960513;663.489113382695;681.2029924987752;683.5249501596393;683.7367613468825;684.736376495048;681.1655721740877;685.1880521581917;811.4603788480654;841.0412130171609;841.8484672758901;792.9801754956114;886.2024178239603;896.5786080542135;902.8781114549517;903.4669544968467;871.0462044061742;890.3678388344283;890.9777255568598;892.6340200517757;893.446063440671;521.2494008627602;642.0113025940046;670.6311528659218;671.3856821008771;674.0765151742096;12225.449854756469;12385.318807465586;12396.876031239663;12403.25580465175;12414;12033.699157521045;12166.57416990401;13406.664833379145;13533.526326578523;13626.318684065856;10029.69910902674;12402.767501849663;12403.689907752288;12410.5656302029;12573.656196116614;4547.931781023254;4594.586487216133;4601.1595361855525;4604.740728976405;4830.9275360869615;103879;110444;120812;123161.38419307845;123229;10856.837147442795;11205.551777928791;11206.15953803468;11428.25716328436;11596;20340.288089916783;22388;22401.66397504039;22447.43881402978;22482.437939051655;7643.388528917673;7656.9234307657325;7775.377969762406;7904.762857114296;7945.960270198612;4130.524989626171;4144.294634689631;4198.307279298881;4200.600942910419;4321.632661223831;3395.168183794986;3493.5458390409085;3497.3529896969144;3500.807455589916;3506.8246587670515;622.4273668225244;625.4058644287944;645.132297060453;651.5211319680031;651.8142329436088;537.2263939926571;572.4704648200398;617.2592888533777;618.2766163588612;618.8576627375729;8155.34757219421;8738.563071846382;8787.868181977276;8856.202941735146;9035.63857445708;578.5429510686511;606.0848119339829;611.1260896917362;623.1431781300712;623.7692053940071;4087.4890638670167;4129.587041295907;4148.191102734998;4316.5467625899455;4347.5217726050105;83748;92522.53738731264;119679.40160299143;129299;164467.02030327555;139917;142574.28712856368;143713;146118;146970.2651486757;121538.39230803789;121616.39191804161;121747;121838;122149.38925305316;7374.889376659462;8092.109867914407;8121.75634730959;8254;8392.622331994953;62276.37723622706;63528;63691;63707.72584548376;67638.30904227434;96303;96977;105627.47186264019;106133;107922.39005841345;13954.58136255914;14241.216733079365;14484.420623174956;14592.489262875748;14655.193964332064;14742;14979.32593033338;15022.023568468014;15040.699186016438;15050;13761.655958601115;14007.08953917992;14170.220637865017;14264.572062838131;14625.414983400546;13818.239996800272;14262.928685356506;14363.56909292723;14394.928025359806;14996.2501874908;12652.620421387373;13089.869101308865;13137.789495760202;13234.801477977646;13308.866911330762;11129.833052504056;11411.942940285246;11622.535098595961;11694.590689325832;11929.642110736691;9297.814043719223;10953.83569246478;11528.297677186116;11649.126315526539;11758.823617645914 05:16:24 INFO - PROCESS | 1655 | __end_tp_report 05:16:24 INFO - PROCESS | 1655 | __start_cc_report 05:16:24 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2553 05:16:24 INFO - PROCESS | 1655 | __end_cc_report 05:16:24 INFO - PROCESS | 1655 | __startTimestamp1476360984882__endTimestamp 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:16:24 INFO - PROCESS | 1655 | Number of tests: 6 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5544.64 Median:5694.49 stddev:627.42 (11.0%) stddev-sans-first:603.97 05:16:24 INFO - PROCESS | 1655 | Values: 3293.1 3302.7 3315.4 3343.0 3367.4 5389.7 5460.5 5497.2 5504.1 5558.4 5450.2 5481.6 5541.3 5576.3 5613.3 4953.9 4967.8 4973.6 4977.5 5007.0 4433.1 5060.8 5158.8 5162.5 5207.5 4995.6 5092.6 5124.3 5142.5 5227.7 5053.3 5106.3 5107.7 5117.2 5127.8 5008.0 5054.3 5080.8 5085.1 5096.2 5509.9 5531.3 5532.2 5536.4 5542.2 5984.4 5990.3 5991.1 5993.7 6003.0 4824.5 5976.6 6019.6 6056.4 6090.3 5523.7 5581.4 5592.5 5596.5 5608.4 6139.3 6188.6 6191.9 6237.3 6254.3 5882.1 5907.6 5913.3 5915.7 5970.8 5887.9 5948.3 5954.4 5958.0 6083.3 5907.8 5996.3 6000.1 6024.0 6028.5 5974.9 5986.5 5990.4 5992.7 6054.3 4750.2 4771.8 4774.6 4782.1 4808.9 4208.9 4235.0 4251.9 4261.2 4270.2 5578.8 5582.3 5586.8 5591.0 5596.3 5989.6 6001.2 6002.8 6002.8 6011.8 5484.7 5568.7 5577.5 5587.8 5594.2 4624.7 5169.8 5223.9 5224.3 5236.2 6271.7 6285.7 6299.7 6301.5 6303.7 6102.8 6111.2 6124.7 6182.8 6201.9 5484.6 6273.0 6333.1 6335.9 6346.9 5787.8 5827.5 5827.7 5893.9 5902.8 5917.2 6104.1 6104.3 6114.0 6118.0 5850.0 5854.9 5861.8 5868.1 5907.6 5700.9 5757.5 5857.5 5859.3 5859.4 5632.9 5683.5 5683.6 5694.5 5698.0 4587.4 5759.8 5765.1 5818.1 5829.6 5691.7 5695.9 5714.2 5714.2 5724.6 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:68082.42 Median:39025.51 stddev:154149.84 (395.0%) stddev-sans-first:154498.68 05:16:24 INFO - PROCESS | 1655 | Values: 467.9 478.4 508.7 526.6 559.5 38127.0 39056.6 39349.6 39473.6 41909.2 34737.7 38994.4 41018.6 41225.6 41354.6 9455.4 9639.1 9642.8 9643.0 9649.3 9448.8 9544.1 9557.8 9569.7 9580.2 9400.2 9558.7 9586.9 9596.6 9633.6 9507.0 9550.5 9556.1 9580.1 9584.0 9441.7 9524.6 9541.6 9547.2 9562.0 38948.2 39271.0 39316.0 39350.4 39417.6 3117.9 3137.8 3169.8 3181.4 3257.8 29729.6 30039.4 30458.1 30889.7 31128.5 3185.6 3194.3 3230.0 3318.0 3327.7 438472.7 475657.0 490316.0 492352.5 492740.0 26456.7 54652.0 54943.0 55013.4 55082.0 32909.7 33910.7 34082.0 34097.8 34122.8 14216.4 14416.9 14419.5 14422.6 14424.2 12174.1 12250.7 12266.9 12271.9 12303.1 7523.5 7562.3 7612.0 7633.1 7640.6 3167.4 3215.0 3225.7 3231.3 3253.4 859.6 887.5 1000.8 1030.3 1085.3 28414.6 29220.7 29283.7 30039.2 30251.8 828.9 999.5 1016.2 1029.3 1075.0 14464.5 14769.3 14818.4 15315.0 15322.5 636080.0 637283.8 641129.0 642053.0 643124.8 54155.7 54564.5 54738.7 54811.0 54846.7 1114.9 1204.1 1233.8 1330.6 1352.4 29333.3 30393.3 30669.8 30773.2 31049.8 43663.8 43913.6 43949.0 44063.9 44108.8 538984.0 541335.0 542260.0 542960.0 770465.0 39202.5 39732.6 39828.6 40158.4 40159.2 44332.6 44560.0 44973.0 44998.0 45023.3 27791.2 31682.4 32467.5 32906.0 34219.7 28349.9 36266.0 36830.4 37243.0 37283.8 36131.9 36260.1 36750.8 36773.8 36813.0 31174.7 33045.8 33195.0 33294.0 33345.8 23928.9 24162.0 24324.3 24780.5 24792.8 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33999.12 Median:348.39 stddev:97793.23 (28070.1%) stddev-sans-first:98034.90 05:16:24 INFO - PROCESS | 1655 | Values: 332.5 345.4 351.3 352.1 374.4 13266.5 14804.4 15152.8 15869.3 20133.7 19875.5 20087.9 20242.9 20861.3 21042.1 5539.8 5877.7 5879.3 5891.6 5899.8 4642.7 5694.7 5722.7 5782.7 5840.9 5753.0 5793.4 5797.7 5804.3 5809.9 5701.3 5777.8 5969.6 6023.8 6078.9 5641.8 5849.5 5859.7 5863.4 5866.4 12673.4 13024.5 13060.2 13061.7 13062.4 1955.9 1991.9 2133.6 2153.1 2789.0 9363.9 9838.9 10118.7 10362.0 10421.0 1759.6 1765.6 1873.7 1881.9 1953.7 96690.5 116390.4 124657.0 127285.0 129687.0 11089.9 11412.9 11446.8 11473.0 11623.5 14421.2 14459.1 14467.9 14482.2 14492.6 8122.2 8406.4 8445.6 8564.1 8589.6 8306.3 8311.9 8332.7 8483.5 8519.0 4378.8 4508.3 4521.6 4571.7 4575.8 1717.3 1834.3 1842.9 1894.3 1894.5 743.1 814.9 903.9 959.5 1002.9 16208.0 18612.8 19551.9 21487.6 21571.8 873.8 914.3 927.2 946.0 963.7 10499.5 10989.1 11002.9 11034.3 11078.2 567871.0 581938.0 589238.0 589529.0 590753.0 124134.4 128205.0 129782.0 130858.3 136883.3 946.8 950.7 962.4 971.2 971.6 12858.5 15664.8 18363.4 19726.7 19878.3 53151.0 54246.9 54481.5 55247.7 55635.4 74018.0 75487.9 75509.0 75974.2 76431.0 6864.4 7154.9 7163.8 7259.7 7415.7 9505.5 10139.6 12574.6 12942.6 13048.6 6903.6 9962.9 10508.8 10928.7 11007.3 9922.5 10510.0 10925.0 10946.7 11103.0 10009.7 10509.4 11132.6 11269.3 11346.8 7803.9 7821.3 7960.7 8208.3 8221.0 10688.9 10736.7 10810.3 11073.0 11109.7 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5669.19 Median:4710.59 stddev:3233.00 (68.6%) stddev-sans-first:3225.57 05:16:24 INFO - PROCESS | 1655 | Values: 1323.1 1500.1 1631.3 1836.9 1879.2 8456.7 8968.9 8995.8 9002.5 9004.6 7787.1 8111.1 8117.6 8496.3 8736.1 2308.3 2342.9 2343.4 2344.3 2349.1 2286.8 2315.5 2328.1 2328.4 2329.5 1984.9 2297.3 2310.9 2317.7 2323.9 2276.7 2287.6 2296.3 2298.7 2300.3 2303.8 2305.4 2312.3 2315.2 2316.8 7405.3 8943.0 8977.7 8993.0 8993.4 5119.8 5296.0 5305.8 5306.4 5312.6 3341.3 3432.9 3552.0 3562.4 3568.6 999.3 1033.2 1040.6 1056.6 1057.4 11607.1 11746.5 11777.8 11792.6 11799.6 4458.2 4594.7 4675.1 4707.7 4713.5 10016.5 10022.1 10025.6 10045.4 10053.3 5260.4 5268.2 5275.4 5278.4 5278.5 4193.7 4271.5 4366.1 4798.5 4838.7 3794.6 3808.2 3893.4 3921.2 3924.4 1209.1 1212.3 1216.5 1225.5 1227.4 832.8 852.1 884.9 888.7 929.1 4894.6 5571.7 5717.5 5998.5 6036.0 698.7 889.4 927.5 931.1 959.9 3396.0 3760.7 3945.3 3953.5 4102.4 10486.8 10755.4 10865.7 10919.5 10919.7 10138.5 10698.0 10870.7 10874.7 10897.7 8043.4 8244.9 8248.4 8253.9 8256.2 5469.3 5793.9 5819.8 5863.7 6098.3 9993.7 10012.4 10075.9 10095.3 10145.8 9740.5 10144.3 10199.4 10320.0 10326.5 7002.3 7252.9 7284.7 7446.7 7676.9 8205.3 8346.6 8472.3 8478.6 8496.6 7287.1 7338.0 7357.1 7429.8 7518.1 7125.0 7235.0 7324.9 7374.1 7424.8 7018.8 7026.8 7113.8 7113.8 7127.1 4164.2 4350.7 4944.2 4955.7 4983.1 4878.9 4911.2 4935.4 4965.7 5178.3 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4320.02 Median:4839.65 stddev:1349.71 (27.9%) stddev-sans-first:1319.07 05:16:24 INFO - PROCESS | 1655 | Values: 284.8 285.0 293.1 297.2 300.9 4402.9 5060.8 5149.5 5150.7 5193.8 4165.8 4835.8 4976.7 4991.9 5007.8 3450.9 3616.6 3755.3 3848.3 3893.0 3271.9 3916.9 3932.0 3933.0 3933.4 3190.6 3640.2 3851.3 3879.7 3881.0 3863.5 3874.3 3874.8 3887.2 3889.7 2722.9 3297.9 3317.6 3320.7 3332.8 4915.8 4946.4 4957.6 4984.8 5046.7 3618.3 4067.6 4291.7 4301.7 4392.9 4483.3 5284.5 5303.3 5332.5 5360.9 3631.9 3671.6 3671.8 3676.7 3685.0 4662.7 5370.0 5597.0 5671.6 5709.6 3686.5 3692.3 3697.8 3702.4 3703.4 3426.5 3648.9 3752.6 3769.4 3770.0 3657.9 4355.9 4370.1 4389.8 4394.5 4301.5 5082.8 5379.7 5396.4 5470.0 1063.4 1095.2 1111.8 1220.8 1264.3 797.7 821.4 824.1 837.5 850.9 4342.4 5112.9 5164.4 5179.6 5182.3 4826.7 5460.9 5531.2 5536.1 5574.7 4807.3 5019.6 5059.0 5078.2 5094.5 2523.0 2528.7 2878.3 2904.8 2909.9 4885.4 5398.6 5741.0 5746.8 5759.9 5243.6 5361.3 5578.2 5626.9 5643.5 4760.5 4824.5 5716.9 5745.3 5753.9 4564.7 5116.4 5484.0 5487.6 5526.7 4902.4 5175.4 5451.4 5532.3 5601.2 4798.0 5540.6 5831.7 5844.5 5864.9 3471.8 3595.5 3715.4 3715.6 3738.7 5179.5 5522.3 5548.6 5581.8 5589.4 4369.7 5447.4 5453.9 5463.8 5467.3 4700.9 5159.8 5292.3 5339.8 5391.6 3997.0 4552.9 4830.2 5127.3 5364.8 5359.0 5364.8 5382.4 5384.9 5387.9 4322.5 4774.0 4830.2 4843.5 5170.0 05:16:24 INFO - PROCESS | 1655 | 05:16:24 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25163.45 Median:3444.36 stddev:40502.92 (1175.9%) stddev-sans-first:40574.79 05:16:24 INFO - PROCESS | 1655 | Values: 668.3 690.4 697.7 699.9 736.4 14110.0 14381.2 14598.4 14789.3 14794.7 13066.8 13125.6 13145.1 13147.2 13157.8 663.5 681.2 683.5 683.7 684.7 681.2 685.2 811.5 841.0 841.8 793.0 886.2 896.6 902.9 903.5 871.0 890.4 891.0 892.6 893.4 521.2 642.0 670.6 671.4 674.1 12225.4 12385.3 12396.9 12403.3 12414.0 12033.7 12166.6 13406.7 13533.5 13626.3 10029.7 12402.8 12403.7 12410.6 12573.7 4547.9 4594.6 4601.2 4604.7 4830.9 103879.0 110444.0 120812.0 123161.4 123229.0 10856.8 11205.6 11206.2 11428.3 11596.0 20340.3 22388.0 22401.7 22447.4 22482.4 7643.4 7656.9 7775.4 7904.8 7946.0 4130.5 4144.3 4198.3 4200.6 4321.6 3395.2 3493.5 3497.4 3500.8 3506.8 622.4 625.4 645.1 651.5 651.8 537.2 572.5 617.3 618.3 618.9 8155.3 8738.6 8787.9 8856.2 9035.6 578.5 606.1 611.1 623.1 623.8 4087.5 4129.6 4148.2 4316.5 4347.5 83748.0 92522.5 119679.4 129299.0 164467.0 139917.0 142574.3 143713.0 146118.0 146970.3 121538.4 121616.4 121747.0 121838.0 122149.4 7374.9 8092.1 8121.8 8254.0 8392.6 62276.4 63528.0 63691.0 63707.7 67638.3 96303.0 96977.0 105627.5 106133.0 107922.4 13954.6 14241.2 14484.4 14592.5 14655.2 14742.0 14979.3 15022.0 15040.7 15050.0 13761.7 14007.1 14170.2 14264.6 14625.4 13818.2 14262.9 14363.6 14394.9 14996.3 12652.6 13089.9 13137.8 13234.8 13308.9 11129.8 11411.9 11622.5 11694.6 11929.6 9297.8 10953.8 11528.3 11649.1 11758.8 05:16:24 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:16:24 INFO - PROCESS | 1655 | 05:16:25 INFO - TEST-INFO | 1655: exit 0 05:16:25 INFO - TEST-OK | dromaeo_css | took 1121487ms 05:16:25 INFO - TEST-START | kraken 05:16:25 INFO - operating with platform_type : mac_ 05:16:25 INFO - Initialising browser for kraken test... 05:16:25 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpSYOZ9q/profile http://localhost:49267/getInfo.html) 05:16:27 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 05:16:27 INFO - PROCESS | 1665 | colorDepth:24 05:16:27 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 05:16:27 INFO - PROCESS | 1665 | __metrics 05:16:28 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:16:28 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:16:28 INFO - TEST-INFO | 1665: exit 0 05:16:28 INFO - Browser initialized. 05:16:28 INFO - Running cycle 1/1 for kraken test... 05:16:28 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpSYOZ9q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:16:41 INFO - PROCESS | 1666 | RSS: Main: 235171840 05:16:41 INFO - PROCESS | 1666 | 05:16:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 05:16:44 INFO - PROCESS | 1666 | RSS: Main: 293810176 05:16:44 INFO - PROCESS | 1666 | 05:16:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 05:16:48 INFO - PROCESS | 1666 | RSS: Main: 293908480 05:16:48 INFO - PROCESS | 1666 | 05:16:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 05:16:52 INFO - PROCESS | 1666 | RSS: Main: 382615552 05:16:52 INFO - PROCESS | 1666 | 05:16:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 05:16:54 INFO - PROCESS | 1666 | RSS: Main: 284659712 05:16:54 INFO - PROCESS | 1666 | 05:16:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:57 INFO - PROCESS | 1666 | RSS: Main: 288575488 05:16:57 INFO - PROCESS | 1666 | 05:17:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 05:17:00 INFO - PROCESS | 1666 | RSS: Main: 326123520 05:17:00 INFO - PROCESS | 1666 | 05:17:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 05:17:04 INFO - PROCESS | 1666 | RSS: Main: 327864320 05:17:04 INFO - PROCESS | 1666 | 05:17:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 05:17:07 INFO - PROCESS | 1666 | RSS: Main: 329027584 05:17:07 INFO - PROCESS | 1666 | 05:17:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:17:10 INFO - PROCESS | 1666 | RSS: Main: 301989888 05:17:10 INFO - PROCESS | 1666 | 05:17:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:17:12 INFO - PROCESS | 1666 | RSS: Main: 305192960 05:17:12 INFO - PROCESS | 1666 | 05:17:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:17:15 INFO - PROCESS | 1666 | RSS: Main: 307752960 05:17:15 INFO - PROCESS | 1666 | 05:17:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:17:19 INFO - PROCESS | 1666 | RSS: Main: 321748992 05:17:19 INFO - PROCESS | 1666 | 05:17:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:17:22 INFO - PROCESS | 1666 | RSS: Main: 321388544 05:17:22 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 05:17:24 INFO - PROCESS | 1666 | RSS: Main: 365264896 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | __start_tp_report 05:17:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:17:24 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:17:24 INFO - PROCESS | 1666 | |i|pagename|runs| 05:17:24 INFO - PROCESS | 1666 | |0;ai-astar;114;114;114;114;113;114;112;114;113;113 05:17:24 INFO - PROCESS | 1666 | |1;audio-beat-detection;169;101;136;180;143;108;141;143;181;114 05:17:24 INFO - PROCESS | 1666 | |2;audio-dft;183;181;185;187;183;184;183;184;185;181 05:17:24 INFO - PROCESS | 1666 | |3;audio-fft;82;59;59;87;63;58;59;59;82;57 05:17:24 INFO - PROCESS | 1666 | |4;audio-oscillator;89;95;123;100;69;70;94;67;70;90 05:17:24 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;90;87;90;87;90;87;91;88;91;86 05:17:24 INFO - PROCESS | 1666 | |6;imaging-darkroom;95;94;95;95;96;94;95;117;125;95 05:17:24 INFO - PROCESS | 1666 | |7;imaging-desaturate;83;86;86;84;86;87;84;85;85;84 05:17:24 INFO - PROCESS | 1666 | |8;json-parse-financial;59;55;56;56;53;57;56;55;55;56 05:17:24 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;65;71;68;75;72;69;72;70;70;71 05:17:24 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;86;86;87;77;85;94;105;111;75;97 05:17:24 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;147;140;152;153;145;144;161;165;209;157 05:17:24 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;179;172;173;172;179;173;172;170;173;173 05:17:24 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;68;68;68;68;68;69;69;67;68;68 05:17:24 INFO - PROCESS | 1666 | __end_tp_report 05:17:24 INFO - PROCESS | 1666 | __start_cc_report 05:17:24 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2063 05:17:24 INFO - PROCESS | 1666 | __end_cc_report 05:17:24 INFO - PROCESS | 1666 | __startTimestamp1476361044934__endTimestamp 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:17:24 INFO - PROCESS | 1666 | Number of tests: 14 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 05:17:24 INFO - PROCESS | 1666 | Values: 114.0 114.0 114.0 114.0 113.0 114.0 112.0 114.0 113.0 113.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:143.00 stddev:28.58 (20.0%) stddev-sans-first:28.54 05:17:24 INFO - PROCESS | 1666 | Values: 169.0 101.0 136.0 180.0 143.0 108.0 141.0 143.0 181.0 114.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.84 (1.0%) stddev-sans-first:1.94 05:17:24 INFO - PROCESS | 1666 | Values: 183.0 181.0 185.0 187.0 183.0 184.0 183.0 184.0 185.0 181.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.50 Median:61.00 stddev:12.02 (19.7%) stddev-sans-first:11.37 05:17:24 INFO - PROCESS | 1666 | Values: 82.0 59.0 59.0 87.0 63.0 58.0 59.0 59.0 82.0 57.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:79.50 stddev:17.93 (22.5%) stddev-sans-first:18.99 05:17:24 INFO - PROCESS | 1666 | Values: 89.0 95.0 123.0 100.0 69.0 70.0 94.0 67.0 70.0 90.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:90.00 stddev:1.89 (2.1%) stddev-sans-first:1.94 05:17:24 INFO - PROCESS | 1666 | Values: 90.0 87.0 90.0 87.0 90.0 87.0 91.0 88.0 91.0 86.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:95.00 stddev:11.19 (11.8%) stddev-sans-first:11.72 05:17:24 INFO - PROCESS | 1666 | Values: 95.0 94.0 95.0 95.0 96.0 94.0 95.0 117.0 125.0 95.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:1.25 (1.5%) stddev-sans-first:1.09 05:17:24 INFO - PROCESS | 1666 | Values: 83.0 86.0 86.0 84.0 86.0 87.0 84.0 85.0 85.0 84.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.55 (2.8%) stddev-sans-first:1.13 05:17:24 INFO - PROCESS | 1666 | Values: 59.0 55.0 56.0 56.0 53.0 57.0 56.0 55.0 55.0 56.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:2.67 (3.8%) stddev-sans-first:2.03 05:17:24 INFO - PROCESS | 1666 | Values: 65.0 71.0 68.0 75.0 72.0 69.0 72.0 70.0 70.0 71.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:86.00 stddev:11.50 (13.4%) stddev-sans-first:12.09 05:17:24 INFO - PROCESS | 1666 | Values: 86.0 86.0 87.0 77.0 85.0 94.0 105.0 111.0 75.0 97.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:155.00 stddev:19.79 (12.8%) stddev-sans-first:20.64 05:17:24 INFO - PROCESS | 1666 | Values: 147.0 140.0 152.0 153.0 145.0 144.0 161.0 165.0 209.0 157.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:173.00 stddev:2.99 (1.7%) stddev-sans-first:2.45 05:17:24 INFO - PROCESS | 1666 | Values: 179.0 172.0 173.0 172.0 179.0 173.0 172.0 170.0 173.0 173.0 05:17:24 INFO - PROCESS | 1666 | 05:17:24 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 05:17:24 INFO - PROCESS | 1666 | Values: 68.0 68.0 68.0 68.0 68.0 69.0 69.0 67.0 68.0 68.0 05:17:24 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:17:24 INFO - PROCESS | 1666 | 05:17:25 INFO - TEST-INFO | 1666: exit 0 05:17:25 INFO - TEST-OK | kraken | took 60310ms 05:17:25 INFO - SUITE-END | took 1181s 05:17:26 INFO - Completed test suite (00:19:42) 05:17:26 INFO - javascript benchmark 05:17:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.1108600677817, 3302.669733026697, 3315.3535060663166, 3343.0, 3367.444371678673, 5389.73051347433, 5460.481254280842, 5497.202905578689, 5504.0918248489, 5558.360788509328, 5450.236966824647, 5481.588880833933, 5541.251930989315, 5576.302962129734, 5613.326400831896, 4953.900921981558, 4967.801287948478, 4973.552380285773, 4977.50224977503, 5007.0, 4433.113377324532, 5060.848174554751, 5158.793648254065, 5162.483751624845, 5207.453217412174, 4995.5753760930365, 5092.618053645972, 5124.282600435942, 5142.485751424865, 5227.660202086852, 5053.292539044537, 5106.336176297094, 5107.668001579886, 5117.2068329409, 5127.820526281562, 5008.023435430093, 5054.3176671149295, 5080.79676812927, 5085.084684756743, 5096.159133742928, 5509.862253443656, 5531.280933478622, 5532.197831314437, 5536.363318218376, 5542.196381524656, 5984.371640977722, 5990.341062483123, 5991.101334799815, 5993.700314984233, 6003.0, 4824.541668541483, 5976.641401515923, 6019.63882167067, 6056.394360563952, 6090.299615544225, 5523.6685798852195, 5581.413951535031, 5592.496675299202, 5596.468335508161, 5608.439156084359, 6139.2550544168025, 6188.597741146811, 6191.876162476815, 6237.345078766664, 6254.312025677172, 5882.117682347682, 5907.586468947218, 5913.2608423947, 5915.704214789243, 5970.76116955317, 5887.882242355129, 5948.315943666534, 5954.4045595440975, 5957.970210148921, 6083.330833608299, 5907.763689452459, 5996.310424301261, 6000.069989151689, 6024.0, 6028.457438830438, 5974.9402505974385, 5986.491148252448, 5990.430909063632, 5992.700364981733, 6054.303755068136, 4750.168720473903, 4771.832985845548, 4774.618030557547, 4782.0914026334885, 4808.903821923542, 4208.936865946952, 4235.0, 4251.936220956688, 4261.232978063917, 4270.188664153801, 5578.8326350208745, 5582.274304340491, 5586.776528938797, 5591.049521581341, 5596.3284405871555, 5989.55078369124, 6001.1898393717165, 6002.819915402545, 6002.819915402545, 6011.849703757441, 5484.698341591251, 5568.749406276646, 5577.525910297589, 5587.832365029056, 5594.1888426177975, 4624.686175616352, 5169.7932082717025, 5223.9477605223465, 5224.320838291073, 5236.240745092016, 6271.6550589718, 6285.685715714196, 6299.6535190564955, 6301.52738544611, 6303.716332765129, 6102.755889764538, 6111.174991376108, 6124.693765311627, 6182.814515564541, 6201.937980620136, 5484.643498172606, 6272.968635156795, 6333.050042493663, 6335.873282534416, 6346.873062538816, 5787.7974270902005, 5827.475527202572, 5827.650340979555, 5893.882122357443, 5902.793402231073, 5917.201177840936, 6104.095385180791, 6104.3075792339105, 6114.0236562776045, 6117.979653188475, 5850.0, 5854.853628659147, 5861.7655293787775, 5868.09044598088, 5907.645541267538, 5700.914486282626, 5757.453041961007, 5857.47282744555, 5859.267591551056, 5859.384764599656, 5632.9436705632415, 5683.488486036276, 5683.573731970201, 5694.487496125369, 5697.971510142423, 4587.38070360497, 5759.769609215585, 5765.135229715576, 5818.098194779646, 5829.621074630136, 5691.658500489983, 5695.857603559778, 5714.20001199824, 5714.228579141929, 5724.599278050663], "value": 5505.675918798595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.904079663669, 478.428278207542, 508.7049511283456, 526.6050462153385, 559.5104283751717, 38127.0, 39056.60943390565, 39349.60650393495, 39473.60526394735, 41909.16181676365, 34737.65262347376, 38994.415083773696, 41018.58981410185, 41225.58774412255, 41354.58645413545, 9455.385399948998, 9639.084286992724, 9642.75893102671, 9643.035696430336, 9649.276304277173, 9448.763780905463, 9544.093311135432, 9557.808843823119, 9569.66506172284, 9580.185684216849, 9400.153986141246, 9558.713238602819, 9586.90413095867, 9596.616135354578, 9633.566489507988, 9507.0, 9550.474723890218, 9556.139947404698, 9580.0898914603, 9583.952080239624, 9441.716748497556, 9524.619015239452, 9541.61833526665, 9547.188488978445, 9562.0, 38948.221035579416, 39271.0, 39316.0, 39350.40974385387, 39417.60582394168, 3117.9220519486967, 3137.7803553751246, 3169.7781155319135, 3181.3955348483714, 3257.820819854907, 29729.608938547484, 30039.399212015855, 30458.086257412313, 30889.69110308913, 31128.53307200394, 3185.5540224368606, 3194.3132226571165, 3229.9664107485614, 3318.0, 3327.667233276677, 438472.7283634034, 475657.0, 490316.0, 492352.53823731013, 492740.0, 26456.735432645426, 54652.0, 54943.0, 55013.449865501636, 55082.0, 32909.67090329066, 33910.66089339124, 34082.0, 34097.82951085278, 34122.829385852914, 14216.360263788154, 14416.91873109521, 14419.495317663832, 14422.567322980327, 14424.206668633326, 12174.14780965324, 12250.693732656755, 12266.877331226751, 12271.877281227253, 12303.138780285293, 7523.548587084792, 7562.281583249583, 7612.048493938258, 7633.122190948112, 7640.617969101522, 3167.4456970030615, 3214.967850321467, 3225.70968612826, 3231.321422501254, 3253.4469140246215, 859.58739804894, 887.4985633117268, 1000.7848312612678, 1030.253066526762, 1085.2511766880828, 28414.573781393297, 29220.707792922225, 29283.707162928524, 30039.249018774706, 30251.848740756155, 828.9461185022955, 999.5306898858667, 1016.2124353625999, 1029.264076185516, 1074.9731256718644, 14464.493742718954, 14769.261536923326, 14818.369499472697, 15315.0, 15322.463713769965, 636080.0, 637283.8135809292, 641129.0, 642053.0, 643124.7843760845, 54155.72922135364, 54564.45435545673, 54738.72630636821, 54811.0, 54846.72576637171, 1114.8829372915727, 1204.0909113619161, 1233.8396008518835, 1330.6274243211917, 1352.3914238592477, 29333.26666833346, 30393.32836694003, 30669.8466507666, 30773.21435684903, 31049.8447507761, 43663.781681091394, 43913.56086439094, 43949.0, 44063.898402540195, 44108.779456102515, 538984.0, 541335.0, 542260.0417970704, 542960.0, 770465.0, 39202.491713452175, 39732.60267397289, 39828.60171398249, 40158.397624036246, 40159.17691934711, 44332.556674432846, 44560.0, 44973.0, 44998.0, 45023.324650130926, 27791.16626501208, 31682.366352673278, 32467.512987305683, 32906.0, 34219.65780342264, 28349.858250708614, 36266.0, 36830.44754328633, 37243.0, 37283.81358093192, 36131.916042518766, 36260.09349766171, 36750.8162459186, 36773.81613091917, 36813.0, 31174.68825311718, 33045.843395481046, 33195.0, 33294.0, 33345.83327083349, 23928.88035559811, 24162.03351865976, 24324.27027189187, 24780.50438991174, 24792.752072479045], "value": 18193.68727425484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.5062282510473, 345.44383542496576, 351.3359750072363, 352.13726370392527, 374.40843467321633, 13266.469341226351, 14804.40782368704, 15152.77270840937, 15869.285882135302, 20133.697994530095, 19875.503112422197, 20087.899560502177, 20242.89878550605, 20861.26985555506, 21042.053107610194, 5539.750711218005, 5877.7355019024035, 5879.294484661825, 5891.617044892089, 5899.764009439617, 4642.675012749109, 5694.743736531866, 5722.742476588543, 5782.653040817543, 5840.912386314209, 5752.993226185401, 5793.362730099686, 5797.681127537984, 5804.303483581976, 5809.883802323951, 5701.287339082594, 5777.826665200029, 5969.5821292509545, 6023.789167379121, 6078.8784224315705, 5641.802536911188, 5849.532037436995, 5859.6777177255235, 5863.413658634145, 5866.384029676908, 12673.429695663717, 13024.479020839155, 13060.216387016715, 13061.673458163526, 13062.412191451407, 1955.9413217603494, 1991.8705284156483, 2133.626615342309, 2153.1064608187585, 2788.9721102789117, 9363.859542106873, 9838.852417213748, 10118.696439106838, 10362.0, 10421.0, 1759.568905618119, 1765.5586103474132, 1873.7095750158746, 1881.8964956927364, 1953.658109830774, 96690.51654741752, 116390.41804790922, 124656.9999999991, 127285.0, 129687.0, 11089.889101109047, 11412.942935285271, 11446.828297575543, 11473.0, 11623.535058597561, 14421.206833624252, 14459.13245205291, 14467.914906382064, 14482.203478808557, 14492.637684057772, 8122.187781221949, 8406.41155119148, 8445.619947102396, 8564.057953625097, 8589.570521474026, 8306.293965012921, 8311.875321870177, 8332.6666933322, 8483.490990540588, 8518.957405212934, 4378.824847006084, 4508.301213311942, 4521.570450807168, 4571.6799824012, 4575.839845605387, 1717.2615775216527, 1834.293796888181, 1842.926282948667, 1894.2801735340483, 1894.4790182699592, 743.1045590064068, 814.8736945773414, 903.915301638037, 959.5106495687111, 1002.8997100289913, 16208.027518348938, 18612.813871861377, 19551.90224048899, 21487.57024859494, 21571.78428215698, 873.7597160780836, 914.337105598449, 927.1562877781187, 945.9810803783886, 963.7253382786013, 10499.475026248658, 10989.06592939593, 11002.944985275022, 11034.337939723642, 11078.224524283383, 567871.0, 581938.0, 589238.0, 589529.0, 590753.0, 124134.37932810278, 128205.0, 129782.0, 130858.34570827275, 136883.31558342345, 946.7633091727068, 950.6530116507425, 962.4033099478369, 971.1793534463367, 971.6453494474466, 12858.485660573473, 15664.76502852481, 18363.35728249505, 19726.704099438808, 19878.304259350854, 53151.0, 54246.915061699336, 54481.45518544764, 55247.723761380934, 55635.44364556303, 74018.0, 75487.86768198371, 75509.0, 75974.24025759671, 76431.0, 6864.4165245954655, 7154.892676609751, 7163.785086447415, 7259.709611615476, 7415.666295016629, 9505.477198754135, 10139.64511242103, 12574.559890403792, 12942.611721648365, 13048.607362085737, 6903.551269167488, 9962.900370996198, 10508.842367364648, 10928.6721398355, 11007.284526505751, 9922.503874806087, 10510.0, 10925.016748492673, 10946.671599852018, 11103.0, 10009.69970900874, 10509.421981791074, 11132.554697811996, 11269.323840569592, 11346.773064538498, 7803.928771774816, 7821.296083352526, 7960.656524259709, 8208.343332533503, 8221.0, 10688.89311106879, 10736.73158171052, 10810.29733067348, 11073.0, 11109.666709998713], "value": 9100.314721844099, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.146570462052, 1500.1299246437063, 1631.2577777111414, 1836.9357072502464, 1879.2389082421616, 8456.746297611066, 8968.910310896888, 8995.820083598324, 9002.504862232574, 9004.639814407432, 7787.104482984459, 8111.107778144399, 8117.553534555597, 8496.2778163856, 8736.0827113153, 2308.261356365968, 2342.917997870075, 2343.4492894169857, 2344.3318654183563, 2349.0955981946986, 2286.7941885230325, 2315.5021670340875, 2328.1036800831635, 2328.382978510696, 2329.5108027314286, 1984.8709833860826, 2297.2648752399155, 2310.9306720798318, 2317.73346065203, 2323.9302820915314, 2276.715410573678, 2287.5653625811124, 2296.3111066680012, 2298.6552017197555, 2300.2754132448226, 2303.8156947444163, 2305.4236440889777, 2312.2716344351475, 2315.1549684365255, 2316.779905908936, 7405.333519983174, 8943.016268210491, 8977.730668079968, 8993.010768815426, 8993.415427997159, 5119.795208191705, 5296.0, 5305.814296499604, 5306.442823503515, 5312.60155488336, 3341.3484370547753, 3432.916578098117, 3552.0409489437707, 3562.4300111982216, 3568.553930758655, 999.3254553176649, 1033.1992705653179, 1040.583766493401, 1056.5668076088843, 1057.3972835483778, 11607.129465290138, 11746.471408786625, 11777.823332650018, 11792.646220613395, 11799.646010619696, 4458.152950939311, 4594.724316541018, 4675.111728771522, 4707.670463067552, 4713.505081966344, 10016.499175041365, 10022.098011178883, 10025.598976040877, 10045.447500387549, 10053.346532475365, 5260.395054568697, 5268.15709486488, 5275.419703832576, 5278.392984806796, 5278.5249327560705, 4193.727407718488, 4271.466066741657, 4366.083122544238, 4798.49615790344, 4838.7338696372035, 3794.6395092466173, 3808.1622043150464, 3893.4354518594646, 3921.1961547882615, 3924.4309575111447, 1209.1294268126935, 1212.3271584270815, 1216.5194748074462, 1225.473046589975, 1227.4476485581524, 832.7895961069988, 852.058475384691, 884.9292056635454, 888.657866721317, 929.0616477357917, 4894.559489645949, 5571.693556854331, 5717.542596592262, 5998.490128339053, 6036.0, 698.6995591895433, 889.3863234368265, 927.5223260021095, 931.092185119503, 959.8608201810838, 3396.0, 3760.7179461540495, 3945.309570825094, 3953.545342285674, 4102.384642303678, 10486.790264194673, 10755.354678719303, 10865.728356790985, 10919.508622112024, 10919.727006824734, 10138.543765530569, 10698.03717665414, 10870.72823179411, 10874.728131796768, 10897.67306980792, 8043.436959412902, 8244.876326854983, 8248.381371397289, 8253.876191857127, 8256.174382561696, 5469.261649677402, 5793.884122317614, 5819.82540523785, 5863.677497737665, 6098.329183789869, 9993.650222242186, 10012.449315287438, 10075.899241007495, 10095.343802652804, 10145.847812282675, 9740.464274464972, 10144.289899707244, 10199.38803671782, 10320.019598138166, 10326.535305911402, 7002.299770022957, 7252.854942901218, 7284.672189751368, 7446.664900079401, 7676.884846727416, 8205.302549283379, 8346.624401901807, 8472.322214222724, 8478.618462169341, 8496.617652205541, 7287.125544934642, 7337.963310183415, 7357.0803649543805, 7429.814254643461, 7518.097828260644, 7125.0, 7234.96382518084, 7324.926750732638, 7374.078240219973, 7424.777256682308, 7018.75434359795, 7026.789196324118, 7113.82215444618, 7113.82215444618, 7127.10911136108, 4164.208800327928, 4350.73895566267, 4944.208926571731, 4955.677880937727, 4983.07813054586, 4878.853634390974, 4911.189653707242, 4935.383077115361, 4965.677230979974, 5178.32681751385], "value": 4525.222729129932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.782141661629, 284.9857507124644, 293.0826513014265, 297.2449977058272, 300.9262730630996, 4402.877266297094, 5060.84817455476, 5149.485051494848, 5150.742462876855, 5193.766280517376, 4165.750054996694, 4835.782389792459, 4976.726280054596, 4991.8768277137715, 5007.798128449164, 3450.8792192273277, 3616.62025487324, 3755.342815007363, 3848.3073046851555, 3892.9805350973206, 3271.8854840080603, 3916.8824935251987, 3931.9803400982955, 3932.9577661919616, 3933.3706606942897, 3190.585223920887, 3640.2173532690467, 3851.326017946862, 3879.7196925014678, 3880.9715425412287, 3863.478430411915, 3874.2638898609175, 3874.8450061997487, 3887.241987812378, 3889.727719059667, 2722.891084356623, 3297.851596678131, 3317.552130462405, 3320.704925079221, 3332.750043746729, 4915.754212289407, 4946.3817022872145, 4957.603391728653, 4984.775685094143, 5046.7224302663335, 3618.3125206211, 4067.617010216511, 4291.678124140702, 4301.741895486281, 4392.89017774555, 4483.2963473880045, 5284.524392804666, 5303.257543943851, 5332.466753324676, 5360.91958620621, 3631.89104326868, 3671.632836716307, 3671.834767435472, 3676.6874815640704, 3684.9631503684886, 4662.7202367858035, 5369.973150134263, 5596.985085369264, 5671.631343962629, 5709.600327977043, 3686.5023221864703, 3692.2615476904725, 3697.815109244527, 3702.426123950792, 3703.4444833274683, 3426.5031570422148, 3648.868850656305, 3752.587215406303, 3769.3780526212868, 3769.962300377016, 3657.9149884566073, 4355.891102722394, 4370.125974805052, 4389.780510974502, 4394.472663280427, 4301.526832048472, 5082.796688132507, 5379.731013449312, 5396.406395296514, 5470.0, 1063.3726101600093, 1095.2059756676347, 1111.7554138089608, 1220.8463002462765, 1264.3437097402432, 797.7287722174373, 821.4373154389262, 824.1428913929566, 837.4975014991079, 850.8638617821119, 4342.413774140514, 5112.897742045213, 5164.3802743671, 5179.585633149339, 5182.274481572583, 4826.686265392738, 5460.918086228709, 5531.197976293415, 5536.141898005815, 5574.665520068809, 4807.302941073524, 5019.573336266385, 5059.026979478006, 5078.187490001582, 5094.465081166498, 2522.965584110506, 2528.7471252874934, 2878.337982264091, 2904.7966642335255, 2909.9272518187213, 4885.389326334208, 5398.571906839185, 5741.048043665174, 5746.827595172152, 5759.913601295984, 5243.580513558983, 5361.303030606075, 5578.242853501134, 5626.943730562723, 5643.492085712305, 4760.4763476016915, 4824.541668541483, 5716.942830571724, 5745.339285982082, 5753.913691294634, 4564.6576506762785, 5116.386033675983, 5483.970930755646, 5487.560995120461, 5526.696031718294, 4902.3872015998, 5175.419753518088, 5451.400345961862, 5532.30846144232, 5601.243832082617, 4797.95202047989, 5540.556755459635, 5831.708414579339, 5844.503217226414, 5864.912026319694, 3471.8437670304393, 3595.4966304716836, 3715.4241092629604, 3715.628437156263, 3738.7382883197915, 5179.507946745053, 5522.266478093369, 5548.583856210881, 5581.7767289309595, 5589.441055894378, 4369.672274579483, 5447.404712889474, 5453.945460545344, 5463.836084917459, 5467.261919640798, 4700.876045110846, 5159.845204643867, 5292.259083728358, 5339.786408543615, 5391.649542779707, 3997.040710229582, 4552.931706024346, 4830.154722923545, 5127.333446652061, 5364.785408583769, 5359.0, 5364.785408583613, 5382.3895410726545, 5384.946150538444, 5387.895481426376, 4322.546132656152, 4774.0, 4830.227163653799, 4843.522725568735, 5170.043541944752], "value": 3885.565847629341, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.2916108924538, 690.4441924250978, 697.7243988624493, 699.8705239530684, 736.3924762071292, 14110.012299139064, 14381.209033503153, 14598.416063357454, 14789.334479948402, 14794.704105917875, 13066.803997940038, 13125.606231813037, 13145.145565538234, 13147.211167329968, 13157.802632960513, 663.489113382695, 681.2029924987752, 683.5249501596393, 683.7367613468825, 684.736376495048, 681.1655721740877, 685.1880521581917, 811.4603788480654, 841.0412130171609, 841.8484672758901, 792.9801754956114, 886.2024178239603, 896.5786080542135, 902.8781114549517, 903.4669544968467, 871.0462044061742, 890.3678388344283, 890.9777255568598, 892.6340200517757, 893.446063440671, 521.2494008627602, 642.0113025940046, 670.6311528659218, 671.3856821008771, 674.0765151742096, 12225.449854756469, 12385.318807465586, 12396.876031239663, 12403.25580465175, 12414.0, 12033.699157521045, 12166.57416990401, 13406.664833379145, 13533.526326578523, 13626.318684065856, 10029.69910902674, 12402.767501849663, 12403.689907752288, 12410.5656302029, 12573.656196116614, 4547.931781023254, 4594.586487216133, 4601.1595361855525, 4604.740728976405, 4830.9275360869615, 103879.0, 110444.0, 120812.0, 123161.38419307845, 123229.0, 10856.837147442795, 11205.551777928791, 11206.15953803468, 11428.25716328436, 11596.0, 20340.288089916783, 22388.0, 22401.66397504039, 22447.43881402978, 22482.437939051655, 7643.388528917673, 7656.9234307657325, 7775.377969762406, 7904.762857114296, 7945.960270198612, 4130.524989626171, 4144.294634689631, 4198.307279298881, 4200.600942910419, 4321.632661223831, 3395.168183794986, 3493.5458390409085, 3497.3529896969144, 3500.807455589916, 3506.8246587670515, 622.4273668225244, 625.4058644287944, 645.132297060453, 651.5211319680031, 651.8142329436088, 537.2263939926571, 572.4704648200398, 617.2592888533777, 618.2766163588612, 618.8576627375729, 8155.34757219421, 8738.563071846382, 8787.868181977276, 8856.202941735146, 9035.63857445708, 578.5429510686511, 606.0848119339829, 611.1260896917362, 623.1431781300712, 623.7692053940071, 4087.4890638670167, 4129.587041295907, 4148.191102734998, 4316.5467625899455, 4347.5217726050105, 83748.0, 92522.53738731264, 119679.40160299143, 129299.0, 164467.02030327555, 139917.0, 142574.28712856368, 143713.0, 146118.0, 146970.2651486757, 121538.39230803789, 121616.39191804161, 121747.0, 121838.0, 122149.38925305316, 7374.889376659462, 8092.109867914407, 8121.75634730959, 8254.0, 8392.622331994953, 62276.37723622706, 63528.0, 63691.0, 63707.72584548376, 67638.30904227434, 96303.0, 96977.0, 105627.47186264019, 106133.0, 107922.39005841345, 13954.58136255914, 14241.216733079365, 14484.420623174956, 14592.489262875748, 14655.193964332064, 14742.0, 14979.32593033338, 15022.023568468014, 15040.699186016438, 15050.0, 13761.655958601115, 14007.08953917992, 14170.220637865017, 14264.572062838131, 14625.414983400546, 13818.239996800272, 14262.928685356506, 14363.56909292723, 14394.928025359806, 14996.2501874908, 12652.620421387373, 13089.869101308865, 13137.789495760202, 13234.801477977646, 13308.866911330762, 11129.833052504056, 11411.942940285246, 11622.535098595961, 11694.590689325832, 11929.642110736691, 9297.814043719223, 10953.83569246478, 11528.297677186116, 11649.126315526539, 11758.823617645914], "value": 7662.9150864865105, "unit": "score"}], "name": "dromaeo_css", "value": 7050.504924861027, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 112.0, 114.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 101.0, 136.0, 180.0, 143.0, 108.0, 141.0, 143.0, 181.0, 114.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 185.0, 187.0, 183.0, 184.0, 183.0, 184.0, 185.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 87.0, 63.0, 58.0, 59.0, 59.0, 82.0, 57.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 123.0, 100.0, 69.0, 70.0, 94.0, 67.0, 70.0, 90.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 90.0, 87.0, 91.0, 88.0, 91.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 96.0, 94.0, 95.0, 117.0, 125.0, 95.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 84.0, 86.0, 87.0, 84.0, 85.0, 85.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 55.0, 56.0, 56.0, 53.0, 57.0, 56.0, 55.0, 55.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 68.0, 75.0, 72.0, 69.0, 72.0, 70.0, 70.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 77.0, 85.0, 94.0, 105.0, 111.0, 75.0, 97.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 140.0, 152.0, 153.0, 145.0, 144.0, 161.0, 165.0, 209.0, 157.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 172.0, 173.0, 172.0, 179.0, 173.0, 172.0, 170.0, 173.0, 173.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 69.0, 69.0, 67.0, 68.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1481.0999999999997, "alertThreshold": 2.0}]} 05:17:26 INFO - Return code: 0 05:17:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:17:26 INFO - # TBPL SUCCESS # 05:17:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:26 INFO - 2016-10-13 05:17:26.264 screenresolution[1670:10179] starting screenresolution argv=screenresolution get 05:17:26 INFO - 2016-10-13 05:17:26.267 screenresolution[1670:10179] Display 0: 1600x1200x32@60 05:17:26 INFO - 2016-10-13 05:17:26.273 screenresolution[1671:10182] starting screenresolution argv=screenresolution list 05:17:26 INFO - Available Modes on Display 0 05:17:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:26 INFO - Intel Iris: 05:17:26 INFO - Chipset Model: Intel Iris 05:17:26 INFO - Type: GPU 05:17:26 INFO - Bus: Built-In 05:17:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:26 INFO - Vendor: Intel (0x8086) 05:17:26 INFO - Device ID: 0x0a2e 05:17:26 INFO - Revision ID: 0x0009 05:17:26 INFO - Displays: 05:17:26 INFO - Display: 05:17:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:26 INFO - Display Serial Number: 1600x1200 60 05:17:26 INFO - Main Display: Yes 05:17:26 INFO - Mirror: Off 05:17:26 INFO - Online: Yes 05:17:26 INFO - Rotation: Supported 05:17:26 INFO - Return code: 0 05:17:26 INFO - Running post-action listener: _resource_record_post_action 05:17:26 INFO - [mozharness: 2016-10-13 12:17:26.509131Z] Finished run-tests step (success) 05:17:26 INFO - Running post-run listener: _resource_record_post_run 05:17:26 INFO - Total resource usage - Wall time: 1217s; CPU: 53.0%; Read bytes: 38470656; Write bytes: 710702592; Read time: 386; Write time: 2026 05:17:26 INFO - TinderboxPrint: CPU usage
53.4% 05:17:26 INFO - TinderboxPrint: I/O read bytes / time
38,470,656 / 386 05:17:26 INFO - TinderboxPrint: I/O write bytes / time
710,702,592 / 2,026 05:17:26 INFO - TinderboxPrint: CPU idle
2,269.5 (46.6%) 05:17:26 INFO - TinderboxPrint: CPU system
972.8 (20.0%) 05:17:26 INFO - TinderboxPrint: CPU user
1,627.4 (33.4%) 05:17:26 INFO - TinderboxPrint: Swap in / out
445,890,560 / 0 05:17:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393349632; Write bytes: 388128256; Read time: 29351; Write time: 763 05:17:26 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 37508096; Write bytes: 300189696; Read time: 371; Write time: 1213 05:17:27 INFO - Running post-run listener: _upload_blobber_files 05:17:27 INFO - Blob upload gear active. 05:17:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:17:27 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 05:17:27 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:17:27 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:17:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:17:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:17:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:17:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:17:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:17:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:17:28 INFO - (blobuploader) - INFO - Done attempting. 05:17:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:17:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:17:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:17:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:17:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:17:29 INFO - (blobuploader) - INFO - Done attempting. 05:17:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:17:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:17:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:17:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:17:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:17:31 INFO - (blobuploader) - INFO - Done attempting. 05:17:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:17:31 INFO - Return code: 0 05:17:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:17:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:17:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf5e42dc17bba5353d9e67031b2c59fdf970af1eaa106135de351582232eb71e7bdc536523794dfe6cc25d37c76e77686fcfd06899028f55df1586ec5e9b808d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d610a811c6f7336083c1e2a1899e02d23891be0c76491247a711bb83ed87c79c89b735c933f5cbaa6b299bbfc2962a45ba3bbe04d889c37cd380f7484d2e041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b602d6086613e3a50f7c9fce78687570a6f36303dc4c6423928b1fc246d5d3d2c7dc2f3850e00607fb02266c85ff5e23db89234d83bd172dc5da33897cf996ee"} 05:17:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:17:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:17:31 INFO - Contents: 05:17:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf5e42dc17bba5353d9e67031b2c59fdf970af1eaa106135de351582232eb71e7bdc536523794dfe6cc25d37c76e77686fcfd06899028f55df1586ec5e9b808d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d610a811c6f7336083c1e2a1899e02d23891be0c76491247a711bb83ed87c79c89b735c933f5cbaa6b299bbfc2962a45ba3bbe04d889c37cd380f7484d2e041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b602d6086613e3a50f7c9fce78687570a6f36303dc4c6423928b1fc246d5d3d2c7dc2f3850e00607fb02266c85ff5e23db89234d83bd172dc5da33897cf996ee"} 05:17:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:17:31 INFO - Copying logs to upload dir... 05:17:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:17:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.079564 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-13 05:17:32.272628) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:17:32.276628) ========= 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.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf5e42dc17bba5353d9e67031b2c59fdf970af1eaa106135de351582232eb71e7bdc536523794dfe6cc25d37c76e77686fcfd06899028f55df1586ec5e9b808d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d610a811c6f7336083c1e2a1899e02d23891be0c76491247a711bb83ed87c79c89b735c933f5cbaa6b299bbfc2962a45ba3bbe04d889c37cd380f7484d2e041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b602d6086613e3a50f7c9fce78687570a6f36303dc4c6423928b1fc246d5d3d2c7dc2f3850e00607fb02266c85ff5e23db89234d83bd172dc5da33897cf996ee"} build_url:https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010857 build_url: 'https://queue.taskcluster.net/v1/task/CwkeBvFIRUS_Ud3O6zX0hQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf5e42dc17bba5353d9e67031b2c59fdf970af1eaa106135de351582232eb71e7bdc536523794dfe6cc25d37c76e77686fcfd06899028f55df1586ec5e9b808d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d610a811c6f7336083c1e2a1899e02d23891be0c76491247a711bb83ed87c79c89b735c933f5cbaa6b299bbfc2962a45ba3bbe04d889c37cd380f7484d2e041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b602d6086613e3a50f7c9fce78687570a6f36303dc4c6423928b1fc246d5d3d2c7dc2f3850e00607fb02266c85ff5e23db89234d83bd172dc5da33897cf996ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:17:32.310415) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:17:32.310804) ========= 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.axtTVV7ftW/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.xHh78ZaaWb/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005298 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:17:32.360040) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:17:32.363391) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:17:32.363709) ========= ========= Total master_lag: 0.44 =========