builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0338 starttime: 1476729124.01 results: success (0) buildid: 20161017093749 builduid: e4b4d281068e46c7ae8f57d200ded904 revision: e109b8c04871ad7e08cd86fd4689daa59b003dc0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.014040) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.014739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.015081) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.039047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.039693) ========= 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.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-17 11:32:04-- 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% 20.7M=0.001s 2016-10-17 11:32:04 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092706 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.154618) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.155051) ========= 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.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.020034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:04.206022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 11:32:04.206676) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e109b8c04871ad7e08cd86fd4689daa59b003dc0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e109b8c04871ad7e08cd86fd4689daa59b003dc0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-17 11:32:04,290 truncating revision to first 12 chars 2016-10-17 11:32:04,290 Setting DEBUG logging. 2016-10-17 11:32:04,290 attempt 1/10 2016-10-17 11:32:04,290 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e109b8c04871?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 11:32:05,618 unpacking tar archive at: autoland-e109b8c04871/testing/mozharness/ program finished with exit code 0 elapsedTime=1.587952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 11:32:05.817494) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:05.817823) ========= script_repo_revision: e109b8c04871ad7e08cd86fd4689daa59b003dc0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:05.818286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:05.818562) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 11:32:05.836950) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 11:32:05.837373) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:06 INFO - MultiFileLogger online at 20161017 11:32:06 in /builds/slave/test 11:32:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:32:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:32:06 INFO - {'append_to_log': False, 11:32:06 INFO - 'base_work_dir': '/builds/slave/test', 11:32:06 INFO - 'blob_upload_branch': 'Autoland', 11:32:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:32:06 INFO - 'branch': 'Autoland', 11:32:06 INFO - 'buildbot_json_path': 'buildprops.json', 11:32:06 INFO - 'config_files': ('talos/mac_config.py',), 11:32:06 INFO - 'default_actions': ('clobber', 11:32:06 INFO - 'read-buildbot-config', 11:32:06 INFO - 'download-and-extract', 11:32:06 INFO - 'populate-webroot', 11:32:06 INFO - 'create-virtualenv', 11:32:06 INFO - 'install', 11:32:06 INFO - 'run-tests'), 11:32:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:32:06 INFO - 'download_minidump_stackwalk': True, 11:32:06 INFO - 'download_symbols': 'ondemand', 11:32:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:32:06 INFO - 'tooltool.py': '/tools/tooltool.py', 11:32:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:32:06 INFO - '/tools/misc-python/virtualenv.py')}, 11:32:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:32:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:32:06 INFO - 'installer_path': 'installer.exe', 11:32:06 INFO - 'log_level': 'info', 11:32:06 INFO - 'log_name': 'talos', 11:32:06 INFO - 'log_to_console': True, 11:32:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:32:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:32:06 INFO - 'opt_config_files': (), 11:32:06 INFO - 'pip_index': False, 11:32:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:32:06 INFO - 'cmd': ('bash', 11:32:06 INFO - '-c', 11:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:32:06 INFO - 'enabled': True, 11:32:06 INFO - 'halt_on_failure': False, 11:32:06 INFO - 'name': 'check_screen_resolution'},), 11:32:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:32:06 INFO - 'cmd': ('bash', 11:32:06 INFO - '-c', 11:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:32:06 INFO - 'enabled': True, 11:32:06 INFO - 'halt_on_failure': False, 11:32:06 INFO - 'name': 'check_screen_resolution'},), 11:32:06 INFO - 'run_cmd_checks_enabled': True, 11:32:06 INFO - 'sps_profile': False, 11:32:06 INFO - 'sps_profile_interval': 0, 11:32:06 INFO - 'suite': 'dromaeojs', 11:32:06 INFO - 'system_bits': '32', 11:32:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:32:06 INFO - 'title': 't-yosemite-r7-0338', 11:32:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:32:06 INFO - 'use_talos_json': True, 11:32:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:32:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:32:06 INFO - 'work_dir': 'build'} 11:32:06 INFO - [mozharness: 2016-10-17 18:32:06.037792Z] Running clobber step. 11:32:06 INFO - Running pre-action listener: _resource_record_pre_action 11:32:06 INFO - Running main action method: clobber 11:32:06 INFO - rmtree: /builds/slave/test/build 11:32:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:32:07 INFO - Running post-action listener: _resource_record_post_action 11:32:07 INFO - [mozharness: 2016-10-17 18:32:07.571974Z] Finished clobber step (success) 11:32:07 INFO - [mozharness: 2016-10-17 18:32:07.572228Z] Running read-buildbot-config step. 11:32:07 INFO - Running pre-action listener: _resource_record_pre_action 11:32:07 INFO - Running main action method: read_buildbot_config 11:32:07 INFO - Using buildbot properties: 11:32:07 INFO - { 11:32:07 INFO - "project": "", 11:32:07 INFO - "product": "firefox", 11:32:07 INFO - "script_repo_revision": "production", 11:32:07 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:32:07 INFO - "repository": "", 11:32:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:32:07 INFO - "buildid": "20161017093749", 11:32:07 INFO - "pgo_build": "False", 11:32:07 INFO - "basedir": "/builds/slave/test", 11:32:07 INFO - "buildnumber": 252, 11:32:07 INFO - "slavename": "t-yosemite-r7-0338", 11:32:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:32:07 INFO - "platform": "yosemite_r7", 11:32:07 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:32:07 INFO - "branch": "autoland", 11:32:07 INFO - "repo_path": "integration/autoland", 11:32:07 INFO - "revision": "e109b8c04871ad7e08cd86fd4689daa59b003dc0", 11:32:07 INFO - "stage_platform": "macosx64", 11:32:07 INFO - "builduid": "e4b4d281068e46c7ae8f57d200ded904", 11:32:07 INFO - "slavebuilddir": "test" 11:32:07 INFO - } 11:32:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 11:32:07 INFO - Running post-action listener: _resource_record_post_action 11:32:07 INFO - [mozharness: 2016-10-17 18:32:07.577655Z] Finished read-buildbot-config step (success) 11:32:07 INFO - [mozharness: 2016-10-17 18:32:07.577814Z] Running download-and-extract step. 11:32:07 INFO - Running pre-action listener: _resource_record_pre_action 11:32:07 INFO - Running main action method: download_and_extract 11:32:07 INFO - mkdir: /builds/slave/test/build/tests 11:32:07 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')]} 11:32:07 INFO - trying https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 11:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/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 11:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/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 11:32:08 INFO - Downloaded 1199 bytes. 11:32:08 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:32:08 INFO - Using the following test package requirements: 11:32:08 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:32:08 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 11:32:08 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 11:32:08 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'jsshell-mac.zip'], 11:32:08 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 11:32:08 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:32:08 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 11:32:08 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 11:32:08 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 11:32:08 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:32:08 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 11:32:08 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:32:09 INFO - Http code: 200 11:32:09 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 11:32:09 INFO - x-amz-cf-id: JUeCtFk3AKF3URg-Ktsh8p8Aw4TiK-H0GAMMhuJv3DN0CFZqr93I9Q== 11:32:09 INFO - x-amz-version-id: o6xX5nq4jymrisEaK7Dr5A2CBNkdsqID 11:32:09 INFO - Expected file size: 16483359 11:32:09 INFO - Obtained file size: 16483359 11:32:16 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 11:32:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:32:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:32:16 INFO - Fetch https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:32:16 INFO - Http code: 200 11:32:16 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 11:32:16 INFO - x-amz-cf-id: lnqPwanCAepsz5h1vqGop4yp8uuQnD62oKdPPuAyTSHhRE5CIXiKDA== 11:32:16 INFO - x-amz-version-id: o6xX5nq4jymrisEaK7Dr5A2CBNkdsqID 11:32:17 INFO - Expected file size: 16483359 11:32:17 INFO - Obtained file size: 16483359 11:32:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 11:32:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:32:23 INFO - Fetch https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 11:32:24 INFO - Http code: 200 11:32:24 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 11:32:24 INFO - x-amz-cf-id: xq8W61rUo1obRc3IcYSNHxoy1EoHAv8thLO-8SCHrADBsMXJxK3cPw== 11:32:24 INFO - x-amz-version-id: yrP.tVtGyTraC2qn788Mqre_3MMTXTl. 11:32:24 INFO - Expected file size: 14409028 11:32:24 INFO - Obtained file size: 14409028 11:32:25 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')]} 11:32:25 INFO - trying https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:32:25 INFO - Downloading https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 11:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/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 11:32:27 INFO - Downloaded 111674917 bytes. 11:32:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:32:27 INFO - mkdir: /builds/slave/test/properties 11:32:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:32:27 INFO - Writing to file /builds/slave/test/properties/build_url 11:32:27 INFO - Contents: 11:32:27 INFO - build_url:https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:32:28 INFO - Running post-action listener: _resource_record_post_action 11:32:28 INFO - Running post-action listener: set_extra_try_arguments 11:32:28 INFO - [mozharness: 2016-10-17 18:32:28.622156Z] Finished download-and-extract step (success) 11:32:28 INFO - [mozharness: 2016-10-17 18:32:28.622283Z] Running populate-webroot step. 11:32:28 INFO - Running pre-action listener: _resource_record_pre_action 11:32:28 INFO - Running main action method: populate_webroot 11:32:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:32:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:32:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:32:28 INFO - '--tppagecycles', 11:32:28 INFO - '7'], 11:32:28 INFO - 'tests': ['tsvgm']}}, 11:32:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:32:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:32:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:32:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:32:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:32:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:32:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['damp', 'tps']}, 11:32:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'tests': ['damp', 'tps']}, 11:32:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['dromaeo_dom']}, 11:32:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:32:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['basic_compositor_video']}, 11:32:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:32:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'other-e10s': {'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['a11yr', 11:32:28 INFO - 'ts_paint', 11:32:28 INFO - 'tpaint', 11:32:28 INFO - 'sessionrestore', 11:32:28 INFO - 'sessionrestore_no_auto_restore', 11:32:28 INFO - 'tabpaint']}, 11:32:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['tsvgx', 11:32:28 INFO - 'tsvgr_opacity', 11:32:28 INFO - 'tart', 11:32:28 INFO - 'tscrollx', 11:32:28 INFO - 'cart']}, 11:32:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:32:28 INFO - 'tsvgr_opacity', 11:32:28 INFO - 'tart', 11:32:28 INFO - 'tscrollx', 11:32:28 INFO - 'cart']}, 11:32:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'talos_options': ['--disable-e10s'], 11:32:28 INFO - 'tests': ['tp5o']}, 11:32:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'tests': ['tp5o']}, 11:32:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'talos_options': ['--disable-e10s', 11:32:28 INFO - '--xperf_path', 11:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:32:28 INFO - 'tests': ['tp5n']}, 11:32:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:32:28 INFO - 'talos_options': ['--xperf_path', 11:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:32:28 INFO - 'tests': ['tp5n']}}, 11:32:28 INFO - 'talos.zip': {'path': '', 11:32:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:32:28 INFO - Running post-action listener: _resource_record_post_action 11:32:28 INFO - [mozharness: 2016-10-17 18:32:28.640872Z] Finished populate-webroot step (success) 11:32:28 INFO - [mozharness: 2016-10-17 18:32:28.641002Z] Running create-virtualenv step. 11:32:28 INFO - Running pre-action listener: _resource_record_pre_action 11:32:28 INFO - Running main action method: create_virtualenv 11:32:28 INFO - Creating virtualenv /builds/slave/test/build/venv 11:32:28 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 11:32:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:32:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:32:28 INFO - Using real prefix '/tools/python27' 11:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 11:32:32 INFO - Installing pip.................done. 11:32:32 INFO - Return code: 0 11:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:32:32 INFO - Reading from file tmpfile_stdout 11:32:32 INFO - Output received: 11:32:32 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) 11:32:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:32 INFO - 'HOME': '/Users/cltbld', 11:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:32 INFO - 'LOGNAME': 'cltbld', 11:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:32 INFO - 'MOZ_NO_REMOTE': '1', 11:32:32 INFO - 'NO_EM_RESTART': '1', 11:32:32 INFO - 'PAGER': '/bin/cat', 11:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:32 INFO - 'PWD': '/builds/slave/test', 11:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:32 INFO - 'SHELL': '/bin/bash', 11:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:32 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:32 INFO - 'USER': 'cltbld', 11:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:32 INFO - 'XPC_FLAGS': '0x0', 11:32:32 INFO - 'XPC_SERVICE_NAME': '0', 11:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:32 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 11:32:32 INFO - Cleaning up... 11:32:32 INFO - Return code: 0 11:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:32 INFO - 'HOME': '/Users/cltbld', 11:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:32 INFO - 'LOGNAME': 'cltbld', 11:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:32 INFO - 'MOZ_NO_REMOTE': '1', 11:32:32 INFO - 'NO_EM_RESTART': '1', 11:32:32 INFO - 'PAGER': '/bin/cat', 11:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:32 INFO - 'PWD': '/builds/slave/test', 11:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:32 INFO - 'SHELL': '/bin/bash', 11:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:32 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:32 INFO - 'USER': 'cltbld', 11:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:32 INFO - 'XPC_FLAGS': '0x0', 11:32:32 INFO - 'XPC_SERVICE_NAME': '0', 11:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:33 INFO - Downloading/unpacking psutil>=3.1.1 11:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:36 INFO - Installing collected packages: psutil 11:32:36 INFO - Running setup.py install for psutil 11:32:36 INFO - building 'psutil._psutil_osx' extension 11:32:36 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 11:32:36 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 11:32:37 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 11:32:37 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 11:32:37 INFO - building 'psutil._psutil_posix' extension 11:32:37 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 11:32:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:32:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:32:37 INFO - ^ 11:32:37 INFO - 1 warning generated. 11:32:37 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 11:32:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:37 INFO - Successfully installed psutil 11:32:37 INFO - Cleaning up... 11:32:37 INFO - Return code: 0 11:32:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:32:37 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')]} 11:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:37 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')]} 11:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:37 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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:37 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 11:32:37 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 11:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:37 INFO - 'HOME': '/Users/cltbld', 11:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:37 INFO - 'LOGNAME': 'cltbld', 11:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:37 INFO - 'MOZ_NO_REMOTE': '1', 11:32:37 INFO - 'NO_EM_RESTART': '1', 11:32:37 INFO - 'PAGER': '/bin/cat', 11:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:37 INFO - 'PWD': '/builds/slave/test', 11:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:37 INFO - 'SHELL': '/bin/bash', 11:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:37 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:37 INFO - 'USER': 'cltbld', 11:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:37 INFO - 'XPC_FLAGS': '0x0', 11:32:37 INFO - 'XPC_SERVICE_NAME': '0', 11:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:32:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:32:40 INFO - Installing collected packages: mozsystemmonitor 11:32:40 INFO - Running setup.py install for mozsystemmonitor 11:32:40 INFO - Successfully installed mozsystemmonitor 11:32:40 INFO - Cleaning up... 11:32:40 INFO - Return code: 0 11:32:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:40 INFO - 'HOME': '/Users/cltbld', 11:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:40 INFO - 'LOGNAME': 'cltbld', 11:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:40 INFO - 'MOZ_NO_REMOTE': '1', 11:32:40 INFO - 'NO_EM_RESTART': '1', 11:32:40 INFO - 'PAGER': '/bin/cat', 11:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:40 INFO - 'PWD': '/builds/slave/test', 11:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:40 INFO - 'SHELL': '/bin/bash', 11:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:40 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:40 INFO - 'USER': 'cltbld', 11:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:40 INFO - 'XPC_FLAGS': '0x0', 11:32:40 INFO - 'XPC_SERVICE_NAME': '0', 11:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:40 INFO - Downloading/unpacking jsonschema==2.5.1 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:32:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:43 INFO - Downloading functools32-3.2.3-2.tar.gz 11:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:32:43 INFO - warning: no files found matching '*.txt' 11:32:43 INFO - no previously-included directories found matching 'build' 11:32:43 INFO - no previously-included directories found matching 'dist' 11:32:43 INFO - no previously-included directories found matching '.git*' 11:32:43 INFO - Installing collected packages: jsonschema, functools32 11:32:43 INFO - Running setup.py install for functools32 11:32:43 INFO - warning: no files found matching '*.txt' 11:32:43 INFO - no previously-included directories found matching 'build' 11:32:43 INFO - no previously-included directories found matching 'dist' 11:32:43 INFO - no previously-included directories found matching '.git*' 11:32:44 INFO - Successfully installed jsonschema functools32 11:32:44 INFO - Cleaning up... 11:32:44 INFO - Return code: 0 11:32:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', '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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:32: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 functools32==3.2.3-2 11:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:44 INFO - 'HOME': '/Users/cltbld', 11:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:44 INFO - 'LOGNAME': 'cltbld', 11:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:44 INFO - 'MOZ_NO_REMOTE': '1', 11:32:44 INFO - 'NO_EM_RESTART': '1', 11:32:44 INFO - 'PAGER': '/bin/cat', 11:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:44 INFO - 'PWD': '/builds/slave/test', 11:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:44 INFO - 'SHELL': '/bin/bash', 11:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:44 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:44 INFO - 'USER': 'cltbld', 11:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:44 INFO - 'XPC_FLAGS': '0x0', 11:32:44 INFO - 'XPC_SERVICE_NAME': '0', 11:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:32:44 INFO - Cleaning up... 11:32:44 INFO - Return code: 0 11:32:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', '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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:32: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 blobuploader==1.2.4 11:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:44 INFO - 'HOME': '/Users/cltbld', 11:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:44 INFO - 'LOGNAME': 'cltbld', 11:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:44 INFO - 'MOZ_NO_REMOTE': '1', 11:32:44 INFO - 'NO_EM_RESTART': '1', 11:32:44 INFO - 'PAGER': '/bin/cat', 11:32:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:44 INFO - 'PWD': '/builds/slave/test', 11:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:44 INFO - 'SHELL': '/bin/bash', 11:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:44 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:44 INFO - 'USER': 'cltbld', 11:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:44 INFO - 'XPC_FLAGS': '0x0', 11:32:44 INFO - 'XPC_SERVICE_NAME': '0', 11:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:44 INFO - Downloading/unpacking blobuploader==1.2.4 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:47 INFO - Downloading blobuploader-1.2.4.tar.gz 11:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:32:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:32:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:48 INFO - Downloading docopt-0.6.1.tar.gz 11:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:32:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:32:48 INFO - Running setup.py install for blobuploader 11:32:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:32:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:32:48 INFO - Running setup.py install for requests 11:32:49 INFO - Running setup.py install for docopt 11:32:49 INFO - Successfully installed blobuploader requests docopt 11:32:49 INFO - Cleaning up... 11:32:49 INFO - Return code: 0 11:32:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:49 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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:49 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 11:32:49 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 11:32:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:49 INFO - 'HOME': '/Users/cltbld', 11:32:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:49 INFO - 'LOGNAME': 'cltbld', 11:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:49 INFO - 'MOZ_NO_REMOTE': '1', 11:32:49 INFO - 'NO_EM_RESTART': '1', 11:32:49 INFO - 'PAGER': '/bin/cat', 11:32:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:49 INFO - 'PWD': '/builds/slave/test', 11:32:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:49 INFO - 'SHELL': '/bin/bash', 11:32:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:49 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:49 INFO - 'USER': 'cltbld', 11:32:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:49 INFO - 'XPC_FLAGS': '0x0', 11:32:49 INFO - 'XPC_SERVICE_NAME': '0', 11:32:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-CFrZtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9ZleqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-00QyvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-dMIlp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-vmST6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-KUEd8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-rx4mdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fLZKxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-vkCyQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-WHuP7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9ru2RP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-lAqWLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-8Psk0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2PEM8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:51 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-09ew59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:51 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fHokL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:51 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-OPIXss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:32:51 INFO - Running setup.py install for manifestparser 11:32:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:32:51 INFO - Running setup.py install for mozcrash 11:32:51 INFO - Running setup.py install for mozdebug 11:32:51 INFO - Running setup.py install for mozdevice 11:32:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:32:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:32:51 INFO - Running setup.py install for mozfile 11:32:52 INFO - Running setup.py install for mozhttpd 11:32:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Running setup.py install for mozinfo 11:32:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Running setup.py install for mozInstall 11:32:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Running setup.py install for mozleak 11:32:52 INFO - Running setup.py install for mozlog 11:32:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Running setup.py install for moznetwork 11:32:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:32:52 INFO - Running setup.py install for mozprocess 11:32:53 INFO - Running setup.py install for mozprofile 11:32:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:32:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:32:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:32:53 INFO - Running setup.py install for mozrunner 11:32:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:32:53 INFO - Running setup.py install for mozscreenshot 11:32:53 INFO - Running setup.py install for moztest 11:32:53 INFO - Running setup.py install for mozversion 11:32:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:32:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:32:53 INFO - Cleaning up... 11:32:53 INFO - Return code: 0 11:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:53 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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:53 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 11:32:53 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 11:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:53 INFO - 'HOME': '/Users/cltbld', 11:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:53 INFO - 'LOGNAME': 'cltbld', 11:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:53 INFO - 'MOZ_NO_REMOTE': '1', 11:32:53 INFO - 'NO_EM_RESTART': '1', 11:32:53 INFO - 'PAGER': '/bin/cat', 11:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:53 INFO - 'PWD': '/builds/slave/test', 11:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:53 INFO - 'SHELL': '/bin/bash', 11:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:53 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:53 INFO - 'USER': 'cltbld', 11:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:53 INFO - 'XPC_FLAGS': '0x0', 11:32:53 INFO - 'XPC_SERVICE_NAME': '0', 11:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Vh8lPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-cI77Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-R6zAud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Pvy7Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tYa0_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-E5JmqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-8E50sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:54 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)) 11:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-S9i0D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jYkL75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-MEyTEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-DmtRcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jcIwtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-hiLBb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-cC3qEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-W7f8eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fx5Hzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:55 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)) 11:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-xJcnhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:56 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)) 11:32:56 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)) 11:32:56 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)) 11:32:56 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)) 11:32:56 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)) 11:32:56 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)) 11:32:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:58 INFO - Downloading blessings-1.6.tar.gz 11:32:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:32:58 INFO - Installing collected packages: blessings 11:32:58 INFO - Running setup.py install for blessings 11:32:58 INFO - Successfully installed blessings 11:32:58 INFO - Cleaning up... 11:32:58 INFO - Return code: 0 11:32:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:59 INFO - Reading from file tmpfile_stdout 11:32:59 INFO - Current package versions: 11:32:59 INFO - blessings == 1.6 11:32:59 INFO - blobuploader == 1.2.4 11:32:59 INFO - docopt == 0.6.1 11:32:59 INFO - functools32 == 3.2.3-2 11:32:59 INFO - jsonschema == 2.5.1 11:32:59 INFO - manifestparser == 1.1 11:32:59 INFO - mozInstall == 1.12 11:32:59 INFO - mozcrash == 0.17 11:32:59 INFO - mozdebug == 0.1 11:32:59 INFO - mozdevice == 0.48 11:32:59 INFO - mozfile == 1.2 11:32:59 INFO - mozhttpd == 0.7 11:32:59 INFO - mozinfo == 0.9 11:32:59 INFO - mozleak == 0.1 11:32:59 INFO - mozlog == 3.4 11:32:59 INFO - moznetwork == 0.27 11:32:59 INFO - mozprocess == 0.23 11:32:59 INFO - mozprofile == 0.28 11:32:59 INFO - mozrunner == 6.12 11:32:59 INFO - mozscreenshot == 0.1 11:32:59 INFO - mozsystemmonitor == 0.3 11:32:59 INFO - moztest == 0.7 11:32:59 INFO - mozversion == 1.4 11:32:59 INFO - psutil == 3.1.1 11:32:59 INFO - requests == 1.2.3 11:32:59 INFO - wsgiref == 0.1.2 11:32:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:59 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')]} 11:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:59 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')]} 11:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:59 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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:59 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 11:32:59 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 11:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:59 INFO - 'HOME': '/Users/cltbld', 11:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:59 INFO - 'LOGNAME': 'cltbld', 11:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:59 INFO - 'MOZ_NO_REMOTE': '1', 11:32:59 INFO - 'NO_EM_RESTART': '1', 11:32:59 INFO - 'PAGER': '/bin/cat', 11:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:59 INFO - 'PWD': '/builds/slave/test', 11:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:59 INFO - 'SHELL': '/bin/bash', 11:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:59 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:59 INFO - 'USER': 'cltbld', 11:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:59 INFO - 'XPC_FLAGS': '0x0', 11:32:59 INFO - 'XPC_SERVICE_NAME': '0', 11:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 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)) 11:32:59 INFO - Cleaning up... 11:32:59 INFO - Return code: 0 11:32:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:32:59 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')]} 11:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:59 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')]} 11:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:59 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 0x109d0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc17bcdb870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, '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.0kaerQajZp/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.3rGFey0nsZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 11:32:59 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 11:32:59 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 11:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:59 INFO - 'HOME': '/Users/cltbld', 11:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:59 INFO - 'LOGNAME': 'cltbld', 11:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:59 INFO - 'MOZ_NO_REMOTE': '1', 11:32:59 INFO - 'NO_EM_RESTART': '1', 11:32:59 INFO - 'PAGER': '/bin/cat', 11:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:59 INFO - 'PWD': '/builds/slave/test', 11:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:59 INFO - 'SHELL': '/bin/bash', 11:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:32:59 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:59 INFO - 'USER': 'cltbld', 11:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:59 INFO - 'XPC_FLAGS': '0x0', 11:32:59 INFO - 'XPC_SERVICE_NAME': '0', 11:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:32:59 INFO - Cleaning up... 11:32:59 INFO - Return code: 0 11:32:59 INFO - Running post-action listener: _resource_record_post_action 11:32:59 INFO - Running post-action listener: _start_resource_monitoring 11:32:59 INFO - Starting resource monitoring. 11:32:59 INFO - [mozharness: 2016-10-17 18:32:59.732850Z] Finished create-virtualenv step (success) 11:32:59 INFO - [mozharness: 2016-10-17 18:32:59.733850Z] Running install step. 11:32:59 INFO - Running pre-action listener: _resource_record_pre_action 11:32:59 INFO - Running main action method: install 11:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:59 INFO - Reading from file tmpfile_stdout 11:32:59 INFO - Detecting whether we're running mozinstall >=1.0... 11:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:33:00 INFO - Reading from file tmpfile_stdout 11:33:00 INFO - Output received: 11:33:00 INFO - Usage: mozinstall [options] installer 11:33:00 INFO - Options: 11:33:00 INFO - -h, --help show this help message and exit 11:33:00 INFO - -d DEST, --destination=DEST 11:33:00 INFO - Directory to install application into. [default: 11:33:00 INFO - "/builds/slave/test"] 11:33:00 INFO - --app=APP Application being installed. [default: firefox] 11:33:00 INFO - mkdir: /builds/slave/test/build/application 11:33:00 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'] 11:33:00 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 11:33:34 INFO - Reading from file tmpfile_stdout 11:33:34 INFO - Output received: 11:33:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:33:34 INFO - Running post-action listener: _resource_record_post_action 11:33:34 INFO - [mozharness: 2016-10-17 18:33:34.493652Z] Finished install step (success) 11:33:34 INFO - [mozharness: 2016-10-17 18:33:34.493803Z] Running run-tests step. 11:33:34 INFO - Running pre-action listener: _resource_record_pre_action 11:33:34 INFO - Running main action method: run_tests 11:33:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:33:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:33:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:33:34 INFO - 2016-10-17 11:33:34.508 screenresolution[2366:30693] starting screenresolution argv=screenresolution get 11:33:34 INFO - 2016-10-17 11:33:34.511 screenresolution[2366:30693] Display 0: 1600x1200x32@60 11:33:34 INFO - 2016-10-17 11:33:34.516 screenresolution[2367:30697] starting screenresolution argv=screenresolution list 11:33:34 INFO - Available Modes on Display 0 11:33:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:33:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:33:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:33:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:33:34 INFO - Intel Iris: 11:33:34 INFO - Chipset Model: Intel Iris 11:33:34 INFO - Type: GPU 11:33:34 INFO - Bus: Built-In 11:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 11:33:34 INFO - Vendor: Intel (0x8086) 11:33:34 INFO - Device ID: 0x0a2e 11:33:34 INFO - Revision ID: 0x0009 11:33:34 INFO - Displays: 11:33:34 INFO - Display: 11:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:33:34 INFO - Display Serial Number: 1600x1200 60 11:33:34 INFO - Main Display: Yes 11:33:34 INFO - Mirror: Off 11:33:34 INFO - Online: Yes 11:33:34 INFO - Rotation: Supported 11:33:34 INFO - Return code: 0 11:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:33:34 INFO - Python 2.7.3 11:33:34 INFO - Return code: 0 11:33:34 INFO - grabbing minidump binary from tooltool 11:33:34 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')]} 11:33:34 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 0x109b22d30>, '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 0x109948db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b1ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:33:34 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 11:33:34 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 11:33:34 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 11:33:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:33:34 INFO - Return code: 0 11:33:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0kaerQajZp/Render', 11:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:34 INFO - 'HOME': '/Users/cltbld', 11:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:34 INFO - 'LOGNAME': 'cltbld', 11:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:34 INFO - 'MOZ_NO_REMOTE': '1', 11:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:33:34 INFO - 'NO_EM_RESTART': '1', 11:33:34 INFO - 'PAGER': '/bin/cat', 11:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:34 INFO - 'PWD': '/builds/slave/test', 11:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:34 INFO - 'SHELL': '/bin/bash', 11:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3rGFey0nsZ/Listeners', 11:33:34 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 11:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:34 INFO - 'USER': 'cltbld', 11:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:34 INFO - 'XPC_FLAGS': '0x0', 11:33:34 INFO - 'XPC_SERVICE_NAME': '0', 11:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:33:35 INFO - mozversion application_buildid: 20161017093749 11:33:35 INFO - mozversion application_changeset: e109b8c04871ad7e08cd86fd4689daa59b003dc0 11:33:35 INFO - mozversion application_display_name: Nightly 11:33:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:33:35 INFO - mozversion application_name: Firefox 11:33:35 INFO - mozversion application_remotingname: firefox 11:33:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:33:35 INFO - mozversion application_vendor: Mozilla 11:33:35 INFO - mozversion application_version: 52.0a1 11:33:35 INFO - mozversion platform_buildid: 20161017093749 11:33:35 INFO - mozversion platform_changeset: e109b8c04871ad7e08cd86fd4689daa59b003dc0 11:33:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:33:35 INFO - mozversion platform_version: 52.0a1 11:33:35 INFO - using testdate: 1476729215 11:33:35 INFO - actual date: 1476729215 11:33:35 INFO - starting webserver on 'localhost:52761' 11:33:35 INFO - SUITE-START | Running 2 tests 11:33:35 INFO - TEST-START | dromaeo_css 11:33:35 INFO - operating with platform_type : mac_ 11:33:35 INFO - Initialising browser for dromaeo_css test... 11:33:35 INFO - TEST-INFO | started process 2377 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpQ8BlEF/profile http://localhost:52761/getInfo.html) 11:33:37 INFO - PROCESS | 2377 | __metrics Screen width/height:1600/1200 11:33:37 INFO - PROCESS | 2377 | colorDepth:24 11:33:37 INFO - PROCESS | 2377 | Browser inner width/height: 1024/689 11:33:37 INFO - PROCESS | 2377 | __metrics 11:33:37 INFO - PROCESS | 2377 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:33:37 INFO - PROCESS | 2377 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:33:38 INFO - TEST-INFO | 2377: exit 0 11:33:38 INFO - Browser initialized. 11:33:38 INFO - Running cycle 1/1 for dromaeo_css test... 11:33:38 INFO - TEST-INFO | started process 2379 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpQ8BlEF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:33:50 INFO - PROCESS | 2379 | RSS: Main: 230825984 11:33:50 INFO - PROCESS | 2379 | 11:34:41 INFO - PROCESS | 2379 | 1476729281413 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] 11:36:54 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52761/tests/dromaeo/cssquery-ext.html) 11:36:57 INFO - PROCESS | 2379 | RSS: Main: 478818304 11:36:57 INFO - PROCESS | 2379 | 11:40:00 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-ext.html (next: http://localhost:52761/tests/dromaeo/cssquery-jquery.html) 11:40:01 INFO - PROCESS | 2379 | RSS: Main: 378023936 11:40:01 INFO - PROCESS | 2379 | 11:43:03 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52761/tests/dromaeo/cssquery-mootools.html) 11:43:04 INFO - PROCESS | 2379 | RSS: Main: 350044160 11:43:04 INFO - PROCESS | 2379 | 11:46:06 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52761/tests/dromaeo/cssquery-prototype.html) 11:46:07 INFO - PROCESS | 2379 | RSS: Main: 355938304 11:46:07 INFO - PROCESS | 2379 | 11:49:10 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52761/tests/dromaeo/cssquery-yui.html) 11:49:11 INFO - PROCESS | 2379 | RSS: Main: 334553088 11:49:11 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | Cycle 1(1): loaded http://localhost:52761/tests/dromaeo/cssquery-yui.html (next: http://localhost:52761/tests/dromaeo/cssquery-dojo.html) 11:52:14 INFO - PROCESS | 2379 | RSS: Main: 510693376 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | __start_tp_report 11:52:14 INFO - PROCESS | 2379 | _x_x_mozilla_page_load 11:52:14 INFO - PROCESS | 2379 | _x_x_mozilla_page_load_details 11:52:14 INFO - PROCESS | 2379 | |i|pagename|runs| 11:52:14 INFO - PROCESS | 2379 | |0;dojo.html;3302.810988044304;3335.2995870867117;3338.499225116232;3385.5091011803306;3388.932221355573;5612.522935550483;5710.114932185513;5729.111987641912;5801.187833703285;5828.2423284973;5731.45551172639;5762.106873434614;5770.884582308362;5771.480566748992;5771.913421298674;5178.171492561191;5214.243934629495;5217.817376391827;5260.842174734764;5263.289455923459;5266.183741520071;5266.447023062581;5266.605004624649;5266.81566145185;5268.9473105269035;5152.793888244466;5240.816571420001;5255.264263003182;5258.079836028699;5268.868278293055;5297.549708274802;5300.469953004688;5319.46805319465;5325.893482130375;5379.542738867201;5097.719625420637;5105.5149760772665;5162.019216348882;5234.947650523484;5241.528262456359;5565.526930210937;5674.3190817101795;5686.516646085088;5710.200571919934;5737.885242295172;4689.929651055237;5728.112142617901;6278.497720182374;6292.150559674431;6295.213098362705;5487.149491828773;6122.855804403507;6212.347703491158;6215.658138802364;6337.651429171394;5765.7405416756765;5831.88336233273;5835.67903765297;5836.445537673999;5892.6759028253;6253.280872699606;6300.684965751694;6308.27454842699;6316.684165791692;6336.52476064297;6018.337982821886;6127.938720612826;6181.629102253879;6209.285932117774;6212.099245609361;6120.9347721082095;6176.011838105971;6242.75028998844;6254.280757712831;6276.560640755103;6214.689265536796;6238.844028899223;6242.625442473466;6244.12582238487;6258.812235632939;6128.693565321716;6251.562390632703;6277.435030847246;6279.215098112736;6283.151774510474;4851.442084160296;4923.90152196954;4927.96512732323;4957.628177886673;4993;4372.256716358227;4458.286674132188;4479.17135329965;4481.35020422037;4489.1021795641;5796.101604251347;5809.419058094241;5816.738246778905;5843.649381037151;5873.588848780544;6284;6315.494760419156;6322.71547780351;6335.429811316912;6363.045543168561;5635.042042852725;5725.484706376446;5734.77060917567;5813.447722466359;5833.620814647034;5309.681419114865;5360.624756267024;5364.6512976656395;5398.24424580559;5406.135018397038;6241.743397201015;6282.465990390868;6296.181496405438;6300.810975670737;6303.527235457359;6214.689265536796;6277.058441233851;6277.30949595535;6281;6294.1188233648245;6217.844553886008;6221.751129954751;6252.999520076766;6256.092866534236;6281.748730050746;6195.96902015487;6213.782517611862;6242.531810114168;6255.812325630239;6257.9687101564205;6133.723982420713;6184.381561843779;6281.52888533371;6285.43431091204;6322.146510221061;5991.730372133177;6098.817035488943;6112.969435152973;6163.291221509584;6209.348018457997;6107.85643655375;6125.754969801158;6127.111568822585;6128.448439640454;6139.662318572521;5813.244278243801;5953.047512397996;5965.761369545169;5971.28344598651;5971.940280597312;6095.3904609538695;6097.857415702912;6099.703252314951;6130.111133885473;6140.8771824564155;5933.821985340447;5952.315483719254;5953.166556681981;5959.314678811992;5963.821085367446 11:52:14 INFO - PROCESS | 2379 | |1;ext.html;466.68032397807497;503.312977785323;517.6790389958227;520.1677316293931;536.8523655994603;30121.397572048572;39731.602683973186;40847.18305633886;41099.99999999996;43326.35010474845;42108.157836843246;42413.36379954303;42497;42506.36240456396;42686.35970460438;9374.578143983521;9556.80886382272;9570.904290957105;9583.28125390595;9608.423494590312;8481.872771908425;8720.258778003878;11356.43217839109;11562.190646654737;11576.131790115733;11483.770324593503;11572.074234061254;11577.189596728233;11584.304941703482;11597.826032609517;8539.829203415897;11361.488733006992;11444.084473242205;11453.713657158554;11455;8465.365097617641;11100.55597776088;11379.203455758101;11408.486618102123;11559.017483513911;40124.398134028015;40395.78812635626;40453.393199102036;40478;40534.986625334604;3390.7965522068753;3447.6035255945635;3715;4007.4189242559664;4130.194612050651;22062.558748825093;29006.709932900612;29203.831846726105;29301.974430894817;29648.110556683117;3182.443072462333;3216.549683044352;3243.6431992480807;3308.2721801203697;3349.69852713257;475784;477714;477739;477822;478065;55781;57438.5640358986;57894;57957.4204257952;57959;61083.08375374284;61098;61104.38895611076;61108;61154.38845611576;15607.765883511756;15624.062556246661;16063.518094457186;16123.919380403024;16165.838341616669;11237.101031917427;11565.479553419947;12318;12652.240865548096;12743.426545805462;13951.302434878418;15293.617659558378;15566.766498502531;15683.29425175847;15975.281112349972;5755.884882302331;6100.389961003864;6265.1517802248445;6272.937270627235;6293.716782744787;806.6612022950375;866.9176428239307;941.3692825806726;953.5280036382036;954.1651055326589;28512.429751404856;29300.56049159264;29319;30157.849210753804;30210.697893021228;996.9930370326023;1051.6529545250048;1097.3415950429837;1124.3310230412892;1147.839302497651;14875;15309.617259568377;15454.227288635524;15794.763078553831;15808.762868556982;620126;624838;625123;625621;626623;56139;56316;56361;56377;56471.435285647436;1356.789697596874;1393.2476462710024;1418.702072564773;1418.9432422702976;1420.4957240180001;28499;29096.854515727286;29282.853585731937;29379.706202937698;29899;48390;48640;48736.75631621961;48766.75616622036;48838.75580622074;536653;543860;562431;564605;566493;35250.94247172589;37174.44238336481;40026.79517686844;40198.39702404525;40252.79873600613;36049.459258110626;36686.816565917;43296.350554741075;46737.728359014545;48994;37736.69590716145;37767.81116094402;38167;39227.803860980515;39445;38466.615333846305;38537.80731096327;38913.18793577045;39887.800560997006;40038.79980600078;38033;38062.238755224185;38399.616003840725;38581.42127868028;38621.227575447774;32150.517742233416;33343.99968000964;34106.48840267348;34152;34304.142396440286;20779;25235.87382063078;25436.982520698966;25449.745502545476;25644.487110257316 11:52:14 INFO - PROCESS | 2379 | |2;jquery.html;367.2819637608476;374.12828110502534;381.755676367125;384.4195265149627;397.1877510491046;18762.812371876276;20766;21393.82333971631;21412.8222947738;21534;20426.080826362813;20913;20989.055492502837;21218.787812121875;21540.784592154072;5415.620906536544;5507.917381239275;5512;5752.769889204426;5768.653880767147;4043.555208927016;4283.764392958386;5668.291463567054;5801.07182850744;5802.767889284423;4108.383742438628;4154.273002224613;4305.892352691177;6317.336679648617;6326.145970293997;6338.651374174418;6407.295197528269;6414.486841052728;6414.679266036703;6420.5505614607;5039.21892106724;5369.355677318707;5374.113271310229;5420.647657902224;5431.158170483582;13245.867541324656;13526.256055916921;13527.120737152054;13543.796843047363;13580.86419135806;2000.099955020232;2227.8774667393286;2276.977230227693;2547.3504256414494;2877.1512403840916;10182;10269.691909242734;10510.527026283835;12529.248245105322;15162.166080865549;1750.483607335839;1791.787142878544;1871.8689691721586;1916.5400303927097;1963.2245263120988;97230;102201.4889925553;104455.95544044614;104953.02946220097;105678.47160764223;9790.8740494844;10902.783678814769;11363.204575679789;11585.420728963518;11736.237144585575;14591.78112328316;14595.197264150574;14653.340599672827;14665.780013299809;14676.266186690622;8187.590620469072;8327.541985190752;8401.579921003926;8445.239928406472;8555.06467025163;8288.171182881664;8314.584270786436;8456.957715211507;8474.449160804528;8494.957525212334;4427.490838553543;4487.160264291625;4529.705569137996;4602.815887364468;4609.7695115244105;1840.3098837935775;1894.7726272847347;1917.7986311437098;1950.5318723506257;1955.1690531524046;862.2670729879552;866.5277423804042;931.3759780946782;939.990600094004;947.8009617980164;20899;21609.891950540146;21610.567788644454;21631.459213519473;21674.783252167275;790.4822341366414;876.4705588747676;887.8135591525852;899.9505027223435;906.931980101495;7931.444798864024;11151.944240278908;11299.548018079186;11299.717507062225;11372.545098196146;573117;583542;590890;596681;599809;121006;121810;122057.38971305087;122436;124981;958.3770549143113;968;978.8923218445965;979.9265055121037;981.097390400833;15133.463282679328;18475.260989560265;19015.33446329372;19027.429177124155;19160.233590656775;47165.52834471749;47960.04079918452;48061.5193848057;48431.51568484411;49711.51442427936;67936.6603166981;69992;70376;70550.58898822097;75301.24698752942;5744.827655170351;7432.442566807403;7748.263914928073;7962.124166341582;8408.201220884006;9363.4381937084;13217.14088584239;13418.597442076752;13444.46222151103;13657.863421365659;11159.27464714791;11470.942645286721;11477.598284060017;11979.640610781691;11991.100667449802;10858.968398002176;10993.670189894316;11104.898456377088;11418.371989540656;11760.941515263667;11351.318920864775;11674.124440667154;12055.397230138282;12056.638300850638;12101.273923564615;7173.426125910019;7414.777556673308;7521.4734968551675;7582.469227154046;7620.161782203842;9657.806843862943;9926.652567160114;11470.197086203885;11779.411029448665;11910.52357905674 11:52:14 INFO - PROCESS | 2379 | |3;mootools.html;1370.3970253088646;1526.1529850932732;1556.2607761313377;1794.2822870851667;1966.5673551818604;7872.960635196823;8326.501229778645;8598.699045533407;8715.91284087159;8854.424462409956;8695.826083478327;8753.16844899735;8768.824623507526;8770.386072974894;8780.42927209731;2327.7323107842603;2356.8821558922073;2357.127862690807;2357.9882100589475;2359.1860808021206;2303.7005189325364;2329.4060014696315;2344.4842134730334;2347.6126439137606;2351.6825228594093;2297.7242730872235;2326.964500797146;2332.3003099070297;2346.7301260355107;2349.2482405630203;1999.9700004499944;2289.404754763757;2322.2684854270933;2327.906883724649;2331.0209711921034;2303.631418972973;2312.5374925014894;2313.0747700919596;2314.2941402872116;2321.1063740460045;8242.516347057528;8577.099404562556;8712;8765.474071555727;8779.517126558037;4968.1554135797005;5339.70631615261;5398.4871437213405;5401.486858748452;5410.998965191452;2980.448617005861;3585.8386372613295;3660.7803531788177;3681.9263614727556;3685.4705297301857;1075.231209685071;1084.3927400655657;1089.2266490791546;1092.4537731134433;1105.8341248812742;11583.478743456564;11651.30092194471;11709.238899471507;11715.4142292885;11736.002439792628;4317.84887528935;4357.9564204358185;4539.387182730289;4539.84110556129;4586.243269860435;8944.955275223712;8953.507557084293;8968.775780605538;8992.325575581857;9000.954995224982;5118.309028281209;5210.166373380241;5230.241614965808;5230.320058392384;5232.9738351308;4144.461220041436;4395.340698895191;4554.749488778149;4566.315052742115;4900.142475066849;3312.1554003728893;3719.6094410087094;3791.2038471920655;3797.6582107610448;3802.5627052888717;1076.6703121644762;1081.7944590527775;1104.8232282834708;1151.9827202591969;1156.3639998001233;796.3589310605018;853.9573021348908;903.2457897655399;910.7905181808088;955.7945041816043;5768.3366412862215;5792.304923409218;5952.434518720715;6066.787662431793;6152.661603611844;911.9680811171577;946.5835032585778;952.6046690623314;974.6345120579782;1011.9494025298706;3830.751001184914;3866.8839934802;3939.2318497893207;3962.465067215889;4031.9798401008393;10312.639057632947;10761;10790.244682872124;10905.563777448813;10963.122950163968;10676.786464270826;10808.351498910091;10831.67504974852;10867.184961127947;10888.891111088788;6639;8228.50628962264;8236.052853921756;8252.752417427488;8254.752357429286;5324.494173053554;5352.223927530564;5620.1288800236025;5917;6053.636781793107;9788.216942644429;10116.797664046826;10129.746756330856;10141.949290253502;10145.594176232868;9263.629454822001;9705.563249653642;10013.599456021677;10222.539985700512;10300.433476158883;7019.29807019294;7269.345758881726;7272.745453909088;7275.1997280298065;7276.017737605567;7812.257835505845;8008.919910800817;8255.050669173123;8286.875696864432;8382.203690649378;7324.780256592311;7331.596762178131;7436.516626419266;7476.5514069156025;7616.314531692175;7315.743948961761;7375.668094935633;7376.299251571092;7443.665035073543;7468.813279668052;6931.514793964576;7075.575465472089;7083.504154709327;7086.255943126103;7102.573845569283;4490.326451032241;4865.513448655106;5030.924536131888;5057.519535644108;5110.923336149886;4499.66252531068;4605.608523275425;4858.92711609319;4893.779779909842;5199.428062913001 11:52:14 INFO - PROCESS | 2379 | |4;prototype.html;269.87855465040735;288.82381747134247;292.616672159471;300.42018903516214;301.86265249311566;4549.818007279709;5147.536721695052;5192.402873669529;5217.165253559431;5236.738163091849;4462.843800466984;4733.733723730624;5165.896682066356;5171.586273098153;5252.842414727555;3350.659736105553;3986.322325204708;4000.8599699010542;4018.397240413932;4021.235965166624;3524.3083320006413;3796.449514820363;3845.692344612424;4013.3077568636777;4036;3615.3311637347174;3766.623337666239;3881.3789793633023;3905.628965248297;3917.6278253566006;3592.9640703593022;3911.2568611963634;3922.5680078351606;3953.762774233526;3966.2464131814863;3458.8270586470576;3459.930801383958;3466.376052310583;3466.596028608418;3493.6506349365113;4415.933760993587;4904.975475122637;4974.328465657162;4977.20820504618;5065.366829146356;4451.443569553806;4461.665375096848;4486.070989474525;4486.170058539181;4496.662750293708;4637.559431853969;5181.663191892552;5366.087765079946;5418.972905135449;5423.3491980962535;3685.244524872395;3797.2785170817456;3798.487204227449;3817.7327587068917;3817.8854634361014;5193.065248255312;5244.134717771563;5409.83770486886;5612.989661860864;5710.943475457054;3450.4824276358745;3862.3240932836643;3890.6498415142773;3891.591382904811;3894.337962546317;3792.0140763401714;3924.274009308287;3944.684425245973;3945.921081578352;3956.643902048829;3742.812859357076;3755.6056614055133;4476.597106260452;4492.3710680504755;4512.826665067106;5392.892142157135;5397.217403476552;5415.998040362546;5436.401995780461;5477.452254774491;1100.7908497385472;1144.2447984330302;1319.5381616434172;1349.089364678838;1351.6620844788804;689.5483458334799;789.4868335581803;833.3832963606887;850.9829803403898;875.1642181716445;4968.552830245223;5108.897822043613;5130.974345128325;5141.614378921521;5144.382674079135;4794.568488836021;5449.836504904859;5490.1764735289225;5500.074973759152;5505.752241149158;4304.569543045671;5012.947281070943;5025.497450254945;5115.769790359443;5222.92165617516;2615.843049417041;2744.519709050908;2942.764578833688;2946.7495262902894;2952.5571164325524;5155.690658560498;5794.855128621818;5849.561282903799;5875.853103672443;5904.763809447574;5120.820771273062;5183.377994640668;5832.591718579743;5838.591298609056;5859.941400586024;4933.50664933511;5500.22996780443;5540.088669409048;5541.6674999499355;5905.409459054146;5341.78632854697;5470.288862448016;5718.485336319751;5736.5410767139365;5826.621269617462;5263.263123169421;5682.43175682445;5695.753420550247;5706.828795136153;5795;5235.947640523546;5690.573207009574;5888.4700376964565;5919.526437885045;5966.403359663999;3583.874564390234;3776.2069965306496;3813.0657988792923;3860.3051450739135;3873.7094717896834;4925.655204135819;5127.564157046693;5633.6619802811965;5676.864627074684;5779.479846813807;4927.975360123177;5345.529979255736;5647.632903861236;5665.716714164193;5688.44020190924;5347.181958134253;5448;5465.808696695596;5518.972405137949;5521.889562208814;4650.465196502446;5332.013577488177;5352.411234764095;5371.677699338053;5372.409035006224;5370.516653501204;5393.298871146726;5393.514583687487;5541.9168712470155;5543.805966791143;4913.606911447004;5164.070467315769;5408.161734931092;5438.776275338176;5478.945210547843 11:52:14 INFO - PROCESS | 2379 | |5;yui.html;679.6296018669824;784.1374488063132;786.1077676836788;799.3565180030075;828.2587084559324;14857.034292770975;15577.22113894305;15578.298976546055;15600.063996160225;15685.411907035326;14109.506167284148;14237;14251.073680210779;14253.786193207085;14257;698.0541366448465;717.5622870049266;862.5514732339179;886.9290456763459;909.9499527525984;771.3212373111654;904.7104926423547;908.0692290402325;908.554808144008;910.2399496420463;954.6372378496162;955.2596737528402;956.4834989105874;957.0142752964423;959.0744931141447;731.9743808966714;761.2387612387612;957.1672644799035;957.5355952363099;960.428545015718;546.8851541176358;556.7550277877721;558.5615291995764;560.3639868748959;712.9786106416764;14547;14858.628534286621;14873.628159295997;14878.330475128645;14886.330115144845;13520.188788672613;13528.526501572496;13870.65323366924;13874.583762487142;14402.639934001629;11013.779724405467;12116.81824772629;12596.181248218929;12682.492700291978;12758.489660413572;4492.663050271242;4506.504284528699;4526.140033393677;4700;4722.881927951795;87695.12304876934;88836.09273165556;100604.49697751537;101604;103272.9345413095;9955.701328960144;10184.643537476153;10341.431221282752;10476.790464190673;10496.580136794442;19659.803401965797;19924.3026494072;20002.499937501678;20038.799612003986;20100.69848952267;7357.41140708742;7450.329470347695;7539.698412063456;7556.433267504959;7814.21857814214;4217.240896638573;4230.407742915994;4285;4308.332208507686;4393.494748104009;3456.8098754568236;3571.6964058054837;3576.3741345264993;3576.5529308836394;3579.982100089483;651.6415971215812;651.7588492257895;661.8213082467803;664.378805816563;667.1560475997867;539.382407143826;550.8980838544882;568.5139205978828;576.2019602850082;615.2555407956334;6804;8796.296296296281;9024.593893274818;9202.946618720063;9215.400998935047;596.4125336543501;599.1432251879854;612.7395856760859;623.8471574464195;641.3426238105978;4278.614924656796;4430.579094985971;4478.283474644107;4489.349044388546;4590.311453282035;95976;127338.36330818286;141300;145330;152971;114767.42616286865;129020.0646990296;135676.64323356838;136104.8752806126;136183.63816361906;111054;111132;116969;117725;117974;8338.62476188585;8525.104863989192;8538.44500107491;8614.396992210484;8735.56322183901;52309;54002;54149.45850541444;57592.4240757587;66054.33945660482;66968.99546506903;80675;81684.18315816765;83547.65809367801;83775;13111.016673749316;13260.138091024053;13606.319684015958;13621.182729035892;13723.382447789674;14199.929000354932;14876.553703388916;14930.328135233724;15048.398064077313;15280.541583752061;13134.868651313365;13842.169469831842;14150.646233844236;14291.499797507038;14460.349284282443;13629.86370136286;13688.863111368759;14303;14364.569062928129;14385.712285754016;13307.698821044733;13601.659958501117;13615.387307571382;13683.247421391521;14312.284385780878;10893.954765172624;11148.329897113166;11285.774284514428;11427.771444571228;11833;10345.534450949875;11299.794947343327;11341.4896329667;11586.246893951866;11592.246503977214 11:52:14 INFO - PROCESS | 2379 | __end_tp_report 11:52:14 INFO - PROCESS | 2379 | __start_cc_report 11:52:14 INFO - PROCESS | 2379 | _x_x_mozilla_cycle_collect,3213 11:52:14 INFO - PROCESS | 2379 | __end_cc_report 11:52:14 INFO - PROCESS | 2379 | __startTimestamp1476730334704__endTimestamp 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | ------- Summary: start ------- 11:52:14 INFO - PROCESS | 2379 | Number of tests: 6 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#0] dojo.html Cycles:165 Average:5752.46 Median:5953.17 stddev:640.84 (10.8%) stddev-sans-first:613.32 11:52:14 INFO - PROCESS | 2379 | Values: 3302.8 3335.3 3338.5 3385.5 3388.9 5612.5 5710.1 5729.1 5801.2 5828.2 5731.5 5762.1 5770.9 5771.5 5771.9 5178.2 5214.2 5217.8 5260.8 5263.3 5266.2 5266.4 5266.6 5266.8 5268.9 5152.8 5240.8 5255.3 5258.1 5268.9 5297.5 5300.5 5319.5 5325.9 5379.5 5097.7 5105.5 5162.0 5234.9 5241.5 5565.5 5674.3 5686.5 5710.2 5737.9 4689.9 5728.1 6278.5 6292.2 6295.2 5487.1 6122.9 6212.3 6215.7 6337.7 5765.7 5831.9 5835.7 5836.4 5892.7 6253.3 6300.7 6308.3 6316.7 6336.5 6018.3 6127.9 6181.6 6209.3 6212.1 6120.9 6176.0 6242.8 6254.3 6276.6 6214.7 6238.8 6242.6 6244.1 6258.8 6128.7 6251.6 6277.4 6279.2 6283.2 4851.4 4923.9 4928.0 4957.6 4993.0 4372.3 4458.3 4479.2 4481.4 4489.1 5796.1 5809.4 5816.7 5843.6 5873.6 6284.0 6315.5 6322.7 6335.4 6363.0 5635.0 5725.5 5734.8 5813.4 5833.6 5309.7 5360.6 5364.7 5398.2 5406.1 6241.7 6282.5 6296.2 6300.8 6303.5 6214.7 6277.1 6277.3 6281.0 6294.1 6217.8 6221.8 6253.0 6256.1 6281.7 6196.0 6213.8 6242.5 6255.8 6258.0 6133.7 6184.4 6281.5 6285.4 6322.1 5991.7 6098.8 6113.0 6163.3 6209.3 6107.9 6125.8 6127.1 6128.4 6139.7 5813.2 5953.0 5965.8 5971.3 5971.9 6095.4 6097.9 6099.7 6130.1 6140.9 5933.8 5952.3 5953.2 5959.3 5963.8 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#1] ext.html Cycles:180 Average:68682.41 Median:38559.61 stddev:148280.65 (384.5%) stddev-sans-first:148608.16 11:52:14 INFO - PROCESS | 2379 | Values: 466.7 503.3 517.7 520.2 536.9 30121.4 39731.6 40847.2 41100.0 43326.4 42108.2 42413.4 42497.0 42506.4 42686.4 9374.6 9556.8 9570.9 9583.3 9608.4 8481.9 8720.3 11356.4 11562.2 11576.1 11483.8 11572.1 11577.2 11584.3 11597.8 8539.8 11361.5 11444.1 11453.7 11455.0 8465.4 11100.6 11379.2 11408.5 11559.0 40124.4 40395.8 40453.4 40478.0 40535.0 3390.8 3447.6 3715.0 4007.4 4130.2 22062.6 29006.7 29203.8 29302.0 29648.1 3182.4 3216.5 3243.6 3308.3 3349.7 475784.0 477714.0 477739.0 477822.0 478065.0 55781.0 57438.6 57894.0 57957.4 57959.0 61083.1 61098.0 61104.4 61108.0 61154.4 15607.8 15624.1 16063.5 16123.9 16165.8 11237.1 11565.5 12318.0 12652.2 12743.4 13951.3 15293.6 15566.8 15683.3 15975.3 5755.9 6100.4 6265.2 6272.9 6293.7 806.7 866.9 941.4 953.5 954.2 28512.4 29300.6 29319.0 30157.8 30210.7 997.0 1051.7 1097.3 1124.3 1147.8 14875.0 15309.6 15454.2 15794.8 15808.8 620126.0 624838.0 625123.0 625621.0 626623.0 56139.0 56316.0 56361.0 56377.0 56471.4 1356.8 1393.2 1418.7 1418.9 1420.5 28499.0 29096.9 29282.9 29379.7 29899.0 48390.0 48640.0 48736.8 48766.8 48838.8 536653.0 543860.0 562431.0 564605.0 566493.0 35250.9 37174.4 40026.8 40198.4 40252.8 36049.5 36686.8 43296.4 46737.7 48994.0 37736.7 37767.8 38167.0 39227.8 39445.0 38466.6 38537.8 38913.2 39887.8 40038.8 38033.0 38062.2 38399.6 38581.4 38621.2 32150.5 33344.0 34106.5 34152.0 34304.1 20779.0 25235.9 25437.0 25449.7 25644.5 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#2] jquery.html Cycles:180 Average:33542.20 Median:377.94 stddev:97854.74 (25891.5%) stddev-sans-first:98097.54 11:52:14 INFO - PROCESS | 2379 | Values: 367.3 374.1 381.8 384.4 397.2 18762.8 20766.0 21393.8 21412.8 21534.0 20426.1 20913.0 20989.1 21218.8 21540.8 5415.6 5507.9 5512.0 5752.8 5768.7 4043.6 4283.8 5668.3 5801.1 5802.8 4108.4 4154.3 4305.9 6317.3 6326.1 6338.7 6407.3 6414.5 6414.7 6420.6 5039.2 5369.4 5374.1 5420.6 5431.2 13245.9 13526.3 13527.1 13543.8 13580.9 2000.1 2227.9 2277.0 2547.4 2877.2 10182.0 10269.7 10510.5 12529.2 15162.2 1750.5 1791.8 1871.9 1916.5 1963.2 97230.0 102201.5 104456.0 104953.0 105678.5 9790.9 10902.8 11363.2 11585.4 11736.2 14591.8 14595.2 14653.3 14665.8 14676.3 8187.6 8327.5 8401.6 8445.2 8555.1 8288.2 8314.6 8457.0 8474.4 8495.0 4427.5 4487.2 4529.7 4602.8 4609.8 1840.3 1894.8 1917.8 1950.5 1955.2 862.3 866.5 931.4 940.0 947.8 20899.0 21609.9 21610.6 21631.5 21674.8 790.5 876.5 887.8 900.0 906.9 7931.4 11151.9 11299.5 11299.7 11372.5 573117.0 583542.0 590890.0 596681.0 599809.0 121006.0 121810.0 122057.4 122436.0 124981.0 958.4 968.0 978.9 979.9 981.1 15133.5 18475.3 19015.3 19027.4 19160.2 47165.5 47960.0 48061.5 48431.5 49711.5 67936.7 69992.0 70376.0 70550.6 75301.2 5744.8 7432.4 7748.3 7962.1 8408.2 9363.4 13217.1 13418.6 13444.5 13657.9 11159.3 11470.9 11477.6 11979.6 11991.1 10859.0 10993.7 11104.9 11418.4 11760.9 11351.3 11674.1 12055.4 12056.6 12101.3 7173.4 7414.8 7521.5 7582.5 7620.2 9657.8 9926.7 11470.2 11779.4 11910.5 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#3] mootools.html Cycles:180 Average:5622.76 Median:4862.22 stddev:3174.88 (65.3%) stddev-sans-first:3167.70 11:52:14 INFO - PROCESS | 2379 | Values: 1370.4 1526.2 1556.3 1794.3 1966.6 7873.0 8326.5 8598.7 8715.9 8854.4 8695.8 8753.2 8768.8 8770.4 8780.4 2327.7 2356.9 2357.1 2358.0 2359.2 2303.7 2329.4 2344.5 2347.6 2351.7 2297.7 2327.0 2332.3 2346.7 2349.2 2000.0 2289.4 2322.3 2327.9 2331.0 2303.6 2312.5 2313.1 2314.3 2321.1 8242.5 8577.1 8712.0 8765.5 8779.5 4968.2 5339.7 5398.5 5401.5 5411.0 2980.4 3585.8 3660.8 3681.9 3685.5 1075.2 1084.4 1089.2 1092.5 1105.8 11583.5 11651.3 11709.2 11715.4 11736.0 4317.8 4358.0 4539.4 4539.8 4586.2 8945.0 8953.5 8968.8 8992.3 9001.0 5118.3 5210.2 5230.2 5230.3 5233.0 4144.5 4395.3 4554.7 4566.3 4900.1 3312.2 3719.6 3791.2 3797.7 3802.6 1076.7 1081.8 1104.8 1152.0 1156.4 796.4 854.0 903.2 910.8 955.8 5768.3 5792.3 5952.4 6066.8 6152.7 912.0 946.6 952.6 974.6 1011.9 3830.8 3866.9 3939.2 3962.5 4032.0 10312.6 10761.0 10790.2 10905.6 10963.1 10676.8 10808.4 10831.7 10867.2 10888.9 6639.0 8228.5 8236.1 8252.8 8254.8 5324.5 5352.2 5620.1 5917.0 6053.6 9788.2 10116.8 10129.7 10141.9 10145.6 9263.6 9705.6 10013.6 10222.5 10300.4 7019.3 7269.3 7272.7 7275.2 7276.0 7812.3 8008.9 8255.1 8286.9 8382.2 7324.8 7331.6 7436.5 7476.6 7616.3 7315.7 7375.7 7376.3 7443.7 7468.8 6931.5 7075.6 7083.5 7086.3 7102.6 4490.3 4865.5 5030.9 5057.5 5110.9 4499.7 4605.6 4858.9 4893.8 5199.4 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#4] prototype.html Cycles:180 Average:4455.53 Median:5118.30 stddev:1372.79 (26.8%) stddev-sans-first:1340.21 11:52:14 INFO - PROCESS | 2379 | Values: 269.9 288.8 292.6 300.4 301.9 4549.8 5147.5 5192.4 5217.2 5236.7 4462.8 4733.7 5165.9 5171.6 5252.8 3350.7 3986.3 4000.9 4018.4 4021.2 3524.3 3796.4 3845.7 4013.3 4036.0 3615.3 3766.6 3881.4 3905.6 3917.6 3593.0 3911.3 3922.6 3953.8 3966.2 3458.8 3459.9 3466.4 3466.6 3493.7 4415.9 4905.0 4974.3 4977.2 5065.4 4451.4 4461.7 4486.1 4486.2 4496.7 4637.6 5181.7 5366.1 5419.0 5423.3 3685.2 3797.3 3798.5 3817.7 3817.9 5193.1 5244.1 5409.8 5613.0 5710.9 3450.5 3862.3 3890.6 3891.6 3894.3 3792.0 3924.3 3944.7 3945.9 3956.6 3742.8 3755.6 4476.6 4492.4 4512.8 5392.9 5397.2 5416.0 5436.4 5477.5 1100.8 1144.2 1319.5 1349.1 1351.7 689.5 789.5 833.4 851.0 875.2 4968.6 5108.9 5131.0 5141.6 5144.4 4794.6 5449.8 5490.2 5500.1 5505.8 4304.6 5012.9 5025.5 5115.8 5222.9 2615.8 2744.5 2942.8 2946.7 2952.6 5155.7 5794.9 5849.6 5875.9 5904.8 5120.8 5183.4 5832.6 5838.6 5859.9 4933.5 5500.2 5540.1 5541.7 5905.4 5341.8 5470.3 5718.5 5736.5 5826.6 5263.3 5682.4 5695.8 5706.8 5795.0 5235.9 5690.6 5888.5 5919.5 5966.4 3583.9 3776.2 3813.1 3860.3 3873.7 4925.7 5127.6 5633.7 5676.9 5779.5 4928.0 5345.5 5647.6 5665.7 5688.4 5347.2 5448.0 5465.8 5519.0 5521.9 4650.5 5332.0 5352.4 5371.7 5372.4 5370.5 5393.3 5393.5 5541.9 5543.8 4913.6 5164.1 5408.2 5438.8 5478.9 11:52:14 INFO - PROCESS | 2379 | 11:52:14 INFO - PROCESS | 2379 | [#5] yui.html Cycles:180 Average:23672.55 Median:3898.61 stddev:37389.52 (959.0%) stddev-sans-first:37454.55 11:52:14 INFO - PROCESS | 2379 | Values: 679.6 784.1 786.1 799.4 828.3 14857.0 15577.2 15578.3 15600.1 15685.4 14109.5 14237.0 14251.1 14253.8 14257.0 698.1 717.6 862.6 886.9 909.9 771.3 904.7 908.1 908.6 910.2 954.6 955.3 956.5 957.0 959.1 732.0 761.2 957.2 957.5 960.4 546.9 556.8 558.6 560.4 713.0 14547.0 14858.6 14873.6 14878.3 14886.3 13520.2 13528.5 13870.7 13874.6 14402.6 11013.8 12116.8 12596.2 12682.5 12758.5 4492.7 4506.5 4526.1 4700.0 4722.9 87695.1 88836.1 100604.5 101604.0 103272.9 9955.7 10184.6 10341.4 10476.8 10496.6 19659.8 19924.3 20002.5 20038.8 20100.7 7357.4 7450.3 7539.7 7556.4 7814.2 4217.2 4230.4 4285.0 4308.3 4393.5 3456.8 3571.7 3576.4 3576.6 3580.0 651.6 651.8 661.8 664.4 667.2 539.4 550.9 568.5 576.2 615.3 6804.0 8796.3 9024.6 9202.9 9215.4 596.4 599.1 612.7 623.8 641.3 4278.6 4430.6 4478.3 4489.3 4590.3 95976.0 127338.4 141300.0 145330.0 152971.0 114767.4 129020.1 135676.6 136104.9 136183.6 111054.0 111132.0 116969.0 117725.0 117974.0 8338.6 8525.1 8538.4 8614.4 8735.6 52309.0 54002.0 54149.5 57592.4 66054.3 66969.0 80675.0 81684.2 83547.7 83775.0 13111.0 13260.1 13606.3 13621.2 13723.4 14199.9 14876.6 14930.3 15048.4 15280.5 13134.9 13842.2 14150.6 14291.5 14460.3 13629.9 13688.9 14303.0 14364.6 14385.7 13307.7 13601.7 13615.4 13683.2 14312.3 10894.0 11148.3 11285.8 11427.8 11833.0 10345.5 11299.8 11341.5 11586.2 11592.2 11:52:14 INFO - PROCESS | 2379 | -------- Summary: end -------- 11:52:14 INFO - PROCESS | 2379 | 11:52:15 INFO - TEST-INFO | 2379: exit 0 11:52:15 INFO - TEST-OK | dromaeo_css | took 1120279ms 11:52:15 INFO - TEST-START | kraken 11:52:15 INFO - operating with platform_type : mac_ 11:52:15 INFO - Initialising browser for kraken test... 11:52:15 INFO - TEST-INFO | started process 2382 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpehtgjE/profile http://localhost:52761/getInfo.html) 11:52:17 INFO - PROCESS | 2382 | __metrics Screen width/height:1600/1200 11:52:17 INFO - PROCESS | 2382 | colorDepth:24 11:52:17 INFO - PROCESS | 2382 | Browser inner width/height: 1024/689 11:52:17 INFO - PROCESS | 2382 | __metrics 11:52:17 INFO - PROCESS | 2382 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:52:17 INFO - PROCESS | 2382 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:52:18 INFO - TEST-INFO | 2382: exit 0 11:52:18 INFO - Browser initialized. 11:52:18 INFO - Running cycle 1/1 for kraken test... 11:52:18 INFO - TEST-INFO | started process 2383 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpehtgjE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:30 INFO - PROCESS | 2383 | RSS: Main: 232148992 11:52:30 INFO - PROCESS | 2383 | 11:52:33 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52761/tests/kraken/driver.html?testName=audio-beat-detection) 11:52:34 INFO - PROCESS | 2383 | RSS: Main: 290963456 11:52:34 INFO - PROCESS | 2383 | 11:52:36 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52761/tests/kraken/driver.html?testName=audio-dft) 11:52:37 INFO - PROCESS | 2383 | RSS: Main: 292089856 11:52:37 INFO - PROCESS | 2383 | 11:52:40 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52761/tests/kraken/driver.html?testName=audio-fft) 11:52:41 INFO - PROCESS | 2383 | RSS: Main: 384954368 11:52:41 INFO - PROCESS | 2383 | 11:52:43 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52761/tests/kraken/driver.html?testName=audio-oscillator) 11:52:44 INFO - PROCESS | 2383 | RSS: Main: 286560256 11:52:44 INFO - PROCESS | 2383 | 11:52:46 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52761/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:52:46 INFO - PROCESS | 2383 | RSS: Main: 291008512 11:52:46 INFO - PROCESS | 2383 | 11:52:49 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52761/tests/kraken/driver.html?testName=imaging-darkroom) 11:52:50 INFO - PROCESS | 2383 | RSS: Main: 328634368 11:52:50 INFO - PROCESS | 2383 | 11:52:53 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52761/tests/kraken/driver.html?testName=imaging-desaturate) 11:52:54 INFO - PROCESS | 2383 | RSS: Main: 328777728 11:52:54 INFO - PROCESS | 2383 | 11:52:56 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52761/tests/kraken/driver.html?testName=json-parse-financial) 11:52:57 INFO - PROCESS | 2383 | RSS: Main: 326545408 11:52:57 INFO - PROCESS | 2383 | 11:52:59 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52761/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:52:59 INFO - PROCESS | 2383 | RSS: Main: 303276032 11:52:59 INFO - PROCESS | 2383 | 11:53:01 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:53:02 INFO - PROCESS | 2383 | RSS: Main: 307597312 11:53:02 INFO - PROCESS | 2383 | 11:53:04 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:53:04 INFO - PROCESS | 2383 | RSS: Main: 312078336 11:53:04 INFO - PROCESS | 2383 | 11:53:07 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:53:08 INFO - PROCESS | 2383 | RSS: Main: 319356928 11:53:08 INFO - PROCESS | 2383 | 11:53:11 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:53:12 INFO - PROCESS | 2383 | RSS: Main: 319332352 11:53:12 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:52761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52761/tests/kraken/driver.html?testName=ai-astar) 11:53:14 INFO - PROCESS | 2383 | RSS: Main: 363302912 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | __start_tp_report 11:53:14 INFO - PROCESS | 2383 | _x_x_mozilla_page_load 11:53:14 INFO - PROCESS | 2383 | _x_x_mozilla_page_load_details 11:53:14 INFO - PROCESS | 2383 | |i|pagename|runs| 11:53:14 INFO - PROCESS | 2383 | |0;ai-astar;104;113;114;113;113;113;113;113;112;113 11:53:14 INFO - PROCESS | 2383 | |1;audio-beat-detection;151;117;110;152;114;115;111;112;144;113 11:53:14 INFO - PROCESS | 2383 | |2;audio-dft;184;183;183;183;182;183;178;185;183;185 11:53:14 INFO - PROCESS | 2383 | |3;audio-fft;83;60;59;89;61;58;59;58;82;58 11:53:14 INFO - PROCESS | 2383 | |4;audio-oscillator;83;72;90;71;68;69;94;68;68;113 11:53:14 INFO - PROCESS | 2383 | |5;imaging-gaussian-blur;91;130;122;86;88;128;90;87;88;85 11:53:14 INFO - PROCESS | 2383 | |6;imaging-darkroom;131;94;88;113;148;110;98;89;103;148 11:53:14 INFO - PROCESS | 2383 | |7;imaging-desaturate;85;81;82;81;83;81;84;84;100;124 11:53:14 INFO - PROCESS | 2383 | |8;json-parse-financial;57;51;52;52;54;53;54;54;54;54 11:53:14 INFO - PROCESS | 2383 | |9;json-stringify-tinderbox;63;72;70;76;74;69;74;71;72;72 11:53:14 INFO - PROCESS | 2383 | |10;stanford-crypto-aes;92;85;92;91;86;86;89;87;87;92 11:53:14 INFO - PROCESS | 2383 | |11;stanford-crypto-ccm;147;145;144;150;162;146;145;147;182;147 11:53:14 INFO - PROCESS | 2383 | |12;stanford-crypto-pbkdf2;172;169;175;170;149;212;239;169;172;170 11:53:14 INFO - PROCESS | 2383 | |13;stanford-crypto-sha256-iterative;69;67;69;68;68;70;67;68;70;73 11:53:14 INFO - PROCESS | 2383 | __end_tp_report 11:53:14 INFO - PROCESS | 2383 | __start_cc_report 11:53:14 INFO - PROCESS | 2383 | _x_x_mozilla_cycle_collect,2003 11:53:14 INFO - PROCESS | 2383 | __end_cc_report 11:53:14 INFO - PROCESS | 2383 | __startTimestamp1476730394333__endTimestamp 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | ------- Summary: start ------- 11:53:14 INFO - PROCESS | 2383 | Number of tests: 14 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.88 (2.6%) stddev-sans-first:0.50 11:53:14 INFO - PROCESS | 2383 | Values: 104.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:116.00 stddev:17.55 (15.1%) stddev-sans-first:15.64 11:53:14 INFO - PROCESS | 2383 | Values: 151.0 117.0 110.0 152.0 114.0 115.0 111.0 112.0 144.0 113.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.97 (1.1%) stddev-sans-first:2.05 11:53:14 INFO - PROCESS | 2383 | Values: 184.0 183.0 183.0 183.0 182.0 183.0 178.0 185.0 183.0 185.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.50 stddev:12.56 (20.8%) stddev-sans-first:11.86 11:53:14 INFO - PROCESS | 2383 | Values: 83.0 60.0 59.0 89.0 61.0 58.0 59.0 58.0 82.0 58.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:71.50 stddev:15.24 (21.3%) stddev-sans-first:16.12 11:53:14 INFO - PROCESS | 2383 | Values: 83.0 72.0 90.0 71.0 68.0 69.0 94.0 68.0 68.0 113.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:87.50 stddev:18.93 (21.6%) stddev-sans-first:19.82 11:53:14 INFO - PROCESS | 2383 | Values: 91.0 130.0 122.0 86.0 88.0 128.0 90.0 87.0 88.0 85.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#6] imaging-darkroom Cycles:10 Average:112.20 Median:118.00 stddev:22.76 (19.3%) stddev-sans-first:23.11 11:53:14 INFO - PROCESS | 2383 | Values: 131.0 94.0 88.0 113.0 148.0 110.0 98.0 89.0 103.0 148.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#7] imaging-desaturate Cycles:10 Average:88.50 Median:82.50 stddev:13.69 (16.6%) stddev-sans-first:14.46 11:53:14 INFO - PROCESS | 2383 | Values: 85.0 81.0 82.0 81.0 83.0 81.0 84.0 84.0 100.0 124.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.65 (3.1%) stddev-sans-first:1.17 11:53:14 INFO - PROCESS | 2383 | Values: 57.0 51.0 52.0 52.0 54.0 53.0 54.0 54.0 54.0 54.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:3.56 (4.9%) stddev-sans-first:2.17 11:53:14 INFO - PROCESS | 2383 | Values: 63.0 72.0 70.0 76.0 74.0 69.0 74.0 71.0 72.0 72.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:90.00 stddev:2.83 (3.1%) stddev-sans-first:2.74 11:53:14 INFO - PROCESS | 2383 | Values: 92.0 85.0 92.0 91.0 86.0 86.0 89.0 87.0 87.0 92.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:147.00 stddev:11.90 (8.1%) stddev-sans-first:12.51 11:53:14 INFO - PROCESS | 2383 | Values: 147.0 145.0 144.0 150.0 162.0 146.0 145.0 147.0 182.0 147.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:172.00 stddev:25.95 (15.1%) stddev-sans-first:27.37 11:53:14 INFO - PROCESS | 2383 | Values: 172.0 169.0 175.0 170.0 149.0 212.0 239.0 169.0 172.0 170.0 11:53:14 INFO - PROCESS | 2383 | 11:53:14 INFO - PROCESS | 2383 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.79 (2.6%) stddev-sans-first:1.90 11:53:14 INFO - PROCESS | 2383 | Values: 69.0 67.0 69.0 68.0 68.0 70.0 67.0 68.0 70.0 73.0 11:53:14 INFO - PROCESS | 2383 | -------- Summary: end -------- 11:53:14 INFO - PROCESS | 2383 | 11:53:15 INFO - TEST-INFO | 2383: exit 0 11:53:15 INFO - TEST-OK | kraken | took 59938ms 11:53:15 INFO - SUITE-END | took 1180s 11:53:15 INFO - Completed test suite (00:19:40) 11:53:15 INFO - javascript benchmark 11:53:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3302.810988044304, 3335.2995870867117, 3338.499225116232, 3385.5091011803306, 3388.932221355573, 5612.522935550483, 5710.114932185513, 5729.111987641912, 5801.187833703285, 5828.2423284973, 5731.45551172639, 5762.106873434614, 5770.884582308362, 5771.480566748992, 5771.913421298674, 5178.171492561191, 5214.243934629495, 5217.817376391827, 5260.842174734764, 5263.289455923459, 5266.183741520071, 5266.447023062581, 5266.605004624649, 5266.81566145185, 5268.9473105269035, 5152.793888244466, 5240.816571420001, 5255.264263003182, 5258.079836028699, 5268.868278293055, 5297.549708274802, 5300.469953004688, 5319.46805319465, 5325.893482130375, 5379.542738867201, 5097.719625420637, 5105.5149760772665, 5162.019216348882, 5234.947650523484, 5241.528262456359, 5565.526930210937, 5674.3190817101795, 5686.516646085088, 5710.200571919934, 5737.885242295172, 4689.929651055237, 5728.112142617901, 6278.497720182374, 6292.150559674431, 6295.213098362705, 5487.149491828773, 6122.855804403507, 6212.347703491158, 6215.658138802364, 6337.651429171394, 5765.7405416756765, 5831.88336233273, 5835.67903765297, 5836.445537673999, 5892.6759028253, 6253.280872699606, 6300.684965751694, 6308.27454842699, 6316.684165791692, 6336.52476064297, 6018.337982821886, 6127.938720612826, 6181.629102253879, 6209.285932117774, 6212.099245609361, 6120.9347721082095, 6176.011838105971, 6242.75028998844, 6254.280757712831, 6276.560640755103, 6214.689265536796, 6238.844028899223, 6242.625442473466, 6244.12582238487, 6258.812235632939, 6128.693565321716, 6251.562390632703, 6277.435030847246, 6279.215098112736, 6283.151774510474, 4851.442084160296, 4923.90152196954, 4927.96512732323, 4957.628177886673, 4993.0, 4372.256716358227, 4458.286674132188, 4479.17135329965, 4481.35020422037, 4489.1021795641, 5796.101604251347, 5809.419058094241, 5816.738246778905, 5843.649381037151, 5873.588848780544, 6284.0, 6315.494760419156, 6322.71547780351, 6335.429811316912, 6363.045543168561, 5635.042042852725, 5725.484706376446, 5734.77060917567, 5813.447722466359, 5833.620814647034, 5309.681419114865, 5360.624756267024, 5364.6512976656395, 5398.24424580559, 5406.135018397038, 6241.743397201015, 6282.465990390868, 6296.181496405438, 6300.810975670737, 6303.527235457359, 6214.689265536796, 6277.058441233851, 6277.30949595535, 6281.0, 6294.1188233648245, 6217.844553886008, 6221.751129954751, 6252.999520076766, 6256.092866534236, 6281.748730050746, 6195.96902015487, 6213.782517611862, 6242.531810114168, 6255.812325630239, 6257.9687101564205, 6133.723982420713, 6184.381561843779, 6281.52888533371, 6285.43431091204, 6322.146510221061, 5991.730372133177, 6098.817035488943, 6112.969435152973, 6163.291221509584, 6209.348018457997, 6107.85643655375, 6125.754969801158, 6127.111568822585, 6128.448439640454, 6139.662318572521, 5813.244278243801, 5953.047512397996, 5965.761369545169, 5971.28344598651, 5971.940280597312, 6095.3904609538695, 6097.857415702912, 6099.703252314951, 6130.111133885473, 6140.8771824564155, 5933.821985340447, 5952.315483719254, 5953.166556681981, 5959.314678811992, 5963.821085367446], "value": 5711.539633152375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.68032397807497, 503.312977785323, 517.6790389958227, 520.1677316293931, 536.8523655994603, 30121.397572048572, 39731.602683973186, 40847.18305633886, 41099.99999999996, 43326.35010474845, 42108.157836843246, 42413.36379954303, 42497.0, 42506.36240456396, 42686.35970460438, 9374.578143983521, 9556.80886382272, 9570.904290957105, 9583.28125390595, 9608.423494590312, 8481.872771908425, 8720.258778003878, 11356.43217839109, 11562.190646654737, 11576.131790115733, 11483.770324593503, 11572.074234061254, 11577.189596728233, 11584.304941703482, 11597.826032609517, 8539.829203415897, 11361.488733006992, 11444.084473242205, 11453.713657158554, 11455.0, 8465.365097617641, 11100.55597776088, 11379.203455758101, 11408.486618102123, 11559.017483513911, 40124.398134028015, 40395.78812635626, 40453.393199102036, 40478.0, 40534.986625334604, 3390.7965522068753, 3447.6035255945635, 3715.0, 4007.4189242559664, 4130.194612050651, 22062.558748825093, 29006.709932900612, 29203.831846726105, 29301.974430894817, 29648.110556683117, 3182.443072462333, 3216.549683044352, 3243.6431992480807, 3308.2721801203697, 3349.69852713257, 475784.0, 477714.0, 477739.0, 477822.0, 478065.0, 55781.0, 57438.5640358986, 57894.0, 57957.4204257952, 57959.0, 61083.08375374284, 61098.0, 61104.38895611076, 61108.0, 61154.38845611576, 15607.765883511756, 15624.062556246661, 16063.518094457186, 16123.919380403024, 16165.838341616669, 11237.101031917427, 11565.479553419947, 12318.0, 12652.240865548096, 12743.426545805462, 13951.302434878418, 15293.617659558378, 15566.766498502531, 15683.29425175847, 15975.281112349972, 5755.884882302331, 6100.389961003864, 6265.1517802248445, 6272.937270627235, 6293.716782744787, 806.6612022950375, 866.9176428239307, 941.3692825806726, 953.5280036382036, 954.1651055326589, 28512.429751404856, 29300.56049159264, 29319.0, 30157.849210753804, 30210.697893021228, 996.9930370326023, 1051.6529545250048, 1097.3415950429837, 1124.3310230412892, 1147.839302497651, 14875.0, 15309.617259568377, 15454.227288635524, 15794.763078553831, 15808.762868556982, 620126.0, 624838.0, 625123.0, 625621.0, 626623.0, 56139.0, 56316.0, 56361.0, 56377.0, 56471.435285647436, 1356.789697596874, 1393.2476462710024, 1418.702072564773, 1418.9432422702976, 1420.4957240180001, 28499.0, 29096.854515727286, 29282.853585731937, 29379.706202937698, 29899.0, 48390.0, 48640.0, 48736.75631621961, 48766.75616622036, 48838.75580622074, 536653.0, 543860.0, 562431.0, 564605.0, 566493.0, 35250.94247172589, 37174.44238336481, 40026.79517686844, 40198.39702404525, 40252.79873600613, 36049.459258110626, 36686.816565917, 43296.350554741075, 46737.728359014545, 48994.0, 37736.69590716145, 37767.81116094402, 38167.0, 39227.803860980515, 39445.0, 38466.615333846305, 38537.80731096327, 38913.18793577045, 39887.800560997006, 40038.79980600078, 38033.0, 38062.238755224185, 38399.616003840725, 38581.42127868028, 38621.227575447774, 32150.517742233416, 33343.99968000964, 34106.48840267348, 34152.0, 34304.142396440286, 20779.0, 25235.87382063078, 25436.982520698966, 25449.745502545476, 25644.487110257316], "value": 19955.663473845703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.2819637608476, 374.12828110502534, 381.755676367125, 384.4195265149627, 397.1877510491046, 18762.812371876276, 20766.0, 21393.82333971631, 21412.8222947738, 21534.0, 20426.080826362813, 20913.0, 20989.055492502837, 21218.787812121875, 21540.784592154072, 5415.620906536544, 5507.917381239275, 5512.0, 5752.769889204426, 5768.653880767147, 4043.555208927016, 4283.764392958386, 5668.291463567054, 5801.07182850744, 5802.767889284423, 4108.383742438628, 4154.273002224613, 4305.892352691177, 6317.336679648617, 6326.145970293997, 6338.651374174418, 6407.295197528269, 6414.486841052728, 6414.679266036703, 6420.5505614607, 5039.21892106724, 5369.355677318707, 5374.113271310229, 5420.647657902224, 5431.158170483582, 13245.867541324656, 13526.256055916921, 13527.120737152054, 13543.796843047363, 13580.86419135806, 2000.099955020232, 2227.8774667393286, 2276.977230227693, 2547.3504256414494, 2877.1512403840916, 10182.0, 10269.691909242734, 10510.527026283835, 12529.248245105322, 15162.166080865549, 1750.483607335839, 1791.787142878544, 1871.8689691721586, 1916.5400303927097, 1963.2245263120988, 97230.0, 102201.4889925553, 104455.95544044614, 104953.02946220097, 105678.47160764223, 9790.8740494844, 10902.783678814769, 11363.204575679789, 11585.420728963518, 11736.237144585575, 14591.78112328316, 14595.197264150574, 14653.340599672827, 14665.780013299809, 14676.266186690622, 8187.590620469072, 8327.541985190752, 8401.579921003926, 8445.239928406472, 8555.06467025163, 8288.171182881664, 8314.584270786436, 8456.957715211507, 8474.449160804528, 8494.957525212334, 4427.490838553543, 4487.160264291625, 4529.705569137996, 4602.815887364468, 4609.7695115244105, 1840.3098837935775, 1894.7726272847347, 1917.7986311437098, 1950.5318723506257, 1955.1690531524046, 862.2670729879552, 866.5277423804042, 931.3759780946782, 939.990600094004, 947.8009617980164, 20899.0, 21609.891950540146, 21610.567788644454, 21631.459213519473, 21674.783252167275, 790.4822341366414, 876.4705588747676, 887.8135591525852, 899.9505027223435, 906.931980101495, 7931.444798864024, 11151.944240278908, 11299.548018079186, 11299.717507062225, 11372.545098196146, 573117.0, 583542.0, 590890.0, 596681.0, 599809.0, 121006.0, 121810.0, 122057.38971305087, 122436.0, 124981.0, 958.3770549143113, 968.0, 978.8923218445965, 979.9265055121037, 981.097390400833, 15133.463282679328, 18475.260989560265, 19015.33446329372, 19027.429177124155, 19160.233590656775, 47165.52834471749, 47960.04079918452, 48061.5193848057, 48431.51568484411, 49711.51442427936, 67936.6603166981, 69992.0, 70376.0, 70550.58898822097, 75301.24698752942, 5744.827655170351, 7432.442566807403, 7748.263914928073, 7962.124166341582, 8408.201220884006, 9363.4381937084, 13217.14088584239, 13418.597442076752, 13444.46222151103, 13657.863421365659, 11159.27464714791, 11470.942645286721, 11477.598284060017, 11979.640610781691, 11991.100667449802, 10858.968398002176, 10993.670189894316, 11104.898456377088, 11418.371989540656, 11760.941515263667, 11351.318920864775, 11674.124440667154, 12055.397230138282, 12056.638300850638, 12101.273923564615, 7173.426125910019, 7414.777556673308, 7521.4734968551675, 7582.469227154046, 7620.161782203842, 9657.806843862943, 9926.652567160114, 11470.197086203885, 11779.411029448665, 11910.52357905674], "value": 9226.452276666545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.3970253088646, 1526.1529850932732, 1556.2607761313377, 1794.2822870851667, 1966.5673551818604, 7872.960635196823, 8326.501229778645, 8598.699045533407, 8715.91284087159, 8854.424462409956, 8695.826083478327, 8753.16844899735, 8768.824623507526, 8770.386072974894, 8780.42927209731, 2327.7323107842603, 2356.8821558922073, 2357.127862690807, 2357.9882100589475, 2359.1860808021206, 2303.7005189325364, 2329.4060014696315, 2344.4842134730334, 2347.6126439137606, 2351.6825228594093, 2297.7242730872235, 2326.964500797146, 2332.3003099070297, 2346.7301260355107, 2349.2482405630203, 1999.9700004499944, 2289.404754763757, 2322.2684854270933, 2327.906883724649, 2331.0209711921034, 2303.631418972973, 2312.5374925014894, 2313.0747700919596, 2314.2941402872116, 2321.1063740460045, 8242.516347057528, 8577.099404562556, 8712.0, 8765.474071555727, 8779.517126558037, 4968.1554135797005, 5339.70631615261, 5398.4871437213405, 5401.486858748452, 5410.998965191452, 2980.448617005861, 3585.8386372613295, 3660.7803531788177, 3681.9263614727556, 3685.4705297301857, 1075.231209685071, 1084.3927400655657, 1089.2266490791546, 1092.4537731134433, 1105.8341248812742, 11583.478743456564, 11651.30092194471, 11709.238899471507, 11715.4142292885, 11736.002439792628, 4317.84887528935, 4357.9564204358185, 4539.387182730289, 4539.84110556129, 4586.243269860435, 8944.955275223712, 8953.507557084293, 8968.775780605538, 8992.325575581857, 9000.954995224982, 5118.309028281209, 5210.166373380241, 5230.241614965808, 5230.320058392384, 5232.9738351308, 4144.461220041436, 4395.340698895191, 4554.749488778149, 4566.315052742115, 4900.142475066849, 3312.1554003728893, 3719.6094410087094, 3791.2038471920655, 3797.6582107610448, 3802.5627052888717, 1076.6703121644762, 1081.7944590527775, 1104.8232282834708, 1151.9827202591969, 1156.3639998001233, 796.3589310605018, 853.9573021348908, 903.2457897655399, 910.7905181808088, 955.7945041816043, 5768.3366412862215, 5792.304923409218, 5952.434518720715, 6066.787662431793, 6152.661603611844, 911.9680811171577, 946.5835032585778, 952.6046690623314, 974.6345120579782, 1011.9494025298706, 3830.751001184914, 3866.8839934802, 3939.2318497893207, 3962.465067215889, 4031.9798401008393, 10312.639057632947, 10761.0, 10790.244682872124, 10905.563777448813, 10963.122950163968, 10676.786464270826, 10808.351498910091, 10831.67504974852, 10867.184961127947, 10888.891111088788, 6639.0, 8228.50628962264, 8236.052853921756, 8252.752417427488, 8254.752357429286, 5324.494173053554, 5352.223927530564, 5620.1288800236025, 5917.0, 6053.636781793107, 9788.216942644429, 10116.797664046826, 10129.746756330856, 10141.949290253502, 10145.594176232868, 9263.629454822001, 9705.563249653642, 10013.599456021677, 10222.539985700512, 10300.433476158883, 7019.29807019294, 7269.345758881726, 7272.745453909088, 7275.1997280298065, 7276.017737605567, 7812.257835505845, 8008.919910800817, 8255.050669173123, 8286.875696864432, 8382.203690649378, 7324.780256592311, 7331.596762178131, 7436.516626419266, 7476.5514069156025, 7616.314531692175, 7315.743948961761, 7375.668094935633, 7376.299251571092, 7443.665035073543, 7468.813279668052, 6931.514793964576, 7075.575465472089, 7083.504154709327, 7086.255943126103, 7102.573845569283, 4490.326451032241, 4865.513448655106, 5030.924536131888, 5057.519535644108, 5110.923336149886, 4499.66252531068, 4605.608523275425, 4858.92711609319, 4893.779779909842, 5199.428062913001], "value": 4510.882836291214, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.87855465040735, 288.82381747134247, 292.616672159471, 300.42018903516214, 301.86265249311566, 4549.818007279709, 5147.536721695052, 5192.402873669529, 5217.165253559431, 5236.738163091849, 4462.843800466984, 4733.733723730624, 5165.896682066356, 5171.586273098153, 5252.842414727555, 3350.659736105553, 3986.322325204708, 4000.8599699010542, 4018.397240413932, 4021.235965166624, 3524.3083320006413, 3796.449514820363, 3845.692344612424, 4013.3077568636777, 4036.0, 3615.3311637347174, 3766.623337666239, 3881.3789793633023, 3905.628965248297, 3917.6278253566006, 3592.9640703593022, 3911.2568611963634, 3922.5680078351606, 3953.762774233526, 3966.2464131814863, 3458.8270586470576, 3459.930801383958, 3466.376052310583, 3466.596028608418, 3493.6506349365113, 4415.933760993587, 4904.975475122637, 4974.328465657162, 4977.20820504618, 5065.366829146356, 4451.443569553806, 4461.665375096848, 4486.070989474525, 4486.170058539181, 4496.662750293708, 4637.559431853969, 5181.663191892552, 5366.087765079946, 5418.972905135449, 5423.3491980962535, 3685.244524872395, 3797.2785170817456, 3798.487204227449, 3817.7327587068917, 3817.8854634361014, 5193.065248255312, 5244.134717771563, 5409.83770486886, 5612.989661860864, 5710.943475457054, 3450.4824276358745, 3862.3240932836643, 3890.6498415142773, 3891.591382904811, 3894.337962546317, 3792.0140763401714, 3924.274009308287, 3944.684425245973, 3945.921081578352, 3956.643902048829, 3742.812859357076, 3755.6056614055133, 4476.597106260452, 4492.3710680504755, 4512.826665067106, 5392.892142157135, 5397.217403476552, 5415.998040362546, 5436.401995780461, 5477.452254774491, 1100.7908497385472, 1144.2447984330302, 1319.5381616434172, 1349.089364678838, 1351.6620844788804, 689.5483458334799, 789.4868335581803, 833.3832963606887, 850.9829803403898, 875.1642181716445, 4968.552830245223, 5108.897822043613, 5130.974345128325, 5141.614378921521, 5144.382674079135, 4794.568488836021, 5449.836504904859, 5490.1764735289225, 5500.074973759152, 5505.752241149158, 4304.569543045671, 5012.947281070943, 5025.497450254945, 5115.769790359443, 5222.92165617516, 2615.843049417041, 2744.519709050908, 2942.764578833688, 2946.7495262902894, 2952.5571164325524, 5155.690658560498, 5794.855128621818, 5849.561282903799, 5875.853103672443, 5904.763809447574, 5120.820771273062, 5183.377994640668, 5832.591718579743, 5838.591298609056, 5859.941400586024, 4933.50664933511, 5500.22996780443, 5540.088669409048, 5541.6674999499355, 5905.409459054146, 5341.78632854697, 5470.288862448016, 5718.485336319751, 5736.5410767139365, 5826.621269617462, 5263.263123169421, 5682.43175682445, 5695.753420550247, 5706.828795136153, 5795.0, 5235.947640523546, 5690.573207009574, 5888.4700376964565, 5919.526437885045, 5966.403359663999, 3583.874564390234, 3776.2069965306496, 3813.0657988792923, 3860.3051450739135, 3873.7094717896834, 4925.655204135819, 5127.564157046693, 5633.6619802811965, 5676.864627074684, 5779.479846813807, 4927.975360123177, 5345.529979255736, 5647.632903861236, 5665.716714164193, 5688.44020190924, 5347.181958134253, 5448.0, 5465.808696695596, 5518.972405137949, 5521.889562208814, 4650.465196502446, 5332.013577488177, 5352.411234764095, 5371.677699338053, 5372.409035006224, 5370.516653501204, 5393.298871146726, 5393.514583687487, 5541.9168712470155, 5543.805966791143, 4913.606911447004, 5164.070467315769, 5408.161734931092, 5438.776275338176, 5478.945210547843], "value": 4005.5634659978036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.6296018669824, 784.1374488063132, 786.1077676836788, 799.3565180030075, 828.2587084559324, 14857.034292770975, 15577.22113894305, 15578.298976546055, 15600.063996160225, 15685.411907035326, 14109.506167284148, 14237.0, 14251.073680210779, 14253.786193207085, 14257.0, 698.0541366448465, 717.5622870049266, 862.5514732339179, 886.9290456763459, 909.9499527525984, 771.3212373111654, 904.7104926423547, 908.0692290402325, 908.554808144008, 910.2399496420463, 954.6372378496162, 955.2596737528402, 956.4834989105874, 957.0142752964423, 959.0744931141447, 731.9743808966714, 761.2387612387612, 957.1672644799035, 957.5355952363099, 960.428545015718, 546.8851541176358, 556.7550277877721, 558.5615291995764, 560.3639868748959, 712.9786106416764, 14547.0, 14858.628534286621, 14873.628159295997, 14878.330475128645, 14886.330115144845, 13520.188788672613, 13528.526501572496, 13870.65323366924, 13874.583762487142, 14402.639934001629, 11013.779724405467, 12116.81824772629, 12596.181248218929, 12682.492700291978, 12758.489660413572, 4492.663050271242, 4506.504284528699, 4526.140033393677, 4700.0, 4722.881927951795, 87695.12304876934, 88836.09273165556, 100604.49697751537, 101604.0, 103272.9345413095, 9955.701328960144, 10184.643537476153, 10341.431221282752, 10476.790464190673, 10496.580136794442, 19659.803401965797, 19924.3026494072, 20002.499937501678, 20038.799612003986, 20100.69848952267, 7357.41140708742, 7450.329470347695, 7539.698412063456, 7556.433267504959, 7814.21857814214, 4217.240896638573, 4230.407742915994, 4285.0, 4308.332208507686, 4393.494748104009, 3456.8098754568236, 3571.6964058054837, 3576.3741345264993, 3576.5529308836394, 3579.982100089483, 651.6415971215812, 651.7588492257895, 661.8213082467803, 664.378805816563, 667.1560475997867, 539.382407143826, 550.8980838544882, 568.5139205978828, 576.2019602850082, 615.2555407956334, 6804.0, 8796.296296296281, 9024.593893274818, 9202.946618720063, 9215.400998935047, 596.4125336543501, 599.1432251879854, 612.7395856760859, 623.8471574464195, 641.3426238105978, 4278.614924656796, 4430.579094985971, 4478.283474644107, 4489.349044388546, 4590.311453282035, 95976.0, 127338.36330818286, 141300.0, 145330.0, 152971.0, 114767.42616286865, 129020.0646990296, 135676.64323356838, 136104.8752806126, 136183.63816361906, 111054.0, 111132.0, 116969.0, 117725.0, 117974.0, 8338.62476188585, 8525.104863989192, 8538.44500107491, 8614.396992210484, 8735.56322183901, 52309.0, 54002.0, 54149.45850541444, 57592.4240757587, 66054.33945660482, 66968.99546506903, 80675.0, 81684.18315816765, 83547.65809367801, 83775.0, 13111.016673749316, 13260.138091024053, 13606.319684015958, 13621.182729035892, 13723.382447789674, 14199.929000354932, 14876.553703388916, 14930.328135233724, 15048.398064077313, 15280.541583752061, 13134.868651313365, 13842.169469831842, 14150.646233844236, 14291.499797507038, 14460.349284282443, 13629.86370136286, 13688.863111368759, 14303.0, 14364.569062928129, 14385.712285754016, 13307.698821044733, 13601.659958501117, 13615.387307571382, 13683.247421391521, 14312.284385780878, 10893.954765172624, 11148.329897113166, 11285.774284514428, 11427.771444571228, 11833.0, 10345.534450949875, 11299.794947343327, 11341.4896329667, 11586.246893951866, 11592.246503977214], "value": 7677.634412859952, "unit": "score"}], "name": "dromaeo_css", "value": 7255.638130054915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 110.0, 152.0, 114.0, 115.0, 111.0, 112.0, 144.0, 113.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 183.0, 182.0, 183.0, 178.0, 185.0, 183.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 59.0, 89.0, 61.0, 58.0, 59.0, 58.0, 82.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 90.0, 71.0, 68.0, 69.0, 94.0, 68.0, 68.0, 113.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 130.0, 122.0, 86.0, 88.0, 128.0, 90.0, 87.0, 88.0, 85.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 94.0, 88.0, 113.0, 148.0, 110.0, 98.0, 89.0, 103.0, 148.0], "value": 112.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 82.0, 81.0, 83.0, 81.0, 84.0, 84.0, 100.0, 124.0], "value": 88.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 51.0, 52.0, 52.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 70.0, 76.0, 74.0, 69.0, 74.0, 71.0, 72.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 92.0, 91.0, 86.0, 86.0, 89.0, 87.0, 87.0, 92.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 144.0, 150.0, 162.0, 146.0, 145.0, 147.0, 182.0, 147.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 175.0, 170.0, 149.0, 212.0, 239.0, 169.0, 172.0, 170.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 68.0, 68.0, 70.0, 67.0, 68.0, 70.0, 73.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1479.0, "alertThreshold": 2.0}]} 11:53:15 INFO - Return code: 0 11:53:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:53:15 INFO - # TBPL SUCCESS # 11:53:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:53:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:53:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:53:15 INFO - 2016-10-17 11:53:15.659 screenresolution[2387:32679] starting screenresolution argv=screenresolution get 11:53:15 INFO - 2016-10-17 11:53:15.662 screenresolution[2387:32679] Display 0: 1600x1200x32@60 11:53:15 INFO - 2016-10-17 11:53:15.668 screenresolution[2388:32682] starting screenresolution argv=screenresolution list 11:53:15 INFO - Available Modes on Display 0 11:53:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:53:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:53:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:53:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:53:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:53:15 INFO - Intel Iris: 11:53:15 INFO - Chipset Model: Intel Iris 11:53:15 INFO - Type: GPU 11:53:15 INFO - Bus: Built-In 11:53:15 INFO - VRAM (Dynamic, Max): 1536 MB 11:53:15 INFO - Vendor: Intel (0x8086) 11:53:15 INFO - Device ID: 0x0a2e 11:53:15 INFO - Revision ID: 0x0009 11:53:15 INFO - Displays: 11:53:15 INFO - Display: 11:53:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:53:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:53:15 INFO - Display Serial Number: 1600x1200 60 11:53:15 INFO - Main Display: Yes 11:53:15 INFO - Mirror: Off 11:53:15 INFO - Online: Yes 11:53:15 INFO - Rotation: Supported 11:53:15 INFO - Return code: 0 11:53:15 INFO - Running post-action listener: _resource_record_post_action 11:53:15 INFO - [mozharness: 2016-10-17 18:53:15.907679Z] Finished run-tests step (success) 11:53:15 INFO - Running post-run listener: _resource_record_post_run 11:53:15 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 425984; Write bytes: 707234816; Read time: 5; Write time: 1932 11:53:15 INFO - TinderboxPrint: CPU usage
53.6% 11:53:15 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 5 11:53:15 INFO - TinderboxPrint: I/O write bytes / time
707,234,816 / 1,932 11:53:15 INFO - TinderboxPrint: CPU idle
2,254.8 (46.4%) 11:53:15 INFO - TinderboxPrint: CPU system
977.0 (20.1%) 11:53:15 INFO - TinderboxPrint: CPU user
1,629.8 (33.5%) 11:53:15 INFO - TinderboxPrint: Swap in / out
406,536,192 / 0 11:53:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391195136; Write bytes: 386938880; Read time: 29174; Write time: 785 11:53:15 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 425984; Write bytes: 296678400; Read time: 5; Write time: 1090 11:53:16 INFO - Running post-run listener: _upload_blobber_files 11:53:16 INFO - Blob upload gear active. 11:53:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:53:16 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 11:53:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:53:16 INFO - (blobuploader) - INFO - Open directory for files ... 11:53:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:53:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:17 INFO - (blobuploader) - INFO - Done attempting. 11:53:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:53:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:18 INFO - (blobuploader) - INFO - Done attempting. 11:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:53:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:53:20 INFO - (blobuploader) - INFO - Done attempting. 11:53:20 INFO - (blobuploader) - INFO - Iteration through files over. 11:53:20 INFO - Return code: 0 11:53:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:53:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:53:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b656ef4559aaaaae21871fc9d678f83db6244704250e9d061ef97f53bef34310c77c9f890bc8a1c5530a23b3be0dc0234593ee210ab8ba34077840578695718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b78e5bc991669ba1e1889f24062d0fbe44f01a097fc8f539d54fcd4dac90976fc105222d1a5f6cd78658dae8acbb62ab44ea545a5bffbd01af9777f52a79da3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68632dd0a5d998288bd70ac702b90589fe3dc5f3344191ac205047bf573fddedf08016d429d5c7aad933694fe88991bece3f9114ac6137f1877aaa1b8f8c1b5c"} 11:53:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:53:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:53:20 INFO - Contents: 11:53:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b656ef4559aaaaae21871fc9d678f83db6244704250e9d061ef97f53bef34310c77c9f890bc8a1c5530a23b3be0dc0234593ee210ab8ba34077840578695718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b78e5bc991669ba1e1889f24062d0fbe44f01a097fc8f539d54fcd4dac90976fc105222d1a5f6cd78658dae8acbb62ab44ea545a5bffbd01af9777f52a79da3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68632dd0a5d998288bd70ac702b90589fe3dc5f3344191ac205047bf573fddedf08016d429d5c7aad933694fe88991bece3f9114ac6137f1877aaa1b8f8c1b5c"} 11:53:20 INFO - Running post-run listener: copy_logs_to_upload_dir 11:53:20 INFO - Copying logs to upload dir... 11:53:20 INFO - mkdir: /builds/slave/test/build/upload/logs 11:53:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.053731 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-17 11:53:20.927941) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 11:53:20.931762) ========= 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.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b656ef4559aaaaae21871fc9d678f83db6244704250e9d061ef97f53bef34310c77c9f890bc8a1c5530a23b3be0dc0234593ee210ab8ba34077840578695718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b78e5bc991669ba1e1889f24062d0fbe44f01a097fc8f539d54fcd4dac90976fc105222d1a5f6cd78658dae8acbb62ab44ea545a5bffbd01af9777f52a79da3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68632dd0a5d998288bd70ac702b90589fe3dc5f3344191ac205047bf573fddedf08016d429d5c7aad933694fe88991bece3f9114ac6137f1877aaa1b8f8c1b5c"} build_url:https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/To44nGCMQmqjBmOMjy5mzQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2b656ef4559aaaaae21871fc9d678f83db6244704250e9d061ef97f53bef34310c77c9f890bc8a1c5530a23b3be0dc0234593ee210ab8ba34077840578695718", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b78e5bc991669ba1e1889f24062d0fbe44f01a097fc8f539d54fcd4dac90976fc105222d1a5f6cd78658dae8acbb62ab44ea545a5bffbd01af9777f52a79da3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68632dd0a5d998288bd70ac702b90589fe3dc5f3344191ac205047bf573fddedf08016d429d5c7aad933694fe88991bece3f9114ac6137f1877aaa1b8f8c1b5c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 11:53:20.964972) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:53:20.965322) ========= 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.0kaerQajZp/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.3rGFey0nsZ/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.004831 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 11:53:21.015345) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 11:53:21.015664) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 11:53:21.015980) ========= ========= Total master_lag: 0.18 =========