builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0389 starttime: 1476806167.76 results: success (0) buildid: 20161018060721 builduid: a8307c90d64446ba877ddfcdc0430ee1 revision: 01ab78dd98805e150b0311cce2351d5b408f3001 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:07.761408) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:07.761914) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:07.762244) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:07.785540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:07.785970) ========= 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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-18 08:56:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2016-10-18 08:56:07 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135616 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:08.588062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:08.588456) ========= 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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.019342 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:08.732828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-18 08:56:08.733232) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 01ab78dd98805e150b0311cce2351d5b408f3001 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 01ab78dd98805e150b0311cce2351d5b408f3001 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-18 08:56:08,814 truncating revision to first 12 chars 2016-10-18 08:56:08,814 Setting DEBUG logging. 2016-10-18 08:56:08,814 attempt 1/10 2016-10-18 08:56:08,814 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/01ab78dd9880?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 08:56:10,084 unpacking tar archive at: mozilla-central-01ab78dd9880/testing/mozharness/ program finished with exit code 0 elapsedTime=1.617051 ========= master_lag: 1.80 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-18 08:56:12.149105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:12.149619) ========= script_repo_revision: 01ab78dd98805e150b0311cce2351d5b408f3001 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:12.150090) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:12.150440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 08:56:12.453756) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 08:56:12.454245) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:56:12 INFO - MultiFileLogger online at 20161018 08:56:12 in /builds/slave/test 08:56:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:56:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:12 INFO - {'append_to_log': False, 08:56:12 INFO - 'base_work_dir': '/builds/slave/test', 08:56:12 INFO - 'blob_upload_branch': 'Firefox', 08:56:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:12 INFO - 'branch': 'Firefox', 08:56:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:12 INFO - 'config_files': ('talos/mac_config.py',), 08:56:12 INFO - 'default_actions': ('clobber', 08:56:12 INFO - 'read-buildbot-config', 08:56:12 INFO - 'download-and-extract', 08:56:12 INFO - 'populate-webroot', 08:56:12 INFO - 'create-virtualenv', 08:56:12 INFO - 'install', 08:56:12 INFO - 'run-tests'), 08:56:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:12 INFO - 'download_minidump_stackwalk': True, 08:56:12 INFO - 'download_symbols': 'ondemand', 08:56:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:12 INFO - 'installer_path': 'installer.exe', 08:56:12 INFO - 'log_level': 'info', 08:56:12 INFO - 'log_name': 'talos', 08:56:12 INFO - 'log_to_console': True, 08:56:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:56:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:56:12 INFO - 'opt_config_files': (), 08:56:12 INFO - 'pip_index': False, 08:56:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:12 INFO - 'cmd': ('bash', 08:56:12 INFO - '-c', 08:56:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:12 INFO - 'enabled': True, 08:56:12 INFO - 'halt_on_failure': False, 08:56:12 INFO - 'name': 'check_screen_resolution'},), 08:56:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:12 INFO - 'cmd': ('bash', 08:56:12 INFO - '-c', 08:56:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:12 INFO - 'enabled': True, 08:56:12 INFO - 'halt_on_failure': False, 08:56:12 INFO - 'name': 'check_screen_resolution'},), 08:56:12 INFO - 'run_cmd_checks_enabled': True, 08:56:12 INFO - 'sps_profile': False, 08:56:12 INFO - 'sps_profile_interval': 0, 08:56:12 INFO - 'suite': 'dromaeojs-e10s', 08:56:12 INFO - 'system_bits': '32', 08:56:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:12 INFO - 'title': 't-yosemite-r7-0389', 08:56:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:12 INFO - 'use_talos_json': True, 08:56:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:12 INFO - 'work_dir': 'build'} 08:56:12 INFO - [mozharness: 2016-10-18 15:56:12.649981Z] Running clobber step. 08:56:12 INFO - Running pre-action listener: _resource_record_pre_action 08:56:12 INFO - Running main action method: clobber 08:56:12 INFO - rmtree: /builds/slave/test/build 08:56:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:14 INFO - Running post-action listener: _resource_record_post_action 08:56:14 INFO - [mozharness: 2016-10-18 15:56:14.403502Z] Finished clobber step (success) 08:56:14 INFO - [mozharness: 2016-10-18 15:56:14.403631Z] Running read-buildbot-config step. 08:56:14 INFO - Running pre-action listener: _resource_record_pre_action 08:56:14 INFO - Running main action method: read_buildbot_config 08:56:14 INFO - Using buildbot properties: 08:56:14 INFO - { 08:56:14 INFO - "project": "", 08:56:14 INFO - "product": "firefox", 08:56:14 INFO - "script_repo_revision": "production", 08:56:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:56:14 INFO - "repository": "", 08:56:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:56:14 INFO - "buildid": "20161018060721", 08:56:14 INFO - "pgo_build": "False", 08:56:14 INFO - "basedir": "/builds/slave/test", 08:56:14 INFO - "buildnumber": 23, 08:56:14 INFO - "slavename": "t-yosemite-r7-0389", 08:56:14 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:56:14 INFO - "platform": "yosemite_r7", 08:56:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:56:14 INFO - "branch": "mozilla-central", 08:56:14 INFO - "repo_path": "mozilla-central", 08:56:14 INFO - "revision": "01ab78dd98805e150b0311cce2351d5b408f3001", 08:56:14 INFO - "stage_platform": "macosx64", 08:56:14 INFO - "builduid": "a8307c90d64446ba877ddfcdc0430ee1", 08:56:14 INFO - "slavebuilddir": "test" 08:56:14 INFO - } 08:56:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:56:14 INFO - Running post-action listener: _resource_record_post_action 08:56:14 INFO - [mozharness: 2016-10-18 15:56:14.406953Z] Finished read-buildbot-config step (success) 08:56:14 INFO - [mozharness: 2016-10-18 15:56:14.407086Z] Running download-and-extract step. 08:56:14 INFO - Running pre-action listener: _resource_record_pre_action 08:56:14 INFO - Running main action method: download_and_extract 08:56:14 INFO - mkdir: /builds/slave/test/build/tests 08:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:14 INFO - trying https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:56:14 INFO - Downloading https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:56:15 INFO - Downloaded 1199 bytes. 08:56:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:56:15 INFO - Using the following test package requirements: 08:56:15 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:56:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:56:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:56:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'jsshell-mac.zip'], 08:56:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:56:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:56:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:56:15 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:56:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:56:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:56:15 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:56:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:56:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:56:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:56:15 INFO - Fetch https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:56:16 INFO - Http code: 200 08:56:16 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 08:56:16 INFO - x-amz-cf-id: MYzjwmMMzU4hcW28pB1VScP_ovCHa5AXTPgnKbO3jscW-tRbPHW4wA== 08:56:16 INFO - x-amz-version-id: m2HwG_Hhw50jKXUxhBsWbqKynLJxhQ.. 08:56:16 INFO - Expected file size: 16483691 08:56:16 INFO - Obtained file size: 16483691 08:56:23 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:56:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:56:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:56:23 INFO - Fetch https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:56:23 INFO - Http code: 200 08:56:23 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 08:56:23 INFO - x-amz-cf-id: GS0DAroQ3W9fDCVqBKfyoLu-c-vpUoq4Xn4sKJ2qLnqDejbPvNZ53A== 08:56:23 INFO - x-amz-version-id: m2HwG_Hhw50jKXUxhBsWbqKynLJxhQ.. 08:56:24 INFO - Expected file size: 16483691 08:56:24 INFO - Obtained file size: 16483691 08:56:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:56:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:56:30 INFO - Fetch https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:56:31 INFO - Http code: 200 08:56:31 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 08:56:31 INFO - x-amz-cf-id: LNAtWPV0fWGyAdHrIYm9q3qlJAeC_KDk3ycxEpqKecHJH1XgHYslzw== 08:56:31 INFO - x-amz-version-id: FGpQlKQsEf1PxzC8Elh21VU8QBp_E2l8 08:56:31 INFO - Expected file size: 14409028 08:56:31 INFO - Obtained file size: 14409028 08:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:31 INFO - trying https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:56:31 INFO - Downloading https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:56:34 INFO - Downloaded 111623219 bytes. 08:56:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:56:34 INFO - mkdir: /builds/slave/test/properties 08:56:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:56:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:56:34 INFO - Contents: 08:56:34 INFO - build_url:https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:56:35 INFO - Running post-action listener: _resource_record_post_action 08:56:35 INFO - Running post-action listener: set_extra_try_arguments 08:56:35 INFO - [mozharness: 2016-10-18 15:56:35.590884Z] Finished download-and-extract step (success) 08:56:35 INFO - [mozharness: 2016-10-18 15:56:35.591013Z] Running populate-webroot step. 08:56:35 INFO - Running pre-action listener: _resource_record_pre_action 08:56:35 INFO - Running main action method: populate_webroot 08:56:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:56:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:56:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:56:35 INFO - '--tppagecycles', 08:56:35 INFO - '7'], 08:56:35 INFO - 'tests': ['tsvgm']}}, 08:56:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:56:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:56:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:56:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:56:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['damp', 'tps']}, 08:56:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'tests': ['damp', 'tps']}, 08:56:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['dromaeo_dom']}, 08:56:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:56:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['basic_compositor_video']}, 08:56:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:56:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'other-e10s': {'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['a11yr', 08:56:35 INFO - 'ts_paint', 08:56:35 INFO - 'tpaint', 08:56:35 INFO - 'sessionrestore', 08:56:35 INFO - 'sessionrestore_no_auto_restore', 08:56:35 INFO - 'tabpaint']}, 08:56:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['tsvgx', 08:56:35 INFO - 'tsvgr_opacity', 08:56:35 INFO - 'tart', 08:56:35 INFO - 'tscrollx', 08:56:35 INFO - 'cart']}, 08:56:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:56:35 INFO - 'tsvgr_opacity', 08:56:35 INFO - 'tart', 08:56:35 INFO - 'tscrollx', 08:56:35 INFO - 'cart']}, 08:56:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'talos_options': ['--disable-e10s'], 08:56:35 INFO - 'tests': ['tp5o']}, 08:56:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'tests': ['tp5o']}, 08:56:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'talos_options': ['--disable-e10s', 08:56:35 INFO - '--xperf_path', 08:56:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:35 INFO - 'tests': ['tp5n']}, 08:56:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:56:35 INFO - 'talos_options': ['--xperf_path', 08:56:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:35 INFO - 'tests': ['tp5n']}}, 08:56:35 INFO - 'talos.zip': {'path': '', 08:56:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:56:35 INFO - Running post-action listener: _resource_record_post_action 08:56:35 INFO - [mozharness: 2016-10-18 15:56:35.610783Z] Finished populate-webroot step (success) 08:56:35 INFO - [mozharness: 2016-10-18 15:56:35.610892Z] Running create-virtualenv step. 08:56:35 INFO - Running pre-action listener: _resource_record_pre_action 08:56:35 INFO - Running main action method: create_virtualenv 08:56:35 INFO - Creating virtualenv /builds/slave/test/build/venv 08:56:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:56:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:56:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:56:35 INFO - Using real prefix '/tools/python27' 08:56:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:56:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:56:39 INFO - Installing pip.................done. 08:56:39 INFO - Return code: 0 08:56:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:56:39 INFO - Reading from file tmpfile_stdout 08:56:39 INFO - Output received: 08:56:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:56:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:39 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:56:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:39 INFO - 'HOME': '/Users/cltbld', 08:56:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:39 INFO - 'LOGNAME': 'cltbld', 08:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:39 INFO - 'MOZ_NO_REMOTE': '1', 08:56:39 INFO - 'NO_EM_RESTART': '1', 08:56:39 INFO - 'PAGER': '/bin/cat', 08:56:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:39 INFO - 'PWD': '/builds/slave/test', 08:56:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:39 INFO - 'SHELL': '/bin/bash', 08:56:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:39 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:39 INFO - 'USER': 'cltbld', 08:56:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:39 INFO - 'XPC_FLAGS': '0x0', 08:56:39 INFO - 'XPC_SERVICE_NAME': '0', 08:56:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:56:39 INFO - Cleaning up... 08:56:39 INFO - Return code: 0 08:56:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:39 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:56:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:39 INFO - 'HOME': '/Users/cltbld', 08:56:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:39 INFO - 'LOGNAME': 'cltbld', 08:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:39 INFO - 'MOZ_NO_REMOTE': '1', 08:56:39 INFO - 'NO_EM_RESTART': '1', 08:56:39 INFO - 'PAGER': '/bin/cat', 08:56:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:39 INFO - 'PWD': '/builds/slave/test', 08:56:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:39 INFO - 'SHELL': '/bin/bash', 08:56:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:39 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:39 INFO - 'USER': 'cltbld', 08:56:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:39 INFO - 'XPC_FLAGS': '0x0', 08:56:39 INFO - 'XPC_SERVICE_NAME': '0', 08:56:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:40 INFO - Downloading/unpacking psutil>=3.1.1 08:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:56:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:43 INFO - Installing collected packages: psutil 08:56:43 INFO - Running setup.py install for psutil 08:56:43 INFO - building 'psutil._psutil_osx' extension 08:56:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:56:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:56:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:56:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:56:43 INFO - building 'psutil._psutil_posix' extension 08:56:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:56:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:56:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:56:44 INFO - ^ 08:56:44 INFO - 1 warning generated. 08:56:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:56:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:44 INFO - Successfully installed psutil 08:56:44 INFO - Cleaning up... 08:56:44 INFO - Return code: 0 08:56:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:44 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:44 INFO - 'HOME': '/Users/cltbld', 08:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:44 INFO - 'LOGNAME': 'cltbld', 08:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:44 INFO - 'MOZ_NO_REMOTE': '1', 08:56:44 INFO - 'NO_EM_RESTART': '1', 08:56:44 INFO - 'PAGER': '/bin/cat', 08:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:44 INFO - 'PWD': '/builds/slave/test', 08:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:44 INFO - 'SHELL': '/bin/bash', 08:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:44 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:44 INFO - 'USER': 'cltbld', 08:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:44 INFO - 'XPC_FLAGS': '0x0', 08:56:44 INFO - 'XPC_SERVICE_NAME': '0', 08:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:56:47 INFO - Installing collected packages: mozsystemmonitor 08:56:47 INFO - Running setup.py install for mozsystemmonitor 08:56:47 INFO - Successfully installed mozsystemmonitor 08:56:47 INFO - Cleaning up... 08:56:47 INFO - Return code: 0 08:56:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:47 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:47 INFO - 'HOME': '/Users/cltbld', 08:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:47 INFO - 'LOGNAME': 'cltbld', 08:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:47 INFO - 'MOZ_NO_REMOTE': '1', 08:56:47 INFO - 'NO_EM_RESTART': '1', 08:56:47 INFO - 'PAGER': '/bin/cat', 08:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:47 INFO - 'PWD': '/builds/slave/test', 08:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:47 INFO - 'SHELL': '/bin/bash', 08:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:47 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:47 INFO - 'USER': 'cltbld', 08:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:47 INFO - 'XPC_FLAGS': '0x0', 08:56:47 INFO - 'XPC_SERVICE_NAME': '0', 08:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:47 INFO - Downloading/unpacking jsonschema==2.5.1 08:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:56:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:50 INFO - Downloading functools32-3.2.3-2.tar.gz 08:56:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:56:50 INFO - warning: no files found matching '*.txt' 08:56:50 INFO - no previously-included directories found matching 'build' 08:56:50 INFO - no previously-included directories found matching 'dist' 08:56:50 INFO - no previously-included directories found matching '.git*' 08:56:50 INFO - Installing collected packages: jsonschema, functools32 08:56:50 INFO - Running setup.py install for functools32 08:56:50 INFO - warning: no files found matching '*.txt' 08:56:50 INFO - no previously-included directories found matching 'build' 08:56:50 INFO - no previously-included directories found matching 'dist' 08:56:50 INFO - no previously-included directories found matching '.git*' 08:56:50 INFO - Successfully installed jsonschema functools32 08:56:50 INFO - Cleaning up... 08:56:50 INFO - Return code: 0 08:56:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:50 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:56:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:50 INFO - 'HOME': '/Users/cltbld', 08:56:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:50 INFO - 'LOGNAME': 'cltbld', 08:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:50 INFO - 'MOZ_NO_REMOTE': '1', 08:56:50 INFO - 'NO_EM_RESTART': '1', 08:56:50 INFO - 'PAGER': '/bin/cat', 08:56:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:50 INFO - 'PWD': '/builds/slave/test', 08:56:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:50 INFO - 'SHELL': '/bin/bash', 08:56:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:50 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:50 INFO - 'USER': 'cltbld', 08:56:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:50 INFO - 'XPC_FLAGS': '0x0', 08:56:50 INFO - 'XPC_SERVICE_NAME': '0', 08:56:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:56:51 INFO - Cleaning up... 08:56:51 INFO - Return code: 0 08:56:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:51 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:56:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:51 INFO - 'HOME': '/Users/cltbld', 08:56:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:51 INFO - 'LOGNAME': 'cltbld', 08:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:51 INFO - 'MOZ_NO_REMOTE': '1', 08:56:51 INFO - 'NO_EM_RESTART': '1', 08:56:51 INFO - 'PAGER': '/bin/cat', 08:56:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:51 INFO - 'PWD': '/builds/slave/test', 08:56:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:51 INFO - 'SHELL': '/bin/bash', 08:56:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:51 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:51 INFO - 'USER': 'cltbld', 08:56:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:51 INFO - 'XPC_FLAGS': '0x0', 08:56:51 INFO - 'XPC_SERVICE_NAME': '0', 08:56:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:51 INFO - Downloading/unpacking blobuploader==1.2.4 08:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:53 INFO - Downloading blobuploader-1.2.4.tar.gz 08:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:56:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:56:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:55 INFO - Downloading docopt-0.6.1.tar.gz 08:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:56:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:56:55 INFO - Running setup.py install for blobuploader 08:56:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:56:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:56:55 INFO - Running setup.py install for requests 08:56:55 INFO - Running setup.py install for docopt 08:56:56 INFO - Successfully installed blobuploader requests docopt 08:56:56 INFO - Cleaning up... 08:56:56 INFO - Return code: 0 08:56:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:56 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:56 INFO - 'HOME': '/Users/cltbld', 08:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:56 INFO - 'LOGNAME': 'cltbld', 08:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:56 INFO - 'MOZ_NO_REMOTE': '1', 08:56:56 INFO - 'NO_EM_RESTART': '1', 08:56:56 INFO - 'PAGER': '/bin/cat', 08:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:56 INFO - 'PWD': '/builds/slave/test', 08:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:56 INFO - 'SHELL': '/bin/bash', 08:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:56:56 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:56 INFO - 'USER': 'cltbld', 08:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:56 INFO - 'XPC_FLAGS': '0x0', 08:56:56 INFO - 'XPC_SERVICE_NAME': '0', 08:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-yt1ZAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-kPVRum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-QUuQPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-fi0vWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-So3HKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:56:56 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-xeW7OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:56:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-yGcDU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-HErEO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-4t1HKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-HQnfCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-ZNaAQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-BxaUQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-sjpD8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-VlDLR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-IUVWra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:56:57 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-dKILRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:56:58 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-Ltqf5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:56:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:56:58 INFO - Running setup.py install for manifestparser 08:56:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:56:58 INFO - Running setup.py install for mozcrash 08:56:58 INFO - Running setup.py install for mozdebug 08:56:58 INFO - Running setup.py install for mozdevice 08:56:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:56:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:56:58 INFO - Running setup.py install for mozfile 08:56:58 INFO - Running setup.py install for mozhttpd 08:56:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Running setup.py install for mozinfo 08:56:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Running setup.py install for mozInstall 08:56:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Running setup.py install for mozleak 08:56:59 INFO - Running setup.py install for mozlog 08:56:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Running setup.py install for moznetwork 08:56:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:56:59 INFO - Running setup.py install for mozprocess 08:56:59 INFO - Running setup.py install for mozprofile 08:57:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:00 INFO - Running setup.py install for mozrunner 08:57:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:00 INFO - Running setup.py install for mozscreenshot 08:57:00 INFO - Running setup.py install for moztest 08:57:00 INFO - Running setup.py install for mozversion 08:57:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:00 INFO - Cleaning up... 08:57:00 INFO - Return code: 0 08:57:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:00 INFO - 'HOME': '/Users/cltbld', 08:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:00 INFO - 'LOGNAME': 'cltbld', 08:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:00 INFO - 'MOZ_NO_REMOTE': '1', 08:57:00 INFO - 'NO_EM_RESTART': '1', 08:57:00 INFO - 'PAGER': '/bin/cat', 08:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:00 INFO - 'PWD': '/builds/slave/test', 08:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:00 INFO - 'SHELL': '/bin/bash', 08:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:57:00 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:00 INFO - 'USER': 'cltbld', 08:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:00 INFO - 'XPC_FLAGS': '0x0', 08:57:00 INFO - 'XPC_SERVICE_NAME': '0', 08:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-vY0GWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-SPoY6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-PLYq4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-LCR_S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-IGnr4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-yTkW19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-6RI3cN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-hTfxua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-lBDQme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:01 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-1pHj6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-c5cStx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-XXqyyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-nMy8uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-GcoPxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-A5Z358-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-6XbL6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:57:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:02 INFO - Running setup.py (path:/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/pip-dKnlF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:05 INFO - Downloading blessings-1.6.tar.gz 08:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:05 INFO - Installing collected packages: blessings 08:57:05 INFO - Running setup.py install for blessings 08:57:05 INFO - Successfully installed blessings 08:57:05 INFO - Cleaning up... 08:57:05 INFO - Return code: 0 08:57:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:05 INFO - Reading from file tmpfile_stdout 08:57:05 INFO - Current package versions: 08:57:05 INFO - blessings == 1.6 08:57:05 INFO - blobuploader == 1.2.4 08:57:05 INFO - docopt == 0.6.1 08:57:05 INFO - functools32 == 3.2.3-2 08:57:05 INFO - jsonschema == 2.5.1 08:57:05 INFO - manifestparser == 1.1 08:57:05 INFO - mozInstall == 1.12 08:57:05 INFO - mozcrash == 0.17 08:57:05 INFO - mozdebug == 0.1 08:57:05 INFO - mozdevice == 0.48 08:57:05 INFO - mozfile == 1.2 08:57:05 INFO - mozhttpd == 0.7 08:57:05 INFO - mozinfo == 0.9 08:57:05 INFO - mozleak == 0.1 08:57:05 INFO - mozlog == 3.4 08:57:05 INFO - moznetwork == 0.27 08:57:05 INFO - mozprocess == 0.23 08:57:05 INFO - mozprofile == 0.28 08:57:05 INFO - mozrunner == 6.12 08:57:05 INFO - mozscreenshot == 0.1 08:57:05 INFO - mozsystemmonitor == 0.3 08:57:05 INFO - moztest == 0.7 08:57:05 INFO - mozversion == 1.4 08:57:05 INFO - psutil == 3.1.1 08:57:05 INFO - requests == 1.2.3 08:57:05 INFO - wsgiref == 0.1.2 08:57:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:06 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:06 INFO - 'HOME': '/Users/cltbld', 08:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:06 INFO - 'LOGNAME': 'cltbld', 08:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:06 INFO - 'MOZ_NO_REMOTE': '1', 08:57:06 INFO - 'NO_EM_RESTART': '1', 08:57:06 INFO - 'PAGER': '/bin/cat', 08:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:06 INFO - 'PWD': '/builds/slave/test', 08:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:06 INFO - 'SHELL': '/bin/bash', 08:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:57:06 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:06 INFO - 'USER': 'cltbld', 08:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:06 INFO - 'XPC_FLAGS': '0x0', 08:57:06 INFO - 'XPC_SERVICE_NAME': '0', 08:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:06 INFO - Cleaning up... 08:57:06 INFO - Return code: 0 08:57:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:07 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 0x10ec04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff28258a2b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, '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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:07 INFO - 'HOME': '/Users/cltbld', 08:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:07 INFO - 'LOGNAME': 'cltbld', 08:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:07 INFO - 'MOZ_NO_REMOTE': '1', 08:57:07 INFO - 'NO_EM_RESTART': '1', 08:57:07 INFO - 'PAGER': '/bin/cat', 08:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:07 INFO - 'PWD': '/builds/slave/test', 08:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:07 INFO - 'SHELL': '/bin/bash', 08:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:57:07 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:07 INFO - 'USER': 'cltbld', 08:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:07 INFO - 'XPC_FLAGS': '0x0', 08:57:07 INFO - 'XPC_SERVICE_NAME': '0', 08:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:07 INFO - Cleaning up... 08:57:07 INFO - Return code: 0 08:57:07 INFO - Running post-action listener: _resource_record_post_action 08:57:07 INFO - Running post-action listener: _start_resource_monitoring 08:57:07 INFO - Starting resource monitoring. 08:57:07 INFO - [mozharness: 2016-10-18 15:57:07.769118Z] Finished create-virtualenv step (success) 08:57:07 INFO - [mozharness: 2016-10-18 15:57:07.770116Z] Running install step. 08:57:07 INFO - Running pre-action listener: _resource_record_pre_action 08:57:07 INFO - Running main action method: install 08:57:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:08 INFO - Reading from file tmpfile_stdout 08:57:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:08 INFO - Reading from file tmpfile_stdout 08:57:08 INFO - Output received: 08:57:08 INFO - Usage: mozinstall [options] installer 08:57:08 INFO - Options: 08:57:08 INFO - -h, --help show this help message and exit 08:57:08 INFO - -d DEST, --destination=DEST 08:57:08 INFO - Directory to install application into. [default: 08:57:08 INFO - "/builds/slave/test"] 08:57:08 INFO - --app=APP Application being installed. [default: firefox] 08:57:08 INFO - mkdir: /builds/slave/test/build/application 08:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:57:42 INFO - Reading from file tmpfile_stdout 08:57:42 INFO - Output received: 08:57:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:57:42 INFO - Running post-action listener: _resource_record_post_action 08:57:42 INFO - [mozharness: 2016-10-18 15:57:42.202764Z] Finished install step (success) 08:57:42 INFO - [mozharness: 2016-10-18 15:57:42.202874Z] Running run-tests step. 08:57:42 INFO - Running pre-action listener: _resource_record_pre_action 08:57:42 INFO - Running main action method: run_tests 08:57:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:42 INFO - 2016-10-18 08:57:42.217 screenresolution[1932:15521] starting screenresolution argv=screenresolution get 08:57:42 INFO - 2016-10-18 08:57:42.220 screenresolution[1932:15521] Display 0: 1600x1200x32@60 08:57:42 INFO - 2016-10-18 08:57:42.225 screenresolution[1933:15523] starting screenresolution argv=screenresolution list 08:57:42 INFO - Available Modes on Display 0 08:57:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:57:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:57:42 INFO - Intel Iris: 08:57:42 INFO - Chipset Model: Intel Iris 08:57:42 INFO - Type: GPU 08:57:42 INFO - Bus: Built-In 08:57:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:57:42 INFO - Vendor: Intel (0x8086) 08:57:42 INFO - Device ID: 0x0a2e 08:57:42 INFO - Revision ID: 0x0009 08:57:42 INFO - Displays: 08:57:42 INFO - Display: 08:57:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:57:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:57:42 INFO - Display Serial Number: 1600x1200 60 08:57:42 INFO - Main Display: Yes 08:57:42 INFO - Mirror: Off 08:57:42 INFO - Online: Yes 08:57:42 INFO - Rotation: Supported 08:57:42 INFO - Return code: 0 08:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:42 INFO - Python 2.7.3 08:57:42 INFO - Return code: 0 08:57:42 INFO - grabbing minidump binary from tooltool 08:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:42 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 0x10ea18d30>, '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 0x10e83edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:57:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:57:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:57:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:42 INFO - Return code: 0 08:57:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:57:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:57:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0389 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:57:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hjjexcF0ng/Render', 08:57:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:42 INFO - 'HOME': '/Users/cltbld', 08:57:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:42 INFO - 'LOGNAME': 'cltbld', 08:57:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:42 INFO - 'MOZ_NO_REMOTE': '1', 08:57:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:42 INFO - 'NO_EM_RESTART': '1', 08:57:42 INFO - 'PAGER': '/bin/cat', 08:57:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:42 INFO - 'PWD': '/builds/slave/test', 08:57:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:42 INFO - 'SHELL': '/bin/bash', 08:57:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QXxmXQv3Qb/Listeners', 08:57:42 INFO - 'TMPDIR': '/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/', 08:57:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:42 INFO - 'USER': 'cltbld', 08:57:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:42 INFO - 'XPC_FLAGS': '0x0', 08:57:42 INFO - 'XPC_SERVICE_NAME': '0', 08:57:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0389', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:57:42 INFO - mozversion application_buildid: 20161018060721 08:57:42 INFO - mozversion application_changeset: 01ab78dd98805e150b0311cce2351d5b408f3001 08:57:42 INFO - mozversion application_display_name: Nightly 08:57:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:42 INFO - mozversion application_name: Firefox 08:57:42 INFO - mozversion application_remotingname: firefox 08:57:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:57:42 INFO - mozversion application_vendor: Mozilla 08:57:42 INFO - mozversion application_version: 52.0a1 08:57:42 INFO - mozversion platform_buildid: 20161018060721 08:57:42 INFO - mozversion platform_changeset: 01ab78dd98805e150b0311cce2351d5b408f3001 08:57:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:57:42 INFO - mozversion platform_version: 52.0a1 08:57:42 INFO - using testdate: 1476806262 08:57:42 INFO - actual date: 1476806262 08:57:42 INFO - starting webserver on 'localhost:52682' 08:57:42 INFO - SUITE-START | Running 2 tests 08:57:42 INFO - TEST-START | dromaeo_css 08:57:42 INFO - operating with platform_type : mac_ 08:57:42 INFO - Initialising browser for dromaeo_css test... 08:57:42 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpsjLytX/profile http://localhost:52682/getInfo.html) 08:57:45 INFO - PROCESS | 1943 | 2016-10-18 08:57:45.599 plugin-container[1945:15686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 08:57:45 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:45 INFO - PROCESS | 1943 | 2016-10-18 08:57:45.603 plugin-container[1945:15686] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 08:57:45 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:45 INFO - PROCESS | 1943 | 2016-10-18 08:57:45.603 plugin-container[1945:15686] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:45 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 08:57:45 INFO - PROCESS | 1943 | colorDepth:24 08:57:45 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 08:57:45 INFO - PROCESS | 1943 | __metrics 08:57:45 ERROR - PROCESS | 1943 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 08:57:46 INFO - TEST-INFO | 1943: exit 0 08:57:46 INFO - Browser initialized. 08:57:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:57:46 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpsjLytX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:48 INFO - PROCESS | 1946 | 2016-10-18 08:57:48.948 plugin-container[1947:15805] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:57:48 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:48 INFO - PROCESS | 1946 | 2016-10-18 08:57:48.949 plugin-container[1947:15805] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 08:57:48 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:48 INFO - PROCESS | 1946 | 2016-10-18 08:57:48.949 plugin-container[1947:15805] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:58 INFO - PROCESS | 1946 | RSS: Main: 237281280 08:57:58 INFO - PROCESS | 1946 | 08:58:48 INFO - PROCESS | 1946 | 1476806328318 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:01:01 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52682/tests/dromaeo/cssquery-ext.html) 09:01:03 INFO - PROCESS | 1946 | RSS: Main: 251449344 09:01:03 INFO - PROCESS | 1946 | 09:04:05 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-ext.html (next: http://localhost:52682/tests/dromaeo/cssquery-jquery.html) 09:04:06 INFO - PROCESS | 1946 | RSS: Main: 263778304 09:04:06 INFO - PROCESS | 1946 | 09:07:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52682/tests/dromaeo/cssquery-mootools.html) 09:07:09 INFO - PROCESS | 1946 | RSS: Main: 268439552 09:07:09 INFO - PROCESS | 1946 | 09:10:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52682/tests/dromaeo/cssquery-prototype.html) 09:10:11 INFO - PROCESS | 1946 | RSS: Main: 268312576 09:10:11 INFO - PROCESS | 1946 | 09:13:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52682/tests/dromaeo/cssquery-yui.html) 09:13:15 INFO - PROCESS | 1946 | RSS: Main: 273715200 09:13:15 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52682/tests/dromaeo/cssquery-yui.html (next: http://localhost:52682/tests/dromaeo/cssquery-dojo.html) 09:16:18 INFO - PROCESS | 1946 | RSS: Main: 276389888 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | __start_tp_report 09:16:18 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 09:16:18 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 09:16:18 INFO - PROCESS | 1946 | |i|pagename|runs| 09:16:18 INFO - PROCESS | 1946 | |0;dojo.html;3144.952825707615;3284.310294838085;3324.9501257481143;3357.7649564530484;3374.696277335039;4565.406497155369;5545.528630066449;5579.107342825148;5605.411431799664;5705.172749951254;5557.527610153132;5586.972065139679;5648.350439699436;5686.545076393889;5694.743736531856;5066.771995260222;5080.745962701869;5094.668846524983;5211.843644690665;5212.139996900507;5111.488851114896;5255.710935898524;5256.474352564751;5264.736763161845;5268.92096618551;4127.40152677863;5048.6465947383695;5125.794968201268;5132.743362831843;5212.452692467275;5152.072626927152;5162.328897243372;5175.611829112794;5215.0873597120735;5221.425643179247;5122.103631864409;5129.9743501282255;5183.714895680736;5189.221616757479;5203.947960520384;5447.700376479292;5453.209284653703;5530.6128571000045;5532.806351777709;5577.3028371453565;6145.631262124287;6148.1392605035335;6200.441960223601;6212.596181248249;6232.688365581703;6201.317855035943;6208.192934918477;6214.875702485925;6233;6245.188125543696;5660.518855897212;5686.601937864392;5726.828195154152;5818.557069288826;5843.678597677126;6114.905704492616;6167.722452489649;6191.876162476815;6209.348018458088;6217.626942383471;6053.152558641706;6104.572679912363;6121.4490695837585;6128.448439640365;6129.877402451926;6097.706167156046;6103.664298463627;6145.028258742197;6205.627662340274;6206.503479721611;4978.800847966113;5027.321311622957;6109.541784366191;6149.907751383733;6213.906791398133;6139.447449729546;6194.845128871724;6210.7515699371515;6223.066540019034;6233.625982441068;4369.847055353048;4893.730844803499;4910.533499317559;4914.680545764585;4938.703677779345;3803.2963901678277;4024.9132734161612;4228.4080228768;4350.390945267665;4364.432623758891;4597.655175861757;5188.636795424284;5749.425057494301;5777.508911742465;5811.186433899257;6186.597871138451;6186.628802271878;6187.504999600022;6205.844853878689;6211.782587609502;4539.795709193094;4630.699004564693;5265.52610265078;5787.88424231513;5882.294124705063;4463.732176069446;5418.133098704188;5427.620066595379;5454.318210223722;5479.178123281539;6239.625622462667;6277.623342599459;6291.591046582049;6297.464715499143;6307.779227727007;6041.154238406539;6086;6134.877302454015;6167.229096362955;6167.568270221221;6188.133661287513;6195.318514963441;6203.596766210182;6282.528810339151;6314.494840412664;5970.223870896755;6118.174046503843;6128.019516877278;6142.170806941006;6170.598911070766;6106.08408738675;6112.26032301701;6126.761967422209;6131.284916200975;6160.507159427146;5878.882422351615;5908.822735317947;5968.015277479168;6042.274927008787;6105.938940610537;5984.581079324405;6098.704546243851;6124.136831511461;6125.937554509019;6133.671320645445;5858.121281807593;5956.970215148896;6003.759849605967;6015.39846015395;6032.366601506778;6013.87972240544;6037.335893051673;6049.758009679564;6062.18160548338;6119.265688117454;5944.524438045032;5968.283805943314;5969.552283578836;5987.042073268256;6071.908921366095 09:16:18 INFO - PROCESS | 1946 | |1;ext.html;473.87916081399237;485.2091091520821;528.9100852855016;529.2669652531246;538.98670499461;34420.623175073;42100.736977890636;42402.36396454056;43896.34155487662;44009.55990440094;43199.136017279634;43443.3483497747;43732.56267437332;43908.78045609767;43914.90212744683;9555.474448905308;9705.75735606612;9718.854217186748;9772.804543909118;9784.657536986171;9576.90423095767;9625.22998160149;9724.902750972471;9725.27060470464;9732.65935692251;9679.225661947028;9713.028697130301;9732.2214222862;9735.51322433879;9748.463834489101;9637.807243855119;9710.465924374157;9720.07659272368;9729.70810875675;9740.220782337396;9526.571304291254;9622.615095396175;9648.276379271512;9665.46839923804;9678.322517423712;40417.19165616671;40881.386779198336;41256.1748765023;41271;41445.37831932523;3479.373712731707;3602.513660655804;3678.6873115785193;4044.737092089005;4143.461350024478;30629.846850765825;31504.212394690087;31644.683553164403;31759;32358.838205809057;2557.4373637799654;2640.4719056188646;3076.3385872037397;3248.5776849009712;3285.8192799396024;390736.99999999715;394301;433736;438492;444086;59054.70472647609;59441.40558594445;59546.70226648839;59569.702151488964;59985.40014599886;62325.68837155785;62563;62957.685211574564;63013;63072;14588.927055364655;14732.484363047242;14762.188079655507;14847.109173449628;14848.777268340984;9914.157296629868;11239;11273;11367.943160284145;11376.203665743484;15145.242737863064;16211.675766484605;16213.432529861399;16234.431794887123;16234.944728592603;5879.823605291848;6789.32106789327;6966.164060312795;7021.566096322304;7052.506324557232;785.6935795039939;866.757307953774;904.6336233825311;932.0306880843982;946.3186505715873;28085.859570702425;29915.252118697208;30925.69074309273;31105.377892442026;31258.21854453657;871.8186856809059;1025.3898930136556;1070.5182667799522;1073.162932912314;1111.4053981120062;11585.710357241136;15897.046177229173;16097.839021609634;16106;16290.022598643882;562278;599240;604191;607785;609842;44105.77947110244;59258;59320.406795931485;59326;59359.516012100044;1323.3118778235457;1361.7276544691101;1385.3211926156112;1394.2749770119683;1394.8186735724291;25008.624870627318;29977.04165300147;30352.116548927817;30755.692443075284;30999.380012400077;50625.74687126541;50847.745761270955;51277.230841538156;51338.48661513337;51350.22974655309;459613;463021.10936671955;463172;463368.68315658206;466147;26432.0748773792;26664.60003099916;26846.865765671046;26882.32794180161;27023.324416889736;47343.28985065296;47534;48527.78680533015;49305.75347123241;49436.50563494319;39202.60797392104;39343.80328098455;39468.21063578655;40589.79705101455;40788.59211407966;38925.6107438922;38952.41571376375;39251.21497569975;40277.597224027384;41128;37963.24073518459;38850;38884;39058.218835623695;39119.41320880246;30963;34518.30963380668;34613;34637.575369723876;35302.94091177269;25045.624315634916;25845.870770646026;25941.481170376865;26006.73993260043;26075.73924260809 09:16:18 INFO - PROCESS | 1946 | |2;jquery.html;359.37110057399553;365.9817009149542;373.10454908220265;375.9003863976045;393.6378531750789;16564.585885352873;19401.514962125955;20462.795372046276;20670.6899396509;20851.791482085173;11502.42487875607;11795.882041179586;11910.821337679941;11911.67722294153;11934.224275422092;5023.572996295319;5309.495597918191;5374.220737992989;5409.891802163955;5455.972720136394;3715.1826598148364;3868.3423817951016;5311.389190243113;5363.168708850135;5474.534664553517;4765.332853400527;5860.912086318709;6059.424354686298;6077.209962704865;6088.573799834014;6069.939300607004;6096.390360963913;6546.410823025951;6563.54055216135;6571.3757193066585;5500.532454741352;5735.598508104435;5785.161151633031;5795.2176456178295;5814.912776308359;12970.80543791844;13230.933845330808;13316.73366532664;13355.931525477938;13383.732325353438;2366.420227044368;2579.3293743626605;2675.5184066867955;2898.318895059659;2898.840563768992;9830.852537211947;9879.901200988043;10301.587936482532;12270.447829847679;15738.213089345487;1847.4919397165752;1891.2056936086876;1897.8671492995495;1913.1677720191622;1941.3981665683684;119492.40253798763;127037.36481317534;128325.99999999907;128832;128879;9238.260939124853;11261.042811360961;11306.773864522664;11615.883841161649;11708.531658733726;14645.414183432544;14660.413583456755;14662.486812961495;14663.853361466248;14666.580014297522;7835;8099;8113.716019939392;8255.628496717662;8277.95861020691;7768.844623107506;7843.607819608997;7854;7947.125816160218;7979;4485.551444855553;4498.482674492475;4500.954990450119;4501.144782491317;4574.954250457453;1583.2558697412198;1662.8586570141674;1900.4108726294892;1950.3368854589507;1993.2425678242175;843.2158092973593;848.5587494502947;872.3065163195148;894.186290475664;981.3326937682444;15281.541553753405;16224.918875405547;17759.822401776077;20732.792672073087;20775.68836467454;839.1063517353998;952.6141912525438;960.5485421851719;963.6868017894212;1005.1255407795265;10248.128909042814;10361.74095647615;10895.073918716998;10918.235723499438;10959.671209863716;569967;573106;577620.1118994462;580598;582682;127374.08938865924;129133;130654.34672826766;131347.34326328308;131444;980.6616717232544;990.5344488090586;991.8561808537721;1002.8044531316469;1004.6282875336026;18086.638267234845;18136.63726725432;18201.45395638133;18366.081695914883;18490.35283765062;52650.94698106093;53135.46864531305;53764.731176345435;54351;55161.72419137879;74153.88769168359;75758.62120689361;77662.61168694346;79986;85664.57167714121;7836.333911617577;11549.538018479167;11556.191066625255;11811.409429528661;12077.27536347822;10974.780504390028;12989.48042078344;13165.078444509176;13352.933235333761;13455.46178152863;11320.490577924178;11601.593944211912;11767;11817.231879927776;11908.92819646236;11261.268017578832;11339.376334301693;11570.76858462843;11588;11623.186376953532;11531.731509533776;11648.883511165119;11795.410229488663;11948.462319195483;12010.339431331011;12023.63929082129;12107.757844842878;12291.69270768238;12323.815142773044;12432.502699891904;11669.708257293296;11758.470868810753;11887.108466864847;11932.403379830801;12026.398680065786 09:16:18 INFO - PROCESS | 1946 | |3;mootools.html;1470.6249906273902;1512.7352713275175;1521.4598817419817;1714.5199344183625;1871.7753869535659;7984.081830589469;8276.379271554628;8473.91526084739;8975.865362019567;9005.45967241965;8483.939507561554;8776.956115219415;8786.956065219681;8839.425437346568;8889.977652569956;2355.4817940053244;2364.3143488388346;2393.748656391071;2407.879606019701;2410.3853517353136;2358.174638876388;2385.988070059656;2395.317334559651;2397.2927986244013;2408.614621660535;2355.458244603742;2400.3879010852297;2402.8798560072014;2406.1939250351065;2406.3984003999;2338.8830558472177;2356.057576969209;2367.633016882386;2370.9644355334685;2372.50177462733;2341.262502311766;2352.8941197646145;2352.9529409411716;2360;2364.2788949370433;8751.693690720795;8814.427062240999;8895.510746908918;8898.976617688984;8905.955470222672;5261.473852614746;5364.168553874117;5429.701366424845;5430.15832545956;5434.076207044812;3366.646502117267;3382.678645528671;3433.4163192257374;3508.912277193065;3527.417976033951;1076.0100707349256;1082.242430298786;1084.7613525024483;1086.8369744538304;1089.9727506812314;10016.699499014969;11468.254563453349;11532.538698452052;11619.6514104577;11620.418979051097;4554.95445045552;4636.374089497942;4813.002603919334;4825.324454576362;4831.51684831514;9798.46108464027;9820.4107753535;9903.851442228372;9909.306348555532;9910.55402506889;5337.226102215157;5358.544523715528;5395.2986111806;5398.271233383443;5405.324334458192;4398.73607583546;4566.3150527420485;4738.810447582058;4868.805247790119;4938.975305123451;3870.5548861881243;3907.8632247871187;3974.344233201547;3978.0054986253435;4021.879343619696;1291.877271659191;1305.6996890715;1310.541310541322;1315.3883444198493;1318.8021796730568;695.9060526828914;909.0818273543769;922.7324076018014;928.8513837785922;930.7068273493828;5718.285214348207;5859.2090067841145;5913.438223368773;6083.695815209222;6089.056196289582;812.0174588747564;897.8204359128069;963.3786207895868;967.9787823845769;1012.104287705386;3807.181455986921;3884.7280690351404;3892.416137579329;3953.2488827122756;3999.460072890123;10302.742431439125;10789.62236321725;10792.352458852338;10808;10811.081058110149;10750.516226769814;10756.677299681021;10757.354558726502;10774.353538787698;10778.29941053829;7690.653920573588;8075.878861817077;8081.989751281089;8113.0669972953865;8200.712975045726;4468.754218517883;4953.628477864103;5216.982688375882;5874.559408044328;6285.905711414424;7709.9614501927135;8814.338924580554;9263.749393832017;9854.901450985399;9942.005799420289;9243.491607961627;9606.423614583147;9813.313068085166;9853.901460985298;10201.489925503844;6951.304869513008;7196.280371962762;7345.1553071397475;7403.222661620668;7631.122420921665;8276;8398.83202335962;8453.154684531497;8465.746027619181;8466.322694184573;7529.510581812165;7564.4704870658525;7597.582132982739;7666.8083297918;7669.156392796899;7515.210902855121;7566.886496702444;7617.619119044137;7658.578778167254;7723.34351580122;6780.1524809398825;6909.93090069113;6956.582605043714;6964;6982.546134501241;5066.620003499678;5076.873078173076;5170.741462926914;5192.299039629602;5330.866728331823;5140.408852981955;5202.765875535686;5350.892982140414;5429.158480435539;5436.510714035756 09:16:18 INFO - PROCESS | 1946 | |4;prototype.html;252.64881814278152;306.90639354996733;308.9181366937762;313.96389415217243;316.6247996124591;4419.845305414311;5618.381977982423;5676.914846277309;5687.374388817238;5712.800051998181;4557.544245575441;5408.3509978802595;5470.316210473691;5530.944690553093;5614.466625670555;4024.396340548926;4060.127072679373;4142.378643203529;4204.684648651363;4223.535411104776;3633.309671162484;4187.497500299968;4198.244316023115;4260.8721738347895;4283.7215580987295;4168.520620128678;4218.746875187483;4248.87253382399;4251.277282861921;4266.146770645868;3383.526306317105;3925.3523168677134;4188.015816283171;4227.809748561292;4247.87256382309;3015.517517197238;3615.963840361589;3621.4567814827724;3646.179609587848;3648.0847555033556;4196.433481479991;5373.274607927919;5390.973045134789;5424.511793938525;5510.6418082824885;4144.668426525871;4433.512313645497;4753.548412900769;4906.705597664116;4924.926126108111;5867.589268751189;5916.497097746696;5937.812437512514;5943.524518038546;5990.341062483123;3080.7843450958444;3235.4823228283362;3365.8653653853817;3957.526545908807;4063.2686116499017;4544.681872268942;5305.522502974751;5616;6126.264848218242;6169.537284703665;3539.8938031859084;3806.01043728627;3944.3097457944746;3973.8807835764974;4069.064115253508;3416;3824.240849209339;3858.944782335094;4085.6935729820384;4109.321961876317;3824.541055073409;4754.572088511982;4769.260764581495;4818.759062046884;4829.251466022772;5187.688738675692;5584.966781145501;5788.855278617984;5867.413258674183;5956.761729530857;1405.845357010728;1418.319206780751;1444.3572610188364;1447.1751101871832;1454.8908831837655;756.5196100476237;868.8740132680726;877.9385443018928;894.6645008121955;895.8611415230649;5331.466853314715;5364.999999999922;5488.572971027562;5503.449655034545;5532.75102620383;5021.824236151717;5908.784205922779;5977.521798256129;6091.604045737013;6119.326874043852;4806.519348065165;5026.84919452417;5408.45915408456;5435.972820135874;5550.500454959073;3124.265797537577;3127.2338277121794;3138.98430507846;3140.309131990958;3163.430582495127;5663.971680141655;5758.021136406821;6180.393097794606;6188.545691762432;6252.436890777306;4929.876753081202;6042.764177254444;6060.484858786965;6066.636001839904;6123.84690382744;5278.498542638443;5353.973230133903;5748.7125643717645;5858.7070646466655;6204.968975155186;4348.543402942645;4873.537013983666;5328.786848526171;5346.786128554814;5949.464548190684;4740.857774266778;5163.302954101148;5385.434529374359;5440.918386224131;5940.851478713067;5113;5458.235846981505;6151.323354431099;6158.969205153946;6159.538034647509;3303.5147918945745;3634.4911712359763;3847.7306588539655;3978.622030907059;4067.7559346439307;5490;6052.9445271668965;6063.6664983426335;6081.209442772588;6110;5124.333836601218;5378.489043540857;5897.616654917417;5897.879402913432;5914.467697907209;4916.328448327443;5032.62255330859;5453.945460545502;5652.632578882524;5907.232059832194;4330.783460826883;5314.760835762478;5778.595498315078;5830.2129212555765;5860.912086318794;4890.584300334512;5512;5605.831825045255;5731.512821410227;5750.993576124245;5729;5733.168690539932;5769.624974376652;5782.421757824352;5800.216970708901 09:16:18 INFO - PROCESS | 1946 | |5;yui.html;672.3914857054367;675.5203805298238;720.7225218290963;775.9961200194002;817.0807841178674;15582.22088895555;15701.685966280667;16264.83735162648;16273.186340682978;16337.83662163378;14640.975131740783;14753.557393278192;14798;14836.703265934675;14841.406343746237;694.1600663197512;707.101980484784;848.4569875279826;857.2327766648846;873.0919843362894;884.8628462588309;888.7467071884514;891.5453118909338;895;896.5114012863005;942.9339946203769;943.245403677059;945.4327403557845;946.2950102173891;947.1333729637408;935.8642996765465;939.0703203828161;939.2532936315612;939.286142531674;940.2055263302499;690.9516333856632;697.5500801982744;699.6257002503687;701.424831638057;705;15376.077435353804;15391.615209619737;15403;15411.614709632237;15418.691626167527;14588.635284117876;14727.969042167053;14798.926005369904;14830.406783728638;14847;10535.10451611614;11110.444477776078;11844.88155118455;12116.878831211752;12198.817017744741;3761.2101458693705;3846;3861.708937707492;3999.060220848098;4000.5599384067727;108975;109146;116193;116491.41754291175;120778;8529.82940341202;11201.148222348382;11230.66308010761;11317.264377815416;11425.428728563706;19120.521986950436;23540.7645923542;23579.7642023581;23845.165419210243;23900.64149037731;7970.202979701983;7984.281414672707;8078.39412044099;8132.9186708132165;8251.092379838214;4275.187714334266;4308.612224899712;4332.913341733148;4367.847125350598;4472.7092738972515;3583.4624806279267;3618.565772107311;3688.243909998444;3694.6674799268194;3695.3348397288746;583.6954406900574;612.2928018139056;644.2687549631203;661.8047675935611;670.6713710281927;571.5684658083121;579.802867025205;587.9739853954876;673.8147009572407;709.5352544083634;8960;9215;9547.379420337656;9556.522173891277;9806.803863922682;542.5442628192337;582.7552427980258;601.6901295832649;627.632834791652;678.6267552845915;3776.792276424823;4377.649788016951;4533.549264235479;4624.121416930772;4629.504992945594;125283;161170;162635.1868240675;163353;167512;108825;110483.34274985881;111042.44478777554;123015.38492307482;130679;104530;105019.34748482802;105510.47244763726;107832;108177;9251.306152038489;9791.706248812547;9908.405495670282;9943.25425593069;10104.595816167272;52738;55751.44248557625;59105;59449.13580617246;59469.70265148646;52699;52898.73550632222;68494;76726;80422;12730.936345318214;12808.551700690432;13036.804447933098;13056.869431305946;13396.330183490982;11846.585369512026;12048.698782530508;13165.392112760457;14286.357113930106;14664.926675366554;11216.943915280372;11810.82283765727;11931.880681193077;12090.516379344728;12252.632421027383;11475.483603237708;11808.468618912342;12056.03551715843;12384.195027323196;13316.33418329099;11794.410279486163;12956.93521532424;13434.731305374033;13475.450323212955;13668.93165534166;10653.627123050655;11379.374134422686;11618.244814087057;11744.58893938708;11868;10574.632618047217;10712.442432904669;10794.676159715222;11500.597479088192;11650.592229272273 09:16:18 INFO - PROCESS | 1946 | __end_tp_report 09:16:18 INFO - PROCESS | 1946 | __start_cc_report 09:16:18 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,359 09:16:18 INFO - PROCESS | 1946 | __end_cc_report 09:16:18 INFO - PROCESS | 1946 | __startTimestamp1476807378529__endTimestamp 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | ------- Summary: start ------- 09:16:18 INFO - PROCESS | 1946 | Number of tests: 6 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5636.12 Median:5968.02 stddev:697.12 (11.7%) stddev-sans-first:671.31 09:16:18 INFO - PROCESS | 1946 | Values: 3145.0 3284.3 3325.0 3357.8 3374.7 4565.4 5545.5 5579.1 5605.4 5705.2 5557.5 5587.0 5648.4 5686.5 5694.7 5066.8 5080.7 5094.7 5211.8 5212.1 5111.5 5255.7 5256.5 5264.7 5268.9 4127.4 5048.6 5125.8 5132.7 5212.5 5152.1 5162.3 5175.6 5215.1 5221.4 5122.1 5130.0 5183.7 5189.2 5203.9 5447.7 5453.2 5530.6 5532.8 5577.3 6145.6 6148.1 6200.4 6212.6 6232.7 6201.3 6208.2 6214.9 6233.0 6245.2 5660.5 5686.6 5726.8 5818.6 5843.7 6114.9 6167.7 6191.9 6209.3 6217.6 6053.2 6104.6 6121.4 6128.4 6129.9 6097.7 6103.7 6145.0 6205.6 6206.5 4978.8 5027.3 6109.5 6149.9 6213.9 6139.4 6194.8 6210.8 6223.1 6233.6 4369.8 4893.7 4910.5 4914.7 4938.7 3803.3 4024.9 4228.4 4350.4 4364.4 4597.7 5188.6 5749.4 5777.5 5811.2 6186.6 6186.6 6187.5 6205.8 6211.8 4539.8 4630.7 5265.5 5787.9 5882.3 4463.7 5418.1 5427.6 5454.3 5479.2 6239.6 6277.6 6291.6 6297.5 6307.8 6041.2 6086.0 6134.9 6167.2 6167.6 6188.1 6195.3 6203.6 6282.5 6314.5 5970.2 6118.2 6128.0 6142.2 6170.6 6106.1 6112.3 6126.8 6131.3 6160.5 5878.9 5908.8 5968.0 6042.3 6105.9 5984.6 6098.7 6124.1 6125.9 6133.7 5858.1 5957.0 6003.8 6015.4 6032.4 6013.9 6037.3 6049.8 6062.2 6119.3 5944.5 5968.3 5969.6 5987.0 6071.9 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:63974.02 Median:41773.06 stddev:132961.36 (318.3%) stddev-sans-first:133248.87 09:16:18 INFO - PROCESS | 1946 | Values: 473.9 485.2 528.9 529.3 539.0 34420.6 42100.7 42402.4 43896.3 44009.6 43199.1 43443.3 43732.6 43908.8 43914.9 9555.5 9705.8 9718.9 9772.8 9784.7 9576.9 9625.2 9724.9 9725.3 9732.7 9679.2 9713.0 9732.2 9735.5 9748.5 9637.8 9710.5 9720.1 9729.7 9740.2 9526.6 9622.6 9648.3 9665.5 9678.3 40417.2 40881.4 41256.2 41271.0 41445.4 3479.4 3602.5 3678.7 4044.7 4143.5 30629.8 31504.2 31644.7 31759.0 32358.8 2557.4 2640.5 3076.3 3248.6 3285.8 390737.0 394301.0 433736.0 438492.0 444086.0 59054.7 59441.4 59546.7 59569.7 59985.4 62325.7 62563.0 62957.7 63013.0 63072.0 14588.9 14732.5 14762.2 14847.1 14848.8 9914.2 11239.0 11273.0 11367.9 11376.2 15145.2 16211.7 16213.4 16234.4 16234.9 5879.8 6789.3 6966.2 7021.6 7052.5 785.7 866.8 904.6 932.0 946.3 28085.9 29915.3 30925.7 31105.4 31258.2 871.8 1025.4 1070.5 1073.2 1111.4 11585.7 15897.0 16097.8 16106.0 16290.0 562278.0 599240.0 604191.0 607785.0 609842.0 44105.8 59258.0 59320.4 59326.0 59359.5 1323.3 1361.7 1385.3 1394.3 1394.8 25008.6 29977.0 30352.1 30755.7 30999.4 50625.7 50847.7 51277.2 51338.5 51350.2 459613.0 463021.1 463172.0 463368.7 466147.0 26432.1 26664.6 26846.9 26882.3 27023.3 47343.3 47534.0 48527.8 49305.8 49436.5 39202.6 39343.8 39468.2 40589.8 40788.6 38925.6 38952.4 39251.2 40277.6 41128.0 37963.2 38850.0 38884.0 39058.2 39119.4 30963.0 34518.3 34613.0 34637.6 35302.9 25045.6 25845.9 25941.5 26006.7 26075.7 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:34322.56 Median:10671.46 stddev:96819.50 (907.3%) stddev-sans-first:97057.52 09:16:18 INFO - PROCESS | 1946 | Values: 359.4 366.0 373.1 375.9 393.6 16564.6 19401.5 20462.8 20670.7 20851.8 11502.4 11795.9 11910.8 11911.7 11934.2 5023.6 5309.5 5374.2 5409.9 5456.0 3715.2 3868.3 5311.4 5363.2 5474.5 4765.3 5860.9 6059.4 6077.2 6088.6 6069.9 6096.4 6546.4 6563.5 6571.4 5500.5 5735.6 5785.2 5795.2 5814.9 12970.8 13230.9 13316.7 13355.9 13383.7 2366.4 2579.3 2675.5 2898.3 2898.8 9830.9 9879.9 10301.6 12270.4 15738.2 1847.5 1891.2 1897.9 1913.2 1941.4 119492.4 127037.4 128326.0 128832.0 128879.0 9238.3 11261.0 11306.8 11615.9 11708.5 14645.4 14660.4 14662.5 14663.9 14666.6 7835.0 8099.0 8113.7 8255.6 8278.0 7768.8 7843.6 7854.0 7947.1 7979.0 4485.6 4498.5 4501.0 4501.1 4575.0 1583.3 1662.9 1900.4 1950.3 1993.2 843.2 848.6 872.3 894.2 981.3 15281.5 16224.9 17759.8 20732.8 20775.7 839.1 952.6 960.5 963.7 1005.1 10248.1 10361.7 10895.1 10918.2 10959.7 569967.0 573106.0 577620.1 580598.0 582682.0 127374.1 129133.0 130654.3 131347.3 131444.0 980.7 990.5 991.9 1002.8 1004.6 18086.6 18136.6 18201.5 18366.1 18490.4 52650.9 53135.5 53764.7 54351.0 55161.7 74153.9 75758.6 77662.6 79986.0 85664.6 7836.3 11549.5 11556.2 11811.4 12077.3 10974.8 12989.5 13165.1 13352.9 13455.5 11320.5 11601.6 11767.0 11817.2 11908.9 11261.3 11339.4 11570.8 11588.0 11623.2 11531.7 11648.9 11795.4 11948.5 12010.3 12023.6 12107.8 12291.7 12323.8 12432.5 11669.7 11758.5 11887.1 11932.4 12026.4 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5679.51 Median:5239.23 stddev:3140.09 (59.9%) stddev-sans-first:3132.96 09:16:18 INFO - PROCESS | 1946 | Values: 1470.6 1512.7 1521.5 1714.5 1871.8 7984.1 8276.4 8473.9 8975.9 9005.5 8483.9 8777.0 8787.0 8839.4 8890.0 2355.5 2364.3 2393.7 2407.9 2410.4 2358.2 2386.0 2395.3 2397.3 2408.6 2355.5 2400.4 2402.9 2406.2 2406.4 2338.9 2356.1 2367.6 2371.0 2372.5 2341.3 2352.9 2353.0 2360.0 2364.3 8751.7 8814.4 8895.5 8899.0 8906.0 5261.5 5364.2 5429.7 5430.2 5434.1 3366.6 3382.7 3433.4 3508.9 3527.4 1076.0 1082.2 1084.8 1086.8 1090.0 10016.7 11468.3 11532.5 11619.7 11620.4 4555.0 4636.4 4813.0 4825.3 4831.5 9798.5 9820.4 9903.9 9909.3 9910.6 5337.2 5358.5 5395.3 5398.3 5405.3 4398.7 4566.3 4738.8 4868.8 4939.0 3870.6 3907.9 3974.3 3978.0 4021.9 1291.9 1305.7 1310.5 1315.4 1318.8 695.9 909.1 922.7 928.9 930.7 5718.3 5859.2 5913.4 6083.7 6089.1 812.0 897.8 963.4 968.0 1012.1 3807.2 3884.7 3892.4 3953.2 3999.5 10302.7 10789.6 10792.4 10808.0 10811.1 10750.5 10756.7 10757.4 10774.4 10778.3 7690.7 8075.9 8082.0 8113.1 8200.7 4468.8 4953.6 5217.0 5874.6 6285.9 7710.0 8814.3 9263.7 9854.9 9942.0 9243.5 9606.4 9813.3 9853.9 10201.5 6951.3 7196.3 7345.2 7403.2 7631.1 8276.0 8398.8 8453.2 8465.7 8466.3 7529.5 7564.5 7597.6 7666.8 7669.2 7515.2 7566.9 7617.6 7658.6 7723.3 6780.2 6909.9 6956.6 6964.0 6982.5 5066.6 5076.9 5170.7 5192.3 5330.9 5140.4 5202.8 5350.9 5429.2 5436.5 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:4641.68 Median:5310.14 stddev:1447.13 (27.3%) stddev-sans-first:1413.20 09:16:18 INFO - PROCESS | 1946 | Values: 252.6 306.9 308.9 314.0 316.6 4419.8 5618.4 5676.9 5687.4 5712.8 4557.5 5408.4 5470.3 5530.9 5614.5 4024.4 4060.1 4142.4 4204.7 4223.5 3633.3 4187.5 4198.2 4260.9 4283.7 4168.5 4218.7 4248.9 4251.3 4266.1 3383.5 3925.4 4188.0 4227.8 4247.9 3015.5 3616.0 3621.5 3646.2 3648.1 4196.4 5373.3 5391.0 5424.5 5510.6 4144.7 4433.5 4753.5 4906.7 4924.9 5867.6 5916.5 5937.8 5943.5 5990.3 3080.8 3235.5 3365.9 3957.5 4063.3 4544.7 5305.5 5616.0 6126.3 6169.5 3539.9 3806.0 3944.3 3973.9 4069.1 3416.0 3824.2 3858.9 4085.7 4109.3 3824.5 4754.6 4769.3 4818.8 4829.3 5187.7 5585.0 5788.9 5867.4 5956.8 1405.8 1418.3 1444.4 1447.2 1454.9 756.5 868.9 877.9 894.7 895.9 5331.5 5365.0 5488.6 5503.4 5532.8 5021.8 5908.8 5977.5 6091.6 6119.3 4806.5 5026.8 5408.5 5436.0 5550.5 3124.3 3127.2 3139.0 3140.3 3163.4 5664.0 5758.0 6180.4 6188.5 6252.4 4929.9 6042.8 6060.5 6066.6 6123.8 5278.5 5354.0 5748.7 5858.7 6205.0 4348.5 4873.5 5328.8 5346.8 5949.5 4740.9 5163.3 5385.4 5440.9 5940.9 5113.0 5458.2 6151.3 6159.0 6159.5 3303.5 3634.5 3847.7 3978.6 4067.8 5490.0 6052.9 6063.7 6081.2 6110.0 5124.3 5378.5 5897.6 5897.9 5914.5 4916.3 5032.6 5453.9 5652.6 5907.2 4330.8 5314.8 5778.6 5830.2 5860.9 4890.6 5512.0 5605.8 5731.5 5751.0 5729.0 5733.2 5769.6 5782.4 5800.2 09:16:18 INFO - PROCESS | 1946 | 09:16:18 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:23867.93 Median:3601.01 stddev:38497.35 (1069.1%) stddev-sans-first:38565.95 09:16:18 INFO - PROCESS | 1946 | Values: 672.4 675.5 720.7 776.0 817.1 15582.2 15701.7 16264.8 16273.2 16337.8 14641.0 14753.6 14798.0 14836.7 14841.4 694.2 707.1 848.5 857.2 873.1 884.9 888.7 891.5 895.0 896.5 942.9 943.2 945.4 946.3 947.1 935.9 939.1 939.3 939.3 940.2 691.0 697.6 699.6 701.4 705.0 15376.1 15391.6 15403.0 15411.6 15418.7 14588.6 14728.0 14798.9 14830.4 14847.0 10535.1 11110.4 11844.9 12116.9 12198.8 3761.2 3846.0 3861.7 3999.1 4000.6 108975.0 109146.0 116193.0 116491.4 120778.0 8529.8 11201.1 11230.7 11317.3 11425.4 19120.5 23540.8 23579.8 23845.2 23900.6 7970.2 7984.3 8078.4 8132.9 8251.1 4275.2 4308.6 4332.9 4367.8 4472.7 3583.5 3618.6 3688.2 3694.7 3695.3 583.7 612.3 644.3 661.8 670.7 571.6 579.8 588.0 673.8 709.5 8960.0 9215.0 9547.4 9556.5 9806.8 542.5 582.8 601.7 627.6 678.6 3776.8 4377.6 4533.5 4624.1 4629.5 125283.0 161170.0 162635.2 163353.0 167512.0 108825.0 110483.3 111042.4 123015.4 130679.0 104530.0 105019.3 105510.5 107832.0 108177.0 9251.3 9791.7 9908.4 9943.3 10104.6 52738.0 55751.4 59105.0 59449.1 59469.7 52699.0 52898.7 68494.0 76726.0 80422.0 12730.9 12808.6 13036.8 13056.9 13396.3 11846.6 12048.7 13165.4 14286.4 14664.9 11216.9 11810.8 11931.9 12090.5 12252.6 11475.5 11808.5 12056.0 12384.2 13316.3 11794.4 12956.9 13434.7 13475.5 13668.9 10653.6 11379.4 11618.2 11744.6 11868.0 10574.6 10712.4 10794.7 11500.6 11650.6 09:16:18 INFO - PROCESS | 1946 | -------- Summary: end -------- 09:16:18 INFO - PROCESS | 1946 | 09:16:19 INFO - TEST-INFO | 1946: exit 0 09:16:19 INFO - TEST-OK | dromaeo_css | took 1116431ms 09:16:19 INFO - TEST-START | kraken 09:16:19 INFO - operating with platform_type : mac_ 09:16:19 INFO - Initialising browser for kraken test... 09:16:19 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpGriX4s/profile http://localhost:52682/getInfo.html) 09:16:22 INFO - PROCESS | 1951 | 2016-10-18 09:16:22.044 plugin-container[1952:17661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 09:16:22 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:22 INFO - PROCESS | 1951 | 2016-10-18 09:16:22.050 plugin-container[1952:17661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 09:16:22 INFO - PROCESS | 1951 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:22 INFO - PROCESS | 1951 | 2016-10-18 09:16:22.050 plugin-container[1952:17661] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:22 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 09:16:22 INFO - PROCESS | 1951 | colorDepth:24 09:16:22 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 09:16:22 INFO - PROCESS | 1951 | __metrics 09:16:22 ERROR - PROCESS | 1951 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 09:16:22 INFO - PROCESS | 1951 | 09:16:22 INFO - PROCESS | 1951 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x1060015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 09:16:22 INFO - PROCESS | 1951 | 09:16:23 INFO - TEST-INFO | 1951: exit 0 09:16:23 INFO - Browser initialized. 09:16:23 INFO - Running cycle 1/1 for kraken test... 09:16:23 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/tmpGriX4s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:25 INFO - PROCESS | 1953 | 2016-10-18 09:16:25.488 plugin-container[1954:17774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:16:25 INFO - PROCESS | 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:25 INFO - PROCESS | 1953 | 2016-10-18 09:16:25.489 plugin-container[1954:17774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 09:16:25 INFO - PROCESS | 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:25 INFO - PROCESS | 1953 | 2016-10-18 09:16:25.489 plugin-container[1954:17774] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:35 INFO - PROCESS | 1953 | RSS: Main: 237056000 09:16:35 INFO - PROCESS | 1953 | 09:16:37 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:38 INFO - PROCESS | 1953 | RSS: Main: 249708544 09:16:38 INFO - PROCESS | 1953 | 09:16:41 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-dft) 09:16:42 INFO - PROCESS | 1953 | RSS: Main: 258342912 09:16:42 INFO - PROCESS | 1953 | 09:16:45 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-fft) 09:16:46 INFO - PROCESS | 1953 | RSS: Main: 260362240 09:16:46 INFO - PROCESS | 1953 | 09:16:48 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52682/tests/kraken/driver.html?testName=audio-oscillator) 09:16:48 INFO - PROCESS | 1953 | RSS: Main: 261500928 09:16:48 INFO - PROCESS | 1953 | 09:16:50 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:16:51 INFO - PROCESS | 1953 | RSS: Main: 262012928 09:16:51 INFO - PROCESS | 1953 | 09:16:54 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-darkroom) 09:16:54 INFO - PROCESS | 1953 | RSS: Main: 262197248 09:16:54 INFO - PROCESS | 1953 | 09:16:57 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52682/tests/kraken/driver.html?testName=imaging-desaturate) 09:16:58 INFO - PROCESS | 1953 | RSS: Main: 263561216 09:16:58 INFO - PROCESS | 1953 | 09:17:01 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52682/tests/kraken/driver.html?testName=json-parse-financial) 09:17:02 INFO - PROCESS | 1953 | RSS: Main: 263802880 09:17:02 INFO - PROCESS | 1953 | 09:17:03 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:04 INFO - PROCESS | 1953 | RSS: Main: 263229440 09:17:04 INFO - PROCESS | 1953 | 09:17:06 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:17:07 INFO - PROCESS | 1953 | RSS: Main: 263380992 09:17:07 INFO - PROCESS | 1953 | 09:17:09 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:17:10 INFO - PROCESS | 1953 | RSS: Main: 263147520 09:17:10 INFO - PROCESS | 1953 | 09:17:13 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:17:13 INFO - PROCESS | 1953 | RSS: Main: 263610368 09:17:13 INFO - PROCESS | 1953 | 09:17:16 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:17 INFO - PROCESS | 1953 | RSS: Main: 263696384 09:17:17 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52682/tests/kraken/driver.html?testName=ai-astar) 09:17:19 INFO - PROCESS | 1953 | RSS: Main: 265302016 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | __start_tp_report 09:17:19 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 09:17:19 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 09:17:19 INFO - PROCESS | 1953 | |i|pagename|runs| 09:17:19 INFO - PROCESS | 1953 | |0;ai-astar;114;120;118;125;120;119;116;118;121;154 09:17:19 INFO - PROCESS | 1953 | |1;audio-beat-detection;187;122;116;160;148;124;114;115;140;114 09:17:19 INFO - PROCESS | 1953 | |2;audio-dft;228;265;212;230;262;224;196;197;201;219 09:17:19 INFO - PROCESS | 1953 | |3;audio-fft;90;61;59;86;61;57;59;58;80;62 09:17:19 INFO - PROCESS | 1953 | |4;audio-oscillator;85;69;90;72;69;89;127;86;71;87 09:17:19 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;88;87;106;112;83;133;135;89;92;128 09:17:19 INFO - PROCESS | 1953 | |6;imaging-darkroom;97;100;101;99;100;99;99;100;120;173 09:17:19 INFO - PROCESS | 1953 | |7;imaging-desaturate;126;90;125;139;116;87;90;91;85;91 09:17:19 INFO - PROCESS | 1953 | |8;json-parse-financial;57;56;57;59;55;66;67;89;82;48 09:17:19 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;69;74;70;73;113;79;63;89;65;71 09:17:19 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;86;87;79;107;112;111;82;113;111;113 09:17:19 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;163;165;156;145;150;153;175;139;225;168 09:17:19 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;177;175;174;237;219;174;177;174;176;208 09:17:19 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;70;72;69;69;72;68;68;71;70;67 09:17:19 INFO - PROCESS | 1953 | __end_tp_report 09:17:19 INFO - PROCESS | 1953 | __start_cc_report 09:17:19 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,949 09:17:19 INFO - PROCESS | 1953 | __end_cc_report 09:17:19 INFO - PROCESS | 1953 | __startTimestamp1476807439572__endTimestamp 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | ------- Summary: start ------- 09:17:19 INFO - PROCESS | 1953 | Number of tests: 14 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:122.50 Median:120.00 stddev:11.45 (9.5%) stddev-sans-first:11.73 09:17:19 INFO - PROCESS | 1953 | Values: 114.0 120.0 118.0 125.0 120.0 119.0 116.0 118.0 121.0 154.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:132.00 stddev:24.60 (18.6%) stddev-sans-first:17.05 09:17:19 INFO - PROCESS | 1953 | Values: 187.0 122.0 116.0 160.0 148.0 124.0 114.0 115.0 140.0 114.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:223.40 Median:226.00 stddev:24.46 (10.8%) stddev-sans-first:25.89 09:17:19 INFO - PROCESS | 1953 | Values: 228.0 265.0 212.0 230.0 262.0 224.0 196.0 197.0 201.0 219.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:12.75 (20.7%) stddev-sans-first:10.56 09:17:19 INFO - PROCESS | 1953 | Values: 90.0 61.0 59.0 86.0 61.0 57.0 59.0 58.0 80.0 62.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:85.50 stddev:17.27 (20.2%) stddev-sans-first:18.32 09:17:19 INFO - PROCESS | 1953 | Values: 85.0 69.0 90.0 72.0 69.0 89.0 127.0 86.0 71.0 87.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:105.30 Median:85.00 stddev:20.51 (24.1%) stddev-sans-first:20.77 09:17:19 INFO - PROCESS | 1953 | Values: 88.0 87.0 106.0 112.0 83.0 133.0 135.0 89.0 92.0 128.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:135.00 stddev:23.49 (17.4%) stddev-sans-first:24.53 09:17:19 INFO - PROCESS | 1953 | Values: 97.0 100.0 101.0 99.0 100.0 99.0 99.0 100.0 120.0 173.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:104.00 Median:88.50 stddev:20.20 (22.8%) stddev-sans-first:19.80 09:17:19 INFO - PROCESS | 1953 | Values: 126.0 90.0 125.0 139.0 116.0 87.0 90.0 91.0 85.0 91.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:62.50 stddev:12.84 (20.5%) stddev-sans-first:13.40 09:17:19 INFO - PROCESS | 1953 | Values: 57.0 56.0 57.0 59.0 55.0 66.0 67.0 89.0 82.0 48.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:72.00 stddev:14.74 (20.5%) stddev-sans-first:15.38 09:17:19 INFO - PROCESS | 1953 | Values: 69.0 74.0 70.0 73.0 113.0 79.0 63.0 89.0 65.0 71.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:100.10 Median:96.00 stddev:14.54 (15.1%) stddev-sans-first:14.50 09:17:19 INFO - PROCESS | 1953 | Values: 86.0 87.0 79.0 107.0 112.0 111.0 82.0 113.0 111.0 113.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:164.00 stddev:24.10 (14.7%) stddev-sans-first:25.56 09:17:19 INFO - PROCESS | 1953 | Values: 163.0 165.0 156.0 145.0 150.0 153.0 175.0 139.0 225.0 168.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:177.00 stddev:23.32 (13.2%) stddev-sans-first:24.32 09:17:19 INFO - PROCESS | 1953 | Values: 177.0 175.0 174.0 237.0 219.0 174.0 177.0 174.0 176.0 208.0 09:17:19 INFO - PROCESS | 1953 | 09:17:19 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.71 (2.4%) stddev-sans-first:1.81 09:17:19 INFO - PROCESS | 1953 | Values: 70.0 72.0 69.0 69.0 72.0 68.0 68.0 71.0 70.0 67.0 09:17:19 INFO - PROCESS | 1953 | -------- Summary: end -------- 09:17:19 INFO - PROCESS | 1953 | 09:17:20 INFO - TEST-INFO | 1953: exit 0 09:17:20 INFO - TEST-OK | kraken | took 61154ms 09:17:20 INFO - SUITE-END | took 1177s 09:17:20 INFO - Completed test suite (00:19:37) 09:17:20 INFO - javascript benchmark 09:17:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7304.700235779774, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.952825707615, 3284.310294838085, 3324.9501257481143, 3357.7649564530484, 3374.696277335039, 4565.406497155369, 5545.528630066449, 5579.107342825148, 5605.411431799664, 5705.172749951254, 5557.527610153132, 5586.972065139679, 5648.350439699436, 5686.545076393889, 5694.743736531856, 5066.771995260222, 5080.745962701869, 5094.668846524983, 5211.843644690665, 5212.139996900507, 5111.488851114896, 5255.710935898524, 5256.474352564751, 5264.736763161845, 5268.92096618551, 4127.40152677863, 5048.6465947383695, 5125.794968201268, 5132.743362831843, 5212.452692467275, 5152.072626927152, 5162.328897243372, 5175.611829112794, 5215.0873597120735, 5221.425643179247, 5122.103631864409, 5129.9743501282255, 5183.714895680736, 5189.221616757479, 5203.947960520384, 5447.700376479292, 5453.209284653703, 5530.6128571000045, 5532.806351777709, 5577.3028371453565, 6145.631262124287, 6148.1392605035335, 6200.441960223601, 6212.596181248249, 6232.688365581703, 6201.317855035943, 6208.192934918477, 6214.875702485925, 6233.0, 6245.188125543696, 5660.518855897212, 5686.601937864392, 5726.828195154152, 5818.557069288826, 5843.678597677126, 6114.905704492616, 6167.722452489649, 6191.876162476815, 6209.348018458088, 6217.626942383471, 6053.152558641706, 6104.572679912363, 6121.4490695837585, 6128.448439640365, 6129.877402451926, 6097.706167156046, 6103.664298463627, 6145.028258742197, 6205.627662340274, 6206.503479721611, 4978.800847966113, 5027.321311622957, 6109.541784366191, 6149.907751383733, 6213.906791398133, 6139.447449729546, 6194.845128871724, 6210.7515699371515, 6223.066540019034, 6233.625982441068, 4369.847055353048, 4893.730844803499, 4910.533499317559, 4914.680545764585, 4938.703677779345, 3803.2963901678277, 4024.9132734161612, 4228.4080228768, 4350.390945267665, 4364.432623758891, 4597.655175861757, 5188.636795424284, 5749.425057494301, 5777.508911742465, 5811.186433899257, 6186.597871138451, 6186.628802271878, 6187.504999600022, 6205.844853878689, 6211.782587609502, 4539.795709193094, 4630.699004564693, 5265.52610265078, 5787.88424231513, 5882.294124705063, 4463.732176069446, 5418.133098704188, 5427.620066595379, 5454.318210223722, 5479.178123281539, 6239.625622462667, 6277.623342599459, 6291.591046582049, 6297.464715499143, 6307.779227727007, 6041.154238406539, 6086.0, 6134.877302454015, 6167.229096362955, 6167.568270221221, 6188.133661287513, 6195.318514963441, 6203.596766210182, 6282.528810339151, 6314.494840412664, 5970.223870896755, 6118.174046503843, 6128.019516877278, 6142.170806941006, 6170.598911070766, 6106.08408738675, 6112.26032301701, 6126.761967422209, 6131.284916200975, 6160.507159427146, 5878.882422351615, 5908.822735317947, 5968.015277479168, 6042.274927008787, 6105.938940610537, 5984.581079324405, 6098.704546243851, 6124.136831511461, 6125.937554509019, 6133.671320645445, 5858.121281807593, 5956.970215148896, 6003.759849605967, 6015.39846015395, 6032.366601506778, 6013.87972240544, 6037.335893051673, 6049.758009679564, 6062.18160548338, 6119.265688117454, 5944.524438045032, 5968.283805943314, 5969.552283578836, 5987.042073268256, 6071.908921366095], "value": 5590.401258358924, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.87916081399237, 485.2091091520821, 528.9100852855016, 529.2669652531246, 538.98670499461, 34420.623175073, 42100.736977890636, 42402.36396454056, 43896.34155487662, 44009.55990440094, 43199.136017279634, 43443.3483497747, 43732.56267437332, 43908.78045609767, 43914.90212744683, 9555.474448905308, 9705.75735606612, 9718.854217186748, 9772.804543909118, 9784.657536986171, 9576.90423095767, 9625.22998160149, 9724.902750972471, 9725.27060470464, 9732.65935692251, 9679.225661947028, 9713.028697130301, 9732.2214222862, 9735.51322433879, 9748.463834489101, 9637.807243855119, 9710.465924374157, 9720.07659272368, 9729.70810875675, 9740.220782337396, 9526.571304291254, 9622.615095396175, 9648.276379271512, 9665.46839923804, 9678.322517423712, 40417.19165616671, 40881.386779198336, 41256.1748765023, 41271.0, 41445.37831932523, 3479.373712731707, 3602.513660655804, 3678.6873115785193, 4044.737092089005, 4143.461350024478, 30629.846850765825, 31504.212394690087, 31644.683553164403, 31759.0, 32358.838205809057, 2557.4373637799654, 2640.4719056188646, 3076.3385872037397, 3248.5776849009712, 3285.8192799396024, 390736.99999999715, 394301.0, 433736.0, 438492.0, 444086.0, 59054.70472647609, 59441.40558594445, 59546.70226648839, 59569.702151488964, 59985.40014599886, 62325.68837155785, 62563.0, 62957.685211574564, 63013.0, 63072.0, 14588.927055364655, 14732.484363047242, 14762.188079655507, 14847.109173449628, 14848.777268340984, 9914.157296629868, 11239.0, 11273.0, 11367.943160284145, 11376.203665743484, 15145.242737863064, 16211.675766484605, 16213.432529861399, 16234.431794887123, 16234.944728592603, 5879.823605291848, 6789.32106789327, 6966.164060312795, 7021.566096322304, 7052.506324557232, 785.6935795039939, 866.757307953774, 904.6336233825311, 932.0306880843982, 946.3186505715873, 28085.859570702425, 29915.252118697208, 30925.69074309273, 31105.377892442026, 31258.21854453657, 871.8186856809059, 1025.3898930136556, 1070.5182667799522, 1073.162932912314, 1111.4053981120062, 11585.710357241136, 15897.046177229173, 16097.839021609634, 16106.0, 16290.022598643882, 562278.0, 599240.0, 604191.0, 607785.0, 609842.0, 44105.77947110244, 59258.0, 59320.406795931485, 59326.0, 59359.516012100044, 1323.3118778235457, 1361.7276544691101, 1385.3211926156112, 1394.2749770119683, 1394.8186735724291, 25008.624870627318, 29977.04165300147, 30352.116548927817, 30755.692443075284, 30999.380012400077, 50625.74687126541, 50847.745761270955, 51277.230841538156, 51338.48661513337, 51350.22974655309, 459613.0, 463021.10936671955, 463172.0, 463368.68315658206, 466147.0, 26432.0748773792, 26664.60003099916, 26846.865765671046, 26882.32794180161, 27023.324416889736, 47343.28985065296, 47534.0, 48527.78680533015, 49305.75347123241, 49436.50563494319, 39202.60797392104, 39343.80328098455, 39468.21063578655, 40589.79705101455, 40788.59211407966, 38925.6107438922, 38952.41571376375, 39251.21497569975, 40277.597224027384, 41128.0, 37963.24073518459, 38850.0, 38884.0, 39058.218835623695, 39119.41320880246, 30963.0, 34518.30963380668, 34613.0, 34637.575369723876, 35302.94091177269, 25045.624315634916, 25845.870770646026, 25941.481170376865, 26006.73993260043, 26075.73924260809], "value": 19542.953951720097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.37110057399553, 365.9817009149542, 373.10454908220265, 375.9003863976045, 393.6378531750789, 16564.585885352873, 19401.514962125955, 20462.795372046276, 20670.6899396509, 20851.791482085173, 11502.42487875607, 11795.882041179586, 11910.821337679941, 11911.67722294153, 11934.224275422092, 5023.572996295319, 5309.495597918191, 5374.220737992989, 5409.891802163955, 5455.972720136394, 3715.1826598148364, 3868.3423817951016, 5311.389190243113, 5363.168708850135, 5474.534664553517, 4765.332853400527, 5860.912086318709, 6059.424354686298, 6077.209962704865, 6088.573799834014, 6069.939300607004, 6096.390360963913, 6546.410823025951, 6563.54055216135, 6571.3757193066585, 5500.532454741352, 5735.598508104435, 5785.161151633031, 5795.2176456178295, 5814.912776308359, 12970.80543791844, 13230.933845330808, 13316.73366532664, 13355.931525477938, 13383.732325353438, 2366.420227044368, 2579.3293743626605, 2675.5184066867955, 2898.318895059659, 2898.840563768992, 9830.852537211947, 9879.901200988043, 10301.587936482532, 12270.447829847679, 15738.213089345487, 1847.4919397165752, 1891.2056936086876, 1897.8671492995495, 1913.1677720191622, 1941.3981665683684, 119492.40253798763, 127037.36481317534, 128325.99999999907, 128832.0, 128879.0, 9238.260939124853, 11261.042811360961, 11306.773864522664, 11615.883841161649, 11708.531658733726, 14645.414183432544, 14660.413583456755, 14662.486812961495, 14663.853361466248, 14666.580014297522, 7835.0, 8099.0, 8113.716019939392, 8255.628496717662, 8277.95861020691, 7768.844623107506, 7843.607819608997, 7854.0, 7947.125816160218, 7979.0, 4485.551444855553, 4498.482674492475, 4500.954990450119, 4501.144782491317, 4574.954250457453, 1583.2558697412198, 1662.8586570141674, 1900.4108726294892, 1950.3368854589507, 1993.2425678242175, 843.2158092973593, 848.5587494502947, 872.3065163195148, 894.186290475664, 981.3326937682444, 15281.541553753405, 16224.918875405547, 17759.822401776077, 20732.792672073087, 20775.68836467454, 839.1063517353998, 952.6141912525438, 960.5485421851719, 963.6868017894212, 1005.1255407795265, 10248.128909042814, 10361.74095647615, 10895.073918716998, 10918.235723499438, 10959.671209863716, 569967.0, 573106.0, 577620.1118994462, 580598.0, 582682.0, 127374.08938865924, 129133.0, 130654.34672826766, 131347.34326328308, 131444.0, 980.6616717232544, 990.5344488090586, 991.8561808537721, 1002.8044531316469, 1004.6282875336026, 18086.638267234845, 18136.63726725432, 18201.45395638133, 18366.081695914883, 18490.35283765062, 52650.94698106093, 53135.46864531305, 53764.731176345435, 54351.0, 55161.72419137879, 74153.88769168359, 75758.62120689361, 77662.61168694346, 79986.0, 85664.57167714121, 7836.333911617577, 11549.538018479167, 11556.191066625255, 11811.409429528661, 12077.27536347822, 10974.780504390028, 12989.48042078344, 13165.078444509176, 13352.933235333761, 13455.46178152863, 11320.490577924178, 11601.593944211912, 11767.0, 11817.231879927776, 11908.92819646236, 11261.268017578832, 11339.376334301693, 11570.76858462843, 11588.0, 11623.186376953532, 11531.731509533776, 11648.883511165119, 11795.410229488663, 11948.462319195483, 12010.339431331011, 12023.63929082129, 12107.757844842878, 12291.69270768238, 12323.815142773044, 12432.502699891904, 11669.708257293296, 11758.470868810753, 11887.108466864847, 11932.403379830801, 12026.398680065786], "value": 9420.835465510649, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.6249906273902, 1512.7352713275175, 1521.4598817419817, 1714.5199344183625, 1871.7753869535659, 7984.081830589469, 8276.379271554628, 8473.91526084739, 8975.865362019567, 9005.45967241965, 8483.939507561554, 8776.956115219415, 8786.956065219681, 8839.425437346568, 8889.977652569956, 2355.4817940053244, 2364.3143488388346, 2393.748656391071, 2407.879606019701, 2410.3853517353136, 2358.174638876388, 2385.988070059656, 2395.317334559651, 2397.2927986244013, 2408.614621660535, 2355.458244603742, 2400.3879010852297, 2402.8798560072014, 2406.1939250351065, 2406.3984003999, 2338.8830558472177, 2356.057576969209, 2367.633016882386, 2370.9644355334685, 2372.50177462733, 2341.262502311766, 2352.8941197646145, 2352.9529409411716, 2360.0, 2364.2788949370433, 8751.693690720795, 8814.427062240999, 8895.510746908918, 8898.976617688984, 8905.955470222672, 5261.473852614746, 5364.168553874117, 5429.701366424845, 5430.15832545956, 5434.076207044812, 3366.646502117267, 3382.678645528671, 3433.4163192257374, 3508.912277193065, 3527.417976033951, 1076.0100707349256, 1082.242430298786, 1084.7613525024483, 1086.8369744538304, 1089.9727506812314, 10016.699499014969, 11468.254563453349, 11532.538698452052, 11619.6514104577, 11620.418979051097, 4554.95445045552, 4636.374089497942, 4813.002603919334, 4825.324454576362, 4831.51684831514, 9798.46108464027, 9820.4107753535, 9903.851442228372, 9909.306348555532, 9910.55402506889, 5337.226102215157, 5358.544523715528, 5395.2986111806, 5398.271233383443, 5405.324334458192, 4398.73607583546, 4566.3150527420485, 4738.810447582058, 4868.805247790119, 4938.975305123451, 3870.5548861881243, 3907.8632247871187, 3974.344233201547, 3978.0054986253435, 4021.879343619696, 1291.877271659191, 1305.6996890715, 1310.541310541322, 1315.3883444198493, 1318.8021796730568, 695.9060526828914, 909.0818273543769, 922.7324076018014, 928.8513837785922, 930.7068273493828, 5718.285214348207, 5859.2090067841145, 5913.438223368773, 6083.695815209222, 6089.056196289582, 812.0174588747564, 897.8204359128069, 963.3786207895868, 967.9787823845769, 1012.104287705386, 3807.181455986921, 3884.7280690351404, 3892.416137579329, 3953.2488827122756, 3999.460072890123, 10302.742431439125, 10789.62236321725, 10792.352458852338, 10808.0, 10811.081058110149, 10750.516226769814, 10756.677299681021, 10757.354558726502, 10774.353538787698, 10778.29941053829, 7690.653920573588, 8075.878861817077, 8081.989751281089, 8113.0669972953865, 8200.712975045726, 4468.754218517883, 4953.628477864103, 5216.982688375882, 5874.559408044328, 6285.905711414424, 7709.9614501927135, 8814.338924580554, 9263.749393832017, 9854.901450985399, 9942.005799420289, 9243.491607961627, 9606.423614583147, 9813.313068085166, 9853.901460985298, 10201.489925503844, 6951.304869513008, 7196.280371962762, 7345.1553071397475, 7403.222661620668, 7631.122420921665, 8276.0, 8398.83202335962, 8453.154684531497, 8465.746027619181, 8466.322694184573, 7529.510581812165, 7564.4704870658525, 7597.582132982739, 7666.8083297918, 7669.156392796899, 7515.210902855121, 7566.886496702444, 7617.619119044137, 7658.578778167254, 7723.34351580122, 6780.1524809398825, 6909.93090069113, 6956.582605043714, 6964.0, 6982.546134501241, 5066.620003499678, 5076.873078173076, 5170.741462926914, 5192.299039629602, 5330.866728331823, 5140.408852981955, 5202.765875535686, 5350.892982140414, 5429.158480435539, 5436.510714035756], "value": 4583.34953144276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.64881814278152, 306.90639354996733, 308.9181366937762, 313.96389415217243, 316.6247996124591, 4419.845305414311, 5618.381977982423, 5676.914846277309, 5687.374388817238, 5712.800051998181, 4557.544245575441, 5408.3509978802595, 5470.316210473691, 5530.944690553093, 5614.466625670555, 4024.396340548926, 4060.127072679373, 4142.378643203529, 4204.684648651363, 4223.535411104776, 3633.309671162484, 4187.497500299968, 4198.244316023115, 4260.8721738347895, 4283.7215580987295, 4168.520620128678, 4218.746875187483, 4248.87253382399, 4251.277282861921, 4266.146770645868, 3383.526306317105, 3925.3523168677134, 4188.015816283171, 4227.809748561292, 4247.87256382309, 3015.517517197238, 3615.963840361589, 3621.4567814827724, 3646.179609587848, 3648.0847555033556, 4196.433481479991, 5373.274607927919, 5390.973045134789, 5424.511793938525, 5510.6418082824885, 4144.668426525871, 4433.512313645497, 4753.548412900769, 4906.705597664116, 4924.926126108111, 5867.589268751189, 5916.497097746696, 5937.812437512514, 5943.524518038546, 5990.341062483123, 3080.7843450958444, 3235.4823228283362, 3365.8653653853817, 3957.526545908807, 4063.2686116499017, 4544.681872268942, 5305.522502974751, 5616.0, 6126.264848218242, 6169.537284703665, 3539.8938031859084, 3806.01043728627, 3944.3097457944746, 3973.8807835764974, 4069.064115253508, 3416.0, 3824.240849209339, 3858.944782335094, 4085.6935729820384, 4109.321961876317, 3824.541055073409, 4754.572088511982, 4769.260764581495, 4818.759062046884, 4829.251466022772, 5187.688738675692, 5584.966781145501, 5788.855278617984, 5867.413258674183, 5956.761729530857, 1405.845357010728, 1418.319206780751, 1444.3572610188364, 1447.1751101871832, 1454.8908831837655, 756.5196100476237, 868.8740132680726, 877.9385443018928, 894.6645008121955, 895.8611415230649, 5331.466853314715, 5364.999999999922, 5488.572971027562, 5503.449655034545, 5532.75102620383, 5021.824236151717, 5908.784205922779, 5977.521798256129, 6091.604045737013, 6119.326874043852, 4806.519348065165, 5026.84919452417, 5408.45915408456, 5435.972820135874, 5550.500454959073, 3124.265797537577, 3127.2338277121794, 3138.98430507846, 3140.309131990958, 3163.430582495127, 5663.971680141655, 5758.021136406821, 6180.393097794606, 6188.545691762432, 6252.436890777306, 4929.876753081202, 6042.764177254444, 6060.484858786965, 6066.636001839904, 6123.84690382744, 5278.498542638443, 5353.973230133903, 5748.7125643717645, 5858.7070646466655, 6204.968975155186, 4348.543402942645, 4873.537013983666, 5328.786848526171, 5346.786128554814, 5949.464548190684, 4740.857774266778, 5163.302954101148, 5385.434529374359, 5440.918386224131, 5940.851478713067, 5113.0, 5458.235846981505, 6151.323354431099, 6158.969205153946, 6159.538034647509, 3303.5147918945745, 3634.4911712359763, 3847.7306588539655, 3978.622030907059, 4067.7559346439307, 5490.0, 6052.9445271668965, 6063.6664983426335, 6081.209442772588, 6110.0, 5124.333836601218, 5378.489043540857, 5897.616654917417, 5897.879402913432, 5914.467697907209, 4916.328448327443, 5032.62255330859, 5453.945460545502, 5652.632578882524, 5907.232059832194, 4330.783460826883, 5314.760835762478, 5778.595498315078, 5830.2129212555765, 5860.912086318794, 4890.584300334512, 5512.0, 5605.831825045255, 5731.512821410227, 5750.993576124245, 5729.0, 5733.168690539932, 5769.624974376652, 5782.421757824352, 5800.216970708901], "value": 4179.83340274978, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.3914857054367, 675.5203805298238, 720.7225218290963, 775.9961200194002, 817.0807841178674, 15582.22088895555, 15701.685966280667, 16264.83735162648, 16273.186340682978, 16337.83662163378, 14640.975131740783, 14753.557393278192, 14798.0, 14836.703265934675, 14841.406343746237, 694.1600663197512, 707.101980484784, 848.4569875279826, 857.2327766648846, 873.0919843362894, 884.8628462588309, 888.7467071884514, 891.5453118909338, 895.0, 896.5114012863005, 942.9339946203769, 943.245403677059, 945.4327403557845, 946.2950102173891, 947.1333729637408, 935.8642996765465, 939.0703203828161, 939.2532936315612, 939.286142531674, 940.2055263302499, 690.9516333856632, 697.5500801982744, 699.6257002503687, 701.424831638057, 705.0, 15376.077435353804, 15391.615209619737, 15403.0, 15411.614709632237, 15418.691626167527, 14588.635284117876, 14727.969042167053, 14798.926005369904, 14830.406783728638, 14847.0, 10535.10451611614, 11110.444477776078, 11844.88155118455, 12116.878831211752, 12198.817017744741, 3761.2101458693705, 3846.0, 3861.708937707492, 3999.060220848098, 4000.5599384067727, 108975.0, 109146.0, 116193.0, 116491.41754291175, 120778.0, 8529.82940341202, 11201.148222348382, 11230.66308010761, 11317.264377815416, 11425.428728563706, 19120.521986950436, 23540.7645923542, 23579.7642023581, 23845.165419210243, 23900.64149037731, 7970.202979701983, 7984.281414672707, 8078.39412044099, 8132.9186708132165, 8251.092379838214, 4275.187714334266, 4308.612224899712, 4332.913341733148, 4367.847125350598, 4472.7092738972515, 3583.4624806279267, 3618.565772107311, 3688.243909998444, 3694.6674799268194, 3695.3348397288746, 583.6954406900574, 612.2928018139056, 644.2687549631203, 661.8047675935611, 670.6713710281927, 571.5684658083121, 579.802867025205, 587.9739853954876, 673.8147009572407, 709.5352544083634, 8960.0, 9215.0, 9547.379420337656, 9556.522173891277, 9806.803863922682, 542.5442628192337, 582.7552427980258, 601.6901295832649, 627.632834791652, 678.6267552845915, 3776.792276424823, 4377.649788016951, 4533.549264235479, 4624.121416930772, 4629.504992945594, 125283.0, 161170.0, 162635.1868240675, 163353.0, 167512.0, 108825.0, 110483.34274985881, 111042.44478777554, 123015.38492307482, 130679.0, 104530.0, 105019.34748482802, 105510.47244763726, 107832.0, 108177.0, 9251.306152038489, 9791.706248812547, 9908.405495670282, 9943.25425593069, 10104.595816167272, 52738.0, 55751.44248557625, 59105.0, 59449.13580617246, 59469.70265148646, 52699.0, 52898.73550632222, 68494.0, 76726.0, 80422.0, 12730.936345318214, 12808.551700690432, 13036.804447933098, 13056.869431305946, 13396.330183490982, 11846.585369512026, 12048.698782530508, 13165.392112760457, 14286.357113930106, 14664.926675366554, 11216.943915280372, 11810.82283765727, 11931.880681193077, 12090.516379344728, 12252.632421027383, 11475.483603237708, 11808.468618912342, 12056.03551715843, 12384.195027323196, 13316.33418329099, 11794.410279486163, 12956.93521532424, 13434.731305374033, 13475.450323212955, 13668.93165534166, 10653.627123050655, 11379.374134422686, 11618.244814087057, 11744.58893938708, 11868.0, 10574.632618047217, 10712.442432904669, 10794.676159715222, 11500.597479088192, 11650.592229272273], "value": 7703.809394809386, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 118.0, 125.0, 120.0, 119.0, 116.0, 118.0, 121.0, 154.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 122.0, 116.0, 160.0, 148.0, 124.0, 114.0, 115.0, 140.0, 114.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 265.0, 212.0, 230.0, 262.0, 224.0, 196.0, 197.0, 201.0, 219.0], "value": 223.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 61.0, 59.0, 86.0, 61.0, 57.0, 59.0, 58.0, 80.0, 62.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 90.0, 72.0, 69.0, 89.0, 127.0, 86.0, 71.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 106.0, 112.0, 83.0, 133.0, 135.0, 89.0, 92.0, 128.0], "value": 105.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 100.0, 101.0, 99.0, 100.0, 99.0, 99.0, 100.0, 120.0, 173.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 90.0, 125.0, 139.0, 116.0, 87.0, 90.0, 91.0, 85.0, 91.0], "value": 104.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 59.0, 55.0, 66.0, 67.0, 89.0, 82.0, 48.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 70.0, 73.0, 113.0, 79.0, 63.0, 89.0, 65.0, 71.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 79.0, 107.0, 112.0, 111.0, 82.0, 113.0, 111.0, 113.0], "value": 100.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 156.0, 145.0, 150.0, 153.0, 175.0, 139.0, 225.0, 168.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 174.0, 237.0, 219.0, 174.0, 177.0, 174.0, 176.0, 208.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 69.0, 69.0, 72.0, 68.0, 68.0, 71.0, 70.0, 67.0], "value": 69.6, "unit": "score"}]}]} 09:17:20 INFO - Return code: 0 09:17:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:20 ERROR - # TBPL SUCCESS # 09:17:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:20 INFO - 2016-10-18 09:17:20.872 screenresolution[1958:18150] starting screenresolution argv=screenresolution get 09:17:20 INFO - 2016-10-18 09:17:20.874 screenresolution[1958:18150] Display 0: 1600x1200x32@60 09:17:20 INFO - 2016-10-18 09:17:20.880 screenresolution[1959:18153] starting screenresolution argv=screenresolution list 09:17:20 INFO - Available Modes on Display 0 09:17:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:21 INFO - Intel Iris: 09:17:21 INFO - Chipset Model: Intel Iris 09:17:21 INFO - Type: GPU 09:17:21 INFO - Bus: Built-In 09:17:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:21 INFO - Vendor: Intel (0x8086) 09:17:21 INFO - Device ID: 0x0a2e 09:17:21 INFO - Revision ID: 0x0009 09:17:21 INFO - Displays: 09:17:21 INFO - Display: 09:17:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:21 INFO - Display Serial Number: 1600x1200 60 09:17:21 INFO - Main Display: Yes 09:17:21 INFO - Mirror: Off 09:17:21 INFO - Online: Yes 09:17:21 INFO - Rotation: Supported 09:17:21 INFO - Return code: 0 09:17:21 INFO - Running post-action listener: _resource_record_post_action 09:17:21 INFO - [mozharness: 2016-10-18 16:17:21.124275Z] Finished run-tests step (success) 09:17:21 INFO - Running post-run listener: _resource_record_post_run 09:17:21 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 585728; Write bytes: 706583552; Read time: 6; Write time: 2015 09:17:21 INFO - TinderboxPrint: CPU usage
54.5% 09:17:21 INFO - TinderboxPrint: I/O read bytes / time
585,728 / 6 09:17:21 INFO - TinderboxPrint: I/O write bytes / time
706,583,552 / 2,015 09:17:21 INFO - TinderboxPrint: CPU idle
2,204.6 (45.5%) 09:17:21 INFO - TinderboxPrint: CPU system
982.8 (20.3%) 09:17:21 INFO - TinderboxPrint: CPU user
1,662.3 (34.3%) 09:17:21 INFO - TinderboxPrint: Swap in / out
406,708,224 / 0 09:17:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399370752; Write bytes: 394053632; Read time: 29424; Write time: 824 09:17:21 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 585728; Write bytes: 296276992; Read time: 6; Write time: 1141 09:17:21 INFO - Running post-run listener: _upload_blobber_files 09:17:21 INFO - Blob upload gear active. 09:17:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:21 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 09:17:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:17:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:17:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:17:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:22 INFO - (blobuploader) - INFO - Done attempting. 09:17:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:17:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:23 INFO - (blobuploader) - INFO - Done attempting. 09:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:26 INFO - (blobuploader) - INFO - Done attempting. 09:17:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:26 INFO - Return code: 0 09:17:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cadb21e9517a1d2b8e221f631f28484ab997c993262b6ac40c50080e4597b1607949464442d26fa09fc82e769ef8b9c04d5bd036ccf9c097f408a54ea2b749b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f79b4a4b3a0ba3e594be3d7b66d18f5bd5924fe458e3554a647b0ce2df7b23a7016e443bb12c50e0a4d59582169157ca1946daf3ad6a1f660b2171e8fbf3f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d1c65f892556414ada2fb2e1947207a95bcddd23b37a18fe250566398cbeea0a488d63524fbe62c5e8713a873035e888228b126ee728f8165aea964463b3e1d"} 09:17:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:26 INFO - Contents: 09:17:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cadb21e9517a1d2b8e221f631f28484ab997c993262b6ac40c50080e4597b1607949464442d26fa09fc82e769ef8b9c04d5bd036ccf9c097f408a54ea2b749b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f79b4a4b3a0ba3e594be3d7b66d18f5bd5924fe458e3554a647b0ce2df7b23a7016e443bb12c50e0a4d59582169157ca1946daf3ad6a1f660b2171e8fbf3f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d1c65f892556414ada2fb2e1947207a95bcddd23b37a18fe250566398cbeea0a488d63524fbe62c5e8713a873035e888228b126ee728f8165aea964463b3e1d"} 09:17:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:26 INFO - Copying logs to upload dir... 09:17:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.694025 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 09:17:26.201376) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:26.207819) ========= 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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cadb21e9517a1d2b8e221f631f28484ab997c993262b6ac40c50080e4597b1607949464442d26fa09fc82e769ef8b9c04d5bd036ccf9c097f408a54ea2b749b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f79b4a4b3a0ba3e594be3d7b66d18f5bd5924fe458e3554a647b0ce2df7b23a7016e443bb12c50e0a4d59582169157ca1946daf3ad6a1f660b2171e8fbf3f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d1c65f892556414ada2fb2e1947207a95bcddd23b37a18fe250566398cbeea0a488d63524fbe62c5e8713a873035e888228b126ee728f8165aea964463b3e1d"} build_url:https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/OMUmpJgYQpGib469Oww4XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cadb21e9517a1d2b8e221f631f28484ab997c993262b6ac40c50080e4597b1607949464442d26fa09fc82e769ef8b9c04d5bd036ccf9c097f408a54ea2b749b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5f79b4a4b3a0ba3e594be3d7b66d18f5bd5924fe458e3554a647b0ce2df7b23a7016e443bb12c50e0a4d59582169157ca1946daf3ad6a1f660b2171e8fbf3f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0d1c65f892556414ada2fb2e1947207a95bcddd23b37a18fe250566398cbeea0a488d63524fbe62c5e8713a873035e888228b126ee728f8165aea964463b3e1d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:26.245320) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:26.245726) ========= 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.hjjexcF0ng/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.QXxmXQv3Qb/Listeners TMPDIR=/var/folders/fb/2vvs68mj0f558h69t2sk022r00000w/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.005168 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:26.295405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 09:17:26.295823) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 09:17:26.299240) ========= ========= Total master_lag: 2.71 =========