builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1475225205.25 results: success (0) buildid: 20160929231419 builduid: ede7e1b9257e4bee9cb0bc8ece1ab225 revision: 55741185923649a1628de065bb1ec04dc37ccbd5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:45.248402) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:45.248975) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:45.249355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:45.312102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:45.312381) ========= 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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-09-30 01:46:45-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-09-30 01:46:45 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165476 ========= master_lag: 0.66 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:46.134686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:46.134987) ========= 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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.020520 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:46.176482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-30 01:46:46.176825) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 55741185923649a1628de065bb1ec04dc37ccbd5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 55741185923649a1628de065bb1ec04dc37ccbd5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-09-30 01:46:46,254 truncating revision to first 12 chars 2016-09-30 01:46:46,254 Setting DEBUG logging. 2016-09-30 01:46:46,254 attempt 1/10 2016-09-30 01:46:46,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/557411859236?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 01:46:47,264 unpacking tar archive at: fx-team-557411859236/testing/mozharness/ program finished with exit code 0 elapsedTime=2.337769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-30 01:46:48.536438) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:48.536736) ========= script_repo_revision: 55741185923649a1628de065bb1ec04dc37ccbd5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:48.540198) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:48.540477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 01:46:48.561531) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-30 01:46:48.561822) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:46:48 INFO - MultiFileLogger online at 20160930 01:46:48 in /builds/slave/test 01:46:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:46:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:46:48 INFO - {'append_to_log': False, 01:46:48 INFO - 'base_work_dir': '/builds/slave/test', 01:46:48 INFO - 'blob_upload_branch': 'Fx-Team', 01:46:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:46:48 INFO - 'branch': 'Fx-Team', 01:46:48 INFO - 'buildbot_json_path': 'buildprops.json', 01:46:48 INFO - 'config_files': ('talos/mac_config.py',), 01:46:48 INFO - 'default_actions': ('clobber', 01:46:48 INFO - 'read-buildbot-config', 01:46:48 INFO - 'download-and-extract', 01:46:48 INFO - 'populate-webroot', 01:46:48 INFO - 'create-virtualenv', 01:46:48 INFO - 'install', 01:46:48 INFO - 'run-tests'), 01:46:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:46:48 INFO - 'download_minidump_stackwalk': True, 01:46:48 INFO - 'download_symbols': 'ondemand', 01:46:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:46:48 INFO - 'tooltool.py': '/tools/tooltool.py', 01:46:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:46:48 INFO - '/tools/misc-python/virtualenv.py')}, 01:46:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:46:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:46:48 INFO - 'installer_path': 'installer.exe', 01:46:48 INFO - 'log_level': 'info', 01:46:48 INFO - 'log_name': 'talos', 01:46:48 INFO - 'log_to_console': True, 01:46:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:46:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:46:48 INFO - 'opt_config_files': (), 01:46:48 INFO - 'pip_index': False, 01:46:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:46:48 INFO - 'cmd': ('bash', 01:46:48 INFO - '-c', 01:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:46:48 INFO - 'enabled': True, 01:46:48 INFO - 'halt_on_failure': False, 01:46:48 INFO - 'name': 'check_screen_resolution'},), 01:46:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:46:48 INFO - 'cmd': ('bash', 01:46:48 INFO - '-c', 01:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:46:48 INFO - 'enabled': True, 01:46:48 INFO - 'halt_on_failure': False, 01:46:48 INFO - 'name': 'check_screen_resolution'},), 01:46:48 INFO - 'run_cmd_checks_enabled': True, 01:46:48 INFO - 'sps_profile': False, 01:46:48 INFO - 'sps_profile_interval': 0, 01:46:48 INFO - 'suite': 'dromaeojs', 01:46:48 INFO - 'system_bits': '32', 01:46:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:46:48 INFO - 'title': 't-yosemite-r7-0312', 01:46:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:46:48 INFO - 'use_talos_json': True, 01:46:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:46:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:46:48 INFO - 'work_dir': 'build'} 01:46:48 INFO - [mozharness: 2016-09-30 08:46:48.758239Z] Running clobber step. 01:46:48 INFO - Running pre-action listener: _resource_record_pre_action 01:46:48 INFO - Running main action method: clobber 01:46:48 INFO - rmtree: /builds/slave/test/build 01:46:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:46:48 INFO - Running post-action listener: _resource_record_post_action 01:46:48 INFO - [mozharness: 2016-09-30 08:46:48.991959Z] Finished clobber step (success) 01:46:48 INFO - [mozharness: 2016-09-30 08:46:48.992075Z] Running read-buildbot-config step. 01:46:48 INFO - Running pre-action listener: _resource_record_pre_action 01:46:48 INFO - Running main action method: read_buildbot_config 01:46:48 INFO - Using buildbot properties: 01:46:48 INFO - { 01:46:48 INFO - "project": "", 01:46:48 INFO - "product": "firefox", 01:46:48 INFO - "script_repo_revision": "production", 01:46:48 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:46:48 INFO - "repository": "", 01:46:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:46:48 INFO - "buildid": "20160929231419", 01:46:48 INFO - "pgo_build": "False", 01:46:48 INFO - "basedir": "/builds/slave/test", 01:46:48 INFO - "buildnumber": 162, 01:46:48 INFO - "slavename": "t-yosemite-r7-0312", 01:46:48 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:46:48 INFO - "platform": "yosemite_r7", 01:46:48 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:46:48 INFO - "branch": "fx-team", 01:46:48 INFO - "repo_path": "integration/fx-team", 01:46:48 INFO - "revision": "55741185923649a1628de065bb1ec04dc37ccbd5", 01:46:48 INFO - "stage_platform": "macosx64", 01:46:48 INFO - "builduid": "ede7e1b9257e4bee9cb0bc8ece1ab225", 01:46:48 INFO - "slavebuilddir": "test" 01:46:48 INFO - } 01:46:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:46:48 INFO - Running post-action listener: _resource_record_post_action 01:46:48 INFO - [mozharness: 2016-09-30 08:46:48.995589Z] Finished read-buildbot-config step (success) 01:46:48 INFO - [mozharness: 2016-09-30 08:46:48.995721Z] Running download-and-extract step. 01:46:48 INFO - Running pre-action listener: _resource_record_pre_action 01:46:48 INFO - Running main action method: download_and_extract 01:46:48 INFO - mkdir: /builds/slave/test/build/tests 01:46:48 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')]} 01:46:48 INFO - trying https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:46:48 INFO - Downloading https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/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 01:46:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/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 01:46:50 INFO - Downloaded 1199 bytes. 01:46:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:46:50 INFO - Using the following test package requirements: 01:46:50 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:46:50 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:46:50 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:46:50 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'jsshell-mac.zip'], 01:46:50 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:46:50 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:46:50 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:46:50 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:46:50 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:46:50 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:46:50 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:46:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:46:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:46:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:46:50 INFO - Fetch https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:46:50 INFO - Http code: 200 01:46:50 INFO - Content-Encoding: None 01:46:50 INFO - Content-Type: application/zip 01:46:50 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 01:46:50 INFO - x-amz-cf-id: z7WdM-hFcH8r1Q67VSmTRGeMcaBsl0MVYhSTqCAi1okPGl9eK7njzA== 01:46:50 INFO - x-amz-version-id: zOdCd0OuFI.LzXrVcemUBHPw.uds.UTV 01:46:50 INFO - x-cache: Hit from cloudfront 01:46:51 INFO - Expected file size: 16473440 01:46:51 INFO - Obtained file size: 16473440 01:46:57 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 01:46:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:46:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:46:57 INFO - Fetch https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:46:58 INFO - Http code: 200 01:46:58 INFO - Content-Encoding: None 01:46:58 INFO - Content-Type: application/zip 01:46:58 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 01:46:58 INFO - x-amz-cf-id: 0BaJOCQs82ErKUUcceVfOqBv6CDyfSmnGt8oFEnpVbnDRBdV2754xg== 01:46:58 INFO - x-amz-version-id: zOdCd0OuFI.LzXrVcemUBHPw.uds.UTV 01:46:58 INFO - x-cache: Hit from cloudfront 01:46:58 INFO - Expected file size: 16473440 01:46:58 INFO - Obtained file size: 16473440 01:47:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:47:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:47:05 INFO - Fetch https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:47:05 INFO - Http code: 200 01:47:05 INFO - Content-Encoding: None 01:47:05 INFO - Content-Type: application/zip 01:47:05 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 01:47:05 INFO - x-amz-cf-id: 64ENPV2BOkRUUWhnUyL7DFeG3Fz2qIKuSVfkwzY77NdS-gxUxqzc5A== 01:47:05 INFO - x-amz-version-id: iRXxBHKjEPZgwvsFZffuNLitxs6CTM_h 01:47:05 INFO - x-cache: Hit from cloudfront 01:47:05 INFO - Expected file size: 14414067 01:47:05 INFO - Obtained file size: 14414067 01:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:06 INFO - trying https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:47:06 INFO - Downloading https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:47:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/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 01:47:09 INFO - Downloaded 111810628 bytes. 01:47:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:47:09 INFO - mkdir: /builds/slave/test/properties 01:47:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:47:09 INFO - Writing to file /builds/slave/test/properties/build_url 01:47:09 INFO - Contents: 01:47:09 INFO - build_url:https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:47:10 INFO - Running post-action listener: _resource_record_post_action 01:47:10 INFO - Running post-action listener: set_extra_try_arguments 01:47:10 INFO - [mozharness: 2016-09-30 08:47:10.290583Z] Finished download-and-extract step (success) 01:47:10 INFO - [mozharness: 2016-09-30 08:47:10.290722Z] Running populate-webroot step. 01:47:10 INFO - Running pre-action listener: _resource_record_pre_action 01:47:10 INFO - Running main action method: populate_webroot 01:47:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:47:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:47:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:47:10 INFO - '--tppagecycles', 01:47:10 INFO - '7'], 01:47:10 INFO - 'tests': ['tsvgm']}}, 01:47:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:47:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:47:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:47:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:47:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:47:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:47:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['damp', 'tps']}, 01:47:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'tests': ['damp', 'tps']}, 01:47:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['dromaeo_dom']}, 01:47:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:47:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['basic_compositor_video']}, 01:47:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:47:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'other-e10s': {'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['a11yr', 01:47:10 INFO - 'ts_paint', 01:47:10 INFO - 'tpaint', 01:47:10 INFO - 'sessionrestore', 01:47:10 INFO - 'sessionrestore_no_auto_restore', 01:47:10 INFO - 'tabpaint']}, 01:47:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['tsvgx', 01:47:10 INFO - 'tsvgr_opacity', 01:47:10 INFO - 'tart', 01:47:10 INFO - 'tscrollx', 01:47:10 INFO - 'cart']}, 01:47:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:47:10 INFO - 'tsvgr_opacity', 01:47:10 INFO - 'tart', 01:47:10 INFO - 'tscrollx', 01:47:10 INFO - 'cart']}, 01:47:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'talos_options': ['--disable-e10s'], 01:47:10 INFO - 'tests': ['tp5o']}, 01:47:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'tests': ['tp5o']}, 01:47:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'talos_options': ['--disable-e10s', 01:47:10 INFO - '--xperf_path', 01:47:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:47:10 INFO - 'tests': ['tp5n']}, 01:47:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:47:10 INFO - 'talos_options': ['--xperf_path', 01:47:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:47:10 INFO - 'tests': ['tp5n']}}, 01:47:10 INFO - 'talos.zip': {'path': '', 01:47:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:47:10 INFO - Running post-action listener: _resource_record_post_action 01:47:10 INFO - [mozharness: 2016-09-30 08:47:10.309083Z] Finished populate-webroot step (success) 01:47:10 INFO - [mozharness: 2016-09-30 08:47:10.309173Z] Running create-virtualenv step. 01:47:10 INFO - Running pre-action listener: _resource_record_pre_action 01:47:10 INFO - Running main action method: create_virtualenv 01:47:10 INFO - Creating virtualenv /builds/slave/test/build/venv 01:47:10 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 01:47:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:47:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:47:10 INFO - Using real prefix '/tools/python27' 01:47:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:47:11 INFO - Installing distribute.............................................................................................................................................................................................done. 01:47:13 INFO - Installing pip.................done. 01:47:13 INFO - Return code: 0 01:47:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:47:14 INFO - Reading from file tmpfile_stdout 01:47:14 INFO - Output received: 01:47:14 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) 01:47:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:14 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:14 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 01:47:14 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 01:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:14 INFO - 'HOME': '/Users/cltbld', 01:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:14 INFO - 'LOGNAME': 'cltbld', 01:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:14 INFO - 'MOZ_NO_REMOTE': '1', 01:47:14 INFO - 'NO_EM_RESTART': '1', 01:47:14 INFO - 'PAGER': '/bin/cat', 01:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:14 INFO - 'PWD': '/builds/slave/test', 01:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:14 INFO - 'SHELL': '/bin/bash', 01:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:14 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:14 INFO - 'USER': 'cltbld', 01:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:14 INFO - 'XPC_FLAGS': '0x0', 01:47:14 INFO - 'XPC_SERVICE_NAME': '0', 01:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:14 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 01:47:14 INFO - Cleaning up... 01:47:14 INFO - Return code: 0 01:47:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:14 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:14 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 01:47:14 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 01:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:14 INFO - 'HOME': '/Users/cltbld', 01:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:14 INFO - 'LOGNAME': 'cltbld', 01:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:14 INFO - 'MOZ_NO_REMOTE': '1', 01:47:14 INFO - 'NO_EM_RESTART': '1', 01:47:14 INFO - 'PAGER': '/bin/cat', 01:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:14 INFO - 'PWD': '/builds/slave/test', 01:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:14 INFO - 'SHELL': '/bin/bash', 01:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:14 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:14 INFO - 'USER': 'cltbld', 01:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:14 INFO - 'XPC_FLAGS': '0x0', 01:47:14 INFO - 'XPC_SERVICE_NAME': '0', 01:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:14 INFO - Downloading/unpacking psutil>=3.1.1 01:47:14 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 01:47:14 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 01:47:14 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 01:47:14 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 01:47:14 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 01:47:14 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 01:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:47:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:47:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:47:18 INFO - Installing collected packages: psutil 01:47:18 INFO - Running setup.py install for psutil 01:47:18 INFO - building 'psutil._psutil_osx' extension 01:47:18 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 01:47:18 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 01:47:19 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 01:47:19 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 01:47:19 INFO - building 'psutil._psutil_posix' extension 01:47:19 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 01:47:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:47:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:47:19 INFO - ^ 01:47:19 INFO - 1 warning generated. 01:47:19 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 01:47:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:47:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:47:19 INFO - Successfully installed psutil 01:47:19 INFO - Cleaning up... 01:47:19 INFO - Return code: 0 01:47:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:19 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:19 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 01:47:19 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 01:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:19 INFO - 'HOME': '/Users/cltbld', 01:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:19 INFO - 'LOGNAME': 'cltbld', 01:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:19 INFO - 'MOZ_NO_REMOTE': '1', 01:47:19 INFO - 'NO_EM_RESTART': '1', 01:47:19 INFO - 'PAGER': '/bin/cat', 01:47:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:19 INFO - 'PWD': '/builds/slave/test', 01:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:19 INFO - 'SHELL': '/bin/bash', 01:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:19 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:19 INFO - 'USER': 'cltbld', 01:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:19 INFO - 'XPC_FLAGS': '0x0', 01:47:19 INFO - 'XPC_SERVICE_NAME': '0', 01:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:47:19 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 01:47:19 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 01:47:19 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 01:47:19 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 01:47:19 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 01:47:19 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 01:47:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:47:22 INFO - Installing collected packages: mozsystemmonitor 01:47:22 INFO - Running setup.py install for mozsystemmonitor 01:47:22 INFO - Successfully installed mozsystemmonitor 01:47:22 INFO - Cleaning up... 01:47:22 INFO - Return code: 0 01:47:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:47:22 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')]} 01:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:22 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')]} 01:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:22 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:22 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 01:47:22 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 01:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:22 INFO - 'HOME': '/Users/cltbld', 01:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:22 INFO - 'LOGNAME': 'cltbld', 01:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:22 INFO - 'MOZ_NO_REMOTE': '1', 01:47:22 INFO - 'NO_EM_RESTART': '1', 01:47:22 INFO - 'PAGER': '/bin/cat', 01:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:22 INFO - 'PWD': '/builds/slave/test', 01:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:22 INFO - 'SHELL': '/bin/bash', 01:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:22 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:22 INFO - 'USER': 'cltbld', 01:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:22 INFO - 'XPC_FLAGS': '0x0', 01:47:22 INFO - 'XPC_SERVICE_NAME': '0', 01:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:22 INFO - Downloading/unpacking jsonschema==2.5.1 01:47:22 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 01:47:22 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 01:47:22 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 01:47:22 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 01:47:22 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 01:47:22 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 01:47:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:47:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:47:25 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 01:47:25 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 01:47:25 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 01:47:25 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 01:47:25 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 01:47:25 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 01:47:25 INFO - Downloading functools32-3.2.3-2.tar.gz 01:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:47:25 INFO - warning: no files found matching '*.txt' 01:47:25 INFO - no previously-included directories found matching 'build' 01:47:25 INFO - no previously-included directories found matching 'dist' 01:47:25 INFO - no previously-included directories found matching '.git*' 01:47:25 INFO - Installing collected packages: jsonschema, functools32 01:47:25 INFO - Running setup.py install for functools32 01:47:25 INFO - warning: no files found matching '*.txt' 01:47:25 INFO - no previously-included directories found matching 'build' 01:47:25 INFO - no previously-included directories found matching 'dist' 01:47:25 INFO - no previously-included directories found matching '.git*' 01:47:25 INFO - Successfully installed jsonschema functools32 01:47:25 INFO - Cleaning up... 01:47:26 INFO - Return code: 0 01:47:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:26 INFO - 'HOME': '/Users/cltbld', 01:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:26 INFO - 'LOGNAME': 'cltbld', 01:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:26 INFO - 'MOZ_NO_REMOTE': '1', 01:47:26 INFO - 'NO_EM_RESTART': '1', 01:47:26 INFO - 'PAGER': '/bin/cat', 01:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:26 INFO - 'PWD': '/builds/slave/test', 01:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:26 INFO - 'SHELL': '/bin/bash', 01:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:26 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:26 INFO - 'USER': 'cltbld', 01:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:26 INFO - 'XPC_FLAGS': '0x0', 01:47:26 INFO - 'XPC_SERVICE_NAME': '0', 01:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:47:26 INFO - Cleaning up... 01:47:26 INFO - Return code: 0 01:47:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:26 INFO - 'HOME': '/Users/cltbld', 01:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:26 INFO - 'LOGNAME': 'cltbld', 01:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:26 INFO - 'MOZ_NO_REMOTE': '1', 01:47:26 INFO - 'NO_EM_RESTART': '1', 01:47:26 INFO - 'PAGER': '/bin/cat', 01:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:26 INFO - 'PWD': '/builds/slave/test', 01:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:26 INFO - 'SHELL': '/bin/bash', 01:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:26 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:26 INFO - 'USER': 'cltbld', 01:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:26 INFO - 'XPC_FLAGS': '0x0', 01:47:26 INFO - 'XPC_SERVICE_NAME': '0', 01:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:26 INFO - Downloading/unpacking blobuploader==1.2.4 01:47:26 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 01:47:26 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 01:47:26 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 01:47:26 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 01:47:26 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 01:47:26 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 01:47:28 INFO - Downloading blobuploader-1.2.4.tar.gz 01:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:47:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:47:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:47:30 INFO - Downloading docopt-0.6.1.tar.gz 01:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:47:30 INFO - Installing collected packages: blobuploader, requests, docopt 01:47:30 INFO - Running setup.py install for blobuploader 01:47:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:47:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:47:30 INFO - Running setup.py install for requests 01:47:30 INFO - Running setup.py install for docopt 01:47:30 INFO - Successfully installed blobuploader requests docopt 01:47:30 INFO - Cleaning up... 01:47:31 INFO - Return code: 0 01:47:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:31 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:31 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 01:47:31 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 01:47:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:31 INFO - 'HOME': '/Users/cltbld', 01:47:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:31 INFO - 'LOGNAME': 'cltbld', 01:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:31 INFO - 'MOZ_NO_REMOTE': '1', 01:47:31 INFO - 'NO_EM_RESTART': '1', 01:47:31 INFO - 'PAGER': '/bin/cat', 01:47:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:31 INFO - 'PWD': '/builds/slave/test', 01:47:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:31 INFO - 'SHELL': '/bin/bash', 01:47:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:31 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:31 INFO - 'USER': 'cltbld', 01:47:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:31 INFO - 'XPC_FLAGS': '0x0', 01:47:31 INFO - 'XPC_SERVICE_NAME': '0', 01:47:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-NtiSoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-aRnhiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Fgc1S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZgJ7Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-pVegIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Lu3O6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:47:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ajeDDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-M56tJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-kacLyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-n2Bn2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xFOMOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-w0rZhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-bLiP3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Mhj3GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-P5Lvwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:47:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-gm425r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:47:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EzCQhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:47:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:47:33 INFO - Running setup.py install for manifestparser 01:47:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:47:33 INFO - Running setup.py install for mozcrash 01:47:33 INFO - Running setup.py install for mozdebug 01:47:33 INFO - Running setup.py install for mozdevice 01:47:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:47:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:47:33 INFO - Running setup.py install for mozfile 01:47:33 INFO - Running setup.py install for mozhttpd 01:47:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Running setup.py install for mozinfo 01:47:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Running setup.py install for mozInstall 01:47:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Running setup.py install for mozleak 01:47:34 INFO - Running setup.py install for mozlog 01:47:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Running setup.py install for moznetwork 01:47:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:47:34 INFO - Running setup.py install for mozprocess 01:47:34 INFO - Running setup.py install for mozprofile 01:47:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:47:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:47:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:47:35 INFO - Running setup.py install for mozrunner 01:47:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:47:35 INFO - Running setup.py install for mozscreenshot 01:47:35 INFO - Running setup.py install for moztest 01:47:35 INFO - Running setup.py install for mozversion 01:47:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:47:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:47:35 INFO - Cleaning up... 01:47:35 INFO - Return code: 0 01:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:35 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:35 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 01:47:35 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 01:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:35 INFO - 'HOME': '/Users/cltbld', 01:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:35 INFO - 'LOGNAME': 'cltbld', 01:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:35 INFO - 'MOZ_NO_REMOTE': '1', 01:47:35 INFO - 'NO_EM_RESTART': '1', 01:47:35 INFO - 'PAGER': '/bin/cat', 01:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:35 INFO - 'PWD': '/builds/slave/test', 01:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:35 INFO - 'SHELL': '/bin/bash', 01:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:35 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:35 INFO - 'USER': 'cltbld', 01:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:35 INFO - 'XPC_FLAGS': '0x0', 01:47:35 INFO - 'XPC_SERVICE_NAME': '0', 01:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Bx73uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-mVqw_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-c8lq7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xU0X3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-0FfgcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-7hDImy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Mksst0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-z564TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-3ff6CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:47:36 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)) 01:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:47:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zU4Rqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-smeIHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-0J5Ltu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-GJjnBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-SZWzPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-gl0QWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-QrDkO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:47:37 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)) 01:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:47:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-FPH8AE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:47:37 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)) 01:47:37 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)) 01:47:37 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)) 01:47:37 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)) 01:47:37 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)) 01:47:37 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)) 01:47:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:47: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 01:47: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 01:47: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 01:47: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 01:47: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 01:47: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 01:47:40 INFO - Downloading blessings-1.6.tar.gz 01:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:47:40 INFO - Installing collected packages: blessings 01:47:40 INFO - Running setup.py install for blessings 01:47:40 INFO - Successfully installed blessings 01:47:40 INFO - Cleaning up... 01:47:40 INFO - Return code: 0 01:47:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:47:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:47:40 INFO - Reading from file tmpfile_stdout 01:47:40 INFO - Current package versions: 01:47:40 INFO - blessings == 1.6 01:47:40 INFO - blobuploader == 1.2.4 01:47:40 INFO - docopt == 0.6.1 01:47:40 INFO - functools32 == 3.2.3-2 01:47:40 INFO - jsonschema == 2.5.1 01:47:40 INFO - manifestparser == 1.1 01:47:40 INFO - mozInstall == 1.12 01:47:40 INFO - mozcrash == 0.17 01:47:40 INFO - mozdebug == 0.1 01:47:40 INFO - mozdevice == 0.48 01:47:40 INFO - mozfile == 1.2 01:47:40 INFO - mozhttpd == 0.7 01:47:40 INFO - mozinfo == 0.9 01:47:40 INFO - mozleak == 0.1 01:47:40 INFO - mozlog == 3.4 01:47:40 INFO - moznetwork == 0.27 01:47:40 INFO - mozprocess == 0.23 01:47:40 INFO - mozprofile == 0.28 01:47:40 INFO - mozrunner == 6.12 01:47:40 INFO - mozscreenshot == 0.1 01:47:40 INFO - mozsystemmonitor == 0.3 01:47:40 INFO - moztest == 0.7 01:47:40 INFO - mozversion == 1.4 01:47:40 INFO - psutil == 3.1.1 01:47:40 INFO - requests == 1.2.3 01:47:40 INFO - wsgiref == 0.1.2 01:47:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:47: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')]} 01:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47: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')]} 01:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:40 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 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:40 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 01:47:40 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 01:47:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:40 INFO - 'HOME': '/Users/cltbld', 01:47:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:40 INFO - 'LOGNAME': 'cltbld', 01:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:40 INFO - 'MOZ_NO_REMOTE': '1', 01:47:40 INFO - 'NO_EM_RESTART': '1', 01:47:40 INFO - 'PAGER': '/bin/cat', 01:47:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:40 INFO - 'PWD': '/builds/slave/test', 01:47:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:40 INFO - 'SHELL': '/bin/bash', 01:47:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:40 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:40 INFO - 'USER': 'cltbld', 01:47:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:40 INFO - 'XPC_FLAGS': '0x0', 01:47:40 INFO - 'XPC_SERVICE_NAME': '0', 01:47:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 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)) 01:47:41 INFO - Cleaning up... 01:47:41 INFO - Return code: 0 01:47:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dab0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da26570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf215a3bd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, '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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 01:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:47:41 INFO - 'HOME': '/Users/cltbld', 01:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:47:41 INFO - 'LOGNAME': 'cltbld', 01:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:47:41 INFO - 'MOZ_NO_REMOTE': '1', 01:47:41 INFO - 'NO_EM_RESTART': '1', 01:47:41 INFO - 'PAGER': '/bin/cat', 01:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:47:41 INFO - 'PWD': '/builds/slave/test', 01:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:47:41 INFO - 'SHELL': '/bin/bash', 01:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:47:41 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:47:41 INFO - 'USER': 'cltbld', 01:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:47:41 INFO - 'XPC_FLAGS': '0x0', 01:47:41 INFO - 'XPC_SERVICE_NAME': '0', 01:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:47:41 INFO - Cleaning up... 01:47:41 INFO - Return code: 0 01:47:41 INFO - Running post-action listener: _resource_record_post_action 01:47:41 INFO - Running post-action listener: _start_resource_monitoring 01:47:41 INFO - Starting resource monitoring. 01:47:41 INFO - [mozharness: 2016-09-30 08:47:41.489333Z] Finished create-virtualenv step (success) 01:47:41 INFO - [mozharness: 2016-09-30 08:47:41.490352Z] Running install step. 01:47:41 INFO - Running pre-action listener: _resource_record_pre_action 01:47:41 INFO - Running main action method: install 01:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:47:41 INFO - Reading from file tmpfile_stdout 01:47:41 INFO - Detecting whether we're running mozinstall >=1.0... 01:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:47:41 INFO - Reading from file tmpfile_stdout 01:47:41 INFO - Output received: 01:47:41 INFO - Usage: mozinstall [options] installer 01:47:41 INFO - Options: 01:47:41 INFO - -h, --help show this help message and exit 01:47:41 INFO - -d DEST, --destination=DEST 01:47:41 INFO - Directory to install application into. [default: 01:47:41 INFO - "/builds/slave/test"] 01:47:41 INFO - --app=APP Application being installed. [default: firefox] 01:47:41 INFO - mkdir: /builds/slave/test/build/application 01:47:41 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'] 01:47:41 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 01:48:16 INFO - Reading from file tmpfile_stdout 01:48:16 INFO - Output received: 01:48:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:48:16 INFO - Running post-action listener: _resource_record_post_action 01:48:16 INFO - [mozharness: 2016-09-30 08:48:16.039850Z] Finished install step (success) 01:48:16 INFO - [mozharness: 2016-09-30 08:48:16.039985Z] Running run-tests step. 01:48:16 INFO - Running pre-action listener: _resource_record_pre_action 01:48:16 INFO - Running main action method: run_tests 01:48:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:48:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:48:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:48:16 INFO - 2016-09-30 01:48:16.054 screenresolution[2372:31442] starting screenresolution argv=screenresolution get 01:48:16 INFO - 2016-09-30 01:48:16.057 screenresolution[2372:31442] Display 0: 1600x1200x32@60 01:48:16 INFO - 2016-09-30 01:48:16.063 screenresolution[2373:31445] starting screenresolution argv=screenresolution list 01:48:16 INFO - Available Modes on Display 0 01:48:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:48:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:48:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:48:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:48:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:48:16 INFO - Intel Iris: 01:48:16 INFO - Chipset Model: Intel Iris 01:48:16 INFO - Type: GPU 01:48:16 INFO - Bus: Built-In 01:48:16 INFO - VRAM (Dynamic, Max): 1536 MB 01:48:16 INFO - Vendor: Intel (0x8086) 01:48:16 INFO - Device ID: 0x0a2e 01:48:16 INFO - Revision ID: 0x0009 01:48:16 INFO - Displays: 01:48:16 INFO - Display: 01:48:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:48:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:48:16 INFO - Display Serial Number: 1600x1200 60 01:48:16 INFO - Main Display: Yes 01:48:16 INFO - Mirror: Off 01:48:16 INFO - Online: Yes 01:48:16 INFO - Rotation: Supported 01:48:16 INFO - Return code: 0 01:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:48:16 INFO - Python 2.7.3 01:48:16 INFO - Return code: 0 01:48:16 INFO - grabbing minidump binary from tooltool 01:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:48:16 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 0x10d8c4d30>, '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 0x10d6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:48:16 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 01:48:16 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 01:48:16 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 01:48:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:48:16 INFO - Return code: 0 01:48:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:48:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:48:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:48:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:48:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 01:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 01:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1RkBRFb3rS/Render', 01:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:48:16 INFO - 'HOME': '/Users/cltbld', 01:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:48:16 INFO - 'LOGNAME': 'cltbld', 01:48:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:48:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:48:16 INFO - 'MOZ_NO_REMOTE': '1', 01:48:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:48:16 INFO - 'NO_EM_RESTART': '1', 01:48:16 INFO - 'PAGER': '/bin/cat', 01:48:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:48:16 INFO - 'PWD': '/builds/slave/test', 01:48:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:48:16 INFO - 'SHELL': '/bin/bash', 01:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qqfLvXD6Bz/Listeners', 01:48:16 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 01:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:48:16 INFO - 'USER': 'cltbld', 01:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:48:16 INFO - 'XPC_FLAGS': '0x0', 01:48:16 INFO - 'XPC_SERVICE_NAME': '0', 01:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:48:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 01:48:16 INFO - mozversion application_buildid: 20160929231419 01:48:16 INFO - mozversion application_changeset: 55741185923649a1628de065bb1ec04dc37ccbd5 01:48:16 INFO - mozversion application_display_name: Nightly 01:48:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:48:16 INFO - mozversion application_name: Firefox 01:48:16 INFO - mozversion application_remotingname: firefox 01:48:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:48:16 INFO - mozversion application_vendor: Mozilla 01:48:16 INFO - mozversion application_version: 52.0a1 01:48:16 INFO - mozversion platform_buildid: 20160929231419 01:48:16 INFO - mozversion platform_changeset: 55741185923649a1628de065bb1ec04dc37ccbd5 01:48:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:48:16 INFO - mozversion platform_version: 52.0a1 01:48:16 INFO - using testdate: 1475225296 01:48:16 INFO - actual date: 1475225296 01:48:16 INFO - starting webserver on 'localhost:50885' 01:48:16 INFO - SUITE-START | Running 2 tests 01:48:16 INFO - TEST-START | dromaeo_css 01:48:16 INFO - operating with platform_type : mac_ 01:48:16 INFO - Initialising browser for dromaeo_css test... 01:48:16 INFO - TEST-INFO | started process 2383 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpJTTy_0/profile http://localhost:50885/getInfo.html) 01:48:18 INFO - PROCESS | 2383 | __metrics Screen width/height:1600/1200 01:48:18 INFO - PROCESS | 2383 | colorDepth:24 01:48:18 INFO - PROCESS | 2383 | Browser inner width/height: 1024/689 01:48:18 INFO - PROCESS | 2383 | __metrics 01:48:19 INFO - PROCESS | 2383 | 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" 01:48:19 INFO - PROCESS | 2383 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:48:19 INFO - TEST-INFO | 2383: exit 0 01:48:19 INFO - Browser initialized. 01:48:19 INFO - Running cycle 1/1 for dromaeo_css test... 01:48:19 INFO - TEST-INFO | started process 2385 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpJTTy_0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:48:31 INFO - PROCESS | 2385 | RSS: Main: 236249088 01:48:31 INFO - PROCESS | 2385 | 01:49:23 INFO - PROCESS | 2385 | 1475225363392 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] 01:51:34 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50885/tests/dromaeo/cssquery-ext.html) 01:51:36 INFO - PROCESS | 2385 | RSS: Main: 360128512 01:51:36 INFO - PROCESS | 2385 | 01:54:39 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-ext.html (next: http://localhost:50885/tests/dromaeo/cssquery-jquery.html) 01:54:39 INFO - PROCESS | 2385 | RSS: Main: 368668672 01:54:39 INFO - PROCESS | 2385 | 01:57:41 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50885/tests/dromaeo/cssquery-mootools.html) 01:57:42 INFO - PROCESS | 2385 | RSS: Main: 336343040 01:57:42 INFO - PROCESS | 2385 | 02:00:44 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50885/tests/dromaeo/cssquery-prototype.html) 02:00:45 INFO - PROCESS | 2385 | RSS: Main: 346427392 02:00:45 INFO - PROCESS | 2385 | 02:03:48 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50885/tests/dromaeo/cssquery-yui.html) 02:03:49 INFO - PROCESS | 2385 | RSS: Main: 330964992 02:03:49 INFO - PROCESS | 2385 | 02:06:52 INFO - PROCESS | 2385 | Cycle 1(1): loaded http://localhost:50885/tests/dromaeo/cssquery-yui.html (next: http://localhost:50885/tests/dromaeo/cssquery-dojo.html) 02:06:53 INFO - PROCESS | 2385 | RSS: Main: 375345152 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | __start_tp_report 02:06:53 INFO - PROCESS | 2385 | _x_x_mozilla_page_load 02:06:53 INFO - PROCESS | 2385 | _x_x_mozilla_page_load_details 02:06:53 INFO - PROCESS | 2385 | |i|pagename|runs| 02:06:53 INFO - PROCESS | 2385 | |0;dojo.html;2881.971180288197;3319.1536158279637;3334.4998250262456;3367.0740546349766;3381.08710648125;5576.776928922848;5636.549076073915;5692.231548740919;5709.600327977038;5713.828585142442;5678.233438485803;5683.829485115442;5766.625169363989;5773.076307790754;5807.39022402648;5046.949530504685;5192.247124167013;5208.140656791625;5219.269302297681;5255.0803609368395;5230.607704422165;5301.602379821529;5354.892902141974;5374.6506477078865;5392.838214853561;5256.237845512417;5288.65623734456;5296.126139187029;5319.175527793179;5356.062689029423;5074.13739664258;5124.103281925648;5177.223416487519;5178.326817513699;5235.162374020177;5072.264521644341;5087.592992560586;5095.0574143783515;5148.871278218037;5153.252778347156;5537;5544.500994910437;5552.500274975231;5563;5598.1322894951345;6092.634441933498;6108.236470441195;6120.357362476921;6126.540509461764;6145.678679084019;5574.888502229933;5851.473367396912;6192.566520343577;6243.843903902393;6301.180846489973;5762.9135562966585;5818.21454103699;5830.650160990354;5830.941690583039;5878.030125029408;6200.100985357098;6264.686765661699;6274.435300822948;6281.7173227204885;6286.9056964145575;6038.788642397495;6090.939090609126;6118.174046503754;6133.84665383369;6158.692065396713;6228.096925945803;6262.812115636538;6283.2460104787715;6285.937140628626;6287.622742635456;6183.288921774054;6195.783147589813;6199.1011303361665;6229.781957631461;6237.500999919996;5961.642301461926;6082.847928801727;6089.147519347295;6185.969070154621;6269.686515674198;4869.70781753096;4869.780859861244;4905.828296009694;4926.261060840902;4956.132676781549;4409.206342858316;4436.511983681792;4471.686981911235;4478.932816007762;4482.574155455227;5777.19119323295;5785.50823180026;5801.53587712982;5820.272465941757;5827.3007239131575;6180.969095154495;6247.250329960434;6291.245050593867;6297.905531417033;6316.178896743394;5730.828075157752;5818.854528636733;5829.358770535237;5848.005839007379;5905.966455870205;5402.432744561843;5407.729613519308;5409.648372855751;5418.295621569171;5424.620276580601;6179.938200618116;6280.654563998933;6311.747530098744;6323;6343.746250150035;5794.420557944172;6173.629582225081;6196.845078872884;6210.037444196157;6293.874122517615;6279.497640188865;6283.937160628335;6288.119663247057;6296.779612713533;6304.905426418699;6209.968950155401;6220.502359811114;6233.657148856856;6241.157443745036;6241.594296370721;6102.328743838263;6179.81460556184;6214.067889816563;6226.1905952225925;6305.1172835803945;6099.8545999513535;6105.694715264308;6142.293636231891;6169.938300616936;6180.134781130555;5465.863353416196;6139.478144357601;6165.198524191826;6168.414000669929;6172.938270617236;5741.425857414225;5837.357890632112;5858.2677165354335;5917.881642367042;5945.821625351246;6103.186149163709;6122.489639783254;6127.281156068254;6133.548895741652;6138.25566404484;5788.768449261982;5918.260217472816;5923.881522369615;5953.255843019622;6005.219321488178 02:06:53 INFO - PROCESS | 2385 | |1;ext.html;450.71379673907063;516.7416291854071;518.3106468397033;530.2735252703797;540.5675459632293;30699.69300306999;38952;40381.78854634358;40408.59591404089;40414.98962525938;41075.58924410755;41443;41593.79203103988;41617.37573936386;41642.95892602686;9788.461634610094;9962.252831037702;9969.50152492376;9973.900260997407;9985.650502232424;8313.750587482355;9624.326297159201;9727.978562250968;9745.853812192823;9757.219422446222;9742.902570974271;9795.461249631267;9806.9509652452;9824.557894894748;9888.406695598287;9699.321047526675;9757.316987810857;9767.267454940873;9792.363496372713;9795.755106122404;9734.90265097347;9798.75503112428;9807.019298070207;9807.754806129904;9814.90185098147;39354.60645393538;39688.20623587512;39723.60276397228;39725.40411893824;39742.006449838984;3270.394976929276;3301.3727391795483;3330.0509354833757;3352.1619595101224;3759.793211373373;29844;30040.399192016255;30708.00795896519;30747;30830;3221.951670724941;3273.3780581689402;3274.721648659867;3280.78674886134;3362.4788157835574;466845;467869;467902;470073;479192;60661;61160;61208.08187877185;61259.77480450366;61300.38699613036;34418.48372274418;35058.64941350605;35237.47143792845;62368.68815655893;62722;15498.295187529362;16322.67354652924;16476.6704665906;16479.176041197894;16486.68106551473;10682.465876706134;12708.555200567936;12712.872871271355;12728.10903236783;12740.171888827197;14980.775288370683;15056.698866022838;18699;18703.15835787393;18728.906355468134;6573.704183311763;7634.824075138559;7764.456488045896;7805.726799561988;7825.765227043198;838.2120806441925;889.7775556110972;931.6925414613162;938.591712605019;1041.1618646989095;27390.726092739216;27643.308917276827;28962.71037289642;29672.104102822792;30173.245668858017;1013.8681971343678;1029.3750935115427;1048.826943554305;1052.3790963331671;1131.7000994736402;15581.29884155193;15595.766063509056;15735.370585176694;15748.76376854348;16057.839421605868;663440;668494;668898;669590;670275;57454.138187927216;57939.42060579424;58052;58091;58131.128033079534;1210.3645586067385;1250.9749805003848;1333.9999999999807;1342.315419136228;1352.8985326100583;29729.85135074311;31322.21694457657;31363.529547056354;31369.215769605944;31827.840860795546;49570.752146239036;50155.49844501508;50258;50322;50441;528100;528996;532072;532207;533322;23624.29127126189;24171.879140604888;26248.081317153807;26680.066197682987;27060.45879082447;46785.2982205274;47039.76480117578;47192.29211561898;47523.524764751906;48387.75806120947;37458.78848063555;37800;38500.42249366318;38913.2217355657;39497.407538886364;37695.05762355963;38322;38461.4230786544;38686;39896.80309590717;37571.436428454144;38271.42592861054;38408.615913840506;38713.80643096766;38755.031124220994;32339.029829105166;34173.65826341705;34439.31121377608;34765;34770.82614586911;25137.622935656345;25455.490890182464;25576.976920922956;25837.612435813102;25859.224223273333 02:06:53 INFO - PROCESS | 2385 | |2;jquery.html;324.508369819723;351.4219109564768;364.8212375935792;365.6471504997677;401.821189570641;15353.692926141484;15460;16515.421960231408;19376.418707438763;21263.04316305766;19707.113179906937;20389.694154587694;21050.263240786575;21201.787982120175;21255.93720313986;5273.419923808378;5706.115552089433;6118.0822876568645;6158.168647232634;6167.444929956303;5103.974480127613;6666.366695163952;6765.289644587321;6820.829479263009;6826.692798824065;5758.884822303551;6665.270589643094;6683.365080317386;6737.562058466208;6748.493862960273;6476.89892718239;6654.966725166391;6660.367265109807;6664.8000559983275;6669.666516674195;5518.144687573433;5735.713214339308;5796.88406231873;5806.593538452268;5821.476067153934;25415.61876571853;26118.216453506426;26183.869080654476;26349;26417.603735943976;2167.295265331745;2220.9444763881033;2442.0842184180933;2462.4582591829767;3056.052623686642;6862.588244705334;6862.862742745167;7317.594752259123;15928.293732189499;15983;1831.359024341483;1881.8212269834344;1898.1837809741805;1907.675695131831;1960.11794692389;92514;116372.83627163705;119574.20638690426;120205;120481.39759301147;10352.637657681946;10578.418186999636;10670.51982660782;10882.347059176474;11331.093512518979;13494.19034857892;13510.527131550349;13537;13541.322933853267;13599.912007039413;8274.669013239403;8503.787405314917;8541.444806087584;8702.695405660772;8711.956440217886;8562.400631955703;8658.177473140042;8780.868286975572;8857.424267422597;8915.01934787186;6456.935430645727;6603.372679595431;6607.0509199786175;6608.537402381787;6621.602703837785;1810.8370246677598;1830.5789668376349;1832.7250912363252;1838.531174550481;1862.050354319307;878.3982971664431;922.3727865051695;966.2656381150376;969.6703120938914;976.321456587679;19379.51551211203;21399.8930005349;22044.078204228514;22177.44556386071;22309.996050177782;918.9540522973824;920.894097178833;928.6656803550717;954.5275088831073;968.6270785747397;10277.640282590073;10859.457027148612;11819.822702659467;11950.940245298892;11959;493197;498108;503512;561214;590306;91046;140152;141854;144397.5560244405;144417.2065894596;969.9612015519441;975.2344409638541;1021.8007488539663;1022.9897701023043;1023.4371095897227;19422.805771942665;19881.60236795227;19924;20007.59984800325;20121.99390030522;40079.59920400759;48909;57482.31824656919;58665;59725.10412343905;70262;85951.8512037204;86925;88172;90368;7968.880466793119;7979.760607181794;8129.634166462405;8465.238128568459;8503.829923401621;12041.81937270924;13094.410751516014;13218.867811322149;13271.535496257584;13522.594322170351;11241.437928103725;11355.943220283845;11464;11557.82663260035;11758.647240582795;9944.45305508204;10233.385996840214;10896.400697961688;11071.667849964513;11164.776704465703;11419;11480.540778369106;11544.59593914209;11608.593699220824;11746.70633234176;7928.722494712657;8088.9595552021865;8096.230858068474;8231.917680823115;8440.324774017941;11200.103991680484;11319.490622922152;11448.599299024494;11466.484008219484;11490.712732181428 02:06:53 INFO - PROCESS | 2385 | |3;mootools.html;1344.200200880477;1497.3860717105986;1514.3109885002325;1701.4470297153428;1869.5513076861548;7864.921350786491;8649.394542382028;8935.597898094586;8952.328575356858;9028.548572571362;8786.780330491725;8878.511681857495;8889.555522223895;8896.510691911943;8897.065808090138;2024.8481363897693;2204.1293688992837;2287.3938406322254;2396.2212281008697;2418.903243870252;2376.607859703147;2392.377981724747;2402.1112188490197;2413.3966508372905;2431.55016321981;2387.3076807725824;2405.1702162753822;2422.1643532981093;2436.6954130733657;2443.9979608360486;2342.5197834443898;2364.6689463475127;2367.7750613691674;2368.5854975379325;2377.322463098009;2035.918563257468;2365.9171928982573;2403.651470536762;2405.542946840095;2413.5776239158076;8754.912450875474;8959;8971;8974.910250897472;9013.459192448474;5187.221916712486;5408.0535906216255;5408.13469844823;5426.131818908955;5435.0488664483955;3523.224890524081;3662.688671462902;3726.4037753959237;3729.5151630287887;3752.3433399155037;918.5361392496814;1086.5056399338282;1090.1987039525943;1110.3726394587034;1110.5391262626001;11674.241174323643;11719.238249513754;11811;11815.88184118165;11829.171957962946;4393.428854248928;4465.285554311295;4652.209124448852;4653.464851542049;4653.511381304914;9197.218236449977;9216.861747073799;9219.677311294072;9240.445573265626;9264.351495395258;5284.39229488606;5290.047791397508;5291.947080529146;5296.2055691646565;5296.708681022581;4464.196444639931;4507.121111383314;4625.7687115644085;4793.760311984387;4972.005598880239;3732.234891847165;3789.7157713171623;3835.6356146166067;3845.3270677631303;3906.7655940643654;1095.6877289972479;1105.7677887643686;1116.6929094498914;1117.446863802423;1120.137494129532;871.5990644303691;884.6151923913119;886.4326830828275;888.8933328000682;907.9409838360485;5638.379778224392;5768.567357448124;5921.171036054956;6001.279846418458;6028.186194863725;920.6823645842173;922.409657818983;939.1876027236391;956.0487315121499;976.1995163965622;3172.7779055465894;3420.931581368359;3686.5023221864703;3915.960840391617;3957.782321972319;9989.900100999042;10731.731706707238;10869;10888.183386246063;10934.945325273322;10312.793744125076;10837.729056773644;10862.34825910448;10883.292585981886;10914.508847101899;7907.169747176578;8302.667893284322;8310.626021829032;8311.584420778936;8319.792005199919;5714.685692286964;5786.623869448473;5840.948629246606;5898.174255604305;5973.970130149321;9988.200943924614;9995.650152244927;10005.349652272578;10013.24900632441;10017.799644007226;9506.382085164441;10419.114375278186;10423.270371073952;10432.634857779942;10433.739156521147;6546.934530654633;6994.6852391643515;7215.783526494213;7553.7356192533;7694.07671079474;7889;7939.28546430824;8016.198380161937;8026;8408.327333813158;7222.855542889218;7448.888266676113;7480.775576732707;7527.473076884566;7610.010698609367;7151.856962860609;7280.708771649075;7378.815529611803;7411.703531858665;7458.813529661802;6990.231074581894;6997.440204783503;7093.006979023045;7093.255208203271;7098.716051357887;4427.645788336989;4818.229083346648;4974.338728780085;5035.521625445577;5122.615803814654;4971.1797553403285;5096.286519887145;5105.846824595268;5131.846044618667;5180.948190518046 02:06:53 INFO - PROCESS | 2385 | |4;prototype.html;268.20477284850415;291.26746233223435;300.2014641054794;309.84662592016946;315.42907337718714;5056.367954005749;5195.662281951671;5320.4147543770205;5398.13629819229;5403.243545903571;4823.42118945727;5137.280780690697;5218.556422704075;5219.817306394277;5266.894662106755;3870.4774855394585;4053.047533829548;4059.6752259819145;4062.593740625943;4115.650169735569;3425.6060553036473;3461.2558299965503;3840.8669442514524;4106.445629839978;4143.109231515224;3780.225053863965;3808.9809550952205;3889.3971434427567;4063.349864021757;4079.7153593987314;3333.7166340861054;4015.8795236142814;4035.57626449223;4040.9797951010055;4053.4122552230056;2992.835394053326;3537.734669899742;3544.5923718772156;3570.9642903570893;3573.160307327776;4196.538380778112;4899.510048995107;5071.3407257056715;5216;5221.817236396708;3799.67702745267;4546.749928753918;4558.772061396917;4558.954410455919;4615.169269531483;4990.85210401606;5572.832815015556;5573.359063707685;5593.04918163909;5598.992181407345;3208.96791032089;3380.2084895005687;3648.1244501319757;3781.6785573226307;3816.484774555427;5471;5758.309002919635;5764.798232061858;5817.534597232211;5827.941720582782;3388.085731561709;3844.6732027777953;3845.403962385835;3846.1346197105986;3862.729608927404;3144.6540880503126;3754.605766394544;3909.6872250219913;3923.705177291487;3924.645997130985;3425.8972230833115;3983.900402489961;4567.748773817472;4585.5784706741015;4588.95411045892;5038.47096054909;5603.495685388335;5603.607747457639;5616.522595579421;5635.971820140873;1247.7067889046066;1255.1465003797323;1257.7673130470891;1296.8508621508458;1304.315234501894;688.7727050073464;883.337496877342;898.276887105873;901.2924853989587;909.2271569166156;4329.004329004284;4433.5590932946925;4673.854905548094;5210.47895210476;5290.444503327172;4428.468583769968;4782.545658162469;5498.202760599771;5686.004949133885;5698.7177884975545;4378.868633940987;5132.666376685504;5181.715005674649;5203.349581302337;5227.817026404057;2520.5715028445206;2553.438243586408;2971.524556071018;2999.355138645202;3005.609270794783;5598.776048958078;5794;5830.679312637761;5884.882302353929;5894.379045762364;5321.6540924839765;5433.891322173535;5783.797567085132;5818.359980402152;5864.797716468114;4990.90018199634;5027.79888804451;5418.45815418455;5804.622699524602;5921.851953701106;4578.40480737502;4624.861254162381;5664.688442135722;5680.630759000817;5687.8862422752145;5175.378954525481;5463.53559947393;5610.485169004428;5634.408387119293;5673.347565030074;4959.975200123977;5538.289346138016;5796.304443466811;5827.650340979555;5941.554383421347;3314.9171270718425;3412.692857642725;3845.7307988441657;3869;3879.825407856597;5675.631083979528;5760.395158508464;5764.798232061858;5777.7977770777825;5832;4245.61789438952;4319.790458671577;4690.9765451172525;4996.250562415521;5525.585581081355;4555.840545580889;4876;4938.358013458371;5519.586031047607;5579.442055794388;4670.766461676971;5076.1725778720365;5199.206273835521;5339.866503337447;5344.652597581145;5142.0095588638815;5593.7693707384315;5631.267935168402;5636.464535869086;5657.660540367591;4598.540145985375;5103.7448127592725;5429.592780541396;5433.619646624698;5482.890342193214 02:06:53 INFO - PROCESS | 2385 | |5;yui.html;629.4586655476292;718.0880282041807;742.2874040920716;769.8729709597917;828.9886338666827;13198.604041878747;14878.884083693712;15571.610709732247;16154.111523866182;16596.25316860741;12532.624021279353;14474.131552106881;14604.926975365135;14629.122252664847;14658.193799341032;835.8996920369564;845.4335595151264;846.2383854530941;850.4854562989365;850.808568072185;694.6873906741986;707.9610621415845;717.6806321187074;845.3913182508614;857.7212405968054;900.2437952120217;913.0823522360018;913.4291068082448;914.1498406481969;917.7338571814166;906.1799071839929;908.4322298563397;909.6497848328376;909.6816114359988;911.726482055384;526.0215998243265;537.4437457231785;671.6272468779825;674.0630523572238;677.420805211542;12445.377731113407;12625.368731563476;12635.873641263654;12724.427400766896;14980.47568335114;11960.34218118003;13544.45822167112;14215.502457414044;14289.499867504692;14673.339699713431;11800.987664194216;11845.94077029618;11886.58396956111;11937.701557461047;11949.94025029878;3887.688984881203;3899.98050009751;3997.020729921159;4028.4561584186345;4080.3063479208604;109874.90125098727;111229;112648.31027534594;113267;113543;11380.975712185944;11422;11520.827187592193;11529.827052594217;11986.940065299617;23634.64548031781;23685.28944131679;23722;23730.762692372857;23738;6607.306232845578;7873.448858579959;7969.3226075784205;8354.415190936576;8437.789055273668;4595.655325850575;4599.126166028444;4609.723416595015;4711.599514041345;4798.944232268896;3607.9278414431565;3773.603771603942;3776.490173826553;3778.4899038629433;3779.4897688811657;671.425930829134;691.2741621297617;707.1125737199782;708.2174197511675;711.4592909388798;541.0747621567085;591.3317950715664;660.51782198995;674.7638326585655;680.884249677556;9135.817283654289;9151.908480915106;9565.508589364832;9573.712788616353;9692.17616502591;530.3715097609345;623.7224435126325;664.6264947896312;702.8348338140532;709.1950636028042;4376.071774734747;4470.530594287618;4666.533346665306;4695.366125573072;4713.764311784397;104382;128547.35726321308;132663;139053.6094639061;161712;118959;121268;121756;123732;127831;76021;100703;103201;109956;111496;7995.600219989094;9712.368696034735;9817.950910245403;9853.802923941337;10110.595576176871;57709.13436298375;59319.11021334597;60053.69973150106;61013.69493152506;64268.03597946128;82681.58659206665;85583.57208213919;86682;89054.55472722594;94411.52794235985;13278.336083195609;13509.86490135086;13558;13614.18314901109;13929.582112536234;14984.925075374553;15226.923865380602;15282.235888205767;15955.20223988819;16004;13880.236586987408;14033.508827190999;14101.788473172706;14193.219372934587;14513;13908;14028.438862445388;14170.291485425894;14602.707945841235;14906.850931490546;13553;13657.453701852211;13671.539122957964;13707.246101464516;14111.123853782763;11335.149863760085;11531.094050859037;11742.471588778355;11796.578605885921;12034.217775844541;9479.241660667269;11502.149331374521;11540.192186546861;11650.53397864076;11718.355490448108 02:06:53 INFO - PROCESS | 2385 | __end_tp_report 02:06:53 INFO - PROCESS | 2385 | __start_cc_report 02:06:53 INFO - PROCESS | 2385 | _x_x_mozilla_cycle_collect,1164 02:06:53 INFO - PROCESS | 2385 | __end_cc_report 02:06:53 INFO - PROCESS | 2385 | __startTimestamp1475226413009__endTimestamp 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | ------- Summary: start ------- 02:06:53 INFO - PROCESS | 2385 | Number of tests: 6 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#0] dojo.html Cycles:165 Average:5731.35 Median:5905.97 stddev:643.06 (10.9%) stddev-sans-first:604.93 02:06:53 INFO - PROCESS | 2385 | Values: 2882.0 3319.2 3334.5 3367.1 3381.1 5576.8 5636.5 5692.2 5709.6 5713.8 5678.2 5683.8 5766.6 5773.1 5807.4 5046.9 5192.2 5208.1 5219.3 5255.1 5230.6 5301.6 5354.9 5374.7 5392.8 5256.2 5288.7 5296.1 5319.2 5356.1 5074.1 5124.1 5177.2 5178.3 5235.2 5072.3 5087.6 5095.1 5148.9 5153.3 5537.0 5544.5 5552.5 5563.0 5598.1 6092.6 6108.2 6120.4 6126.5 6145.7 5574.9 5851.5 6192.6 6243.8 6301.2 5762.9 5818.2 5830.7 5830.9 5878.0 6200.1 6264.7 6274.4 6281.7 6286.9 6038.8 6090.9 6118.2 6133.8 6158.7 6228.1 6262.8 6283.2 6285.9 6287.6 6183.3 6195.8 6199.1 6229.8 6237.5 5961.6 6082.8 6089.1 6186.0 6269.7 4869.7 4869.8 4905.8 4926.3 4956.1 4409.2 4436.5 4471.7 4478.9 4482.6 5777.2 5785.5 5801.5 5820.3 5827.3 6181.0 6247.3 6291.2 6297.9 6316.2 5730.8 5818.9 5829.4 5848.0 5906.0 5402.4 5407.7 5409.6 5418.3 5424.6 6179.9 6280.7 6311.7 6323.0 6343.7 5794.4 6173.6 6196.8 6210.0 6293.9 6279.5 6283.9 6288.1 6296.8 6304.9 6210.0 6220.5 6233.7 6241.2 6241.6 6102.3 6179.8 6214.1 6226.2 6305.1 6099.9 6105.7 6142.3 6169.9 6180.1 5465.9 6139.5 6165.2 6168.4 6172.9 5741.4 5837.4 5858.3 5917.9 5945.8 6103.2 6122.5 6127.3 6133.5 6138.3 5788.8 5918.3 5923.9 5953.3 6005.2 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#1] ext.html Cycles:180 Average:68625.51 Median:39592.81 stddev:150429.77 (379.9%) stddev-sans-first:150764.68 02:06:53 INFO - PROCESS | 2385 | Values: 450.7 516.7 518.3 530.3 540.6 30699.7 38952.0 40381.8 40408.6 40415.0 41075.6 41443.0 41593.8 41617.4 41643.0 9788.5 9962.3 9969.5 9973.9 9985.7 8313.8 9624.3 9728.0 9745.9 9757.2 9742.9 9795.5 9807.0 9824.6 9888.4 9699.3 9757.3 9767.3 9792.4 9795.8 9734.9 9798.8 9807.0 9807.8 9814.9 39354.6 39688.2 39723.6 39725.4 39742.0 3270.4 3301.4 3330.1 3352.2 3759.8 29844.0 30040.4 30708.0 30747.0 30830.0 3222.0 3273.4 3274.7 3280.8 3362.5 466845.0 467869.0 467902.0 470073.0 479192.0 60661.0 61160.0 61208.1 61259.8 61300.4 34418.5 35058.6 35237.5 62368.7 62722.0 15498.3 16322.7 16476.7 16479.2 16486.7 10682.5 12708.6 12712.9 12728.1 12740.2 14980.8 15056.7 18699.0 18703.2 18728.9 6573.7 7634.8 7764.5 7805.7 7825.8 838.2 889.8 931.7 938.6 1041.2 27390.7 27643.3 28962.7 29672.1 30173.2 1013.9 1029.4 1048.8 1052.4 1131.7 15581.3 15595.8 15735.4 15748.8 16057.8 663440.0 668494.0 668898.0 669590.0 670275.0 57454.1 57939.4 58052.0 58091.0 58131.1 1210.4 1251.0 1334.0 1342.3 1352.9 29729.9 31322.2 31363.5 31369.2 31827.8 49570.8 50155.5 50258.0 50322.0 50441.0 528100.0 528996.0 532072.0 532207.0 533322.0 23624.3 24171.9 26248.1 26680.1 27060.5 46785.3 47039.8 47192.3 47523.5 48387.8 37458.8 37800.0 38500.4 38913.2 39497.4 37695.1 38322.0 38461.4 38686.0 39896.8 37571.4 38271.4 38408.6 38713.8 38755.0 32339.0 34173.7 34439.3 34765.0 34770.8 25137.6 25455.5 25577.0 25837.6 25859.2 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#2] jquery.html Cycles:180 Average:33438.74 Median:14736.83 stddev:89461.42 (607.1%) stddev-sans-first:89677.84 02:06:53 INFO - PROCESS | 2385 | Values: 324.5 351.4 364.8 365.6 401.8 15353.7 15460.0 16515.4 19376.4 21263.0 19707.1 20389.7 21050.3 21201.8 21255.9 5273.4 5706.1 6118.1 6158.2 6167.4 5104.0 6666.4 6765.3 6820.8 6826.7 5758.9 6665.3 6683.4 6737.6 6748.5 6476.9 6655.0 6660.4 6664.8 6669.7 5518.1 5735.7 5796.9 5806.6 5821.5 25415.6 26118.2 26183.9 26349.0 26417.6 2167.3 2220.9 2442.1 2462.5 3056.1 6862.6 6862.9 7317.6 15928.3 15983.0 1831.4 1881.8 1898.2 1907.7 1960.1 92514.0 116372.8 119574.2 120205.0 120481.4 10352.6 10578.4 10670.5 10882.3 11331.1 13494.2 13510.5 13537.0 13541.3 13599.9 8274.7 8503.8 8541.4 8702.7 8712.0 8562.4 8658.2 8780.9 8857.4 8915.0 6456.9 6603.4 6607.1 6608.5 6621.6 1810.8 1830.6 1832.7 1838.5 1862.1 878.4 922.4 966.3 969.7 976.3 19379.5 21399.9 22044.1 22177.4 22310.0 919.0 920.9 928.7 954.5 968.6 10277.6 10859.5 11819.8 11950.9 11959.0 493197.0 498108.0 503512.0 561214.0 590306.0 91046.0 140152.0 141854.0 144397.6 144417.2 970.0 975.2 1021.8 1023.0 1023.4 19422.8 19881.6 19924.0 20007.6 20122.0 40079.6 48909.0 57482.3 58665.0 59725.1 70262.0 85951.9 86925.0 88172.0 90368.0 7968.9 7979.8 8129.6 8465.2 8503.8 12041.8 13094.4 13218.9 13271.5 13522.6 11241.4 11355.9 11464.0 11557.8 11758.6 9944.5 10233.4 10896.4 11071.7 11164.8 11419.0 11480.5 11544.6 11608.6 11746.7 7928.7 8089.0 8096.2 8231.9 8440.3 11200.1 11319.5 11448.6 11466.5 11490.7 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#3] mootools.html Cycles:180 Average:5683.45 Median:5101.07 stddev:3206.39 (62.9%) stddev-sans-first:3198.80 02:06:53 INFO - PROCESS | 2385 | Values: 1344.2 1497.4 1514.3 1701.4 1869.6 7864.9 8649.4 8935.6 8952.3 9028.5 8786.8 8878.5 8889.6 8896.5 8897.1 2024.8 2204.1 2287.4 2396.2 2418.9 2376.6 2392.4 2402.1 2413.4 2431.6 2387.3 2405.2 2422.2 2436.7 2444.0 2342.5 2364.7 2367.8 2368.6 2377.3 2035.9 2365.9 2403.7 2405.5 2413.6 8754.9 8959.0 8971.0 8974.9 9013.5 5187.2 5408.1 5408.1 5426.1 5435.0 3523.2 3662.7 3726.4 3729.5 3752.3 918.5 1086.5 1090.2 1110.4 1110.5 11674.2 11719.2 11811.0 11815.9 11829.2 4393.4 4465.3 4652.2 4653.5 4653.5 9197.2 9216.9 9219.7 9240.4 9264.4 5284.4 5290.0 5291.9 5296.2 5296.7 4464.2 4507.1 4625.8 4793.8 4972.0 3732.2 3789.7 3835.6 3845.3 3906.8 1095.7 1105.8 1116.7 1117.4 1120.1 871.6 884.6 886.4 888.9 907.9 5638.4 5768.6 5921.2 6001.3 6028.2 920.7 922.4 939.2 956.0 976.2 3172.8 3420.9 3686.5 3916.0 3957.8 9989.9 10731.7 10869.0 10888.2 10934.9 10312.8 10837.7 10862.3 10883.3 10914.5 7907.2 8302.7 8310.6 8311.6 8319.8 5714.7 5786.6 5840.9 5898.2 5974.0 9988.2 9995.7 10005.3 10013.2 10017.8 9506.4 10419.1 10423.3 10432.6 10433.7 6546.9 6994.7 7215.8 7553.7 7694.1 7889.0 7939.3 8016.2 8026.0 8408.3 7222.9 7448.9 7480.8 7527.5 7610.0 7151.9 7280.7 7378.8 7411.7 7458.8 6990.2 6997.4 7093.0 7093.3 7098.7 4427.6 4818.2 4974.3 5035.5 5122.6 4971.2 5096.3 5105.8 5131.8 5180.9 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#4] prototype.html Cycles:180 Average:4429.90 Median:4990.88 stddev:1382.74 (27.7%) stddev-sans-first:1350.88 02:06:53 INFO - PROCESS | 2385 | Values: 268.2 291.3 300.2 309.8 315.4 5056.4 5195.7 5320.4 5398.1 5403.2 4823.4 5137.3 5218.6 5219.8 5266.9 3870.5 4053.0 4059.7 4062.6 4115.7 3425.6 3461.3 3840.9 4106.4 4143.1 3780.2 3809.0 3889.4 4063.3 4079.7 3333.7 4015.9 4035.6 4041.0 4053.4 2992.8 3537.7 3544.6 3571.0 3573.2 4196.5 4899.5 5071.3 5216.0 5221.8 3799.7 4546.7 4558.8 4559.0 4615.2 4990.9 5572.8 5573.4 5593.0 5599.0 3209.0 3380.2 3648.1 3781.7 3816.5 5471.0 5758.3 5764.8 5817.5 5827.9 3388.1 3844.7 3845.4 3846.1 3862.7 3144.7 3754.6 3909.7 3923.7 3924.6 3425.9 3983.9 4567.7 4585.6 4589.0 5038.5 5603.5 5603.6 5616.5 5636.0 1247.7 1255.1 1257.8 1296.9 1304.3 688.8 883.3 898.3 901.3 909.2 4329.0 4433.6 4673.9 5210.5 5290.4 4428.5 4782.5 5498.2 5686.0 5698.7 4378.9 5132.7 5181.7 5203.3 5227.8 2520.6 2553.4 2971.5 2999.4 3005.6 5598.8 5794.0 5830.7 5884.9 5894.4 5321.7 5433.9 5783.8 5818.4 5864.8 4990.9 5027.8 5418.5 5804.6 5921.9 4578.4 4624.9 5664.7 5680.6 5687.9 5175.4 5463.5 5610.5 5634.4 5673.3 4960.0 5538.3 5796.3 5827.7 5941.6 3314.9 3412.7 3845.7 3869.0 3879.8 5675.6 5760.4 5764.8 5777.8 5832.0 4245.6 4319.8 4691.0 4996.3 5525.6 4555.8 4876.0 4938.4 5519.6 5579.4 4670.8 5076.2 5199.2 5339.9 5344.7 5142.0 5593.8 5631.3 5636.5 5657.7 4598.5 5103.7 5429.6 5433.6 5482.9 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - PROCESS | 2385 | [#5] yui.html Cycles:180 Average:24016.65 Median:3775.05 stddev:37294.61 (987.9%) stddev-sans-first:37357.88 02:06:53 INFO - PROCESS | 2385 | Values: 629.5 718.1 742.3 769.9 829.0 13198.6 14878.9 15571.6 16154.1 16596.3 12532.6 14474.1 14604.9 14629.1 14658.2 835.9 845.4 846.2 850.5 850.8 694.7 708.0 717.7 845.4 857.7 900.2 913.1 913.4 914.1 917.7 906.2 908.4 909.6 909.7 911.7 526.0 537.4 671.6 674.1 677.4 12445.4 12625.4 12635.9 12724.4 14980.5 11960.3 13544.5 14215.5 14289.5 14673.3 11801.0 11845.9 11886.6 11937.7 11949.9 3887.7 3900.0 3997.0 4028.5 4080.3 109874.9 111229.0 112648.3 113267.0 113543.0 11381.0 11422.0 11520.8 11529.8 11986.9 23634.6 23685.3 23722.0 23730.8 23738.0 6607.3 7873.4 7969.3 8354.4 8437.8 4595.7 4599.1 4609.7 4711.6 4798.9 3607.9 3773.6 3776.5 3778.5 3779.5 671.4 691.3 707.1 708.2 711.5 541.1 591.3 660.5 674.8 680.9 9135.8 9151.9 9565.5 9573.7 9692.2 530.4 623.7 664.6 702.8 709.2 4376.1 4470.5 4666.5 4695.4 4713.8 104382.0 128547.4 132663.0 139053.6 161712.0 118959.0 121268.0 121756.0 123732.0 127831.0 76021.0 100703.0 103201.0 109956.0 111496.0 7995.6 9712.4 9818.0 9853.8 10110.6 57709.1 59319.1 60053.7 61013.7 64268.0 82681.6 85583.6 86682.0 89054.6 94411.5 13278.3 13509.9 13558.0 13614.2 13929.6 14984.9 15226.9 15282.2 15955.2 16004.0 13880.2 14033.5 14101.8 14193.2 14513.0 13908.0 14028.4 14170.3 14602.7 14906.9 13553.0 13657.5 13671.5 13707.2 14111.1 11335.1 11531.1 11742.5 11796.6 12034.2 9479.2 11502.1 11540.2 11650.5 11718.4 02:06:53 INFO - PROCESS | 2385 | -------- Summary: end -------- 02:06:53 INFO - PROCESS | 2385 | 02:06:53 INFO - TEST-INFO | 2385: exit 0 02:06:53 INFO - TEST-OK | dromaeo_css | took 1117015ms 02:06:53 INFO - TEST-START | kraken 02:06:53 INFO - operating with platform_type : mac_ 02:06:53 INFO - Initialising browser for kraken test... 02:06:53 INFO - TEST-INFO | started process 2388 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpfu9FKR/profile http://localhost:50885/getInfo.html) 02:06:56 INFO - PROCESS | 2388 | __metrics Screen width/height:1600/1200 02:06:56 INFO - PROCESS | 2388 | colorDepth:24 02:06:56 INFO - PROCESS | 2388 | Browser inner width/height: 1024/689 02:06:56 INFO - PROCESS | 2388 | __metrics 02:06:56 INFO - PROCESS | 2388 | 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" 02:06:56 INFO - PROCESS | 2388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:06:56 INFO - TEST-INFO | 2388: exit 0 02:06:56 INFO - Browser initialized. 02:06:56 INFO - Running cycle 1/1 for kraken test... 02:06:56 INFO - TEST-INFO | started process 2389 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpfu9FKR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:07:08 INFO - PROCESS | 2389 | RSS: Main: 236244992 02:07:08 INFO - PROCESS | 2389 | 02:07:11 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50885/tests/kraken/driver.html?testName=audio-beat-detection) 02:07:12 INFO - PROCESS | 2389 | RSS: Main: 296148992 02:07:12 INFO - PROCESS | 2389 | 02:07:15 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50885/tests/kraken/driver.html?testName=audio-dft) 02:07:15 INFO - PROCESS | 2389 | RSS: Main: 295133184 02:07:15 INFO - PROCESS | 2389 | 02:07:19 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50885/tests/kraken/driver.html?testName=audio-fft) 02:07:19 INFO - PROCESS | 2389 | RSS: Main: 385019904 02:07:19 INFO - PROCESS | 2389 | 02:07:21 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50885/tests/kraken/driver.html?testName=audio-oscillator) 02:07:22 INFO - PROCESS | 2389 | RSS: Main: 285323264 02:07:22 INFO - PROCESS | 2389 | 02:07:24 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50885/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:07:25 INFO - PROCESS | 2389 | RSS: Main: 289447936 02:07:25 INFO - PROCESS | 2389 | 02:07:27 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50885/tests/kraken/driver.html?testName=imaging-darkroom) 02:07:28 INFO - PROCESS | 2389 | RSS: Main: 326946816 02:07:28 INFO - PROCESS | 2389 | 02:07:31 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50885/tests/kraken/driver.html?testName=imaging-desaturate) 02:07:31 INFO - PROCESS | 2389 | RSS: Main: 329170944 02:07:31 INFO - PROCESS | 2389 | 02:07:34 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50885/tests/kraken/driver.html?testName=json-parse-financial) 02:07:35 INFO - PROCESS | 2389 | RSS: Main: 326000640 02:07:35 INFO - PROCESS | 2389 | 02:07:36 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50885/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:07:37 INFO - PROCESS | 2389 | RSS: Main: 300101632 02:07:37 INFO - PROCESS | 2389 | 02:07:39 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:07:40 INFO - PROCESS | 2389 | RSS: Main: 306851840 02:07:40 INFO - PROCESS | 2389 | 02:07:42 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:07:42 INFO - PROCESS | 2389 | RSS: Main: 313085952 02:07:42 INFO - PROCESS | 2389 | 02:07:45 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:07:46 INFO - PROCESS | 2389 | RSS: Main: 316174336 02:07:46 INFO - PROCESS | 2389 | 02:07:49 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:07:49 INFO - PROCESS | 2389 | RSS: Main: 317788160 02:07:49 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | Cycle 1(1): loaded http://localhost:50885/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50885/tests/kraken/driver.html?testName=ai-astar) 02:07:51 INFO - PROCESS | 2389 | RSS: Main: 361766912 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | __start_tp_report 02:07:51 INFO - PROCESS | 2389 | _x_x_mozilla_page_load 02:07:51 INFO - PROCESS | 2389 | _x_x_mozilla_page_load_details 02:07:51 INFO - PROCESS | 2389 | |i|pagename|runs| 02:07:51 INFO - PROCESS | 2389 | |0;ai-astar;122;167;147;114;111;112;113;112;113;113 02:07:51 INFO - PROCESS | 2389 | |1;audio-beat-detection;145;108;107;145;110;109;109;105;145;110 02:07:51 INFO - PROCESS | 2389 | |2;audio-dft;183;185;184;183;185;182;179;184;181;183 02:07:51 INFO - PROCESS | 2389 | |3;audio-fft;81;71;58;89;62;58;57;58;81;73 02:07:51 INFO - PROCESS | 2389 | |4;audio-oscillator;83;71;91;71;68;69;91;67;66;90 02:07:51 INFO - PROCESS | 2389 | |5;imaging-gaussian-blur;87;84;87;84;87;84;87;84;87;84 02:07:51 INFO - PROCESS | 2389 | |6;imaging-darkroom;92;93;94;94;92;92;93;93;92;92 02:07:51 INFO - PROCESS | 2389 | |7;imaging-desaturate;86;85;86;85;86;83;83;95;141;144 02:07:51 INFO - PROCESS | 2389 | |8;json-parse-financial;54;53;53;53;54;55;54;54;56;55 02:07:51 INFO - PROCESS | 2389 | |9;json-stringify-tinderbox;65;80;74;75;71;70;72;71;69;71 02:07:51 INFO - PROCESS | 2389 | |10;stanford-crypto-aes;88;85;88;85;86;88;89;89;107;89 02:07:51 INFO - PROCESS | 2389 | |11;stanford-crypto-ccm;147;126;145;149;149;142;136;135;148;151 02:07:51 INFO - PROCESS | 2389 | |12;stanford-crypto-pbkdf2;169;174;168;170;171;166;171;166;169;166 02:07:51 INFO - PROCESS | 2389 | |13;stanford-crypto-sha256-iterative;65;69;68;67;67;67;66;66;65;67 02:07:51 INFO - PROCESS | 2389 | __end_tp_report 02:07:51 INFO - PROCESS | 2389 | __start_cc_report 02:07:51 INFO - PROCESS | 2389 | _x_x_mozilla_cycle_collect,1935 02:07:51 INFO - PROCESS | 2389 | __end_cc_report 02:07:51 INFO - PROCESS | 2389 | __startTimestamp1475226471844__endTimestamp 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | ------- Summary: start ------- 02:07:51 INFO - PROCESS | 2389 | Number of tests: 14 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#0] ai-astar Cycles:10 Average:122.40 Median:113.50 stddev:19.08 (16.8%) stddev-sans-first:20.24 02:07:51 INFO - PROCESS | 2389 | Values: 122.0 167.0 147.0 114.0 111.0 112.0 113.0 112.0 113.0 113.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#1] audio-beat-detection Cycles:10 Average:119.30 Median:110.00 stddev:17.80 (16.2%) stddev-sans-first:16.26 02:07:51 INFO - PROCESS | 2389 | Values: 145.0 108.0 107.0 145.0 110.0 109.0 109.0 105.0 145.0 110.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.50 stddev:1.85 (1.0%) stddev-sans-first:1.96 02:07:51 INFO - PROCESS | 2389 | Values: 183.0 185.0 184.0 183.0 185.0 182.0 179.0 184.0 181.0 183.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#3] audio-fft Cycles:10 Average:68.80 Median:72.00 stddev:11.85 (16.5%) stddev-sans-first:11.72 02:07:51 INFO - PROCESS | 2389 | Values: 81.0 71.0 58.0 89.0 62.0 58.0 57.0 58.0 81.0 73.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:77.00 stddev:10.72 (13.9%) stddev-sans-first:11.12 02:07:51 INFO - PROCESS | 2389 | Values: 83.0 71.0 91.0 71.0 68.0 69.0 91.0 67.0 66.0 90.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:1.58 (1.8%) stddev-sans-first:1.58 02:07:51 INFO - PROCESS | 2389 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 87.0 84.0 87.0 84.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 02:07:51 INFO - PROCESS | 2389 | Values: 92.0 93.0 94.0 94.0 92.0 92.0 93.0 93.0 92.0 92.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:85.50 stddev:24.01 (28.1%) stddev-sans-first:25.11 02:07:51 INFO - PROCESS | 2389 | Values: 86.0 85.0 86.0 85.0 86.0 83.0 83.0 95.0 141.0 144.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 02:07:51 INFO - PROCESS | 2389 | Values: 54.0 53.0 53.0 53.0 54.0 55.0 54.0 54.0 56.0 55.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:3.97 (5.5%) stddev-sans-first:3.36 02:07:51 INFO - PROCESS | 2389 | Values: 65.0 80.0 74.0 75.0 71.0 70.0 72.0 71.0 69.0 71.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:88.00 stddev:6.38 (7.3%) stddev-sans-first:6.75 02:07:51 INFO - PROCESS | 2389 | Values: 88.0 85.0 88.0 85.0 86.0 88.0 89.0 89.0 107.0 89.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:147.50 stddev:8.05 (5.5%) stddev-sans-first:8.40 02:07:51 INFO - PROCESS | 2389 | Values: 147.0 126.0 145.0 149.0 149.0 142.0 136.0 135.0 148.0 151.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.50 stddev:2.62 (1.5%) stddev-sans-first:2.78 02:07:51 INFO - PROCESS | 2389 | Values: 169.0 174.0 168.0 170.0 171.0 166.0 171.0 166.0 169.0 166.0 02:07:51 INFO - PROCESS | 2389 | 02:07:51 INFO - PROCESS | 2389 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.25 (1.9%) stddev-sans-first:1.17 02:07:51 INFO - PROCESS | 2389 | Values: 65.0 69.0 68.0 67.0 67.0 67.0 66.0 66.0 65.0 67.0 02:07:51 INFO - PROCESS | 2389 | -------- Summary: end -------- 02:07:51 INFO - PROCESS | 2389 | 02:07:52 INFO - TEST-INFO | 2389: exit 0 02:07:52 INFO - TEST-OK | kraken | took 59059ms 02:07:52 INFO - SUITE-END | took 1176s 02:07:53 INFO - Completed test suite (00:19:36) 02:07:53 INFO - javascript benchmark 02:07:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2881.971180288197, 3319.1536158279637, 3334.4998250262456, 3367.0740546349766, 3381.08710648125, 5576.776928922848, 5636.549076073915, 5692.231548740919, 5709.600327977038, 5713.828585142442, 5678.233438485803, 5683.829485115442, 5766.625169363989, 5773.076307790754, 5807.39022402648, 5046.949530504685, 5192.247124167013, 5208.140656791625, 5219.269302297681, 5255.0803609368395, 5230.607704422165, 5301.602379821529, 5354.892902141974, 5374.6506477078865, 5392.838214853561, 5256.237845512417, 5288.65623734456, 5296.126139187029, 5319.175527793179, 5356.062689029423, 5074.13739664258, 5124.103281925648, 5177.223416487519, 5178.326817513699, 5235.162374020177, 5072.264521644341, 5087.592992560586, 5095.0574143783515, 5148.871278218037, 5153.252778347156, 5537.0, 5544.500994910437, 5552.500274975231, 5563.0, 5598.1322894951345, 6092.634441933498, 6108.236470441195, 6120.357362476921, 6126.540509461764, 6145.678679084019, 5574.888502229933, 5851.473367396912, 6192.566520343577, 6243.843903902393, 6301.180846489973, 5762.9135562966585, 5818.21454103699, 5830.650160990354, 5830.941690583039, 5878.030125029408, 6200.100985357098, 6264.686765661699, 6274.435300822948, 6281.7173227204885, 6286.9056964145575, 6038.788642397495, 6090.939090609126, 6118.174046503754, 6133.84665383369, 6158.692065396713, 6228.096925945803, 6262.812115636538, 6283.2460104787715, 6285.937140628626, 6287.622742635456, 6183.288921774054, 6195.783147589813, 6199.1011303361665, 6229.781957631461, 6237.500999919996, 5961.642301461926, 6082.847928801727, 6089.147519347295, 6185.969070154621, 6269.686515674198, 4869.70781753096, 4869.780859861244, 4905.828296009694, 4926.261060840902, 4956.132676781549, 4409.206342858316, 4436.511983681792, 4471.686981911235, 4478.932816007762, 4482.574155455227, 5777.19119323295, 5785.50823180026, 5801.53587712982, 5820.272465941757, 5827.3007239131575, 6180.969095154495, 6247.250329960434, 6291.245050593867, 6297.905531417033, 6316.178896743394, 5730.828075157752, 5818.854528636733, 5829.358770535237, 5848.005839007379, 5905.966455870205, 5402.432744561843, 5407.729613519308, 5409.648372855751, 5418.295621569171, 5424.620276580601, 6179.938200618116, 6280.654563998933, 6311.747530098744, 6323.0, 6343.746250150035, 5794.420557944172, 6173.629582225081, 6196.845078872884, 6210.037444196157, 6293.874122517615, 6279.497640188865, 6283.937160628335, 6288.119663247057, 6296.779612713533, 6304.905426418699, 6209.968950155401, 6220.502359811114, 6233.657148856856, 6241.157443745036, 6241.594296370721, 6102.328743838263, 6179.81460556184, 6214.067889816563, 6226.1905952225925, 6305.1172835803945, 6099.8545999513535, 6105.694715264308, 6142.293636231891, 6169.938300616936, 6180.134781130555, 5465.863353416196, 6139.478144357601, 6165.198524191826, 6168.414000669929, 6172.938270617236, 5741.425857414225, 5837.357890632112, 5858.2677165354335, 5917.881642367042, 5945.821625351246, 6103.186149163709, 6122.489639783254, 6127.281156068254, 6133.548895741652, 6138.25566404484, 5788.768449261982, 5918.260217472816, 5923.881522369615, 5953.255843019622, 6005.219321488178], "value": 5688.657344306095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.71379673907063, 516.7416291854071, 518.3106468397033, 530.2735252703797, 540.5675459632293, 30699.69300306999, 38952.0, 40381.78854634358, 40408.59591404089, 40414.98962525938, 41075.58924410755, 41443.0, 41593.79203103988, 41617.37573936386, 41642.95892602686, 9788.461634610094, 9962.252831037702, 9969.50152492376, 9973.900260997407, 9985.650502232424, 8313.750587482355, 9624.326297159201, 9727.978562250968, 9745.853812192823, 9757.219422446222, 9742.902570974271, 9795.461249631267, 9806.9509652452, 9824.557894894748, 9888.406695598287, 9699.321047526675, 9757.316987810857, 9767.267454940873, 9792.363496372713, 9795.755106122404, 9734.90265097347, 9798.75503112428, 9807.019298070207, 9807.754806129904, 9814.90185098147, 39354.60645393538, 39688.20623587512, 39723.60276397228, 39725.40411893824, 39742.006449838984, 3270.394976929276, 3301.3727391795483, 3330.0509354833757, 3352.1619595101224, 3759.793211373373, 29844.0, 30040.399192016255, 30708.00795896519, 30747.0, 30830.0, 3221.951670724941, 3273.3780581689402, 3274.721648659867, 3280.78674886134, 3362.4788157835574, 466845.0, 467869.0, 467902.0, 470073.0, 479192.0, 60661.0, 61160.0, 61208.08187877185, 61259.77480450366, 61300.38699613036, 34418.48372274418, 35058.64941350605, 35237.47143792845, 62368.68815655893, 62722.0, 15498.295187529362, 16322.67354652924, 16476.6704665906, 16479.176041197894, 16486.68106551473, 10682.465876706134, 12708.555200567936, 12712.872871271355, 12728.10903236783, 12740.171888827197, 14980.775288370683, 15056.698866022838, 18699.0, 18703.15835787393, 18728.906355468134, 6573.704183311763, 7634.824075138559, 7764.456488045896, 7805.726799561988, 7825.765227043198, 838.2120806441925, 889.7775556110972, 931.6925414613162, 938.591712605019, 1041.1618646989095, 27390.726092739216, 27643.308917276827, 28962.71037289642, 29672.104102822792, 30173.245668858017, 1013.8681971343678, 1029.3750935115427, 1048.826943554305, 1052.3790963331671, 1131.7000994736402, 15581.29884155193, 15595.766063509056, 15735.370585176694, 15748.76376854348, 16057.839421605868, 663440.0, 668494.0, 668898.0, 669590.0, 670275.0, 57454.138187927216, 57939.42060579424, 58052.0, 58091.0, 58131.128033079534, 1210.3645586067385, 1250.9749805003848, 1333.9999999999807, 1342.315419136228, 1352.8985326100583, 29729.85135074311, 31322.21694457657, 31363.529547056354, 31369.215769605944, 31827.840860795546, 49570.752146239036, 50155.49844501508, 50258.0, 50322.0, 50441.0, 528100.0, 528996.0, 532072.0, 532207.0, 533322.0, 23624.29127126189, 24171.879140604888, 26248.081317153807, 26680.066197682987, 27060.45879082447, 46785.2982205274, 47039.76480117578, 47192.29211561898, 47523.524764751906, 48387.75806120947, 37458.78848063555, 37800.0, 38500.42249366318, 38913.2217355657, 39497.407538886364, 37695.05762355963, 38322.0, 38461.4230786544, 38686.0, 39896.80309590717, 37571.436428454144, 38271.42592861054, 38408.615913840506, 38713.80643096766, 38755.031124220994, 32339.029829105166, 34173.65826341705, 34439.31121377608, 34765.0, 34770.82614586911, 25137.622935656345, 25455.490890182464, 25576.976920922956, 25837.612435813102, 25859.224223273333], "value": 19653.979170465987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.508369819723, 351.4219109564768, 364.8212375935792, 365.6471504997677, 401.821189570641, 15353.692926141484, 15460.0, 16515.421960231408, 19376.418707438763, 21263.04316305766, 19707.113179906937, 20389.694154587694, 21050.263240786575, 21201.787982120175, 21255.93720313986, 5273.419923808378, 5706.115552089433, 6118.0822876568645, 6158.168647232634, 6167.444929956303, 5103.974480127613, 6666.366695163952, 6765.289644587321, 6820.829479263009, 6826.692798824065, 5758.884822303551, 6665.270589643094, 6683.365080317386, 6737.562058466208, 6748.493862960273, 6476.89892718239, 6654.966725166391, 6660.367265109807, 6664.8000559983275, 6669.666516674195, 5518.144687573433, 5735.713214339308, 5796.88406231873, 5806.593538452268, 5821.476067153934, 25415.61876571853, 26118.216453506426, 26183.869080654476, 26349.0, 26417.603735943976, 2167.295265331745, 2220.9444763881033, 2442.0842184180933, 2462.4582591829767, 3056.052623686642, 6862.588244705334, 6862.862742745167, 7317.594752259123, 15928.293732189499, 15983.0, 1831.359024341483, 1881.8212269834344, 1898.1837809741805, 1907.675695131831, 1960.11794692389, 92514.0, 116372.83627163705, 119574.20638690426, 120205.0, 120481.39759301147, 10352.637657681946, 10578.418186999636, 10670.51982660782, 10882.347059176474, 11331.093512518979, 13494.19034857892, 13510.527131550349, 13537.0, 13541.322933853267, 13599.912007039413, 8274.669013239403, 8503.787405314917, 8541.444806087584, 8702.695405660772, 8711.956440217886, 8562.400631955703, 8658.177473140042, 8780.868286975572, 8857.424267422597, 8915.01934787186, 6456.935430645727, 6603.372679595431, 6607.0509199786175, 6608.537402381787, 6621.602703837785, 1810.8370246677598, 1830.5789668376349, 1832.7250912363252, 1838.531174550481, 1862.050354319307, 878.3982971664431, 922.3727865051695, 966.2656381150376, 969.6703120938914, 976.321456587679, 19379.51551211203, 21399.8930005349, 22044.078204228514, 22177.44556386071, 22309.996050177782, 918.9540522973824, 920.894097178833, 928.6656803550717, 954.5275088831073, 968.6270785747397, 10277.640282590073, 10859.457027148612, 11819.822702659467, 11950.940245298892, 11959.0, 493197.0, 498108.0, 503512.0, 561214.0, 590306.0, 91046.0, 140152.0, 141854.0, 144397.5560244405, 144417.2065894596, 969.9612015519441, 975.2344409638541, 1021.8007488539663, 1022.9897701023043, 1023.4371095897227, 19422.805771942665, 19881.60236795227, 19924.0, 20007.59984800325, 20121.99390030522, 40079.59920400759, 48909.0, 57482.31824656919, 58665.0, 59725.10412343905, 70262.0, 85951.8512037204, 86925.0, 88172.0, 90368.0, 7968.880466793119, 7979.760607181794, 8129.634166462405, 8465.238128568459, 8503.829923401621, 12041.81937270924, 13094.410751516014, 13218.867811322149, 13271.535496257584, 13522.594322170351, 11241.437928103725, 11355.943220283845, 11464.0, 11557.82663260035, 11758.647240582795, 9944.45305508204, 10233.385996840214, 10896.400697961688, 11071.667849964513, 11164.776704465703, 11419.0, 11480.540778369106, 11544.59593914209, 11608.593699220824, 11746.70633234176, 7928.722494712657, 8088.9595552021865, 8096.230858068474, 8231.917680823115, 8440.324774017941, 11200.103991680484, 11319.490622922152, 11448.599299024494, 11466.484008219484, 11490.712732181428], "value": 9784.572106478008, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.200200880477, 1497.3860717105986, 1514.3109885002325, 1701.4470297153428, 1869.5513076861548, 7864.921350786491, 8649.394542382028, 8935.597898094586, 8952.328575356858, 9028.548572571362, 8786.780330491725, 8878.511681857495, 8889.555522223895, 8896.510691911943, 8897.065808090138, 2024.8481363897693, 2204.1293688992837, 2287.3938406322254, 2396.2212281008697, 2418.903243870252, 2376.607859703147, 2392.377981724747, 2402.1112188490197, 2413.3966508372905, 2431.55016321981, 2387.3076807725824, 2405.1702162753822, 2422.1643532981093, 2436.6954130733657, 2443.9979608360486, 2342.5197834443898, 2364.6689463475127, 2367.7750613691674, 2368.5854975379325, 2377.322463098009, 2035.918563257468, 2365.9171928982573, 2403.651470536762, 2405.542946840095, 2413.5776239158076, 8754.912450875474, 8959.0, 8971.0, 8974.910250897472, 9013.459192448474, 5187.221916712486, 5408.0535906216255, 5408.13469844823, 5426.131818908955, 5435.0488664483955, 3523.224890524081, 3662.688671462902, 3726.4037753959237, 3729.5151630287887, 3752.3433399155037, 918.5361392496814, 1086.5056399338282, 1090.1987039525943, 1110.3726394587034, 1110.5391262626001, 11674.241174323643, 11719.238249513754, 11811.0, 11815.88184118165, 11829.171957962946, 4393.428854248928, 4465.285554311295, 4652.209124448852, 4653.464851542049, 4653.511381304914, 9197.218236449977, 9216.861747073799, 9219.677311294072, 9240.445573265626, 9264.351495395258, 5284.39229488606, 5290.047791397508, 5291.947080529146, 5296.2055691646565, 5296.708681022581, 4464.196444639931, 4507.121111383314, 4625.7687115644085, 4793.760311984387, 4972.005598880239, 3732.234891847165, 3789.7157713171623, 3835.6356146166067, 3845.3270677631303, 3906.7655940643654, 1095.6877289972479, 1105.7677887643686, 1116.6929094498914, 1117.446863802423, 1120.137494129532, 871.5990644303691, 884.6151923913119, 886.4326830828275, 888.8933328000682, 907.9409838360485, 5638.379778224392, 5768.567357448124, 5921.171036054956, 6001.279846418458, 6028.186194863725, 920.6823645842173, 922.409657818983, 939.1876027236391, 956.0487315121499, 976.1995163965622, 3172.7779055465894, 3420.931581368359, 3686.5023221864703, 3915.960840391617, 3957.782321972319, 9989.900100999042, 10731.731706707238, 10869.0, 10888.183386246063, 10934.945325273322, 10312.793744125076, 10837.729056773644, 10862.34825910448, 10883.292585981886, 10914.508847101899, 7907.169747176578, 8302.667893284322, 8310.626021829032, 8311.584420778936, 8319.792005199919, 5714.685692286964, 5786.623869448473, 5840.948629246606, 5898.174255604305, 5973.970130149321, 9988.200943924614, 9995.650152244927, 10005.349652272578, 10013.24900632441, 10017.799644007226, 9506.382085164441, 10419.114375278186, 10423.270371073952, 10432.634857779942, 10433.739156521147, 6546.934530654633, 6994.6852391643515, 7215.783526494213, 7553.7356192533, 7694.07671079474, 7889.0, 7939.28546430824, 8016.198380161937, 8026.0, 8408.327333813158, 7222.855542889218, 7448.888266676113, 7480.775576732707, 7527.473076884566, 7610.010698609367, 7151.856962860609, 7280.708771649075, 7378.815529611803, 7411.703531858665, 7458.813529661802, 6990.231074581894, 6997.440204783503, 7093.006979023045, 7093.255208203271, 7098.716051357887, 4427.645788336989, 4818.229083346648, 4974.338728780085, 5035.521625445577, 5122.615803814654, 4971.1797553403285, 5096.286519887145, 5105.846824595268, 5131.846044618667, 5180.948190518046], "value": 4549.957591679475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.20477284850415, 291.26746233223435, 300.2014641054794, 309.84662592016946, 315.42907337718714, 5056.367954005749, 5195.662281951671, 5320.4147543770205, 5398.13629819229, 5403.243545903571, 4823.42118945727, 5137.280780690697, 5218.556422704075, 5219.817306394277, 5266.894662106755, 3870.4774855394585, 4053.047533829548, 4059.6752259819145, 4062.593740625943, 4115.650169735569, 3425.6060553036473, 3461.2558299965503, 3840.8669442514524, 4106.445629839978, 4143.109231515224, 3780.225053863965, 3808.9809550952205, 3889.3971434427567, 4063.349864021757, 4079.7153593987314, 3333.7166340861054, 4015.8795236142814, 4035.57626449223, 4040.9797951010055, 4053.4122552230056, 2992.835394053326, 3537.734669899742, 3544.5923718772156, 3570.9642903570893, 3573.160307327776, 4196.538380778112, 4899.510048995107, 5071.3407257056715, 5216.0, 5221.817236396708, 3799.67702745267, 4546.749928753918, 4558.772061396917, 4558.954410455919, 4615.169269531483, 4990.85210401606, 5572.832815015556, 5573.359063707685, 5593.04918163909, 5598.992181407345, 3208.96791032089, 3380.2084895005687, 3648.1244501319757, 3781.6785573226307, 3816.484774555427, 5471.0, 5758.309002919635, 5764.798232061858, 5817.534597232211, 5827.941720582782, 3388.085731561709, 3844.6732027777953, 3845.403962385835, 3846.1346197105986, 3862.729608927404, 3144.6540880503126, 3754.605766394544, 3909.6872250219913, 3923.705177291487, 3924.645997130985, 3425.8972230833115, 3983.900402489961, 4567.748773817472, 4585.5784706741015, 4588.95411045892, 5038.47096054909, 5603.495685388335, 5603.607747457639, 5616.522595579421, 5635.971820140873, 1247.7067889046066, 1255.1465003797323, 1257.7673130470891, 1296.8508621508458, 1304.315234501894, 688.7727050073464, 883.337496877342, 898.276887105873, 901.2924853989587, 909.2271569166156, 4329.004329004284, 4433.5590932946925, 4673.854905548094, 5210.47895210476, 5290.444503327172, 4428.468583769968, 4782.545658162469, 5498.202760599771, 5686.004949133885, 5698.7177884975545, 4378.868633940987, 5132.666376685504, 5181.715005674649, 5203.349581302337, 5227.817026404057, 2520.5715028445206, 2553.438243586408, 2971.524556071018, 2999.355138645202, 3005.609270794783, 5598.776048958078, 5794.0, 5830.679312637761, 5884.882302353929, 5894.379045762364, 5321.6540924839765, 5433.891322173535, 5783.797567085132, 5818.359980402152, 5864.797716468114, 4990.90018199634, 5027.79888804451, 5418.45815418455, 5804.622699524602, 5921.851953701106, 4578.40480737502, 4624.861254162381, 5664.688442135722, 5680.630759000817, 5687.8862422752145, 5175.378954525481, 5463.53559947393, 5610.485169004428, 5634.408387119293, 5673.347565030074, 4959.975200123977, 5538.289346138016, 5796.304443466811, 5827.650340979555, 5941.554383421347, 3314.9171270718425, 3412.692857642725, 3845.7307988441657, 3869.0, 3879.825407856597, 5675.631083979528, 5760.395158508464, 5764.798232061858, 5777.7977770777825, 5832.0, 4245.61789438952, 4319.790458671577, 4690.9765451172525, 4996.250562415521, 5525.585581081355, 4555.840545580889, 4876.0, 4938.358013458371, 5519.586031047607, 5579.442055794388, 4670.766461676971, 5076.1725778720365, 5199.206273835521, 5339.866503337447, 5344.652597581145, 5142.0095588638815, 5593.7693707384315, 5631.267935168402, 5636.464535869086, 5657.660540367591, 4598.540145985375, 5103.7448127592725, 5429.592780541396, 5433.619646624698, 5482.890342193214], "value": 3990.026538307122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.4586655476292, 718.0880282041807, 742.2874040920716, 769.8729709597917, 828.9886338666827, 13198.604041878747, 14878.884083693712, 15571.610709732247, 16154.111523866182, 16596.25316860741, 12532.624021279353, 14474.131552106881, 14604.926975365135, 14629.122252664847, 14658.193799341032, 835.8996920369564, 845.4335595151264, 846.2383854530941, 850.4854562989365, 850.808568072185, 694.6873906741986, 707.9610621415845, 717.6806321187074, 845.3913182508614, 857.7212405968054, 900.2437952120217, 913.0823522360018, 913.4291068082448, 914.1498406481969, 917.7338571814166, 906.1799071839929, 908.4322298563397, 909.6497848328376, 909.6816114359988, 911.726482055384, 526.0215998243265, 537.4437457231785, 671.6272468779825, 674.0630523572238, 677.420805211542, 12445.377731113407, 12625.368731563476, 12635.873641263654, 12724.427400766896, 14980.47568335114, 11960.34218118003, 13544.45822167112, 14215.502457414044, 14289.499867504692, 14673.339699713431, 11800.987664194216, 11845.94077029618, 11886.58396956111, 11937.701557461047, 11949.94025029878, 3887.688984881203, 3899.98050009751, 3997.020729921159, 4028.4561584186345, 4080.3063479208604, 109874.90125098727, 111229.0, 112648.31027534594, 113267.0, 113543.0, 11380.975712185944, 11422.0, 11520.827187592193, 11529.827052594217, 11986.940065299617, 23634.64548031781, 23685.28944131679, 23722.0, 23730.762692372857, 23738.0, 6607.306232845578, 7873.448858579959, 7969.3226075784205, 8354.415190936576, 8437.789055273668, 4595.655325850575, 4599.126166028444, 4609.723416595015, 4711.599514041345, 4798.944232268896, 3607.9278414431565, 3773.603771603942, 3776.490173826553, 3778.4899038629433, 3779.4897688811657, 671.425930829134, 691.2741621297617, 707.1125737199782, 708.2174197511675, 711.4592909388798, 541.0747621567085, 591.3317950715664, 660.51782198995, 674.7638326585655, 680.884249677556, 9135.817283654289, 9151.908480915106, 9565.508589364832, 9573.712788616353, 9692.17616502591, 530.3715097609345, 623.7224435126325, 664.6264947896312, 702.8348338140532, 709.1950636028042, 4376.071774734747, 4470.530594287618, 4666.533346665306, 4695.366125573072, 4713.764311784397, 104382.0, 128547.35726321308, 132663.0, 139053.6094639061, 161712.0, 118959.0, 121268.0, 121756.0, 123732.0, 127831.0, 76021.0, 100703.0, 103201.0, 109956.0, 111496.0, 7995.600219989094, 9712.368696034735, 9817.950910245403, 9853.802923941337, 10110.595576176871, 57709.13436298375, 59319.11021334597, 60053.69973150106, 61013.69493152506, 64268.03597946128, 82681.58659206665, 85583.57208213919, 86682.0, 89054.55472722594, 94411.52794235985, 13278.336083195609, 13509.86490135086, 13558.0, 13614.18314901109, 13929.582112536234, 14984.925075374553, 15226.923865380602, 15282.235888205767, 15955.20223988819, 16004.0, 13880.236586987408, 14033.508827190999, 14101.788473172706, 14193.219372934587, 14513.0, 13908.0, 14028.438862445388, 14170.291485425894, 14602.707945841235, 14906.850931490546, 13553.0, 13657.453701852211, 13671.539122957964, 13707.246101464516, 14111.123853782763, 11335.149863760085, 11531.094050859037, 11742.471588778355, 11796.578605885921, 12034.217775844541, 9479.241660667269, 11502.149331374521, 11540.192186546861, 11650.53397864076, 11718.355490448108], "value": 7827.671235978252, "unit": "score"}], "name": "dromaeo_css", "value": 7332.929488517981, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 167.0, 147.0, 114.0, 111.0, 112.0, 113.0, 112.0, 113.0, 113.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 107.0, 145.0, 110.0, 109.0, 109.0, 105.0, 145.0, 110.0], "value": 119.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 183.0, 185.0, 182.0, 179.0, 184.0, 181.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 58.0, 89.0, 62.0, 58.0, 57.0, 58.0, 81.0, 73.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 91.0, 71.0, 68.0, 69.0, 91.0, 67.0, 66.0, 90.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 86.0, 83.0, 83.0, 95.0, 141.0, 144.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 54.0, 55.0, 54.0, 54.0, 56.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 80.0, 74.0, 75.0, 71.0, 70.0, 72.0, 71.0, 69.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 86.0, 88.0, 89.0, 89.0, 107.0, 89.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 126.0, 145.0, 149.0, 149.0, 142.0, 136.0, 135.0, 148.0, 151.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 174.0, 168.0, 170.0, 171.0, 166.0, 171.0, 166.0, 169.0, 166.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1439.5, "alertThreshold": 2.0}]} 02:07:53 INFO - Return code: 0 02:07:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:07:53 INFO - # TBPL SUCCESS # 02:07:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:07:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:07:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:07:53 INFO - 2016-09-30 02:07:53.201 screenresolution[2393:33584] starting screenresolution argv=screenresolution get 02:07:53 INFO - 2016-09-30 02:07:53.204 screenresolution[2393:33584] Display 0: 1600x1200x32@60 02:07:53 INFO - 2016-09-30 02:07:53.210 screenresolution[2394:33587] starting screenresolution argv=screenresolution list 02:07:53 INFO - Available Modes on Display 0 02:07:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:07:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:07:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:07:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:07:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:07:53 INFO - Intel Iris: 02:07:53 INFO - Chipset Model: Intel Iris 02:07:53 INFO - Type: GPU 02:07:53 INFO - Bus: Built-In 02:07:53 INFO - VRAM (Dynamic, Max): 1536 MB 02:07:53 INFO - Vendor: Intel (0x8086) 02:07:53 INFO - Device ID: 0x0a2e 02:07:53 INFO - Revision ID: 0x0009 02:07:53 INFO - Displays: 02:07:53 INFO - Display: 02:07:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:07:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:07:53 INFO - Display Serial Number: 1600x1200 60 02:07:53 INFO - Main Display: Yes 02:07:53 INFO - Mirror: Off 02:07:53 INFO - Online: Yes 02:07:53 INFO - Rotation: Supported 02:07:53 INFO - Return code: 0 02:07:53 INFO - Running post-action listener: _resource_record_post_action 02:07:53 INFO - [mozharness: 2016-09-30 09:07:53.445814Z] Finished run-tests step (success) 02:07:53 INFO - Running post-run listener: _resource_record_post_run 02:07:53 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 704512; Write bytes: 708750336; Read time: 5; Write time: 1943 02:07:53 INFO - TinderboxPrint: CPU usage
53.4% 02:07:53 INFO - TinderboxPrint: I/O read bytes / time
704,512 / 5 02:07:53 INFO - TinderboxPrint: I/O write bytes / time
708,750,336 / 1,943 02:07:53 INFO - TinderboxPrint: CPU idle
2,256.4 (46.6%) 02:07:53 INFO - TinderboxPrint: CPU system
964.5 (19.9%) 02:07:53 INFO - TinderboxPrint: CPU user
1,624.9 (33.5%) 02:07:53 INFO - TinderboxPrint: Swap in / out
407,764,992 / 0 02:07:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397711872; Write bytes: 393402368; Read time: 29521; Write time: 782 02:07:53 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 704512; Write bytes: 296510464; Read time: 5; Write time: 1116 02:07:53 INFO - Running post-run listener: _upload_blobber_files 02:07:53 INFO - Blob upload gear active. 02:07:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:07:53 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 02:07:53 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:07:53 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:07:54 INFO - (blobuploader) - INFO - Open directory for files ... 02:07:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:07:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:07:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:55 INFO - (blobuploader) - INFO - Done attempting. 02:07:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:07:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:07:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:56 INFO - (blobuploader) - INFO - Done attempting. 02:07:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:07:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:07:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:07:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:07:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:07:58 INFO - (blobuploader) - INFO - Done attempting. 02:07:58 INFO - (blobuploader) - INFO - Iteration through files over. 02:07:58 INFO - Return code: 0 02:07:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:07:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:07:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/842214aeb463a89178d72b04e1e7796bbee3dd380fcc8872b3cd7fafa994fa7abd96a81409ca6bfc0a194f8cbca0662d81790ced3ab8f30e5b1168787f954e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfa0a7c12d76737967041b978ee92cfacd09dc5671da82b4dcff0641991c3b00f330fb7d4df5638eb9703f2e9ef3773d10db8ed5e19a01da84d3833696173e09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7faed99b7d2c33469c682d4ea8a15fc2337ec7d570431fac48dd7b75ff65e433e7d111d6a6b0ee4ee351cccd21a59eb8eb9e879f6ae64ffb5efbb5a7b29ddeb"} 02:07:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:07:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:07:58 INFO - Contents: 02:07:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/842214aeb463a89178d72b04e1e7796bbee3dd380fcc8872b3cd7fafa994fa7abd96a81409ca6bfc0a194f8cbca0662d81790ced3ab8f30e5b1168787f954e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfa0a7c12d76737967041b978ee92cfacd09dc5671da82b4dcff0641991c3b00f330fb7d4df5638eb9703f2e9ef3773d10db8ed5e19a01da84d3833696173e09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7faed99b7d2c33469c682d4ea8a15fc2337ec7d570431fac48dd7b75ff65e433e7d111d6a6b0ee4ee351cccd21a59eb8eb9e879f6ae64ffb5efbb5a7b29ddeb"} 02:07:58 INFO - Running post-run listener: copy_logs_to_upload_dir 02:07:58 INFO - Copying logs to upload dir... 02:07:58 INFO - mkdir: /builds/slave/test/build/upload/logs 02:07:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.320089 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-30 02:07:59.207063) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 02:07:59.211594) ========= 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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/Fx-Team/sha512/842214aeb463a89178d72b04e1e7796bbee3dd380fcc8872b3cd7fafa994fa7abd96a81409ca6bfc0a194f8cbca0662d81790ced3ab8f30e5b1168787f954e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfa0a7c12d76737967041b978ee92cfacd09dc5671da82b4dcff0641991c3b00f330fb7d4df5638eb9703f2e9ef3773d10db8ed5e19a01da84d3833696173e09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7faed99b7d2c33469c682d4ea8a15fc2337ec7d570431fac48dd7b75ff65e433e7d111d6a6b0ee4ee351cccd21a59eb8eb9e879f6ae64ffb5efbb5a7b29ddeb"} build_url:https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/FE9tuF-XSNGx4hunBDXbyQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/842214aeb463a89178d72b04e1e7796bbee3dd380fcc8872b3cd7fafa994fa7abd96a81409ca6bfc0a194f8cbca0662d81790ced3ab8f30e5b1168787f954e8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfa0a7c12d76737967041b978ee92cfacd09dc5671da82b4dcff0641991c3b00f330fb7d4df5638eb9703f2e9ef3773d10db8ed5e19a01da84d3833696173e09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7faed99b7d2c33469c682d4ea8a15fc2337ec7d570431fac48dd7b75ff65e433e7d111d6a6b0ee4ee351cccd21a59eb8eb9e879f6ae64ffb5efbb5a7b29ddeb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 02:07:59.244931) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 02:07:59.245218) ========= 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.1RkBRFb3rS/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.qqfLvXD6Bz/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 02:07:59.293365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 02:07:59.293639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 02:07:59.293946) ========= ========= Total master_lag: 1.09 =========