builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0113 starttime: 1483581528.88 results: success (0) buildid: 20170104154638 builduid: abf349ad6f124b20a910cf66a05fbeab revision: 934c375788011ec2d6ab60a019657acdce054e6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:48.885645) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:48.886100) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:48.886376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:48.916316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:48.916709) ========= 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.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 --2017-01-04 17:58:48-- 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% 159M=0s 2017-01-04 17:58:49 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:49.052667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:49.052977) ========= 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.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.026281 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:49.101252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 17:58:49.101604) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 934c375788011ec2d6ab60a019657acdce054e6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 934c375788011ec2d6ab60a019657acdce054e6f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 2017-01-04 17:58:49,196 truncating revision to first 12 chars 2017-01-04 17:58:49,196 Setting DEBUG logging. 2017-01-04 17:58:49,196 attempt 1/10 2017-01-04 17:58:49,196 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/934c37578801?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 17:58:49,999 unpacking tar archive at: mozilla-aurora-934c37578801/testing/mozharness/ program finished with exit code 0 elapsedTime=1.070051 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 17:58:50.195242) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:50.195582) ========= script_repo_revision: 934c375788011ec2d6ab60a019657acdce054e6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:50.195959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:50.196229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 17:58:50.223090) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 17:58:50.223462) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:58:50 INFO - MultiFileLogger online at 20170104 17:58:50 in /builds/slave/test 17:58:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:58:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:58:50 INFO - {'append_to_log': False, 17:58:50 INFO - 'base_work_dir': '/builds/slave/test', 17:58:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:58:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:58:50 INFO - 'branch': 'Mozilla-Aurora', 17:58:50 INFO - 'buildbot_json_path': 'buildprops.json', 17:58:50 INFO - 'config_files': ('talos/mac_config.py',), 17:58:50 INFO - 'default_actions': ('clobber', 17:58:50 INFO - 'read-buildbot-config', 17:58:50 INFO - 'download-and-extract', 17:58:50 INFO - 'populate-webroot', 17:58:50 INFO - 'create-virtualenv', 17:58:50 INFO - 'install', 17:58:50 INFO - 'run-tests'), 17:58:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:58:50 INFO - 'download_minidump_stackwalk': True, 17:58:50 INFO - 'download_symbols': 'ondemand', 17:58:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:58:50 INFO - 'tooltool.py': '/tools/tooltool.py', 17:58:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:58:50 INFO - '/tools/misc-python/virtualenv.py')}, 17:58:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:58:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:58:50 INFO - 'installer_path': 'installer.exe', 17:58:50 INFO - 'log_level': 'info', 17:58:50 INFO - 'log_name': 'talos', 17:58:50 INFO - 'log_to_console': True, 17:58:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:58:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:58:50 INFO - 'opt_config_files': (), 17:58:50 INFO - 'pip_index': False, 17:58:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:58:50 INFO - 'cmd': ('bash', 17:58:50 INFO - '-c', 17:58:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:58:50 INFO - 'enabled': True, 17:58:50 INFO - 'halt_on_failure': False, 17:58:50 INFO - 'name': 'check_screen_resolution'},), 17:58:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:58:50 INFO - 'cmd': ('bash', 17:58:50 INFO - '-c', 17:58:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:58:50 INFO - 'enabled': True, 17:58:50 INFO - 'halt_on_failure': False, 17:58:50 INFO - 'name': 'check_screen_resolution'},), 17:58:50 INFO - 'run_cmd_checks_enabled': True, 17:58:50 INFO - 'sps_profile': False, 17:58:50 INFO - 'sps_profile_interval': 0, 17:58:50 INFO - 'suite': 'dromaeojs', 17:58:50 INFO - 'system_bits': '32', 17:58:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:58:50 INFO - 'title': 't-yosemite-r7-0113', 17:58:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:58:50 INFO - 'use_talos_json': True, 17:58:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:58:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:58:50 INFO - 'work_dir': 'build'} 17:58:50 INFO - [mozharness: 2017-01-05 01:58:50.430330Z] Running clobber step. 17:58:50 INFO - Running pre-action listener: _resource_record_pre_action 17:58:50 INFO - Running main action method: clobber 17:58:50 INFO - rmtree: /builds/slave/test/build 17:58:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:58:52 INFO - Running post-action listener: _resource_record_post_action 17:58:52 INFO - [mozharness: 2017-01-05 01:58:52.746530Z] Finished clobber step (success) 17:58:52 INFO - [mozharness: 2017-01-05 01:58:52.746721Z] Running read-buildbot-config step. 17:58:52 INFO - Running pre-action listener: _resource_record_pre_action 17:58:52 INFO - Running main action method: read_buildbot_config 17:58:52 INFO - Using buildbot properties: 17:58:52 INFO - { 17:58:52 INFO - "project": "", 17:58:52 INFO - "product": "firefox", 17:58:52 INFO - "script_repo_revision": "production", 17:58:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:58:52 INFO - "repository": "", 17:58:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:58:52 INFO - "buildid": "20170104154638", 17:58:52 INFO - "pgo_build": "False", 17:58:52 INFO - "basedir": "/builds/slave/test", 17:58:52 INFO - "buildnumber": 17, 17:58:52 INFO - "slavename": "t-yosemite-r7-0113", 17:58:52 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:58:52 INFO - "platform": "yosemite_r7", 17:58:52 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:58:52 INFO - "branch": "mozilla-aurora", 17:58:52 INFO - "repo_path": "releases/mozilla-aurora", 17:58:52 INFO - "revision": "934c375788011ec2d6ab60a019657acdce054e6f", 17:58:52 INFO - "stage_platform": "macosx64", 17:58:52 INFO - "builduid": "abf349ad6f124b20a910cf66a05fbeab", 17:58:52 INFO - "slavebuilddir": "test" 17:58:52 INFO - } 17:58:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 17:58:52 INFO - Running post-action listener: _resource_record_post_action 17:58:52 INFO - [mozharness: 2017-01-05 01:58:52.750081Z] Finished read-buildbot-config step (success) 17:58:52 INFO - [mozharness: 2017-01-05 01:58:52.750215Z] Running download-and-extract step. 17:58:52 INFO - Running pre-action listener: _resource_record_pre_action 17:58:52 INFO - Running main action method: download_and_extract 17:58:52 INFO - mkdir: /builds/slave/test/build/tests 17:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:52 INFO - trying https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 17:58:52 INFO - Downloading https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 17:58:54 INFO - Downloaded 1199 bytes. 17:58:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:58:54 INFO - Using the following test package requirements: 17:58:54 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:58:54 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 17:58:54 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 17:58:54 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'jsshell-mac.zip'], 17:58:54 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 17:58:54 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:58:54 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 17:58:54 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 17:58:54 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 17:58:54 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:58:54 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 17:58:54 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:58:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:58:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:58:54 INFO - Fetch https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:58:54 INFO - Http code: 200 17:58:54 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 17:58:54 INFO - x-amz-cf-id: NIBJznMYhOyOGTLbJXTSArQ7vCiiHnaVXEBAoPH9xbMuZQwpN0ZwDg== 17:58:54 INFO - x-amz-version-id: w2XOFX2piX6YpugoD_TETrJ8PjxOdXvy 17:58:54 INFO - Expected file size: 16269691 17:58:54 INFO - Obtained file size: 16269691 17:59:01 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:59:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:59:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:59:01 INFO - Fetch https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:59:02 INFO - Http code: 200 17:59:02 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 17:59:02 INFO - x-amz-cf-id: Kw5P6dN3EWc1924uutth6Mo_8ev9GpSWTB-bZ1JAvZK7N2SDNhCo7A== 17:59:02 INFO - x-amz-version-id: w2XOFX2piX6YpugoD_TETrJ8PjxOdXvy 17:59:02 INFO - Expected file size: 16269691 17:59:02 INFO - Obtained file size: 16269691 17:59:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 17:59:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:59:08 INFO - Fetch https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 17:59:09 INFO - Http code: 200 17:59:09 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 17:59:09 INFO - x-amz-cf-id: ctoSku1G4z3Af8HrJm3o7-0cLSJppIrV7yHs0aKKH0e-vw-VqHj0ng== 17:59:09 INFO - x-amz-version-id: pHtW2.fJAE2u1kNDoI3nv1e2yvmuU4Qa 17:59:09 INFO - Expected file size: 14409038 17:59:09 INFO - Obtained file size: 14409038 17:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:10 INFO - trying https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:59:10 INFO - Downloading https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 17:59:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 17:59:13 INFO - Downloaded 109840837 bytes. 17:59:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:59:13 INFO - mkdir: /builds/slave/test/properties 17:59:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:59:13 INFO - Writing to file /builds/slave/test/properties/build_url 17:59:13 INFO - Contents: 17:59:13 INFO - build_url:https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:59:13 INFO - Running post-action listener: _resource_record_post_action 17:59:13 INFO - Running post-action listener: set_extra_try_arguments 17:59:13 INFO - [mozharness: 2017-01-05 01:59:13.788106Z] Finished download-and-extract step (success) 17:59:13 INFO - [mozharness: 2017-01-05 01:59:13.788270Z] Running populate-webroot step. 17:59:13 INFO - Running pre-action listener: _resource_record_pre_action 17:59:13 INFO - Running main action method: populate_webroot 17:59:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:59:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:59:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:59:13 INFO - '--tppagecycles', 17:59:13 INFO - '7'], 17:59:13 INFO - 'tests': ['tsvgm']}}, 17:59:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:59:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:59:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:59:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:59:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['damp', 'tps']}, 17:59:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'tests': ['damp', 'tps']}, 17:59:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['dromaeo_dom']}, 17:59:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:59:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['basic_compositor_video']}, 17:59:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:59:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'other-e10s': {'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['a11yr', 17:59:13 INFO - 'ts_paint', 17:59:13 INFO - 'tpaint', 17:59:13 INFO - 'sessionrestore', 17:59:13 INFO - 'sessionrestore_no_auto_restore', 17:59:13 INFO - 'tabpaint']}, 17:59:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['tsvgx', 17:59:13 INFO - 'tsvgr_opacity', 17:59:13 INFO - 'tart', 17:59:13 INFO - 'tscrollx', 17:59:13 INFO - 'cart']}, 17:59:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:59:13 INFO - 'tsvgr_opacity', 17:59:13 INFO - 'tart', 17:59:13 INFO - 'tscrollx', 17:59:13 INFO - 'cart']}, 17:59:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'talos_options': ['--disable-e10s'], 17:59:13 INFO - 'tests': ['tp5o']}, 17:59:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'tests': ['tp5o']}, 17:59:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'talos_options': ['--disable-e10s', 17:59:13 INFO - '--xperf_path', 17:59:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:13 INFO - 'tests': ['tp5n']}, 17:59:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:13 INFO - 'talos_options': ['--xperf_path', 17:59:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:13 INFO - 'tests': ['tp5n']}}, 17:59:13 INFO - 'talos.zip': {'path': '', 17:59:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:59:13 INFO - Running post-action listener: _resource_record_post_action 17:59:13 INFO - [mozharness: 2017-01-05 01:59:13.806796Z] Finished populate-webroot step (success) 17:59:13 INFO - [mozharness: 2017-01-05 01:59:13.806906Z] Running create-virtualenv step. 17:59:13 INFO - Running pre-action listener: _resource_record_pre_action 17:59:13 INFO - Running main action method: create_virtualenv 17:59:13 INFO - Creating virtualenv /builds/slave/test/build/venv 17:59:13 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 17:59:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:59:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:59:13 INFO - Using real prefix '/tools/python27' 17:59:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:59:14 INFO - Installing distribute.............................................................................................................................................................................................done. 17:59:17 INFO - Installing pip.................done. 17:59:17 INFO - Return code: 0 17:59:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:59:17 INFO - Reading from file tmpfile_stdout 17:59:17 INFO - Output received: 17:59:17 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) 17:59:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:59:17 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')]} 17:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:17 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')]} 17:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:17 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:17 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 17:59:17 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 17:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:17 INFO - 'HOME': '/Users/cltbld', 17:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:17 INFO - 'LOGNAME': 'cltbld', 17:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:17 INFO - 'MOZ_NO_REMOTE': '1', 17:59:17 INFO - 'NO_EM_RESTART': '1', 17:59:17 INFO - 'PAGER': '/bin/cat', 17:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:17 INFO - 'PWD': '/builds/slave/test', 17:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:17 INFO - 'SHELL': '/bin/bash', 17:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:17 INFO - 'USER': 'cltbld', 17:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:17 INFO - 'XPC_FLAGS': '0x0', 17:59:17 INFO - 'XPC_SERVICE_NAME': '0', 17:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:18 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 17:59:18 INFO - Cleaning up... 17:59:18 INFO - Return code: 0 17:59:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:59:18 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')]} 17:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:18 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')]} 17:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:18 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:18 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 17:59:18 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 17:59:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:18 INFO - 'HOME': '/Users/cltbld', 17:59:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:18 INFO - 'LOGNAME': 'cltbld', 17:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:18 INFO - 'MOZ_NO_REMOTE': '1', 17:59:18 INFO - 'NO_EM_RESTART': '1', 17:59:18 INFO - 'PAGER': '/bin/cat', 17:59:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:18 INFO - 'PWD': '/builds/slave/test', 17:59:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:18 INFO - 'SHELL': '/bin/bash', 17:59:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:18 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:18 INFO - 'USER': 'cltbld', 17:59:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:18 INFO - 'XPC_FLAGS': '0x0', 17:59:18 INFO - 'XPC_SERVICE_NAME': '0', 17:59:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:18 INFO - Downloading/unpacking psutil>=3.1.1 17:59:18 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 17:59:18 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 17:59:18 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 17:59:18 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 17:59:18 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 17:59:18 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 17:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:59:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:59:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:59:22 INFO - Installing collected packages: psutil 17:59:22 INFO - Running setup.py install for psutil 17:59:22 INFO - building 'psutil._psutil_osx' extension 17:59:22 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 17:59:23 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 17:59:23 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 17:59:23 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 17:59:23 INFO - building 'psutil._psutil_posix' extension 17:59:23 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 17:59:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:59:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:59:23 INFO - ^ 17:59:23 INFO - 1 warning generated. 17:59:23 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 17:59:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:59:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:59:23 INFO - Successfully installed psutil 17:59:23 INFO - Cleaning up... 17:59:23 INFO - Return code: 0 17:59:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:59:23 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')]} 17:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:23 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')]} 17:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:23 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:23 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 17:59:23 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 17:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:23 INFO - 'HOME': '/Users/cltbld', 17:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:23 INFO - 'LOGNAME': 'cltbld', 17:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:23 INFO - 'MOZ_NO_REMOTE': '1', 17:59:23 INFO - 'NO_EM_RESTART': '1', 17:59:23 INFO - 'PAGER': '/bin/cat', 17:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:23 INFO - 'PWD': '/builds/slave/test', 17:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:23 INFO - 'SHELL': '/bin/bash', 17:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:23 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:23 INFO - 'USER': 'cltbld', 17:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:23 INFO - 'XPC_FLAGS': '0x0', 17:59:23 INFO - 'XPC_SERVICE_NAME': '0', 17:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:59:23 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 17:59:23 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 17:59:23 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 17:59:23 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 17:59:23 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 17:59:23 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 17:59:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:59:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:59:26 INFO - Installing collected packages: mozsystemmonitor 17:59:26 INFO - Running setup.py install for mozsystemmonitor 17:59:26 INFO - Successfully installed mozsystemmonitor 17:59:26 INFO - Cleaning up... 17:59:26 INFO - Return code: 0 17:59:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:26 INFO - 'HOME': '/Users/cltbld', 17:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:26 INFO - 'LOGNAME': 'cltbld', 17:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:26 INFO - 'MOZ_NO_REMOTE': '1', 17:59:26 INFO - 'NO_EM_RESTART': '1', 17:59:26 INFO - 'PAGER': '/bin/cat', 17:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:26 INFO - 'PWD': '/builds/slave/test', 17:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:26 INFO - 'SHELL': '/bin/bash', 17:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:26 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:26 INFO - 'USER': 'cltbld', 17:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:26 INFO - 'XPC_FLAGS': '0x0', 17:59:26 INFO - 'XPC_SERVICE_NAME': '0', 17:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:27 INFO - Downloading/unpacking jsonschema==2.5.1 17:59:27 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 17:59:27 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 17:59:27 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 17:59:27 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 17:59:27 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 17:59:27 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 17:59:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:59:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:30 INFO - Downloading functools32-3.2.3-2.tar.gz 17:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:59:30 INFO - warning: no files found matching '*.txt' 17:59:30 INFO - no previously-included directories found matching 'build' 17:59:30 INFO - no previously-included directories found matching 'dist' 17:59:30 INFO - no previously-included directories found matching '.git*' 17:59:30 INFO - Installing collected packages: jsonschema, functools32 17:59:30 INFO - Running setup.py install for functools32 17:59:30 INFO - warning: no files found matching '*.txt' 17:59:30 INFO - no previously-included directories found matching 'build' 17:59:30 INFO - no previously-included directories found matching 'dist' 17:59:30 INFO - no previously-included directories found matching '.git*' 17:59:30 INFO - Successfully installed jsonschema functools32 17:59:30 INFO - Cleaning up... 17:59:30 INFO - Return code: 0 17:59:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:30 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:30 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 17:59:30 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 17:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:30 INFO - 'HOME': '/Users/cltbld', 17:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:30 INFO - 'LOGNAME': 'cltbld', 17:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:30 INFO - 'MOZ_NO_REMOTE': '1', 17:59:30 INFO - 'NO_EM_RESTART': '1', 17:59:30 INFO - 'PAGER': '/bin/cat', 17:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:30 INFO - 'PWD': '/builds/slave/test', 17:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:30 INFO - 'SHELL': '/bin/bash', 17:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:30 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:30 INFO - 'USER': 'cltbld', 17:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:30 INFO - 'XPC_FLAGS': '0x0', 17:59:30 INFO - 'XPC_SERVICE_NAME': '0', 17:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:59:30 INFO - Cleaning up... 17:59:30 INFO - Return code: 0 17:59:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:30 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:30 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 17:59:30 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 17:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:30 INFO - 'HOME': '/Users/cltbld', 17:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:30 INFO - 'LOGNAME': 'cltbld', 17:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:30 INFO - 'MOZ_NO_REMOTE': '1', 17:59:30 INFO - 'NO_EM_RESTART': '1', 17:59:30 INFO - 'PAGER': '/bin/cat', 17:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:30 INFO - 'PWD': '/builds/slave/test', 17:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:30 INFO - 'SHELL': '/bin/bash', 17:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:30 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:30 INFO - 'USER': 'cltbld', 17:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:30 INFO - 'XPC_FLAGS': '0x0', 17:59:30 INFO - 'XPC_SERVICE_NAME': '0', 17:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:31 INFO - Downloading/unpacking blobuploader==1.2.4 17:59:31 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 17:59:31 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 17:59:31 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 17:59:31 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 17:59:31 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 17:59:31 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 17:59:33 INFO - Downloading blobuploader-1.2.4.tar.gz 17:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:59:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:59:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:59:34 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 17:59:34 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 17:59:34 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 17:59:34 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 17:59:34 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 17:59:34 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 17:59:34 INFO - Downloading docopt-0.6.1.tar.gz 17:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:59:34 INFO - Installing collected packages: blobuploader, requests, docopt 17:59:34 INFO - Running setup.py install for blobuploader 17:59:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:59:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:59:35 INFO - Running setup.py install for requests 17:59:35 INFO - Running setup.py install for docopt 17:59:35 INFO - Successfully installed blobuploader requests docopt 17:59:35 INFO - Cleaning up... 17:59:35 INFO - Return code: 0 17:59:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:35 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:35 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 17:59:35 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 17:59:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:35 INFO - 'HOME': '/Users/cltbld', 17:59:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:35 INFO - 'LOGNAME': 'cltbld', 17:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:35 INFO - 'MOZ_NO_REMOTE': '1', 17:59:35 INFO - 'NO_EM_RESTART': '1', 17:59:35 INFO - 'PAGER': '/bin/cat', 17:59:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:35 INFO - 'PWD': '/builds/slave/test', 17:59:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:35 INFO - 'SHELL': '/bin/bash', 17:59:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:35 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:35 INFO - 'USER': 'cltbld', 17:59:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:35 INFO - 'XPC_FLAGS': '0x0', 17:59:35 INFO - 'XPC_SERVICE_NAME': '0', 17:59:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ThzsGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-oQyyCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-LQRix7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-J4MTXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-AaJFrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-VBL7x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-H0Vfsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-2kPKgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:59:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:59:36 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-016ZVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Oj2Z7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-f_VtIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-8RP0MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7QtZpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-0rpHxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-4BAzHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-iYqy4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:59:37 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-JaNmP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:59:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:59:37 INFO - Running setup.py install for manifestparser 17:59:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:59:37 INFO - Running setup.py install for mozcrash 17:59:38 INFO - Running setup.py install for mozdebug 17:59:38 INFO - Running setup.py install for mozdevice 17:59:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:59:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:59:38 INFO - Running setup.py install for mozfile 17:59:38 INFO - Running setup.py install for mozhttpd 17:59:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:59:38 INFO - Running setup.py install for mozinfo 17:59:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:59:38 INFO - Running setup.py install for mozInstall 17:59:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Running setup.py install for mozleak 17:59:39 INFO - Running setup.py install for mozlog 17:59:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Running setup.py install for moznetwork 17:59:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Running setup.py install for mozprocess 17:59:39 INFO - Running setup.py install for mozprofile 17:59:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Running setup.py install for mozrunner 17:59:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:59:39 INFO - Running setup.py install for mozscreenshot 17:59:40 INFO - Running setup.py install for moztest 17:59:40 INFO - Running setup.py install for mozversion 17:59:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:59:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:59:40 INFO - Cleaning up... 17:59:40 INFO - Return code: 0 17:59:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:40 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:40 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 17:59:40 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 17:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:40 INFO - 'HOME': '/Users/cltbld', 17:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:40 INFO - 'LOGNAME': 'cltbld', 17:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:40 INFO - 'MOZ_NO_REMOTE': '1', 17:59:40 INFO - 'NO_EM_RESTART': '1', 17:59:40 INFO - 'PAGER': '/bin/cat', 17:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:40 INFO - 'PWD': '/builds/slave/test', 17:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:40 INFO - 'SHELL': '/bin/bash', 17:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:40 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:40 INFO - 'USER': 'cltbld', 17:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:40 INFO - 'XPC_FLAGS': '0x0', 17:59:40 INFO - 'XPC_SERVICE_NAME': '0', 17:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:59:40 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wKu7tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:59:40 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)) 17:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:59:40 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-b7Umj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:59:40 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-liswsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-yiXdGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-v1u8fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-8fhHPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-NzVrVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-3zy4xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-fOgpwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-orHszW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-KTSMAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-yKtS6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:59:41 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)) 17:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:59:41 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-HNMmJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:59:42 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)) 17:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:59:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-YK2y0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-dWxssR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:42 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)) 17:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:59:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-XE8Hsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:59:42 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-bwwAwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:59:42 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)) 17:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:59:42 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)) 17:59:42 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)) 17:59:42 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)) 17:59:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:59:42 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 17:59:42 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 17:59:42 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 17:59:42 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 17:59:42 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 17:59:42 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 17:59:45 INFO - Downloading blessings-1.6.tar.gz 17:59:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:59:45 INFO - Installing collected packages: blessings 17:59:45 INFO - Running setup.py install for blessings 17:59:45 INFO - Successfully installed blessings 17:59:45 INFO - Cleaning up... 17:59:45 INFO - Return code: 0 17:59:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:59:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:59:45 INFO - Reading from file tmpfile_stdout 17:59:45 INFO - Current package versions: 17:59:45 INFO - blessings == 1.6 17:59:45 INFO - blobuploader == 1.2.4 17:59:45 INFO - docopt == 0.6.1 17:59:45 INFO - functools32 == 3.2.3-2 17:59:45 INFO - jsonschema == 2.5.1 17:59:45 INFO - manifestparser == 1.1 17:59:45 INFO - mozInstall == 1.12 17:59:45 INFO - mozcrash == 1.0 17:59:45 INFO - mozdebug == 0.1 17:59:45 INFO - mozdevice == 0.48 17:59:45 INFO - mozfile == 1.2 17:59:45 INFO - mozhttpd == 0.7 17:59:45 INFO - mozinfo == 0.9 17:59:45 INFO - mozleak == 0.1 17:59:45 INFO - mozlog == 3.4 17:59:45 INFO - moznetwork == 0.27 17:59:45 INFO - mozprocess == 0.23 17:59:45 INFO - mozprofile == 0.28 17:59:45 INFO - mozrunner == 6.13 17:59:45 INFO - mozscreenshot == 0.1 17:59:45 INFO - mozsystemmonitor == 0.3 17:59:45 INFO - moztest == 0.8 17:59:45 INFO - mozversion == 1.4 17:59:45 INFO - psutil == 3.1.1 17:59:45 INFO - requests == 1.2.3 17:59:45 INFO - wsgiref == 0.1.2 17:59:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:45 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 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59:45 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 17:59:45 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 17:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:45 INFO - 'HOME': '/Users/cltbld', 17:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:45 INFO - 'LOGNAME': 'cltbld', 17:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:45 INFO - 'MOZ_NO_REMOTE': '1', 17:59:45 INFO - 'NO_EM_RESTART': '1', 17:59:45 INFO - 'PAGER': '/bin/cat', 17:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:45 INFO - 'PWD': '/builds/slave/test', 17:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:45 INFO - 'SHELL': '/bin/bash', 17:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:45 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:45 INFO - 'USER': 'cltbld', 17:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:45 INFO - 'XPC_FLAGS': '0x0', 17:59:45 INFO - 'XPC_SERVICE_NAME': '0', 17:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 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)) 17:59:46 INFO - Cleaning up... 17:59:46 INFO - Return code: 0 17:59:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:59: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')]} 17:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59: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')]} 17:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1088c7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89bbd84730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, '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.brSGuQeXEQ/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.merLiZzVSy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 17:59: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', 'jsonschema'] in /builds/slave/test/build 17:59: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 jsonschema 17:59:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 17:59:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:46 INFO - 'HOME': '/Users/cltbld', 17:59:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:46 INFO - 'LOGNAME': 'cltbld', 17:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:46 INFO - 'MOZ_NO_REMOTE': '1', 17:59:46 INFO - 'NO_EM_RESTART': '1', 17:59:46 INFO - 'PAGER': '/bin/cat', 17:59:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:46 INFO - 'PWD': '/builds/slave/test', 17:59:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:46 INFO - 'SHELL': '/bin/bash', 17:59:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 17:59:46 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 17:59:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:46 INFO - 'USER': 'cltbld', 17:59:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:46 INFO - 'XPC_FLAGS': '0x0', 17:59:46 INFO - 'XPC_SERVICE_NAME': '0', 17:59:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:59:46 INFO - Cleaning up... 17:59:46 INFO - Return code: 0 17:59:46 INFO - Running post-action listener: _resource_record_post_action 17:59:46 INFO - Running post-action listener: _start_resource_monitoring 17:59:46 INFO - Starting resource monitoring. 17:59:46 INFO - [mozharness: 2017-01-05 01:59:46.319994Z] Finished create-virtualenv step (success) 17:59:46 INFO - [mozharness: 2017-01-05 01:59:46.321007Z] Running install step. 17:59:46 INFO - Running pre-action listener: _resource_record_pre_action 17:59:46 INFO - Running main action method: install 17:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:59:46 INFO - Reading from file tmpfile_stdout 17:59:46 INFO - Detecting whether we're running mozinstall >=1.0... 17:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:59:46 INFO - Reading from file tmpfile_stdout 17:59:46 INFO - Output received: 17:59:46 INFO - Usage: mozinstall [options] installer 17:59:46 INFO - Options: 17:59:46 INFO - -h, --help show this help message and exit 17:59:46 INFO - -d DEST, --destination=DEST 17:59:46 INFO - Directory to install application into. [default: 17:59:46 INFO - "/builds/slave/test"] 17:59:46 INFO - --app=APP Application being installed. [default: firefox] 17:59:46 INFO - mkdir: /builds/slave/test/build/application 17:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:00:17 INFO - Reading from file tmpfile_stdout 18:00:17 INFO - Output received: 18:00:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:00:17 INFO - Running post-action listener: _resource_record_post_action 18:00:17 INFO - [mozharness: 2017-01-05 02:00:17.722249Z] Finished install step (success) 18:00:17 INFO - [mozharness: 2017-01-05 02:00:17.722395Z] Running run-tests step. 18:00:17 INFO - Running pre-action listener: _resource_record_pre_action 18:00:17 INFO - Running main action method: run_tests 18:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:00:17 INFO - 2017-01-04 18:00:17.737 screenresolution[1627:8178] starting screenresolution argv=screenresolution get 18:00:17 INFO - 2017-01-04 18:00:17.740 screenresolution[1627:8178] Display 0: 1600x1200x32@60 18:00:17 INFO - 2017-01-04 18:00:17.745 screenresolution[1628:8182] starting screenresolution argv=screenresolution list 18:00:17 INFO - Available Modes on Display 0 18:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:00:17 INFO - Intel Iris: 18:00:17 INFO - Chipset Model: Intel Iris 18:00:17 INFO - Type: GPU 18:00:17 INFO - Bus: Built-In 18:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 18:00:17 INFO - Vendor: Intel (0x8086) 18:00:17 INFO - Device ID: 0x0a2e 18:00:17 INFO - Revision ID: 0x0009 18:00:17 INFO - Displays: 18:00:17 INFO - Display: 18:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:00:17 INFO - Display Serial Number: 1600x1200 60 18:00:17 INFO - Main Display: Yes 18:00:17 INFO - Mirror: Off 18:00:17 INFO - Online: Yes 18:00:17 INFO - Rotation: Supported 18:00:17 INFO - Return code: 0 18:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:00:17 INFO - Python 2.7.3 18:00:17 INFO - Return code: 0 18:00:17 INFO - grabbing minidump binary from tooltool 18:00:17 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')]} 18:00:17 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 0x1086dbd30>, '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 0x108541db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086d3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:00:17 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 18:00:17 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 18:00:17 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 18:00:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:00:18 INFO - Return code: 0 18:00:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:00:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:00:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:00:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:00:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 18:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brSGuQeXEQ/Render', 18:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:00:18 INFO - 'HOME': '/Users/cltbld', 18:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:00:18 INFO - 'LOGNAME': 'cltbld', 18:00:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:00:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:18 INFO - 'MOZ_NO_REMOTE': '1', 18:00:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:00:18 INFO - 'NO_EM_RESTART': '1', 18:00:18 INFO - 'PAGER': '/bin/cat', 18:00:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:18 INFO - 'PWD': '/builds/slave/test', 18:00:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:00:18 INFO - 'SHELL': '/bin/bash', 18:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.merLiZzVSy/Listeners', 18:00:18 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:00:18 INFO - 'USER': 'cltbld', 18:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:18 INFO - 'XPC_FLAGS': '0x0', 18:00:18 INFO - 'XPC_SERVICE_NAME': '0', 18:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:00:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:00:18 INFO - mozversion application_buildid: 20170104154638 18:00:18 INFO - mozversion application_changeset: 934c375788011ec2d6ab60a019657acdce054e6f 18:00:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:00:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:00:18 INFO - mozversion application_name: Firefox 18:00:18 INFO - mozversion application_remotingname: firefox-dev 18:00:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:00:18 INFO - mozversion application_vendor: Mozilla 18:00:18 INFO - mozversion application_version: 52.0a2 18:00:18 INFO - mozversion platform_buildid: 20170104154638 18:00:18 INFO - mozversion platform_changeset: 934c375788011ec2d6ab60a019657acdce054e6f 18:00:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:00:18 INFO - mozversion platform_version: 52.0a2 18:00:18 INFO - using testdate: 1483581618 18:00:18 INFO - actual date: 1483581618 18:00:18 INFO - starting webserver on 'localhost:49238' 18:00:18 INFO - SUITE-START | Running 2 tests 18:00:18 INFO - TEST-START | dromaeo_css 18:00:18 INFO - operating with platform_type : mac_ 18:00:18 INFO - Initialising browser for dromaeo_css test... 18:00:18 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpe3V70k/profile http://localhost:49238/getInfo.html) 18:00:20 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 18:00:20 INFO - PROCESS | 1638 | colorDepth:24 18:00:20 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 18:00:20 INFO - PROCESS | 1638 | __metrics 18:00:21 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:00:21 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:00:21 INFO - TEST-INFO | 1638: exit 0 18:00:21 INFO - Browser initialized. 18:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 18:00:21 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpe3V70k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:00:34 INFO - PROCESS | 1641 | RSS: Main: 222982144 18:00:34 INFO - PROCESS | 1641 | 18:03:37 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 18:03:41 INFO - PROCESS | 1641 | RSS: Main: 506146816 18:03:41 INFO - PROCESS | 1641 | 18:06:44 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 18:06:45 INFO - PROCESS | 1641 | RSS: Main: 349024256 18:06:45 INFO - PROCESS | 1641 | 18:09:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 18:09:48 INFO - PROCESS | 1641 | RSS: Main: 317104128 18:09:48 INFO - PROCESS | 1641 | 18:12:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 18:12:51 INFO - PROCESS | 1641 | RSS: Main: 318537728 18:12:51 INFO - PROCESS | 1641 | 18:15:54 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 18:15:55 INFO - PROCESS | 1641 | RSS: Main: 312639488 18:15:55 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 18:18:58 INFO - PROCESS | 1641 | RSS: Main: 435761152 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | __start_tp_report 18:18:58 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 18:18:58 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 18:18:58 INFO - PROCESS | 1641 | |i|pagename|runs| 18:18:58 INFO - PROCESS | 1641 | |0;dojo.html;2792.4554711831192;3303.834808259587;3355.697987181153;3366.20894089889;3390.084677137173;5668.603197776158;5696.971515142429;5754.395788442216;5758.5393168546525;5768.942310576898;5687.459691329327;5761.798337058204;5774.335951365594;5775.9892018896735;5781.536448056589;5232.92150617741;5248.501392367738;5259.421463638997;5288.0745869472885;5315.867103322428;5133.3326667533165;5173.991071741012;5188.247704082905;5225.6603320784025;5342.439043900393;5262.684238945656;5264.736763161845;5269.077911365515;5276.894462110756;5280.498352656491;5250.18622113573;5251.159814429673;5253.028189784883;5260.105782017066;5260.8684782880355;5114.33513643224;5212.713300768456;5213.687178769286;5217.217417387422;5245.0558899398475;5759.827205183851;5763.942360576424;5774.942250577483;5784.595078344518;5784.68184249866;6138.5416718686565;6157.599756015845;6179.104029915637;6179.351168127372;6184.8763024739255;6241.313455519889;6249.750009999594;6272.1219029335925;6317.652529110897;6325.873482530324;5281.762320695578;5503.724813759296;5744.253247077895;5797.768089276424;5800.680962547058;6263.6241825490615;6269.122322874801;6290.308066112724;6297.4962003039645;6331.2085989251345;6002.6698531580305;6042.516598672096;6132.601380910316;6135.171751813448;6156.230471191101;6245.6252624842655;6267.4045965633195;6301.873962520724;6307.05394190875;6309.274433440213;5861.325947516005;6257.311695713468;6261.5616906816085;6281.214848143982;6294.748210071546;5349.732513374316;6154.353792851775;6181.165542651775;6183.96908015457;6218.844528886814;4908.533689299511;4919.729414882144;4937.136001199848;4941.728204948762;4945.480724523945;4425.468943726773;4432.224360736858;4437.134758722083;4447.6219521341045;4463.308187230984;5580.469855363734;5625.353084395347;5625.578081643893;5677.659340439587;5721.57088218377;6218.875622487525;6223.1287619733275;6283.6858157091965;6284.402981716729;6312.652804095727;5758.481736643722;5761.251037365116;5769.68266745333;5776.989026920272;5793.333766616893;5344.839654810439;5376.274202982626;5408.5402740766685;5426.62013659048;5435.2934118564335;6202.503799696104;6254.781082662085;6271.372862713874;6293.056041593798;6295.276043254901;6293.685315734287;6320.5891617044745;6326.335734747785;6327.90508142369;6328.46208072319;6179.073139029181;6300.873982520416;6309.716062777278;6313.747450101944;6319.368063193828;6061.424164704346;6072.757089716361;6109.480694141048;6271.122042914087;6289.27673317556;6208.658523781235;6236.407541283571;6246.219222597176;6247.906281405873;6248.0940263660605;6105.542084343603;6118.021116621319;6121.724522396414;6127.295361033538;6129.264488261437;5382.973085134549;5907.2911250650195;6066.969665151646;6074.574779765374;6082.513398927987;5802.854928626649;5878.265216847894;5902.291724993029;5907.379725128972;5920.141579470866;4974.527419895249;6049.78825741097;6051.7579296827635;6076.66578338178;6088.025915853442;5713.857153571028;5850.64896106235;5862.149988251595;5877.089051197071;5906.320773111147 18:18:58 INFO - PROCESS | 1641 | |1;ext.html;474.37856408105375;496.87705784926067;520.7630528109711;530.3397270398355;538.5906710899715;29923;38786.224275514476;39374.40938385927;39550.01124971877;41860.16279674412;34581.82709086457;41652.791736041276;42151.78924105375;42400.57599424005;42429.72710818673;9219.815603687923;9384.108509691569;9389.342746007782;9389.62441502339;9392;8408.159184081604;8651.69719059833;11017.669469915916;11271.774564508705;11282.041026512756;11103.666889993314;11156.497957591888;11188.049015833623;11234.32594044356;11242.775144497105;8427.536485493296;11203.775924481506;11279.154063445192;11281.548738050427;11285.266457680225;8424.747257582283;10662.573497060108;11021;11023.283486573428;11194.776104477945;38958;39186.21627567461;39220;39295.41056884149;39327.80336098301;3241.5785947826867;3292.720118789906;3450.413429716954;3502.4396096624673;3609.350316942949;28396.858015709997;28744;29807.552886706715;29870.551941720893;29919.252018699706;3103.953440698391;3125.1874512626882;3169.207698075481;3205.3749518843833;3210.486322188462;492489;502870;503195.84020798816;503290;503621;32713.67286327154;57929.13106303409;58335;58369.70815145982;58422;33836;35044.82477587596;35050.64949350525;35200.64799352025;35269.29461410757;12182.695432614255;13802.17186968765;13885.791713124312;13916.30418479072;14069.507567235098;10798.838017429745;11049.834252486218;11842.28946263227;11925.105617078754;11932.701682458008;7254.637268136572;7526.811829704301;7534.133574638877;7774.844503109906;7778.805529861799;3167.1923659467147;3207.951880721791;3211.132994091582;3232.515122731562;3264.755143364257;863.3999370437612;950.3300173377752;1024.2011231239644;1046.785408991155;1083.7994970930404;28836.279093022422;29442.969496067963;29971.850140749593;30098.54852177219;30415.39169216603;879.5030807593689;1020.3979652005353;1025.99487002566;1085.1264731890747;1126.1755955352219;13877.444902204;15076.849231507764;15321;15433.45982890616;15958.60103497422;332576;336388;336470;336739;336946;57501;57869.4213057864;57927;57962.7101864488;58055.129173062436;1200.3338147328143;1252.1923359433297;1327.4557431453059;1334.292824802856;1374.4776984746134;26012.739872601032;29301;30030.099097027123;30051.69948300489;30779.230519236302;48410.273845891636;49041.50958490512;49105.2813151538;49199.01601967869;49212.75393623009;528995.3550232224;534887;534928;535202;535978;25999.61000584955;26887.327816804736;26934;27727.861360693067;27834.164975050782;36104.63895361013;45467.09065818599;45547.77226113848;45950.31074533951;45966.08067838691;37808.810955945046;38067.80966095152;38153;38165;38542.80728596339;37269.254614908095;38092.80953595214;38247.6175238244;39126.80436597799;39271.80364098161;37446;37693.62306377011;38314.80842595769;38342;38357.84926452211;32173.839130804197;34334.65665343314;34339.82830085834;34342.48486272658;34484.65515344815;25087.498250035263;25352.98588056457;25358.492830142925;25419.36451588725;25910 18:18:58 INFO - PROCESS | 1641 | |2;jquery.html;345.46280533769976;359.881239191067;371.06121512573185;379.2207014585027;393.2802970563868;13019.60941171764;14700.26498675066;14964.5510634681;17041;19760;19034.857908525497;19042;19648.410547683558;19957.90231537265;19963.30128445501;5347.171188465794;5639.295088113986;5643.858903527424;5655.604107712462;5656.717164141797;5347.689815995087;5561.749721262553;5598.972005139969;5614.494695477406;5623.437656234364;4801.375821143247;5533.253010843545;5583.5254003409555;5589.329280486348;5600.96382169298;5312.33595800523;5523.199136125239;5566.944330556683;5593.608447408683;5601.803936862231;4916.852494425173;4923.80304787808;4931.43288521821;4932.753362331869;4935.9753201234125;24116.63825042626;24299.87850060756;24463.510729785303;24636.38409039788;24656.753432465626;2335.9649605255936;2385.8926348314367;2499.30019594512;2638.9868050659625;2783.958240626392;14789.630259243497;14960.102393856403;15069.945103842736;15278.006929549543;15739.449119280885;1787.6960916644202;1854.156358856717;1863.2640107157733;1864.0586503815623;1892.829645331913;61308.2411291493;124326.9999999991;124327;128212.79468013374;129733.70266297404;10561.841572376266;10683.572657093784;10861.891381086247;10885.18361122919;10958.342499450058;13380.799288010687;13410.463581456632;13416.530421435204;13446.865531344756;13448.394822233024;8028.277455028959;8379.790505237417;8395.160483951679;8464.280536154496;8500.14998500145;8457.788555286166;8460.280876125475;8465.36509761758;8621.094785047606;8626;5351.3043304370185;6392.360763923663;6501.837454063591;6512.87382056606;6599.60402375859;1777.9911100444674;1816.3188804198426;1816.8637352198639;1824.5894673698313;1826.662067517487;873.218469469821;928.4846909964899;939.487979051405;960.4285450157146;964.363520076746;20010.49291728078;21265.68101478479;21405.89297053536;21636.25743730911;21681.02435390411;941.8210539997378;955.9330846840794;977.7018009507026;987.2891518106951;1003.2575893838507;10220.335678180894;11314.264572802742;11319.320840749582;11342.659720208407;11366.09071274296;553196;554787;557788.2110589421;563015;567604;66383;73257.63371183217;110085.44957275162;112391;118266.40866795612;975.4976187263564;979.3487330924997;1002.1281485108003;1002.4286156890503;1005.2561104782554;18260.08699565043;18497;18642.906785465984;18768.436946891616;18784.966826824108;50055.74972125116;50102.74948625234;50570.74714626403;51938;52382.95234095221;73998;74930.25069749233;79372;82128;83434.58282708547;11377.488013039267;11470.19708620422;11535.942320288344;11781.941090294493;11824.645260642195;12609;12850.228986260854;12905.677358066123;13108.541201057917;13415.329233538088;11045.558177672803;11189.944050280023;11427.542898283975;11610.6516804496;11708.648740537797;9679.370840895319;11034.613788517363;11056;11156.386398747822;11663.708407289885;9481.478518681539;11038.834417483584;11685.941570292094;11979.460924258256;11998.40007999579;11879.703007424883;11903.345316007357;12013.579524716593;12192.634220973385;12278.386080696107;7808.882866757117;7834.608269586384;11284.43577821122;11301.15241356885;11336.546538138382 18:18:58 INFO - PROCESS | 1641 | |3;mootools.html;1451.666116793138;1487.1374602730416;1624.9593760155994;1789.5168304557762;1812.1301775147938;7993.360531157508;8419.115992820758;8438.535880526577;8442.240198382144;8443.662253509852;7441.218672039439;7675.232476752336;7675.884861727079;7996.440249182545;8198.508089514618;2296.1619009061656;2346.296111166652;2348.049040138738;2348.4011577047827;2348.7181538215436;1997.6404247235491;2336.696229490164;2379.119725701493;2381.583222935988;2382.5949588569983;2309.0763694522157;2311.780380863815;2334.451403920086;2342.0982921575146;2349.9647505287435;2302.7812357826065;2302.9255943315693;2328.2316835444262;2332.0088962191003;2333.9183128590503;2332.195392589554;2351.8000969917575;2353.1646265575646;2353.988230058839;2359.3511784259294;8238.50568965864;8284.212999765074;8346.70786522475;8363.121872203392;8371.497710137412;5017.7491125443585;5180.585553155738;5194.091034069061;5198.636095473318;5200.271961925334;3206.3907857507234;3284.096873359821;3422.4866270059442;3471.0801637566065;3480.7041401480906;1058.867641544807;1068.9037986581245;1082.1451053667593;1098.6813823991033;1101.0750969185922;8964.775880602972;10914.290571112851;10965.396903170322;10968.561257549689;10993.505292261947;3909.7263191576885;4473.910521789611;4570.885727856764;4660.067986402733;4741.5258474153;9399.530023498934;9428.669996550088;9443.433393996382;9461;9465.148136667594;5204.505571970657;5206.557442617344;5208.739563021833;5212.713300768494;5213.113770658997;4224.978875105667;4462.821487140543;4709.317149013443;4780.306855505932;4787.952120478751;3809.3333666608232;3829.195868867569;3901.3562762144497;3928.783916884599;3930.7051971102283;1229.5880879905153;1249.5314257153568;1249.5439164704999;1263.7472505498938;1269.504893091695;802.2098233240254;859.7162936231028;900.3787386703154;901.6483571407157;904.3895370624801;5379.246905433242;5632.464915832989;5859.619124756877;5894.705264736746;6009.429104235091;925.8287216864901;940.6190492850407;964.6430820596424;969.1568335548118;1001.4291853643499;3264.951025734616;3386.356592247465;3777.8299976501125;3782.2813665404037;3870.3937865785947;9621.663241786644;10083.092521673085;10215.131713804261;10229;10237.976202379703;9874.605015799287;10137.543810528545;10159.898401016044;10166.898331016595;10174;7791.337736292366;7930.167332430127;7931.881021784678;7945.761627151194;7962.482438641469;4680.882977925443;5514.944850551604;5592.384837667934;5621.325440947113;5649.37856835756;9011.143941325563;9240.306976976619;9250.907490925274;9398;9399.154076133182;9082.40964337316;9392.201662858733;9439.339246252972;9551.379160354554;9553.331266811256;6642.701078451385;6834.214065382345;7027.4378049756915;7146.071010768567;7371.926280737339;7891.408144389079;8039.196080391914;8055.597220138852;8063.072746634212;8068.07217170033;6090.756369745161;7045.471589630901;7147.3210045045635;7153.177384600629;7248.60132692707;7023.438124949889;7028.543144695578;7036.894446583203;7049.859002820018;7074.434045276468;5763.7406316715005;6599.307072757291;6602.273749887412;6717.56335838169;6750.662466876538;4821.5178482152905;4850.53919877611;4894.216925291937;4899.632527560519;4926.556609905126;4773.665843391068;4798.592119669727;4929.260610908393;4936.2102063669645;4985.202367621163 18:18:58 INFO - PROCESS | 1641 | |4;prototype.html;297.3146896403789;302.23835933447697;305.37703085705164;311.65873368661323;314.197226087347;4521.457425108987;4667.836625718101;4882.853514394574;5312.282841816358;5380;4335.764307172456;5012.248162775585;5099.694018358901;5178.559822415099;5313.628046036779;4042.2521833460755;4046.5751096134923;4066.003829061884;4079.3269110596716;4116.773577453238;4055.3916912463073;4079.4084857695616;4085.55058943516;4113.197926404352;4129.442525259082;3474.513568100468;3726.1867290195864;4018.6584140348104;4046.3525835866267;4057.9043658212263;3533.452314891196;3985.960140398588;3989.660878825289;4006.258842114218;4012.8996775080564;3254.7957255815263;3487.8953631390846;3502.2645244498685;3502.562179727534;3508.6842184203288;4701.553352431513;5005.299258103868;5165.251038599382;5193.376794784613;5235.240890070956;4107.794610269475;4309.383981007122;4335.9566404335865;4537.568930951587;4538.72767633943;4564.794584243717;4869.561739443432;4885.340479035321;5276.947230527722;5557.472040156179;3613.8915832525067;3784.6375304890216;3817.9118951098803;3818.3127037133304;3830.4637350770913;5030.597552195815;5100.69395836251;5102.698811802967;5306.930297184102;5501.862453438656;3821.675157611634;3834.9041273967814;3835.5972622874756;3842.9624001519433;3852.4221366795205;3763.9435408468894;3829.9425508616837;3874.7093967952515;3875.1399328322564;3897.8440862365187;3592.694620957248;4192.8532501362915;4532.252178390595;4538.909221815546;4583.358329833826;4465.620422264079;5527.894421115793;5606.579506537026;5629.746661400165;5629.802956896571;1069.2034434346306;1247.2267194339386;1268.0743057568027;1270.3775150176348;1272.0523210208269;810.3557671651051;811.3306522119275;883.5670521444574;922.760082378573;926.36081104038;4102.343625019982;4341.2200997590835;4410.2282100632265;4777.593904518086;4864.124457597595;4587.541245875385;5276.762545685453;5342.75957581902;5435.83692489226;5489.615726899158;4640.6751527393435;4847;4910.961950790454;4930.57013466098;4961.006389856128;2524.4698613291416;2646.2722751243564;2819.5770634404594;2823.647044119485;2839.1908306132655;4357.651387888961;4375.212461756851;5519.39286678465;5524.4199359066715;5526.937700789997;4488.351872395302;4675.083215881472;4925.7783399747095;5245.370555533361;5532.86167845799;4723.638180909546;5032.917922646649;5119.029173768126;5247.691015952969;5542.9168562472405;4649.0469453761225;5096.490350964874;5302.443243459381;5338.1049727346235;5371.991684656605;4470.530594287553;5291.682499050069;5304.549113325256;5323.748919003953;5351.438098999704;4745.383100196952;5346.893716500655;5404.567634589145;5459.270364817591;5478.383876756287;3327.8169700666695;3661.704465980639;3770.434434834687;3785.394336906192;3813.7139714520968;4520.525344838745;5469.781208751764;5647.858803529944;5661.377248502579;5721.427857214246;4438.267685831931;5186.533212010786;5603.150960183211;5610.831675049755;5622;4478.552144785495;5574.498295153456;5602.907433050516;5609.775608975759;5612.176042786029;4371.584699453547;5223.485189295062;5442.510174084351;5465.480779325958;5469.480399362054;5400.918986215289;5429.7556609953435;5444.428335024923;5595.216669666169;5600.803971860965;4987.10232158215;5084.7966081356335;5418.322709661293;5443.237946687546;5449.863753406196 18:18:58 INFO - PROCESS | 1641 | |5;yui.html;593.6701787994891;726.8182954261434;763.6716212028832;780.1262585903788;797.8284668796208;13784.104033237845;14004.87960963123;14405.567832965002;14493.855061449382;14799.482018129367;13010.47958081678;13215.6696082598;13326;13329.666758331023;13391.397387117579;665.6671664167916;681.8977153427;721.2246834652743;818.0224631565267;831.0235473318857;820.1101804542079;835.678263868409;836.7991681996308;838.2539539809544;838.266516797802;882.8543290357084;884.1379654836516;884.2925659472428;885.1060428966758;886.5345693510926;870.9172628600304;875.8511053120953;875.9649614015432;879.1120967822545;880.0275695356635;645.6513482719373;646.8770933522662;648.0344287012365;648.0667838312814;650.720190318163;11931.821022684666;13866.653333666638;14220.71558568823;14351.138931664134;14357.425702971868;13977.301134943315;14133.717325653532;14188.01460443014;14260.35828387725;14301.998860079799;11363.034142097931;11408.486618102123;11609;11610.303381797119;11648.767024659544;3515.6835884770494;3615.331163734691;3684.4657524658774;3766.886993390203;3771.9057023574355;74682;88104.11895881023;94254;98124.50937745337;98457;10477.161827053817;10738.248322617343;10841.78316433667;10952.452377381258;10986.340819550853;21513.13947442116;21666.56666866654;21794.128234870746;21828.45428864291;22064.669029964563;7600.619969001527;7646.9617651912495;7749.806254843674;7844.725434609762;7916.84166316682;4120.402541631447;4195.077083041726;4259.297215959332;4414.735115893057;4465.419495465634;3370.595528536542;3491.1621210909198;3493.2314890724;3500.0899766061007;3501.4047611906035;628.4438272129198;639.3606393606393;644.7711062572813;647.6373230990661;657.7369052378991;586.2144726067005;644.9484041276687;659.983500412484;662.5386688452422;668.2749217099501;7146.857062858714;8921.375503714808;8941.910580894239;8979.820403591892;8987;628.3873223606946;651.2120334395435;653.6600967496875;660.841398064461;662.1557514169376;4179.477565304337;4377.1683380157665;4413.183561041217;4424.977875110668;4480.619147372445;100674;102308;102786;105323;105405;32979.3404131916;36920.63079369225;66755.664886702;67734.66132669404;79184.20815791884;71741;73239.63380183066;74926.62536687282;82212;86218.56890715506;9227.261819054325;9510.38182518134;9607.471589062668;9623.085806848338;9690.75773105678;48454.27318590153;48958.26562601492;51758;53462.66343341448;53529.66175845635;72571.91142132977;75337.62331188528;77265.61367193128;79395.20604793879;79707.80438293314;11911.761764764482;12029;12148.878511214774;12195.329256890956;12678.922291605317;13354.198748075147;13633;13740.175589464665;13858.792118118035;14131.717365652836;12394.132410731163;12573.874261257637;12609.810852837032;12717.872821271669;12952.481900724242;12471.5011399543;12802.359882006049;12911.031672624404;13039.934800325938;13281.40233689467;10121;12549.87450125487;12738.299393533445;12772.361381931052;13351.66620834448;9240.584173712334;9771.511424428609;9848.852267216142;10667.573297068031;11082.00261976426;10565.717141429286;10876.728081797703;10918.836217456903;10922.453877306261;10945.56217751313 18:18:58 INFO - PROCESS | 1641 | __end_tp_report 18:18:58 INFO - PROCESS | 1641 | __start_cc_report 18:18:58 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,3446 18:18:58 INFO - PROCESS | 1641 | __end_cc_report 18:18:58 INFO - PROCESS | 1641 | __startTimestamp1483582738539__endTimestamp 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | ------- Summary: start ------- 18:18:58 INFO - PROCESS | 1641 | Number of tests: 6 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5726.58 Median:5862.15 stddev:648.78 (11.1%) stddev-sans-first:608.57 18:18:58 INFO - PROCESS | 1641 | Values: 2792.5 3303.8 3355.7 3366.2 3390.1 5668.6 5697.0 5754.4 5758.5 5768.9 5687.5 5761.8 5774.3 5776.0 5781.5 5232.9 5248.5 5259.4 5288.1 5315.9 5133.3 5174.0 5188.2 5225.7 5342.4 5262.7 5264.7 5269.1 5276.9 5280.5 5250.2 5251.2 5253.0 5260.1 5260.9 5114.3 5212.7 5213.7 5217.2 5245.1 5759.8 5763.9 5774.9 5784.6 5784.7 6138.5 6157.6 6179.1 6179.4 6184.9 6241.3 6249.8 6272.1 6317.7 6325.9 5281.8 5503.7 5744.3 5797.8 5800.7 6263.6 6269.1 6290.3 6297.5 6331.2 6002.7 6042.5 6132.6 6135.2 6156.2 6245.6 6267.4 6301.9 6307.1 6309.3 5861.3 6257.3 6261.6 6281.2 6294.7 5349.7 6154.4 6181.2 6184.0 6218.8 4908.5 4919.7 4937.1 4941.7 4945.5 4425.5 4432.2 4437.1 4447.6 4463.3 5580.5 5625.4 5625.6 5677.7 5721.6 6218.9 6223.1 6283.7 6284.4 6312.7 5758.5 5761.3 5769.7 5777.0 5793.3 5344.8 5376.3 5408.5 5426.6 5435.3 6202.5 6254.8 6271.4 6293.1 6295.3 6293.7 6320.6 6326.3 6327.9 6328.5 6179.1 6300.9 6309.7 6313.7 6319.4 6061.4 6072.8 6109.5 6271.1 6289.3 6208.7 6236.4 6246.2 6247.9 6248.1 6105.5 6118.0 6121.7 6127.3 6129.3 5383.0 5907.3 6067.0 6074.6 6082.5 5802.9 5878.3 5902.3 5907.4 5920.1 4974.5 6049.8 6051.8 6076.7 6088.0 5713.9 5850.6 5862.1 5877.1 5906.3 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:59165.31 Median:34341.16 stddev:123972.01 (361.0%) stddev-sans-first:124241.47 18:18:58 INFO - PROCESS | 1641 | Values: 474.4 496.9 520.8 530.3 538.6 29923.0 38786.2 39374.4 39550.0 41860.2 34581.8 41652.8 42151.8 42400.6 42429.7 9219.8 9384.1 9389.3 9389.6 9392.0 8408.2 8651.7 11017.7 11271.8 11282.0 11103.7 11156.5 11188.0 11234.3 11242.8 8427.5 11203.8 11279.2 11281.5 11285.3 8424.7 10662.6 11021.0 11023.3 11194.8 38958.0 39186.2 39220.0 39295.4 39327.8 3241.6 3292.7 3450.4 3502.4 3609.4 28396.9 28744.0 29807.6 29870.6 29919.3 3104.0 3125.2 3169.2 3205.4 3210.5 492489.0 502870.0 503195.8 503290.0 503621.0 32713.7 57929.1 58335.0 58369.7 58422.0 33836.0 35044.8 35050.6 35200.6 35269.3 12182.7 13802.2 13885.8 13916.3 14069.5 10798.8 11049.8 11842.3 11925.1 11932.7 7254.6 7526.8 7534.1 7774.8 7778.8 3167.2 3208.0 3211.1 3232.5 3264.8 863.4 950.3 1024.2 1046.8 1083.8 28836.3 29443.0 29971.9 30098.5 30415.4 879.5 1020.4 1026.0 1085.1 1126.2 13877.4 15076.8 15321.0 15433.5 15958.6 332576.0 336388.0 336470.0 336739.0 336946.0 57501.0 57869.4 57927.0 57962.7 58055.1 1200.3 1252.2 1327.5 1334.3 1374.5 26012.7 29301.0 30030.1 30051.7 30779.2 48410.3 49041.5 49105.3 49199.0 49212.8 528995.4 534887.0 534928.0 535202.0 535978.0 25999.6 26887.3 26934.0 27727.9 27834.2 36104.6 45467.1 45547.8 45950.3 45966.1 37808.8 38067.8 38153.0 38165.0 38542.8 37269.3 38092.8 38247.6 39126.8 39271.8 37446.0 37693.6 38314.8 38342.0 38357.8 32173.8 34334.7 34339.8 34342.5 34484.7 25087.5 25353.0 25358.5 25419.4 25910.0 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:33016.01 Median:21658.64 stddev:93001.57 (429.4%) stddev-sans-first:93230.11 18:18:58 INFO - PROCESS | 1641 | Values: 345.5 359.9 371.1 379.2 393.3 13019.6 14700.3 14964.6 17041.0 19760.0 19034.9 19042.0 19648.4 19957.9 19963.3 5347.2 5639.3 5643.9 5655.6 5656.7 5347.7 5561.7 5599.0 5614.5 5623.4 4801.4 5533.3 5583.5 5589.3 5601.0 5312.3 5523.2 5566.9 5593.6 5601.8 4916.9 4923.8 4931.4 4932.8 4936.0 24116.6 24299.9 24463.5 24636.4 24656.8 2336.0 2385.9 2499.3 2639.0 2784.0 14789.6 14960.1 15069.9 15278.0 15739.4 1787.7 1854.2 1863.3 1864.1 1892.8 61308.2 124327.0 124327.0 128212.8 129733.7 10561.8 10683.6 10861.9 10885.2 10958.3 13380.8 13410.5 13416.5 13446.9 13448.4 8028.3 8379.8 8395.2 8464.3 8500.1 8457.8 8460.3 8465.4 8621.1 8626.0 5351.3 6392.4 6501.8 6512.9 6599.6 1778.0 1816.3 1816.9 1824.6 1826.7 873.2 928.5 939.5 960.4 964.4 20010.5 21265.7 21405.9 21636.3 21681.0 941.8 955.9 977.7 987.3 1003.3 10220.3 11314.3 11319.3 11342.7 11366.1 553196.0 554787.0 557788.2 563015.0 567604.0 66383.0 73257.6 110085.4 112391.0 118266.4 975.5 979.3 1002.1 1002.4 1005.3 18260.1 18497.0 18642.9 18768.4 18785.0 50055.7 50102.7 50570.7 51938.0 52383.0 73998.0 74930.3 79372.0 82128.0 83434.6 11377.5 11470.2 11535.9 11781.9 11824.6 12609.0 12850.2 12905.7 13108.5 13415.3 11045.6 11189.9 11427.5 11610.7 11708.6 9679.4 11034.6 11056.0 11156.4 11663.7 9481.5 11038.8 11685.9 11979.5 11998.4 11879.7 11903.3 12013.6 12192.6 12278.4 7808.9 7834.6 11284.4 11301.2 11336.5 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5427.27 Median:5187.34 stddev:2961.00 (57.1%) stddev-sans-first:2954.23 18:18:58 INFO - PROCESS | 1641 | Values: 1451.7 1487.1 1625.0 1789.5 1812.1 7993.4 8419.1 8438.5 8442.2 8443.7 7441.2 7675.2 7675.9 7996.4 8198.5 2296.2 2346.3 2348.0 2348.4 2348.7 1997.6 2336.7 2379.1 2381.6 2382.6 2309.1 2311.8 2334.5 2342.1 2350.0 2302.8 2302.9 2328.2 2332.0 2333.9 2332.2 2351.8 2353.2 2354.0 2359.4 8238.5 8284.2 8346.7 8363.1 8371.5 5017.7 5180.6 5194.1 5198.6 5200.3 3206.4 3284.1 3422.5 3471.1 3480.7 1058.9 1068.9 1082.1 1098.7 1101.1 8964.8 10914.3 10965.4 10968.6 10993.5 3909.7 4473.9 4570.9 4660.1 4741.5 9399.5 9428.7 9443.4 9461.0 9465.1 5204.5 5206.6 5208.7 5212.7 5213.1 4225.0 4462.8 4709.3 4780.3 4788.0 3809.3 3829.2 3901.4 3928.8 3930.7 1229.6 1249.5 1249.5 1263.7 1269.5 802.2 859.7 900.4 901.6 904.4 5379.2 5632.5 5859.6 5894.7 6009.4 925.8 940.6 964.6 969.2 1001.4 3265.0 3386.4 3777.8 3782.3 3870.4 9621.7 10083.1 10215.1 10229.0 10238.0 9874.6 10137.5 10159.9 10166.9 10174.0 7791.3 7930.2 7931.9 7945.8 7962.5 4680.9 5514.9 5592.4 5621.3 5649.4 9011.1 9240.3 9250.9 9398.0 9399.2 9082.4 9392.2 9439.3 9551.4 9553.3 6642.7 6834.2 7027.4 7146.1 7371.9 7891.4 8039.2 8055.6 8063.1 8068.1 6090.8 7045.5 7147.3 7153.2 7248.6 7023.4 7028.5 7036.9 7049.9 7074.4 5763.7 6599.3 6602.3 6717.6 6750.7 4821.5 4850.5 4894.2 4899.6 4926.6 4773.7 4798.6 4929.3 4936.2 4985.2 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4341.38 Median:4712.60 stddev:1299.97 (27.6%) stddev-sans-first:1267.68 18:18:58 INFO - PROCESS | 1641 | Values: 297.3 302.2 305.4 311.7 314.2 4521.5 4667.8 4882.9 5312.3 5380.0 4335.8 5012.2 5099.7 5178.6 5313.6 4042.3 4046.6 4066.0 4079.3 4116.8 4055.4 4079.4 4085.6 4113.2 4129.4 3474.5 3726.2 4018.7 4046.4 4057.9 3533.5 3986.0 3989.7 4006.3 4012.9 3254.8 3487.9 3502.3 3502.6 3508.7 4701.6 5005.3 5165.3 5193.4 5235.2 4107.8 4309.4 4336.0 4537.6 4538.7 4564.8 4869.6 4885.3 5276.9 5557.5 3613.9 3784.6 3817.9 3818.3 3830.5 5030.6 5100.7 5102.7 5306.9 5501.9 3821.7 3834.9 3835.6 3843.0 3852.4 3763.9 3829.9 3874.7 3875.1 3897.8 3592.7 4192.9 4532.3 4538.9 4583.4 4465.6 5527.9 5606.6 5629.7 5629.8 1069.2 1247.2 1268.1 1270.4 1272.1 810.4 811.3 883.6 922.8 926.4 4102.3 4341.2 4410.2 4777.6 4864.1 4587.5 5276.8 5342.8 5435.8 5489.6 4640.7 4847.0 4911.0 4930.6 4961.0 2524.5 2646.3 2819.6 2823.6 2839.2 4357.7 4375.2 5519.4 5524.4 5526.9 4488.4 4675.1 4925.8 5245.4 5532.9 4723.6 5032.9 5119.0 5247.7 5542.9 4649.0 5096.5 5302.4 5338.1 5372.0 4470.5 5291.7 5304.5 5323.7 5351.4 4745.4 5346.9 5404.6 5459.3 5478.4 3327.8 3661.7 3770.4 3785.4 3813.7 4520.5 5469.8 5647.9 5661.4 5721.4 4438.3 5186.5 5603.2 5610.8 5622.0 4478.6 5574.5 5602.9 5609.8 5612.2 4371.6 5223.5 5442.5 5465.5 5469.5 5400.9 5429.8 5444.4 5595.2 5600.8 4987.1 5084.8 5418.3 5443.2 5449.9 18:18:58 INFO - PROCESS | 1641 | 18:18:58 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19143.66 Median:4419.86 stddev:27348.06 (618.8%) stddev-sans-first:27389.31 18:18:58 INFO - PROCESS | 1641 | Values: 593.7 726.8 763.7 780.1 797.8 13784.1 14004.9 14405.6 14493.9 14799.5 13010.5 13215.7 13326.0 13329.7 13391.4 665.7 681.9 721.2 818.0 831.0 820.1 835.7 836.8 838.3 838.3 882.9 884.1 884.3 885.1 886.5 870.9 875.9 876.0 879.1 880.0 645.7 646.9 648.0 648.1 650.7 11931.8 13866.7 14220.7 14351.1 14357.4 13977.3 14133.7 14188.0 14260.4 14302.0 11363.0 11408.5 11609.0 11610.3 11648.8 3515.7 3615.3 3684.5 3766.9 3771.9 74682.0 88104.1 94254.0 98124.5 98457.0 10477.2 10738.2 10841.8 10952.5 10986.3 21513.1 21666.6 21794.1 21828.5 22064.7 7600.6 7647.0 7749.8 7844.7 7916.8 4120.4 4195.1 4259.3 4414.7 4465.4 3370.6 3491.2 3493.2 3500.1 3501.4 628.4 639.4 644.8 647.6 657.7 586.2 644.9 660.0 662.5 668.3 7146.9 8921.4 8941.9 8979.8 8987.0 628.4 651.2 653.7 660.8 662.2 4179.5 4377.2 4413.2 4425.0 4480.6 100674.0 102308.0 102786.0 105323.0 105405.0 32979.3 36920.6 66755.7 67734.7 79184.2 71741.0 73239.6 74926.6 82212.0 86218.6 9227.3 9510.4 9607.5 9623.1 9690.8 48454.3 48958.3 51758.0 53462.7 53529.7 72571.9 75337.6 77265.6 79395.2 79707.8 11911.8 12029.0 12148.9 12195.3 12678.9 13354.2 13633.0 13740.2 13858.8 14131.7 12394.1 12573.9 12609.8 12717.9 12952.5 12471.5 12802.4 12911.0 13039.9 13281.4 10121.0 12549.9 12738.3 12772.4 13351.7 9240.6 9771.5 9848.9 10667.6 11082.0 10565.7 10876.7 10918.8 10922.5 10945.6 18:18:58 INFO - PROCESS | 1641 | -------- Summary: end -------- 18:18:58 INFO - PROCESS | 1641 | 18:18:59 INFO - TEST-INFO | 1641: exit 0 18:18:59 INFO - TEST-OK | dromaeo_css | took 1120883ms 18:18:59 INFO - TEST-START | kraken 18:18:59 INFO - operating with platform_type : mac_ 18:18:59 INFO - Initialising browser for kraken test... 18:18:59 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpZtlNse/profile http://localhost:49238/getInfo.html) 18:19:01 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:19:01 INFO - PROCESS | 1647 | colorDepth:24 18:19:01 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:19:01 INFO - PROCESS | 1647 | __metrics 18:19:01 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:19:01 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:02 INFO - TEST-INFO | 1647: exit 0 18:19:02 INFO - Browser initialized. 18:19:02 INFO - Running cycle 1/1 for kraken test... 18:19:02 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpZtlNse/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:14 INFO - PROCESS | 1648 | RSS: Main: 222347264 18:19:14 INFO - PROCESS | 1648 | 18:19:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 18:19:18 INFO - PROCESS | 1648 | RSS: Main: 272367616 18:19:18 INFO - PROCESS | 1648 | 18:19:21 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 18:19:21 INFO - PROCESS | 1648 | RSS: Main: 283058176 18:19:21 INFO - PROCESS | 1648 | 18:19:24 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 18:19:25 INFO - PROCESS | 1648 | RSS: Main: 380002304 18:19:25 INFO - PROCESS | 1648 | 18:19:27 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 18:19:27 INFO - PROCESS | 1648 | RSS: Main: 282361856 18:19:27 INFO - PROCESS | 1648 | 18:19:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:19:30 INFO - PROCESS | 1648 | RSS: Main: 284626944 18:19:30 INFO - PROCESS | 1648 | 18:19:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 18:19:33 INFO - PROCESS | 1648 | RSS: Main: 323813376 18:19:33 INFO - PROCESS | 1648 | 18:19:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 18:19:37 INFO - PROCESS | 1648 | RSS: Main: 326361088 18:19:37 INFO - PROCESS | 1648 | 18:19:40 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 18:19:40 INFO - PROCESS | 1648 | RSS: Main: 322760704 18:19:40 INFO - PROCESS | 1648 | 18:19:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:19:42 INFO - PROCESS | 1648 | RSS: Main: 299483136 18:19:42 INFO - PROCESS | 1648 | 18:19:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:19:45 INFO - PROCESS | 1648 | RSS: Main: 301096960 18:19:45 INFO - PROCESS | 1648 | 18:19:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:19:48 INFO - PROCESS | 1648 | RSS: Main: 303575040 18:19:48 INFO - PROCESS | 1648 | 18:19:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:19:51 INFO - PROCESS | 1648 | RSS: Main: 315625472 18:19:51 INFO - PROCESS | 1648 | 18:19:54 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:19:55 INFO - PROCESS | 1648 | RSS: Main: 316010496 18:19:55 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 18:19:57 INFO - PROCESS | 1648 | RSS: Main: 359575552 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | __start_tp_report 18:19:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:19:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:19:57 INFO - PROCESS | 1648 | |i|pagename|runs| 18:19:57 INFO - PROCESS | 1648 | |0;ai-astar;116;114;115;116;115;115;115;114;115;114 18:19:57 INFO - PROCESS | 1648 | |1;audio-beat-detection;146;110;108;143;111;108;108;106;139;111 18:19:57 INFO - PROCESS | 1648 | |2;audio-dft;183;184;180;185;185;185;181;183;183;186 18:19:57 INFO - PROCESS | 1648 | |3;audio-fft;81;59;60;88;61;58;81;63;83;57 18:19:57 INFO - PROCESS | 1648 | |4;audio-oscillator;82;73;93;70;67;68;90;68;69;92 18:19:57 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;89;86;88;86;88;85;90;87;88;86 18:19:57 INFO - PROCESS | 1648 | |6;imaging-darkroom;93;95;93;94;95;98;141;113;93;95 18:19:57 INFO - PROCESS | 1648 | |7;imaging-desaturate;81;83;79;79;84;83;83;83;84;80 18:19:57 INFO - PROCESS | 1648 | |8;json-parse-financial;55;55;53;55;57;58;57;57;57;58 18:19:57 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;64;74;83;76;73;70;72;72;72;73 18:19:57 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;83;84;84;82;82;83;82;95;100;83 18:19:57 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;137;139;138;138;140;142;141;145;182;149 18:19:57 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;167;165;165;166;166;165;166;164;166;177 18:19:57 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;68;68;67;68;65;69;69;61;69 18:19:57 INFO - PROCESS | 1648 | __end_tp_report 18:19:57 INFO - PROCESS | 1648 | __start_cc_report 18:19:57 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1999 18:19:57 INFO - PROCESS | 1648 | __end_cc_report 18:19:57 INFO - PROCESS | 1648 | __startTimestamp1483582797387__endTimestamp 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:19:57 INFO - PROCESS | 1648 | Number of tests: 14 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 18:19:57 INFO - PROCESS | 1648 | Values: 116.0 114.0 115.0 116.0 115.0 115.0 115.0 114.0 115.0 114.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:111.00 stddev:16.49 (14.9%) stddev-sans-first:14.30 18:19:57 INFO - PROCESS | 1648 | Values: 146.0 110.0 108.0 143.0 111.0 108.0 108.0 106.0 139.0 111.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.50 stddev:1.90 (1.0%) stddev-sans-first:2.01 18:19:57 INFO - PROCESS | 1648 | Values: 183.0 184.0 180.0 185.0 185.0 185.0 181.0 183.0 183.0 186.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:69.10 Median:72.00 stddev:12.43 (17.3%) stddev-sans-first:12.42 18:19:57 INFO - PROCESS | 1648 | Values: 81.0 59.0 60.0 88.0 61.0 58.0 81.0 63.0 83.0 57.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.50 stddev:10.88 (14.0%) stddev-sans-first:11.40 18:19:57 INFO - PROCESS | 1648 | Values: 82.0 73.0 93.0 70.0 67.0 68.0 90.0 68.0 69.0 92.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.57 (1.8%) stddev-sans-first:1.54 18:19:57 INFO - PROCESS | 1648 | Values: 89.0 86.0 88.0 86.0 88.0 85.0 90.0 87.0 88.0 86.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:101.00 Median:94.50 stddev:15.28 (16.2%) stddev-sans-first:15.93 18:19:57 INFO - PROCESS | 1648 | Values: 93.0 95.0 93.0 94.0 95.0 98.0 141.0 113.0 93.0 95.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:83.00 stddev:1.97 (2.4%) stddev-sans-first:2.06 18:19:57 INFO - PROCESS | 1648 | Values: 81.0 83.0 79.0 79.0 84.0 83.0 83.0 83.0 84.0 80.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.62 (2.8%) stddev-sans-first:1.66 18:19:57 INFO - PROCESS | 1648 | Values: 55.0 55.0 53.0 55.0 57.0 58.0 57.0 57.0 57.0 58.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.00 stddev:4.75 (6.5%) stddev-sans-first:3.79 18:19:57 INFO - PROCESS | 1648 | Values: 64.0 74.0 83.0 76.0 73.0 70.0 72.0 72.0 72.0 73.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:83.00 stddev:6.32 (7.6%) stddev-sans-first:6.62 18:19:57 INFO - PROCESS | 1648 | Values: 83.0 84.0 84.0 82.0 82.0 83.0 82.0 95.0 100.0 83.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:141.50 stddev:13.47 (9.5%) stddev-sans-first:13.96 18:19:57 INFO - PROCESS | 1648 | Values: 137.0 139.0 138.0 138.0 140.0 142.0 141.0 145.0 182.0 149.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:166.00 stddev:3.71 (2.2%) stddev-sans-first:3.94 18:19:57 INFO - PROCESS | 1648 | Values: 167.0 165.0 165.0 166.0 166.0 165.0 166.0 164.0 166.0 177.0 18:19:57 INFO - PROCESS | 1648 | 18:19:57 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:68.00 stddev:2.47 (3.6%) stddev-sans-first:2.62 18:19:57 INFO - PROCESS | 1648 | Values: 67.0 68.0 68.0 67.0 68.0 65.0 69.0 69.0 61.0 69.0 18:19:57 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:19:57 INFO - PROCESS | 1648 | 18:19:58 INFO - TEST-INFO | 1648: exit 0 18:19:58 INFO - TEST-OK | kraken | took 59241ms 18:19:58 INFO - SUITE-END | took 1180s 18:19:58 INFO - Completed test suite (00:19:40) 18:19:58 INFO - javascript benchmark 18:19:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2792.4554711831192, 3303.834808259587, 3355.697987181153, 3366.20894089889, 3390.084677137173, 5668.603197776158, 5696.971515142429, 5754.395788442216, 5758.5393168546525, 5768.942310576898, 5687.459691329327, 5761.798337058204, 5774.335951365594, 5775.9892018896735, 5781.536448056589, 5232.92150617741, 5248.501392367738, 5259.421463638997, 5288.0745869472885, 5315.867103322428, 5133.3326667533165, 5173.991071741012, 5188.247704082905, 5225.6603320784025, 5342.439043900393, 5262.684238945656, 5264.736763161845, 5269.077911365515, 5276.894462110756, 5280.498352656491, 5250.18622113573, 5251.159814429673, 5253.028189784883, 5260.105782017066, 5260.8684782880355, 5114.33513643224, 5212.713300768456, 5213.687178769286, 5217.217417387422, 5245.0558899398475, 5759.827205183851, 5763.942360576424, 5774.942250577483, 5784.595078344518, 5784.68184249866, 6138.5416718686565, 6157.599756015845, 6179.104029915637, 6179.351168127372, 6184.8763024739255, 6241.313455519889, 6249.750009999594, 6272.1219029335925, 6317.652529110897, 6325.873482530324, 5281.762320695578, 5503.724813759296, 5744.253247077895, 5797.768089276424, 5800.680962547058, 6263.6241825490615, 6269.122322874801, 6290.308066112724, 6297.4962003039645, 6331.2085989251345, 6002.6698531580305, 6042.516598672096, 6132.601380910316, 6135.171751813448, 6156.230471191101, 6245.6252624842655, 6267.4045965633195, 6301.873962520724, 6307.05394190875, 6309.274433440213, 5861.325947516005, 6257.311695713468, 6261.5616906816085, 6281.214848143982, 6294.748210071546, 5349.732513374316, 6154.353792851775, 6181.165542651775, 6183.96908015457, 6218.844528886814, 4908.533689299511, 4919.729414882144, 4937.136001199848, 4941.728204948762, 4945.480724523945, 4425.468943726773, 4432.224360736858, 4437.134758722083, 4447.6219521341045, 4463.308187230984, 5580.469855363734, 5625.353084395347, 5625.578081643893, 5677.659340439587, 5721.57088218377, 6218.875622487525, 6223.1287619733275, 6283.6858157091965, 6284.402981716729, 6312.652804095727, 5758.481736643722, 5761.251037365116, 5769.68266745333, 5776.989026920272, 5793.333766616893, 5344.839654810439, 5376.274202982626, 5408.5402740766685, 5426.62013659048, 5435.2934118564335, 6202.503799696104, 6254.781082662085, 6271.372862713874, 6293.056041593798, 6295.276043254901, 6293.685315734287, 6320.5891617044745, 6326.335734747785, 6327.90508142369, 6328.46208072319, 6179.073139029181, 6300.873982520416, 6309.716062777278, 6313.747450101944, 6319.368063193828, 6061.424164704346, 6072.757089716361, 6109.480694141048, 6271.122042914087, 6289.27673317556, 6208.658523781235, 6236.407541283571, 6246.219222597176, 6247.906281405873, 6248.0940263660605, 6105.542084343603, 6118.021116621319, 6121.724522396414, 6127.295361033538, 6129.264488261437, 5382.973085134549, 5907.2911250650195, 6066.969665151646, 6074.574779765374, 6082.513398927987, 5802.854928626649, 5878.265216847894, 5902.291724993029, 5907.379725128972, 5920.141579470866, 4974.527419895249, 6049.78825741097, 6051.7579296827635, 6076.66578338178, 6088.025915853442, 5713.857153571028, 5850.64896106235, 5862.149988251595, 5877.089051197071, 5906.320773111147], "value": 5683.880661611863, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.37856408105375, 496.87705784926067, 520.7630528109711, 530.3397270398355, 538.5906710899715, 29923.0, 38786.224275514476, 39374.40938385927, 39550.01124971877, 41860.16279674412, 34581.82709086457, 41652.791736041276, 42151.78924105375, 42400.57599424005, 42429.72710818673, 9219.815603687923, 9384.108509691569, 9389.342746007782, 9389.62441502339, 9392.0, 8408.159184081604, 8651.69719059833, 11017.669469915916, 11271.774564508705, 11282.041026512756, 11103.666889993314, 11156.497957591888, 11188.049015833623, 11234.32594044356, 11242.775144497105, 8427.536485493296, 11203.775924481506, 11279.154063445192, 11281.548738050427, 11285.266457680225, 8424.747257582283, 10662.573497060108, 11021.0, 11023.283486573428, 11194.776104477945, 38958.0, 39186.21627567461, 39220.0, 39295.41056884149, 39327.80336098301, 3241.5785947826867, 3292.720118789906, 3450.413429716954, 3502.4396096624673, 3609.350316942949, 28396.858015709997, 28744.0, 29807.552886706715, 29870.551941720893, 29919.252018699706, 3103.953440698391, 3125.1874512626882, 3169.207698075481, 3205.3749518843833, 3210.486322188462, 492489.0, 502870.0, 503195.84020798816, 503290.0, 503621.0, 32713.67286327154, 57929.13106303409, 58335.0, 58369.70815145982, 58422.0, 33836.0, 35044.82477587596, 35050.64949350525, 35200.64799352025, 35269.29461410757, 12182.695432614255, 13802.17186968765, 13885.791713124312, 13916.30418479072, 14069.507567235098, 10798.838017429745, 11049.834252486218, 11842.28946263227, 11925.105617078754, 11932.701682458008, 7254.637268136572, 7526.811829704301, 7534.133574638877, 7774.844503109906, 7778.805529861799, 3167.1923659467147, 3207.951880721791, 3211.132994091582, 3232.515122731562, 3264.755143364257, 863.3999370437612, 950.3300173377752, 1024.2011231239644, 1046.785408991155, 1083.7994970930404, 28836.279093022422, 29442.969496067963, 29971.850140749593, 30098.54852177219, 30415.39169216603, 879.5030807593689, 1020.3979652005353, 1025.99487002566, 1085.1264731890747, 1126.1755955352219, 13877.444902204, 15076.849231507764, 15321.0, 15433.45982890616, 15958.60103497422, 332576.0, 336388.0, 336470.0, 336739.0, 336946.0, 57501.0, 57869.4213057864, 57927.0, 57962.7101864488, 58055.129173062436, 1200.3338147328143, 1252.1923359433297, 1327.4557431453059, 1334.292824802856, 1374.4776984746134, 26012.739872601032, 29301.0, 30030.099097027123, 30051.69948300489, 30779.230519236302, 48410.273845891636, 49041.50958490512, 49105.2813151538, 49199.01601967869, 49212.75393623009, 528995.3550232224, 534887.0, 534928.0, 535202.0, 535978.0, 25999.61000584955, 26887.327816804736, 26934.0, 27727.861360693067, 27834.164975050782, 36104.63895361013, 45467.09065818599, 45547.77226113848, 45950.31074533951, 45966.08067838691, 37808.810955945046, 38067.80966095152, 38153.0, 38165.0, 38542.80728596339, 37269.254614908095, 38092.80953595214, 38247.6175238244, 39126.80436597799, 39271.80364098161, 37446.0, 37693.62306377011, 38314.80842595769, 38342.0, 38357.84926452211, 32173.839130804197, 34334.65665343314, 34339.82830085834, 34342.48486272658, 34484.65515344815, 25087.498250035263, 25352.98588056457, 25358.492830142925, 25419.36451588725, 25910.0], "value": 18150.925422702927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.46280533769976, 359.881239191067, 371.06121512573185, 379.2207014585027, 393.2802970563868, 13019.60941171764, 14700.26498675066, 14964.5510634681, 17041.0, 19760.0, 19034.857908525497, 19042.0, 19648.410547683558, 19957.90231537265, 19963.30128445501, 5347.171188465794, 5639.295088113986, 5643.858903527424, 5655.604107712462, 5656.717164141797, 5347.689815995087, 5561.749721262553, 5598.972005139969, 5614.494695477406, 5623.437656234364, 4801.375821143247, 5533.253010843545, 5583.5254003409555, 5589.329280486348, 5600.96382169298, 5312.33595800523, 5523.199136125239, 5566.944330556683, 5593.608447408683, 5601.803936862231, 4916.852494425173, 4923.80304787808, 4931.43288521821, 4932.753362331869, 4935.9753201234125, 24116.63825042626, 24299.87850060756, 24463.510729785303, 24636.38409039788, 24656.753432465626, 2335.9649605255936, 2385.8926348314367, 2499.30019594512, 2638.9868050659625, 2783.958240626392, 14789.630259243497, 14960.102393856403, 15069.945103842736, 15278.006929549543, 15739.449119280885, 1787.6960916644202, 1854.156358856717, 1863.2640107157733, 1864.0586503815623, 1892.829645331913, 61308.2411291493, 124326.9999999991, 124327.0, 128212.79468013374, 129733.70266297404, 10561.841572376266, 10683.572657093784, 10861.891381086247, 10885.18361122919, 10958.342499450058, 13380.799288010687, 13410.463581456632, 13416.530421435204, 13446.865531344756, 13448.394822233024, 8028.277455028959, 8379.790505237417, 8395.160483951679, 8464.280536154496, 8500.14998500145, 8457.788555286166, 8460.280876125475, 8465.36509761758, 8621.094785047606, 8626.0, 5351.3043304370185, 6392.360763923663, 6501.837454063591, 6512.87382056606, 6599.60402375859, 1777.9911100444674, 1816.3188804198426, 1816.8637352198639, 1824.5894673698313, 1826.662067517487, 873.218469469821, 928.4846909964899, 939.487979051405, 960.4285450157146, 964.363520076746, 20010.49291728078, 21265.68101478479, 21405.89297053536, 21636.25743730911, 21681.02435390411, 941.8210539997378, 955.9330846840794, 977.7018009507026, 987.2891518106951, 1003.2575893838507, 10220.335678180894, 11314.264572802742, 11319.320840749582, 11342.659720208407, 11366.09071274296, 553196.0, 554787.0, 557788.2110589421, 563015.0, 567604.0, 66383.0, 73257.63371183217, 110085.44957275162, 112391.0, 118266.40866795612, 975.4976187263564, 979.3487330924997, 1002.1281485108003, 1002.4286156890503, 1005.2561104782554, 18260.08699565043, 18497.0, 18642.906785465984, 18768.436946891616, 18784.966826824108, 50055.74972125116, 50102.74948625234, 50570.74714626403, 51938.0, 52382.95234095221, 73998.0, 74930.25069749233, 79372.0, 82128.0, 83434.58282708547, 11377.488013039267, 11470.19708620422, 11535.942320288344, 11781.941090294493, 11824.645260642195, 12609.0, 12850.228986260854, 12905.677358066123, 13108.541201057917, 13415.329233538088, 11045.558177672803, 11189.944050280023, 11427.542898283975, 11610.6516804496, 11708.648740537797, 9679.370840895319, 11034.613788517363, 11056.0, 11156.386398747822, 11663.708407289885, 9481.478518681539, 11038.834417483584, 11685.941570292094, 11979.460924258256, 11998.40007999579, 11879.703007424883, 11903.345316007357, 12013.579524716593, 12192.634220973385, 12278.386080696107, 7808.882866757117, 7834.608269586384, 11284.43577821122, 11301.15241356885, 11336.546538138382], "value": 9634.654824173404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.666116793138, 1487.1374602730416, 1624.9593760155994, 1789.5168304557762, 1812.1301775147938, 7993.360531157508, 8419.115992820758, 8438.535880526577, 8442.240198382144, 8443.662253509852, 7441.218672039439, 7675.232476752336, 7675.884861727079, 7996.440249182545, 8198.508089514618, 2296.1619009061656, 2346.296111166652, 2348.049040138738, 2348.4011577047827, 2348.7181538215436, 1997.6404247235491, 2336.696229490164, 2379.119725701493, 2381.583222935988, 2382.5949588569983, 2309.0763694522157, 2311.780380863815, 2334.451403920086, 2342.0982921575146, 2349.9647505287435, 2302.7812357826065, 2302.9255943315693, 2328.2316835444262, 2332.0088962191003, 2333.9183128590503, 2332.195392589554, 2351.8000969917575, 2353.1646265575646, 2353.988230058839, 2359.3511784259294, 8238.50568965864, 8284.212999765074, 8346.70786522475, 8363.121872203392, 8371.497710137412, 5017.7491125443585, 5180.585553155738, 5194.091034069061, 5198.636095473318, 5200.271961925334, 3206.3907857507234, 3284.096873359821, 3422.4866270059442, 3471.0801637566065, 3480.7041401480906, 1058.867641544807, 1068.9037986581245, 1082.1451053667593, 1098.6813823991033, 1101.0750969185922, 8964.775880602972, 10914.290571112851, 10965.396903170322, 10968.561257549689, 10993.505292261947, 3909.7263191576885, 4473.910521789611, 4570.885727856764, 4660.067986402733, 4741.5258474153, 9399.530023498934, 9428.669996550088, 9443.433393996382, 9461.0, 9465.148136667594, 5204.505571970657, 5206.557442617344, 5208.739563021833, 5212.713300768494, 5213.113770658997, 4224.978875105667, 4462.821487140543, 4709.317149013443, 4780.306855505932, 4787.952120478751, 3809.3333666608232, 3829.195868867569, 3901.3562762144497, 3928.783916884599, 3930.7051971102283, 1229.5880879905153, 1249.5314257153568, 1249.5439164704999, 1263.7472505498938, 1269.504893091695, 802.2098233240254, 859.7162936231028, 900.3787386703154, 901.6483571407157, 904.3895370624801, 5379.246905433242, 5632.464915832989, 5859.619124756877, 5894.705264736746, 6009.429104235091, 925.8287216864901, 940.6190492850407, 964.6430820596424, 969.1568335548118, 1001.4291853643499, 3264.951025734616, 3386.356592247465, 3777.8299976501125, 3782.2813665404037, 3870.3937865785947, 9621.663241786644, 10083.092521673085, 10215.131713804261, 10229.0, 10237.976202379703, 9874.605015799287, 10137.543810528545, 10159.898401016044, 10166.898331016595, 10174.0, 7791.337736292366, 7930.167332430127, 7931.881021784678, 7945.761627151194, 7962.482438641469, 4680.882977925443, 5514.944850551604, 5592.384837667934, 5621.325440947113, 5649.37856835756, 9011.143941325563, 9240.306976976619, 9250.907490925274, 9398.0, 9399.154076133182, 9082.40964337316, 9392.201662858733, 9439.339246252972, 9551.379160354554, 9553.331266811256, 6642.701078451385, 6834.214065382345, 7027.4378049756915, 7146.071010768567, 7371.926280737339, 7891.408144389079, 8039.196080391914, 8055.597220138852, 8063.072746634212, 8068.07217170033, 6090.756369745161, 7045.471589630901, 7147.3210045045635, 7153.177384600629, 7248.60132692707, 7023.438124949889, 7028.543144695578, 7036.894446583203, 7049.859002820018, 7074.434045276468, 5763.7406316715005, 6599.307072757291, 6602.273749887412, 6717.56335838169, 6750.662466876538, 4821.5178482152905, 4850.53919877611, 4894.216925291937, 4899.632527560519, 4926.556609905126, 4773.665843391068, 4798.592119669727, 4929.260610908393, 4936.2102063669645, 4985.202367621163], "value": 4415.643877794411, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.3146896403789, 302.23835933447697, 305.37703085705164, 311.65873368661323, 314.197226087347, 4521.457425108987, 4667.836625718101, 4882.853514394574, 5312.282841816358, 5380.0, 4335.764307172456, 5012.248162775585, 5099.694018358901, 5178.559822415099, 5313.628046036779, 4042.2521833460755, 4046.5751096134923, 4066.003829061884, 4079.3269110596716, 4116.773577453238, 4055.3916912463073, 4079.4084857695616, 4085.55058943516, 4113.197926404352, 4129.442525259082, 3474.513568100468, 3726.1867290195864, 4018.6584140348104, 4046.3525835866267, 4057.9043658212263, 3533.452314891196, 3985.960140398588, 3989.660878825289, 4006.258842114218, 4012.8996775080564, 3254.7957255815263, 3487.8953631390846, 3502.2645244498685, 3502.562179727534, 3508.6842184203288, 4701.553352431513, 5005.299258103868, 5165.251038599382, 5193.376794784613, 5235.240890070956, 4107.794610269475, 4309.383981007122, 4335.9566404335865, 4537.568930951587, 4538.72767633943, 4564.794584243717, 4869.561739443432, 4885.340479035321, 5276.947230527722, 5557.472040156179, 3613.8915832525067, 3784.6375304890216, 3817.9118951098803, 3818.3127037133304, 3830.4637350770913, 5030.597552195815, 5100.69395836251, 5102.698811802967, 5306.930297184102, 5501.862453438656, 3821.675157611634, 3834.9041273967814, 3835.5972622874756, 3842.9624001519433, 3852.4221366795205, 3763.9435408468894, 3829.9425508616837, 3874.7093967952515, 3875.1399328322564, 3897.8440862365187, 3592.694620957248, 4192.8532501362915, 4532.252178390595, 4538.909221815546, 4583.358329833826, 4465.620422264079, 5527.894421115793, 5606.579506537026, 5629.746661400165, 5629.802956896571, 1069.2034434346306, 1247.2267194339386, 1268.0743057568027, 1270.3775150176348, 1272.0523210208269, 810.3557671651051, 811.3306522119275, 883.5670521444574, 922.760082378573, 926.36081104038, 4102.343625019982, 4341.2200997590835, 4410.2282100632265, 4777.593904518086, 4864.124457597595, 4587.541245875385, 5276.762545685453, 5342.75957581902, 5435.83692489226, 5489.615726899158, 4640.6751527393435, 4847.0, 4910.961950790454, 4930.57013466098, 4961.006389856128, 2524.4698613291416, 2646.2722751243564, 2819.5770634404594, 2823.647044119485, 2839.1908306132655, 4357.651387888961, 4375.212461756851, 5519.39286678465, 5524.4199359066715, 5526.937700789997, 4488.351872395302, 4675.083215881472, 4925.7783399747095, 5245.370555533361, 5532.86167845799, 4723.638180909546, 5032.917922646649, 5119.029173768126, 5247.691015952969, 5542.9168562472405, 4649.0469453761225, 5096.490350964874, 5302.443243459381, 5338.1049727346235, 5371.991684656605, 4470.530594287553, 5291.682499050069, 5304.549113325256, 5323.748919003953, 5351.438098999704, 4745.383100196952, 5346.893716500655, 5404.567634589145, 5459.270364817591, 5478.383876756287, 3327.8169700666695, 3661.704465980639, 3770.434434834687, 3785.394336906192, 3813.7139714520968, 4520.525344838745, 5469.781208751764, 5647.858803529944, 5661.377248502579, 5721.427857214246, 4438.267685831931, 5186.533212010786, 5603.150960183211, 5610.831675049755, 5622.0, 4478.552144785495, 5574.498295153456, 5602.907433050516, 5609.775608975759, 5612.176042786029, 4371.584699453547, 5223.485189295062, 5442.510174084351, 5465.480779325958, 5469.480399362054, 5400.918986215289, 5429.7556609953435, 5444.428335024923, 5595.216669666169, 5600.803971860965, 4987.10232158215, 5084.7966081356335, 5418.322709661293, 5443.237946687546, 5449.863753406196], "value": 3930.323518775695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.6701787994891, 726.8182954261434, 763.6716212028832, 780.1262585903788, 797.8284668796208, 13784.104033237845, 14004.87960963123, 14405.567832965002, 14493.855061449382, 14799.482018129367, 13010.47958081678, 13215.6696082598, 13326.0, 13329.666758331023, 13391.397387117579, 665.6671664167916, 681.8977153427, 721.2246834652743, 818.0224631565267, 831.0235473318857, 820.1101804542079, 835.678263868409, 836.7991681996308, 838.2539539809544, 838.266516797802, 882.8543290357084, 884.1379654836516, 884.2925659472428, 885.1060428966758, 886.5345693510926, 870.9172628600304, 875.8511053120953, 875.9649614015432, 879.1120967822545, 880.0275695356635, 645.6513482719373, 646.8770933522662, 648.0344287012365, 648.0667838312814, 650.720190318163, 11931.821022684666, 13866.653333666638, 14220.71558568823, 14351.138931664134, 14357.425702971868, 13977.301134943315, 14133.717325653532, 14188.01460443014, 14260.35828387725, 14301.998860079799, 11363.034142097931, 11408.486618102123, 11609.0, 11610.303381797119, 11648.767024659544, 3515.6835884770494, 3615.331163734691, 3684.4657524658774, 3766.886993390203, 3771.9057023574355, 74682.0, 88104.11895881023, 94254.0, 98124.50937745337, 98457.0, 10477.161827053817, 10738.248322617343, 10841.78316433667, 10952.452377381258, 10986.340819550853, 21513.13947442116, 21666.56666866654, 21794.128234870746, 21828.45428864291, 22064.669029964563, 7600.619969001527, 7646.9617651912495, 7749.806254843674, 7844.725434609762, 7916.84166316682, 4120.402541631447, 4195.077083041726, 4259.297215959332, 4414.735115893057, 4465.419495465634, 3370.595528536542, 3491.1621210909198, 3493.2314890724, 3500.0899766061007, 3501.4047611906035, 628.4438272129198, 639.3606393606393, 644.7711062572813, 647.6373230990661, 657.7369052378991, 586.2144726067005, 644.9484041276687, 659.983500412484, 662.5386688452422, 668.2749217099501, 7146.857062858714, 8921.375503714808, 8941.910580894239, 8979.820403591892, 8987.0, 628.3873223606946, 651.2120334395435, 653.6600967496875, 660.841398064461, 662.1557514169376, 4179.477565304337, 4377.1683380157665, 4413.183561041217, 4424.977875110668, 4480.619147372445, 100674.0, 102308.0, 102786.0, 105323.0, 105405.0, 32979.3404131916, 36920.63079369225, 66755.664886702, 67734.66132669404, 79184.20815791884, 71741.0, 73239.63380183066, 74926.62536687282, 82212.0, 86218.56890715506, 9227.261819054325, 9510.38182518134, 9607.471589062668, 9623.085806848338, 9690.75773105678, 48454.27318590153, 48958.26562601492, 51758.0, 53462.66343341448, 53529.66175845635, 72571.91142132977, 75337.62331188528, 77265.61367193128, 79395.20604793879, 79707.80438293314, 11911.761764764482, 12029.0, 12148.878511214774, 12195.329256890956, 12678.922291605317, 13354.198748075147, 13633.0, 13740.175589464665, 13858.792118118035, 14131.717365652836, 12394.132410731163, 12573.874261257637, 12609.810852837032, 12717.872821271669, 12952.481900724242, 12471.5011399543, 12802.359882006049, 12911.031672624404, 13039.934800325938, 13281.40233689467, 10121.0, 12549.87450125487, 12738.299393533445, 12772.361381931052, 13351.66620834448, 9240.584173712334, 9771.511424428609, 9848.852267216142, 10667.573297068031, 11082.00261976426, 10565.717141429286, 10876.728081797703, 10918.836217456903, 10922.453877306261, 10945.56217751313], "value": 7149.708922880238, "unit": "score"}], "name": "dromaeo_css", "value": 7055.417342780336, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 116.0, 115.0, 115.0, 115.0, 114.0, 115.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 108.0, 143.0, 111.0, 108.0, 108.0, 106.0, 139.0, 111.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 180.0, 185.0, 185.0, 185.0, 181.0, 183.0, 183.0, 186.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 61.0, 58.0, 81.0, 63.0, 83.0, 57.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 73.0, 93.0, 70.0, 67.0, 68.0, 90.0, 68.0, 69.0, 92.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 94.0, 95.0, 98.0, 141.0, 113.0, 93.0, 95.0], "value": 101.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 79.0, 79.0, 84.0, 83.0, 83.0, 83.0, 84.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 55.0, 57.0, 58.0, 57.0, 57.0, 57.0, 58.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 83.0, 76.0, 73.0, 70.0, 72.0, 72.0, 72.0, 73.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 82.0, 82.0, 83.0, 82.0, 95.0, 100.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 138.0, 138.0, 140.0, 142.0, 141.0, 145.0, 182.0, 149.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 165.0, 166.0, 166.0, 165.0, 166.0, 164.0, 166.0, 177.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 65.0, 69.0, 69.0, 61.0, 69.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1427.6999999999998, "alertThreshold": 2.0}]} 18:19:58 INFO - Return code: 0 18:19:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:19:58 INFO - # TBPL SUCCESS # 18:19:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:58 INFO - 2017-01-04 18:19:58.722 screenresolution[1652:10591] starting screenresolution argv=screenresolution get 18:19:58 INFO - 2017-01-04 18:19:58.725 screenresolution[1652:10591] Display 0: 1600x1200x32@60 18:19:58 INFO - 2017-01-04 18:19:58.731 screenresolution[1653:10594] starting screenresolution argv=screenresolution list 18:19:58 INFO - Available Modes on Display 0 18:19:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:58 INFO - Intel Iris: 18:19:58 INFO - Chipset Model: Intel Iris 18:19:58 INFO - Type: GPU 18:19:58 INFO - Bus: Built-In 18:19:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:58 INFO - Vendor: Intel (0x8086) 18:19:58 INFO - Device ID: 0x0a2e 18:19:58 INFO - Revision ID: 0x0009 18:19:58 INFO - Displays: 18:19:58 INFO - Display: 18:19:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:58 INFO - Display Serial Number: 1600x1200 60 18:19:58 INFO - Main Display: Yes 18:19:58 INFO - Mirror: Off 18:19:58 INFO - Online: Yes 18:19:58 INFO - Rotation: Supported 18:19:58 INFO - Return code: 0 18:19:58 INFO - Running post-action listener: _resource_record_post_action 18:19:58 INFO - [mozharness: 2017-01-05 02:19:58.976236Z] Finished run-tests step (success) 18:19:58 INFO - Running post-run listener: _resource_record_post_run 18:19:59 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33565696; Write bytes: 661445632; Read time: 317; Write time: 2390 18:19:59 INFO - TinderboxPrint: CPU usage
53.4% 18:19:59 INFO - TinderboxPrint: I/O read bytes / time
33,565,696 / 317 18:19:59 INFO - TinderboxPrint: I/O write bytes / time
661,445,632 / 2,390 18:19:59 INFO - TinderboxPrint: CPU idle
2,258.0 (46.6%) 18:19:59 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 18:19:59 INFO - TinderboxPrint: CPU user
1,623.2 (33.5%) 18:19:59 INFO - TinderboxPrint: Swap in / out
378,966,016 / 0 18:19:59 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 346282496; Write bytes: 346318848; Read time: 26865; Write time: 824 18:19:59 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 31476736; Write bytes: 308032512; Read time: 294; Write time: 1538 18:19:59 INFO - Running post-run listener: _upload_blobber_files 18:19:59 INFO - Blob upload gear active. 18:19:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:19:59 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 18:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:19:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:19:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:19:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:19:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:20:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:01 INFO - (blobuploader) - INFO - Done attempting. 18:20:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:20:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:20:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:02 INFO - (blobuploader) - INFO - Done attempting. 18:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:20:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:04 INFO - (blobuploader) - INFO - Done attempting. 18:20:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:20:04 INFO - Return code: 0 18:20:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:20:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:20:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc1f90b95c235dab5e9e7c9dcd5a7199f9780651fb9910f14756d1c7078c25e7b80fc4b48e00c551afda404e8649f9eea86c36b57b909de5412235864b571510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d9dcb77ceaaf96a2eb36fa8865fad9eea2d49607e7389bcfbe47c1fb1b8e759be9884b138de489a7771015640d3ac13c7c5999df8104c4943ccdd3b1fe42e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2c494abe03a8d4ac2c303cd6389c84ab671536b24682e20f852716f7f81c1b908a391bcacc829ccfe0eee1149bef580e150b397380295bdfe41d407b2b1195"} 18:20:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:20:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:20:04 INFO - Contents: 18:20:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc1f90b95c235dab5e9e7c9dcd5a7199f9780651fb9910f14756d1c7078c25e7b80fc4b48e00c551afda404e8649f9eea86c36b57b909de5412235864b571510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d9dcb77ceaaf96a2eb36fa8865fad9eea2d49607e7389bcfbe47c1fb1b8e759be9884b138de489a7771015640d3ac13c7c5999df8104c4943ccdd3b1fe42e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2c494abe03a8d4ac2c303cd6389c84ab671536b24682e20f852716f7f81c1b908a391bcacc829ccfe0eee1149bef580e150b397380295bdfe41d407b2b1195"} 18:20:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:20:04 INFO - Copying logs to upload dir... 18:20:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:20:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.337035 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 18:20:04.601104) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 18:20:04.605006) ========= 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.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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/Mozilla-Aurora/sha512/bc1f90b95c235dab5e9e7c9dcd5a7199f9780651fb9910f14756d1c7078c25e7b80fc4b48e00c551afda404e8649f9eea86c36b57b909de5412235864b571510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d9dcb77ceaaf96a2eb36fa8865fad9eea2d49607e7389bcfbe47c1fb1b8e759be9884b138de489a7771015640d3ac13c7c5999df8104c4943ccdd3b1fe42e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2c494abe03a8d4ac2c303cd6389c84ab671536b24682e20f852716f7f81c1b908a391bcacc829ccfe0eee1149bef580e150b397380295bdfe41d407b2b1195"} build_url:https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/IK011VEwQ8uAVQ-gTSLA2A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc1f90b95c235dab5e9e7c9dcd5a7199f9780651fb9910f14756d1c7078c25e7b80fc4b48e00c551afda404e8649f9eea86c36b57b909de5412235864b571510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d9dcb77ceaaf96a2eb36fa8865fad9eea2d49607e7389bcfbe47c1fb1b8e759be9884b138de489a7771015640d3ac13c7c5999df8104c4943ccdd3b1fe42e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2c494abe03a8d4ac2c303cd6389c84ab671536b24682e20f852716f7f81c1b908a391bcacc829ccfe0eee1149bef580e150b397380295bdfe41d407b2b1195"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 18:20:04.692735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 18:20:04.693117) ========= 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.brSGuQeXEQ/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.merLiZzVSy/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005040 ========= master_lag: 0.75 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 18:20:05.444193) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 18:20:05.444820) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 18:20:05.446013) ========= ========= Total master_lag: 0.93 =========