builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1476901640.03 results: success (0) buildid: 20161019102522 builduid: fafca15e434243bab1d5f1cae786e901 revision: 557c6a8209fe88525f3a061a9e250aa43962709a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.027065) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.027496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.027774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.055075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.055495) ========= 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.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-19 11:27:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.37M=0.001s 2016-10-19 11:27:20 (8.37 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.231052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.231364) ========= 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.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.019551 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:20.284866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-19 11:27:20.285262) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 557c6a8209fe88525f3a061a9e250aa43962709a --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 557c6a8209fe88525f3a061a9e250aa43962709a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-19 11:27:20,358 truncating revision to first 12 chars 2016-10-19 11:27:20,358 Setting DEBUG logging. 2016-10-19 11:27:20,358 attempt 1/10 2016-10-19 11:27:20,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/557c6a8209fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 11:27:22,140 unpacking tar archive at: fx-team-557c6a8209fe/testing/mozharness/ program finished with exit code 0 elapsedTime=2.034649 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-19 11:27:22.343634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:22.346061) ========= script_repo_revision: 557c6a8209fe88525f3a061a9e250aa43962709a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:22.346554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:22.346841) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 11:27:22.369691) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 11:27:22.370066) ========= /tools/buildbot/bin/python -u 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', '-u', '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:27:22 INFO - MultiFileLogger online at 20161019 11:27:22 in /builds/slave/test 11:27:22 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 11:27:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:27:22 INFO - {'append_to_log': False, 11:27:22 INFO - 'base_work_dir': '/builds/slave/test', 11:27:22 INFO - 'blob_upload_branch': 'Fx-Team', 11:27:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:27:22 INFO - 'branch': 'Fx-Team', 11:27:22 INFO - 'buildbot_json_path': 'buildprops.json', 11:27:22 INFO - 'config_files': ('talos/mac_config.py',), 11:27:22 INFO - 'default_actions': ('clobber', 11:27:22 INFO - 'read-buildbot-config', 11:27:22 INFO - 'download-and-extract', 11:27:22 INFO - 'populate-webroot', 11:27:22 INFO - 'create-virtualenv', 11:27:22 INFO - 'install', 11:27:22 INFO - 'run-tests'), 11:27:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:27:22 INFO - 'download_minidump_stackwalk': True, 11:27:22 INFO - 'download_symbols': 'ondemand', 11:27:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:27:22 INFO - 'tooltool.py': '/tools/tooltool.py', 11:27:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:27:22 INFO - '/tools/misc-python/virtualenv.py')}, 11:27:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:27:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:27:22 INFO - 'installer_path': 'installer.exe', 11:27:22 INFO - 'log_level': 'info', 11:27:22 INFO - 'log_name': 'talos', 11:27:22 INFO - 'log_to_console': True, 11:27:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:27:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:27:22 INFO - 'opt_config_files': (), 11:27:22 INFO - 'pip_index': False, 11:27:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:22 INFO - 'cmd': ('bash', 11:27:22 INFO - '-c', 11:27:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:22 INFO - 'enabled': True, 11:27:22 INFO - 'halt_on_failure': False, 11:27:22 INFO - 'name': 'check_screen_resolution'},), 11:27:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:22 INFO - 'cmd': ('bash', 11:27:22 INFO - '-c', 11:27:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:22 INFO - 'enabled': True, 11:27:22 INFO - 'halt_on_failure': False, 11:27:22 INFO - 'name': 'check_screen_resolution'},), 11:27:22 INFO - 'run_cmd_checks_enabled': True, 11:27:22 INFO - 'sps_profile': False, 11:27:22 INFO - 'sps_profile_interval': 0, 11:27:22 INFO - 'suite': 'dromaeojs', 11:27:22 INFO - 'system_bits': '32', 11:27:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:27:22 INFO - 'title': 't-yosemite-r7-0070', 11:27:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:27:22 INFO - 'use_talos_json': True, 11:27:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:27:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:27:22 INFO - 'work_dir': 'build'} 11:27:22 INFO - [mozharness: 2016-10-19 18:27:22.568877Z] Running clobber step. 11:27:22 INFO - Running pre-action listener: _resource_record_pre_action 11:27:22 INFO - Running main action method: clobber 11:27:22 INFO - rmtree: /builds/slave/test/build 11:27:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:27:24 INFO - Running post-action listener: _resource_record_post_action 11:27:24 INFO - [mozharness: 2016-10-19 18:27:24.238262Z] Finished clobber step (success) 11:27:24 INFO - [mozharness: 2016-10-19 18:27:24.238336Z] Running read-buildbot-config step. 11:27:24 INFO - Running pre-action listener: _resource_record_pre_action 11:27:24 INFO - Running main action method: read_buildbot_config 11:27:24 INFO - Using buildbot properties: 11:27:24 INFO - { 11:27:24 INFO - "project": "", 11:27:24 INFO - "product": "firefox", 11:27:24 INFO - "script_repo_revision": "production", 11:27:24 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:27:24 INFO - "repository": "", 11:27:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:27:24 INFO - "buildid": "20161019102522", 11:27:24 INFO - "pgo_build": "False", 11:27:24 INFO - "basedir": "/builds/slave/test", 11:27:24 INFO - "buildnumber": 188, 11:27:24 INFO - "slavename": "t-yosemite-r7-0070", 11:27:24 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:27:24 INFO - "platform": "yosemite_r7", 11:27:24 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:27:24 INFO - "branch": "fx-team", 11:27:24 INFO - "repo_path": "integration/fx-team", 11:27:24 INFO - "revision": "557c6a8209fe88525f3a061a9e250aa43962709a", 11:27:24 INFO - "stage_platform": "macosx64", 11:27:24 INFO - "builduid": "fafca15e434243bab1d5f1cae786e901", 11:27:24 INFO - "slavebuilddir": "test" 11:27:24 INFO - } 11:27:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 11:27:24 INFO - Running post-action listener: _resource_record_post_action 11:27:24 INFO - [mozharness: 2016-10-19 18:27:24.241743Z] Finished read-buildbot-config step (success) 11:27:24 INFO - [mozharness: 2016-10-19 18:27:24.241853Z] Running download-and-extract step. 11:27:24 INFO - Running pre-action listener: _resource_record_pre_action 11:27:24 INFO - Running main action method: download_and_extract 11:27:24 INFO - mkdir: /builds/slave/test/build/tests 11:27:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:24 INFO - trying https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 11:27:24 INFO - Downloading https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 11:27:25 INFO - Downloaded 1199 bytes. 11:27:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:27:25 INFO - Using the following test package requirements: 11:27:25 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:27:25 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 11:27:25 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 11:27:25 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'jsshell-mac.zip'], 11:27:25 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 11:27:25 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:27:25 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 11:27:25 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 11:27:25 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 11:27:25 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:27:25 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 11:27:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:27:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:27:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:27:25 INFO - Fetch https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:27:26 INFO - Http code: 200 11:27:26 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 11:27:26 INFO - x-amz-cf-id: aoWdNGqGEMJvOHC66MiqMEumxyZD7frSTv0RhGtzpqcCQthGmdDiow== 11:27:26 INFO - x-amz-version-id: wbdwMmxfDQP_y7c6ADczCgX85jYVzmyL 11:27:26 INFO - Expected file size: 16499933 11:27:26 INFO - Obtained file size: 16499933 11:27:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:27:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:27:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:27:33 INFO - Fetch https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:27:33 INFO - Http code: 200 11:27:33 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 11:27:33 INFO - x-amz-cf-id: h4FDizKBSO0LIdLTKm9TFPO-Joujg7Lqj8UyWjVJ10zsg460u1ECJQ== 11:27:33 INFO - x-amz-version-id: wbdwMmxfDQP_y7c6ADczCgX85jYVzmyL 11:27:34 INFO - Expected file size: 16499933 11:27:34 INFO - Obtained file size: 16499933 11:27:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 11:27:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:27:40 INFO - Fetch https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 11:27:41 INFO - Http code: 200 11:27:41 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 11:27:41 INFO - x-amz-cf-id: 3tRoDM71VEkNRgShHAyOOnTmboeMKqI3Ds1jPsUZ8Kvkzijh1Wbjrg== 11:27:41 INFO - x-amz-version-id: QJWzbGb2x1R7jEmJ0NvKvFV.VAiRJ.Lv 11:27:41 INFO - Expected file size: 14409028 11:27:41 INFO - Obtained file size: 14409028 11:27: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')]} 11:27:41 INFO - trying https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:27:41 INFO - Downloading https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 11:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 11:27:44 INFO - Downloaded 111643338 bytes. 11:27:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:27:44 INFO - mkdir: /builds/slave/test/properties 11:27:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:27:44 INFO - Writing to file /builds/slave/test/properties/build_url 11:27:44 INFO - Contents: 11:27:44 INFO - build_url:https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:27:45 INFO - Running post-action listener: _resource_record_post_action 11:27:45 INFO - Running post-action listener: set_extra_try_arguments 11:27:45 INFO - [mozharness: 2016-10-19 18:27:45.123612Z] Finished download-and-extract step (success) 11:27:45 INFO - [mozharness: 2016-10-19 18:27:45.123758Z] Running populate-webroot step. 11:27:45 INFO - Running pre-action listener: _resource_record_pre_action 11:27:45 INFO - Running main action method: populate_webroot 11:27:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:27:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:27:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:27:45 INFO - '--tppagecycles', 11:27:45 INFO - '7'], 11:27:45 INFO - 'tests': ['tsvgm']}}, 11:27:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:27:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:27:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:27:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:27:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:27:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['damp', 'tps']}, 11:27:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'tests': ['damp', 'tps']}, 11:27:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['dromaeo_dom']}, 11:27:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:27:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['basic_compositor_video']}, 11:27:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:27:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'other-e10s': {'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['a11yr', 11:27:45 INFO - 'ts_paint', 11:27:45 INFO - 'tpaint', 11:27:45 INFO - 'sessionrestore', 11:27:45 INFO - 'sessionrestore_no_auto_restore', 11:27:45 INFO - 'tabpaint']}, 11:27:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['tsvgx', 11:27:45 INFO - 'tsvgr_opacity', 11:27:45 INFO - 'tart', 11:27:45 INFO - 'tscrollx', 11:27:45 INFO - 'cart']}, 11:27:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:27:45 INFO - 'tsvgr_opacity', 11:27:45 INFO - 'tart', 11:27:45 INFO - 'tscrollx', 11:27:45 INFO - 'cart']}, 11:27:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'talos_options': ['--disable-e10s'], 11:27:45 INFO - 'tests': ['tp5o']}, 11:27:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'tests': ['tp5o']}, 11:27:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'talos_options': ['--disable-e10s', 11:27:45 INFO - '--xperf_path', 11:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:45 INFO - 'tests': ['tp5n']}, 11:27:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:27:45 INFO - 'talos_options': ['--xperf_path', 11:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:27:45 INFO - 'tests': ['tp5n']}}, 11:27:45 INFO - 'talos.zip': {'path': '', 11:27:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:27:45 INFO - Running post-action listener: _resource_record_post_action 11:27:45 INFO - [mozharness: 2016-10-19 18:27:45.142260Z] Finished populate-webroot step (success) 11:27:45 INFO - [mozharness: 2016-10-19 18:27:45.142385Z] Running create-virtualenv step. 11:27:45 INFO - Running pre-action listener: _resource_record_pre_action 11:27:45 INFO - Running main action method: create_virtualenv 11:27:45 INFO - Creating virtualenv /builds/slave/test/build/venv 11:27:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:27:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:27:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:27:45 INFO - Using real prefix '/tools/python27' 11:27:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:27:45 INFO - Installing distribute.............................................................................................................................................................................................done. 11:27:48 INFO - Installing pip.................done. 11:27:48 INFO - Return code: 0 11:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:27:49 INFO - Reading from file tmpfile_stdout 11:27:49 INFO - Output received: 11:27:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:27:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:27:49 INFO - 'HOME': '/Users/cltbld', 11:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:27:49 INFO - 'LOGNAME': 'cltbld', 11:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:49 INFO - 'MOZ_NO_REMOTE': '1', 11:27:49 INFO - 'NO_EM_RESTART': '1', 11:27:49 INFO - 'PAGER': '/bin/cat', 11:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:49 INFO - 'PWD': '/builds/slave/test', 11:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:27:49 INFO - 'SHELL': '/bin/bash', 11:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:27:49 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:27:49 INFO - 'USER': 'cltbld', 11:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:49 INFO - 'XPC_FLAGS': '0x0', 11:27:49 INFO - 'XPC_SERVICE_NAME': '0', 11:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:27:49 INFO - Cleaning up... 11:27:49 INFO - Return code: 0 11:27:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:27:49 INFO - 'HOME': '/Users/cltbld', 11:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:27:49 INFO - 'LOGNAME': 'cltbld', 11:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:49 INFO - 'MOZ_NO_REMOTE': '1', 11:27:49 INFO - 'NO_EM_RESTART': '1', 11:27:49 INFO - 'PAGER': '/bin/cat', 11:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:49 INFO - 'PWD': '/builds/slave/test', 11:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:27:49 INFO - 'SHELL': '/bin/bash', 11:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:27:49 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:27:49 INFO - 'USER': 'cltbld', 11:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:49 INFO - 'XPC_FLAGS': '0x0', 11:27:49 INFO - 'XPC_SERVICE_NAME': '0', 11:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:49 INFO - Downloading/unpacking psutil>=3.1.1 11:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:53 INFO - Installing collected packages: psutil 11:27:53 INFO - Running setup.py install for psutil 11:27:53 INFO - building 'psutil._psutil_osx' extension 11:27:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:27:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:27:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:27:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:27:53 INFO - building 'psutil._psutil_posix' extension 11:27:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:27:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:27:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:27:53 INFO - ^ 11:27:53 INFO - 1 warning generated. 11:27:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:27:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:27:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:27:53 INFO - Successfully installed psutil 11:27:53 INFO - Cleaning up... 11:27:54 INFO - Return code: 0 11:27:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:54 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:27:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:27:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:27:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:27:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:27:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:27:54 INFO - 'HOME': '/Users/cltbld', 11:27:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:27:54 INFO - 'LOGNAME': 'cltbld', 11:27:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:54 INFO - 'MOZ_NO_REMOTE': '1', 11:27:54 INFO - 'NO_EM_RESTART': '1', 11:27:54 INFO - 'PAGER': '/bin/cat', 11:27:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:27:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:54 INFO - 'PWD': '/builds/slave/test', 11:27:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:27:54 INFO - 'SHELL': '/bin/bash', 11:27:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:27:54 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:27:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:27:54 INFO - 'USER': 'cltbld', 11:27:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:27:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:27:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:54 INFO - 'XPC_FLAGS': '0x0', 11:27:54 INFO - 'XPC_SERVICE_NAME': '0', 11:27:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:27:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:27:56 INFO - Installing collected packages: mozsystemmonitor 11:27:56 INFO - Running setup.py install for mozsystemmonitor 11:27:56 INFO - Successfully installed mozsystemmonitor 11:27:56 INFO - Cleaning up... 11:27:57 INFO - Return code: 0 11:27:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:27:57 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:27:57 INFO - 'HOME': '/Users/cltbld', 11:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:27:57 INFO - 'LOGNAME': 'cltbld', 11:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:57 INFO - 'MOZ_NO_REMOTE': '1', 11:27:57 INFO - 'NO_EM_RESTART': '1', 11:27:57 INFO - 'PAGER': '/bin/cat', 11:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:57 INFO - 'PWD': '/builds/slave/test', 11:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:27:57 INFO - 'SHELL': '/bin/bash', 11:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:27:57 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:27:57 INFO - 'USER': 'cltbld', 11:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:57 INFO - 'XPC_FLAGS': '0x0', 11:27:57 INFO - 'XPC_SERVICE_NAME': '0', 11:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:57 INFO - Downloading/unpacking jsonschema==2.5.1 11:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:27:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:00 INFO - Downloading functools32-3.2.3-2.tar.gz 11:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:28:00 INFO - warning: no files found matching '*.txt' 11:28:00 INFO - no previously-included directories found matching 'build' 11:28:00 INFO - no previously-included directories found matching 'dist' 11:28:00 INFO - no previously-included directories found matching '.git*' 11:28:00 INFO - Installing collected packages: jsonschema, functools32 11:28:00 INFO - Running setup.py install for functools32 11:28:00 INFO - warning: no files found matching '*.txt' 11:28:00 INFO - no previously-included directories found matching 'build' 11:28:00 INFO - no previously-included directories found matching 'dist' 11:28:00 INFO - no previously-included directories found matching '.git*' 11:28:00 INFO - Successfully installed jsonschema functools32 11:28:00 INFO - Cleaning up... 11:28:00 INFO - Return code: 0 11:28:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:00 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:00 INFO - 'HOME': '/Users/cltbld', 11:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:00 INFO - 'LOGNAME': 'cltbld', 11:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:00 INFO - 'MOZ_NO_REMOTE': '1', 11:28:00 INFO - 'NO_EM_RESTART': '1', 11:28:00 INFO - 'PAGER': '/bin/cat', 11:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:00 INFO - 'PWD': '/builds/slave/test', 11:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:00 INFO - 'SHELL': '/bin/bash', 11:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:00 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:00 INFO - 'USER': 'cltbld', 11:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:00 INFO - 'XPC_FLAGS': '0x0', 11:28:00 INFO - 'XPC_SERVICE_NAME': '0', 11:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:28:00 INFO - Cleaning up... 11:28:00 INFO - Return code: 0 11:28:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:00 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:00 INFO - 'HOME': '/Users/cltbld', 11:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:00 INFO - 'LOGNAME': 'cltbld', 11:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:00 INFO - 'MOZ_NO_REMOTE': '1', 11:28:00 INFO - 'NO_EM_RESTART': '1', 11:28:00 INFO - 'PAGER': '/bin/cat', 11:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:00 INFO - 'PWD': '/builds/slave/test', 11:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:00 INFO - 'SHELL': '/bin/bash', 11:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:00 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:00 INFO - 'USER': 'cltbld', 11:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:00 INFO - 'XPC_FLAGS': '0x0', 11:28:00 INFO - 'XPC_SERVICE_NAME': '0', 11:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:28:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:28:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:04 INFO - Downloading docopt-0.6.1.tar.gz 11:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:28:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:28:04 INFO - Running setup.py install for blobuploader 11:28:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:28:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:28:05 INFO - Running setup.py install for requests 11:28:05 INFO - Running setup.py install for docopt 11:28:05 INFO - Successfully installed blobuploader requests docopt 11:28:05 INFO - Cleaning up... 11:28:05 INFO - Return code: 0 11:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:05 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:05 INFO - 'HOME': '/Users/cltbld', 11:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:05 INFO - 'LOGNAME': 'cltbld', 11:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:05 INFO - 'MOZ_NO_REMOTE': '1', 11:28:05 INFO - 'NO_EM_RESTART': '1', 11:28:05 INFO - 'PAGER': '/bin/cat', 11:28:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:05 INFO - 'PWD': '/builds/slave/test', 11:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:05 INFO - 'SHELL': '/bin/bash', 11:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:05 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:05 INFO - 'USER': 'cltbld', 11:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:05 INFO - 'XPC_FLAGS': '0x0', 11:28:05 INFO - 'XPC_SERVICE_NAME': '0', 11:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SZIwJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-p63jGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-KKv7g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jqD_mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aVbhv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PzKwnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-fXkvkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-y3AxlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xBjYyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:06 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PdlsOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-odGhUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ye0WxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Myj0Rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-2gmiaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-V3cB5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OHHlK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:07 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ZPAPHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:28:07 INFO - Running setup.py install for manifestparser 11:28:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:28:07 INFO - Running setup.py install for mozcrash 11:28:08 INFO - Running setup.py install for mozdebug 11:28:08 INFO - Running setup.py install for mozdevice 11:28:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Running setup.py install for mozfile 11:28:08 INFO - Running setup.py install for mozhttpd 11:28:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Running setup.py install for mozinfo 11:28:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Running setup.py install for mozInstall 11:28:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:28:08 INFO - Running setup.py install for mozleak 11:28:09 INFO - Running setup.py install for mozlog 11:28:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Running setup.py install for moznetwork 11:28:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Running setup.py install for mozprocess 11:28:09 INFO - Running setup.py install for mozprofile 11:28:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Running setup.py install for mozrunner 11:28:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:28:09 INFO - Running setup.py install for mozscreenshot 11:28:10 INFO - Running setup.py install for moztest 11:28:10 INFO - Running setup.py install for mozversion 11:28:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:28:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:28:10 INFO - Cleaning up... 11:28:10 INFO - Return code: 0 11:28:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:10 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:10 INFO - 'HOME': '/Users/cltbld', 11:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:10 INFO - 'LOGNAME': 'cltbld', 11:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:10 INFO - 'MOZ_NO_REMOTE': '1', 11:28:10 INFO - 'NO_EM_RESTART': '1', 11:28:10 INFO - 'PAGER': '/bin/cat', 11:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:10 INFO - 'PWD': '/builds/slave/test', 11:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:10 INFO - 'SHELL': '/bin/bash', 11:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:10 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:10 INFO - 'USER': 'cltbld', 11:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:10 INFO - 'XPC_FLAGS': '0x0', 11:28:10 INFO - 'XPC_SERVICE_NAME': '0', 11:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-qHEq2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Px2x2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:10 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-XitGfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-6eKrGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zEJKMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-USvmOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-N8XS8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-02YRJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-4t3u7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Hafx9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-grj4Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OuAnSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:11 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-4r5ukK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-PAjF6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-d4hAGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-rpkXfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:12 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-54D1uB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:28:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:14 INFO - Downloading blessings-1.6.tar.gz 11:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:28:15 INFO - Installing collected packages: blessings 11:28:15 INFO - Running setup.py install for blessings 11:28:15 INFO - Successfully installed blessings 11:28:15 INFO - Cleaning up... 11:28:15 INFO - Return code: 0 11:28:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:15 INFO - Reading from file tmpfile_stdout 11:28:15 INFO - Current package versions: 11:28:15 INFO - blessings == 1.6 11:28:15 INFO - blobuploader == 1.2.4 11:28:15 INFO - docopt == 0.6.1 11:28:15 INFO - functools32 == 3.2.3-2 11:28:15 INFO - jsonschema == 2.5.1 11:28:15 INFO - manifestparser == 1.1 11:28:15 INFO - mozInstall == 1.12 11:28:15 INFO - mozcrash == 0.17 11:28:15 INFO - mozdebug == 0.1 11:28:15 INFO - mozdevice == 0.48 11:28:15 INFO - mozfile == 1.2 11:28:15 INFO - mozhttpd == 0.7 11:28:15 INFO - mozinfo == 0.9 11:28:15 INFO - mozleak == 0.1 11:28:15 INFO - mozlog == 3.4 11:28:15 INFO - moznetwork == 0.27 11:28:15 INFO - mozprocess == 0.23 11:28:15 INFO - mozprofile == 0.28 11:28:15 INFO - mozrunner == 6.12 11:28:15 INFO - mozscreenshot == 0.1 11:28:15 INFO - mozsystemmonitor == 0.3 11:28:15 INFO - moztest == 0.7 11:28:15 INFO - mozversion == 1.4 11:28:15 INFO - psutil == 3.1.1 11:28:15 INFO - requests == 1.2.3 11:28:15 INFO - wsgiref == 0.1.2 11:28:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:15 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:15 INFO - 'HOME': '/Users/cltbld', 11:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:15 INFO - 'LOGNAME': 'cltbld', 11:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:15 INFO - 'MOZ_NO_REMOTE': '1', 11:28:15 INFO - 'NO_EM_RESTART': '1', 11:28:15 INFO - 'PAGER': '/bin/cat', 11:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:15 INFO - 'PWD': '/builds/slave/test', 11:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:15 INFO - 'SHELL': '/bin/bash', 11:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:15 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:15 INFO - 'USER': 'cltbld', 11:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:15 INFO - 'XPC_FLAGS': '0x0', 11:28:15 INFO - 'XPC_SERVICE_NAME': '0', 11:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:28:16 INFO - Cleaning up... 11:28:16 INFO - Return code: 0 11:28:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:28: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')]} 11:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28: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')]} 11:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:28:16 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 0x104322490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ac05e8470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, '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.kX3HxnCax2/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.wG3XzWPHqE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:16 INFO - 'HOME': '/Users/cltbld', 11:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:16 INFO - 'LOGNAME': 'cltbld', 11:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:16 INFO - 'MOZ_NO_REMOTE': '1', 11:28:16 INFO - 'NO_EM_RESTART': '1', 11:28:16 INFO - 'PAGER': '/bin/cat', 11:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:16 INFO - 'PWD': '/builds/slave/test', 11:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:16 INFO - 'SHELL': '/bin/bash', 11:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:16 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:16 INFO - 'USER': 'cltbld', 11:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:16 INFO - 'XPC_FLAGS': '0x0', 11:28:16 INFO - 'XPC_SERVICE_NAME': '0', 11:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:28:16 INFO - Cleaning up... 11:28:16 INFO - Return code: 0 11:28:16 INFO - Running post-action listener: _resource_record_post_action 11:28:16 INFO - Running post-action listener: _start_resource_monitoring 11:28:16 INFO - Starting resource monitoring. 11:28:16 INFO - [mozharness: 2016-10-19 18:28:16.520709Z] Finished create-virtualenv step (success) 11:28:16 INFO - [mozharness: 2016-10-19 18:28:16.521712Z] Running install step. 11:28:16 INFO - Running pre-action listener: _resource_record_pre_action 11:28:16 INFO - Running main action method: install 11:28:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:28:16 INFO - Reading from file tmpfile_stdout 11:28:16 INFO - Detecting whether we're running mozinstall >=1.0... 11:28:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:28:16 INFO - Reading from file tmpfile_stdout 11:28:16 INFO - Output received: 11:28:16 INFO - Usage: mozinstall [options] installer 11:28:16 INFO - Options: 11:28:16 INFO - -h, --help show this help message and exit 11:28:16 INFO - -d DEST, --destination=DEST 11:28:16 INFO - Directory to install application into. [default: 11:28:16 INFO - "/builds/slave/test"] 11:28:16 INFO - --app=APP Application being installed. [default: firefox] 11:28:16 INFO - mkdir: /builds/slave/test/build/application 11:28:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:28:51 INFO - Reading from file tmpfile_stdout 11:28:51 INFO - Output received: 11:28:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:28:51 INFO - Running post-action listener: _resource_record_post_action 11:28:51 INFO - [mozharness: 2016-10-19 18:28:51.274218Z] Finished install step (success) 11:28:51 INFO - [mozharness: 2016-10-19 18:28:51.274377Z] Running run-tests step. 11:28:51 INFO - Running pre-action listener: _resource_record_pre_action 11:28:51 INFO - Running main action method: run_tests 11:28:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:28:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:28:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:28:51 INFO - 2016-10-19 11:28:51.288 screenresolution[1930:13218] starting screenresolution argv=screenresolution get 11:28:51 INFO - 2016-10-19 11:28:51.291 screenresolution[1930:13218] Display 0: 1600x1200x32@60 11:28:51 INFO - 2016-10-19 11:28:51.297 screenresolution[1931:13220] starting screenresolution argv=screenresolution list 11:28:51 INFO - Available Modes on Display 0 11:28:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:28:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:28:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:28:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:28:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:28:51 INFO - Intel Iris: 11:28:51 INFO - Chipset Model: Intel Iris 11:28:51 INFO - Type: GPU 11:28:51 INFO - Bus: Built-In 11:28:51 INFO - VRAM (Dynamic, Max): 1536 MB 11:28:51 INFO - Vendor: Intel (0x8086) 11:28:51 INFO - Device ID: 0x0a2e 11:28:51 INFO - Revision ID: 0x0009 11:28:51 INFO - Displays: 11:28:51 INFO - Display: 11:28:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:28:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:28:51 INFO - Display Serial Number: 1600x1200 60 11:28:51 INFO - Main Display: Yes 11:28:51 INFO - Mirror: Off 11:28:51 INFO - Online: Yes 11:28:51 INFO - Rotation: Supported 11:28:51 INFO - Return code: 0 11:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:28:51 INFO - Python 2.7.3 11:28:51 INFO - Return code: 0 11:28:51 INFO - grabbing minidump binary from tooltool 11:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:51 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 0x104136d30>, '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 0x103f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10412eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:28:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:28:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:28:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:28:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:28:51 INFO - Return code: 0 11:28:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:28:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:28:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:28:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:28:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:28:51 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/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:28:51 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/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kX3HxnCax2/Render', 11:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:51 INFO - 'HOME': '/Users/cltbld', 11:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:51 INFO - 'LOGNAME': 'cltbld', 11:28:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:28:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:51 INFO - 'MOZ_NO_REMOTE': '1', 11:28:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:28:51 INFO - 'NO_EM_RESTART': '1', 11:28:51 INFO - 'PAGER': '/bin/cat', 11:28:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:51 INFO - 'PWD': '/builds/slave/test', 11:28:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:51 INFO - 'SHELL': '/bin/bash', 11:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wG3XzWPHqE/Listeners', 11:28:51 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:51 INFO - 'USER': 'cltbld', 11:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:51 INFO - 'XPC_FLAGS': '0x0', 11:28:51 INFO - 'XPC_SERVICE_NAME': '0', 11:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:51 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/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:28:51 INFO - mozversion application_buildid: 20161019102522 11:28:51 INFO - mozversion application_changeset: 557c6a8209fe88525f3a061a9e250aa43962709a 11:28:51 INFO - mozversion application_display_name: Nightly 11:28:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:28:51 INFO - mozversion application_name: Firefox 11:28:51 INFO - mozversion application_remotingname: firefox 11:28:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:28:51 INFO - mozversion application_vendor: Mozilla 11:28:51 INFO - mozversion application_version: 52.0a1 11:28:51 INFO - mozversion platform_buildid: 20161019102522 11:28:51 INFO - mozversion platform_changeset: 557c6a8209fe88525f3a061a9e250aa43962709a 11:28:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:28:51 INFO - mozversion platform_version: 52.0a1 11:28:51 INFO - using testdate: 1476901731 11:28:51 INFO - actual date: 1476901731 11:28:51 INFO - starting webserver on 'localhost:50912' 11:28:51 INFO - SUITE-START | Running 2 tests 11:28:51 INFO - TEST-START | dromaeo_css 11:28:51 INFO - operating with platform_type : mac_ 11:28:51 INFO - Initialising browser for dromaeo_css test... 11:28:51 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpzSq4vX/profile http://localhost:50912/getInfo.html) 11:28:54 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 11:28:54 INFO - PROCESS | 1941 | colorDepth:24 11:28:54 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 11:28:54 INFO - PROCESS | 1941 | __metrics 11:28:54 INFO - PROCESS | 1941 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:28:54 INFO - PROCESS | 1941 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:28:55 INFO - TEST-INFO | 1941: exit 0 11:28:55 INFO - Browser initialized. 11:28:55 INFO - Running cycle 1/1 for dromaeo_css test... 11:28:55 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpzSq4vX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:07 INFO - PROCESS | 1943 | RSS: Main: 230506496 11:29:07 INFO - PROCESS | 1943 | 11:29:58 INFO - PROCESS | 1943 | 1476901798132 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:32:11 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50912/tests/dromaeo/cssquery-ext.html) 11:32:13 INFO - PROCESS | 1943 | RSS: Main: 355844096 11:32:13 INFO - PROCESS | 1943 | 11:35:16 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-ext.html (next: http://localhost:50912/tests/dromaeo/cssquery-jquery.html) 11:35:16 INFO - PROCESS | 1943 | RSS: Main: 371277824 11:35:16 INFO - PROCESS | 1943 | 11:38:19 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50912/tests/dromaeo/cssquery-mootools.html) 11:38:19 INFO - PROCESS | 1943 | RSS: Main: 362274816 11:38:19 INFO - PROCESS | 1943 | 11:41:22 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50912/tests/dromaeo/cssquery-prototype.html) 11:41:22 INFO - PROCESS | 1943 | RSS: Main: 331173888 11:41:22 INFO - PROCESS | 1943 | 11:44:26 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50912/tests/dromaeo/cssquery-yui.html) 11:44:26 INFO - PROCESS | 1943 | RSS: Main: 326803456 11:44:26 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:50912/tests/dromaeo/cssquery-yui.html (next: http://localhost:50912/tests/dromaeo/cssquery-dojo.html) 11:47:30 INFO - PROCESS | 1943 | RSS: Main: 596238336 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | __start_tp_report 11:47:30 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 11:47:30 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 11:47:30 INFO - PROCESS | 1943 | |i|pagename|runs| 11:47:30 INFO - PROCESS | 1943 | |0;dojo.html;2960.4967155583554;3232.240423500477;3243.0757234188263;3275.197576593735;3296.291297371065;5282.577393808496;5402.162664786964;5524.1989911462815;5578.888422231558;5612.83161505155;5521.934572958089;5604.41153678864;5609.691466969325;5611.382747897728;5616.887662246763;5084.9745751271375;5097.3373461449955;5101.54086132248;5128;5160.535551800337;5131.974340128294;5156.097682905495;5161.458046905077;5206.869828254286;5225.86935326618;4366.0831225442535;5167.4057483389515;5168.870778230536;5187.247849061884;5233.685978841282;5167.509086636764;5171.741412929338;5178.093833579146;5183.352080989876;5262.842114736564;4049.8177582008584;4221.767802770846;4915.139850526144;5095.617828662865;5121.206213036985;5469.179623056533;5500.532454741352;5527.39198688144;5541.972290138524;5572.4148964358565;6114.541409394269;6119.326874043852;6166.84582885427;6173.074038894157;6197.81406557804;6050.939490605082;6070.119832624245;6108.33519775278;6112.724927378279;6217.96891015542;4719.834805781781;4815.518448155227;5686.2039314496005;5688.630239034533;5730.942690573124;6243.032329988859;6246.906296405557;6256.4056414641445;6278.246610406781;6291.056341548804;5832.650040997553;5908.320543137508;5909.734061967136;6009.06859436788;6018.699065046818;5763.164341170591;6076.179715738319;6134.04922237054;6147.016477363601;6149.108379284979;6073.1497590337385;6087.482563982111;6092.421219984094;6102.146793736687;6129.478994285447;5974.163617093611;6027.758889644453;6103.908441373383;6117.571769976059;6146.877062458726;4824.324594556765;4832.34763306949;4867.756612169448;4879.292502587176;4906.165951788204;4326.545712700184;4335.458067741532;4357.9128417431475;4393.934090988638;4415.359772832985;5627.071533197059;5634.5210657093785;5638.633488823213;5643.01247281724;5650.208970744099;5999.430054144849;6102.246889800143;6137.232845894263;6214.720337584819;6220.191375121296;5552.111662134039;5593.244911936918;5598.076317407582;5600.439956004367;5602.075657516546;5302.522772950453;5307.442718514578;5308.416074231832;5309.150535914312;5310.176922577007;6229.1590635264565;6231.22109736292;6237.875242495124;6248.343923887926;6268.373162683695;6124.326324104256;6151.7081412902;6170.568060235741;6226;6227.532935029801;6235.812925612239;6237.5321850860455;6260.436560709376;6265.906011409741;6272.027835685476;6049.546284028804;6062.696865156637;6073.362296958756;6090.056041313604;6093.756249750138;6005.189299444519;6071.241094863142;6074.635521868702;6080.118382834553;6137.96931015342;5991.580589358703;5994.0409534474275;6002.879942401215;6021.337652858095;6046.848828779316;5812.331581868139;5968.820935371772;5978.850528736816;5980.790672326448;5993.940060599512;5609.579281553818;5687.744051517609;5764.07774756037;5772.711364431678;5791.797287094932;5847.649141051551;5885.17607534954;5893.469587737124;5934.109883517507;6014.48876845473;5755.510781583613;5768.538516918553;5769.365369809234;5777.826665200051;5807.157962095557 11:47:30 INFO - PROCESS | 1943 | |1;ext.html;457.4304990287092;508.22749420880285;519.2600544224482;526.2343290512304;547.0972894723706;26587.734122658767;31560;31626.841865790695;31659.841700791465;31680.68319316806;41791.79104104475;42668;42845;44738.77630611842;44775.55224447755;9484.66803661872;9666.661666841663;9675.27435442341;9677.661281855137;9697.272704547151;8651.65393384264;8870.689525866595;9228.077192280785;9333.53332333384;9342.486163261017;11497.712557186054;11771.882281177208;11795;11898.167128301022;11922.225055371373;11538.826917596201;11618.59334923282;11619.76760464786;11629;11635.069194464422;8595.828083438359;8793.164649358301;11301.717457063556;11320.603778867782;11422.885771142266;39761.80119099415;39840.40239396411;39878.60121398778;39954.80022599868;39954.80022599897;3305.190228394059;3372.6795954384293;3450.6376830432696;3986.5016872890887;4055.087605288816;28035.29911752202;28098.719012809815;28107.43785124286;28736;28811.991580294798;3131.3111115554543;3161.0832858471017;3167.144870884871;3176.444122278592;3294.407006738786;465243;472342;473714;474714;479961;49059.754701226266;50148.74925625422;57071;57339.42660573425;57407.71296143576;59450;60506;60772.69613651904;62006.0699089514;63535.68232158902;14797.926010370094;14843.406263749544;14861.479848205261;14862.108273503625;14926.925365373103;11610.419479026015;11630.360330181751;11671.824922626167;11699.532018719327;11700.648980530597;13538.864611353958;13804.102733322303;14737.042092263968;15103.848961510465;15213.31540080699;6707.5975441473665;6799.252082270947;6852.828679282959;6875.58746475203;6907;875.1948207648851;916.0610374366195;917.9127982841619;1015.8628585140912;1037.901399367059;28822.42355152927;28925.710742892723;29174;29952.40095198084;30166.245843854078;928.9874037299376;1036.533559898049;1070.6306324318098;1079.9784004320027;1110.838928355384;14728.484503042342;15359;15595.454159104376;15599.512328630652;15747.763783543256;615315;620099;621459;622503;624332;49012.509874900796;50057.749711251214;55793.7210313954;57286;57517.42482575121;1267.2333238391009;1316.059017802276;1363.3932899859665;1364.8157498737544;1372.5333386648588;27381.86309068442;30143.547846781876;30316.84841575778;30875.845620771754;31099.68900310968;50192.74903625459;55662;55806.162907557235;56692;57106;387799;401709;402120;402642;402844.7866409972;21531.246406375703;24586.262412127664;24621.38446538851;24651.630225546272;24829.751702482743;45557.772211138734;46078.769606151756;46641.30038049364;47862.80342991453;48240.03519929652;37951.81024094862;38329.041773955876;38362.23275534529;39081;39493.60506394899;32703.182420438727;36622.450663240605;38328.6167138325;38617.806910965264;39433.01417464586;37671;38329.80835095807;38381.80809095937;38489.422658660704;38636.80681596686;27261.500617465437;31586.52620210653;33971.66028339784;34306.656933431346;34397;22659;24769.876150619133;25068.373290667878;25444.491110178064;25506 11:47:30 INFO - PROCESS | 1943 | |2;jquery.html;350.7001513705783;372.9179580492292;374.88191219765787;380.4198597139362;399.95000624921886;14903;15009.32458039388;15683.84316156838;20241.39275821724;20650;19104.808951910476;19818.40544783655;20277.391678249674;20319.796802031975;20490.89754551229;5533.750981205855;5726.427357264271;6022.909656355137;6023.548233882454;6033.637981721089;5576.386597474275;5958.106284057383;6020.217371741667;6024.397560243962;6028.3067447243475;5671.120976248668;5863.179154918314;5979.1629171915965;5995.340512543638;5999.970000149993;5844.970775146118;5914.556408269375;5920.5559583031445;5925.20009798676;5928.703564821785;5087.56755675768;5140.974295128538;5147.1507201312115;5351.518363347317;5413.648112872651;12903.741925161537;12980.480780768758;13011.349432528335;13221.603351899459;13229.867701323055;1982.9702554461694;2115.862468939514;2261.0729600863565;2272.159301058619;2382.3567636738157;9487.241020718326;9526.523673816351;9656.27577931658;10034.749131271703;10193.694189174337;1752;1809.1587411853552;1818.1272988965238;1849.3434830635065;1886.5849513107232;74148.62925685337;122144.38927805393;123044;123105;124506.77466126658;9737.172340351008;10797.436845840853;10968.122550195965;11093.778124437466;11224.045956093823;14272.571822845332;14314.355853986595;14316.928415357857;14330.925180611495;14345.282735863166;6795.558288711218;7915.841683166304;8223.259906608433;8359.916400835913;8363.916360836314;6968.233494315621;7921.890935268952;8040.15578364275;8053.395995300376;8228.177182281725;3669.981650091786;4394.472663280363;4411.426514553087;4477.589559288814;4542.273236282245;1890.886546807196;1913.4929243750416;1931.5364312564884;1932.6327997680396;1939.3503176436052;871.8430682477089;920.663957655451;945.4610871803006;1000.5097502223858;1021.4228960637216;19971.301004464774;21256.25603103884;21329.253476128262;21395.16429014259;21544.78455215459;861.5089399042611;880.6785523284084;949.3070058857073;965.5751469353462;1001.1340190735108;10626.893731062744;10978.396188209565;11013.614523491638;11090.334579925231;11096.33421994683;525723;551565;560673.3932660621;566445;568586;87650;88944.11055889487;129038;130705;133924.66075339122;933.5845548730749;972.4845831709206;998.7353351361895;999.2206079258191;999.7500624843934;18259.81740182581;18528.258869645604;18740.78184917976;18992.31766936014;19073.523161921064;54485;54623;55162.448375517335;55889;56353.43646563482;78794.6060269695;79217;79701.20298796937;79974.600126999;80464;7831;7977.680892764224;8014.759557213293;8088.069871964799;8133.593320333841;9165.908340916505;12743.93628031854;13080.411381487665;13127.474901003852;13252.734945300846;11211.327320360804;11225.326480411202;11386.43067846621;11399.430028498376;11420.200585958903;9632.036796320312;10536.367817930948;11179.888201117885;11356.716082098013;11453.770924581628;11184.496697648463;11356.94321528387;11641.53433862636;11644.2431241969;11746.17776755619;6611.33886611335;7377.299156580117;7487.026686530716;8042.477238979448;8307.252347288773;10454.477276136315;10810.081143102925;11011.522407458579;11273.492692828679;11586.71033224176 11:47:30 INFO - PROCESS | 1943 | |3;mootools.html;1387.6114687887396;1502.8947973641843;1543.4906480861314;1787.392286622548;1962.5289930416704;7773.494722843011;8238.258556729901;8465.23812856842;8856.734297971072;8867.73396798097;7536.510126841751;8647.69733059343;8701.260392866614;8728.956355218215;8768.430052046613;2332.1487657005155;2362.751911049341;2365.7515960824126;2366.668666386707;2367.917122900699;1999.4501512084148;2366.526694661066;2369.8815059247054;2371.2649078785544;2376.8455050421667;2322.686437330964;2353.5292941411785;2360.1975328388344;2361.0319768894756;2363.83453158279;2305.5849947009533;2332.125452955142;2339.9415014624515;2344.402177444758;2350.106959355451;2322.3613506285737;2323.8954247058755;2326.93019209424;2328.7787660172426;2329.3477826208687;6456.709448074848;8695.69565065226;8719.956400218021;8817.647294108228;8861.645534178626;5143.459936706629;5331.946680533184;5336.226247194174;5346.4118946915805;5355.062863998824;3320.634730179678;3484.756067075306;3530.75284730069;3586.103474131467;3596.5684117905757;1028.603987464824;1062.389126252408;1063.973400664982;1079.5735684404694;1080.77844041972;11386.430678466044;11429.885701142965;11498.310101393943;11521;11535.077193824474;3753.6621704046765;4301.956980430218;4543.091381723669;4546.454425468899;4551.886202844956;8861.867071993925;8866.77833054166;8871.245944094695;8875.112488751203;8882.86675699865;5189.870253243699;5193.454687257784;5200.401953775288;5203.635745497779;5227.059129356752;4378.846740364072;4396.142752163361;4560.338750881104;4854.296127061556;4891.437484689306;3567.8751243706347;3636.890893273206;3658.5061016762397;3725.4784330193793;3742.120601658608;1137.4369687004985;1148.2995372822572;1152.2395219155317;1153.1812413186565;1160.2748282323548;910.1262787723726;913.7578541686458;922.4926290540176;940.0975063938696;946.6213514594217;4666.719996800135;5524.917126243109;5885.735141918624;5990.131430942576;6010.639361638316;418.89318223852723;420.6424539141766;429.89897374117055;429.91232182578125;449.0569803412807;3814.809259537012;3896.4155376693725;3908.8241029153755;4022.1151346703678;4037.1320166164414;10309;10456.947715261374;10464.581416743396;10556.947215264028;10661.147108231324;10419.895801041892;10424.739381515372;10425.374477531372;10435.739106522398;10669.786604268027;8007.7998050048045;8059.5164290142775;8210.876836847452;8215;8215.917840821516;5683.71581420919;5795.681237531808;5800.796972105956;5866.090755932837;5898.908701890164;9850.31047826674;9888.357256778287;9909.900900990899;9932.950335248566;10012.148967337858;7256.673449694882;9800.411975281506;9803.215742740422;9876.753081173028;10115.190784737057;6877.931220687729;7115.466340024415;7117.110361204849;7246.456515761444;7540.283673051052;8068.556229407439;8337.95831020841;8345;8438.987321521457;8443.49339039635;7327.816804579928;7421;7424.480286380118;7509.586972716553;7590.354819840375;7202.96398518004;7214.6077949909395;7246.6376681164675;7387.1135463744695;7396.778096657109;6779.220389655055;6931.480138989495;6944.513884028272;6946.513744038071;6950.895736563854;4902.240152776325;4954.306397104337;4981.576565991786;5009.649324547247;5059.19052951526;3864.53625564934;4477.507474177773;4521.095780843779;4576.084783043338;4625.514320996247 11:47:30 INFO - PROCESS | 1943 | |4;prototype.html;297.82130721567063;306.60601127551087;307.7276610199973;312.565533907868;315.41805369094016;5270.894582108355;5299.8410047698535;5306.283651707024;5356.8125115620915;5397.272872680744;5239.895202095965;5253.789848406059;5264.710440925747;5297.708626025567;5306.681599104047;3335.666433356669;3787.2993496203153;3794.842573015243;3919.6864250860003;4001.179758149587;3516.6636678062428;3712.1276500022473;3945.9605403946025;3958.960410395888;4025.376066709665;3083.7070478304527;3265.7387409007224;3410.493287433734;3924.941125883114;3937.803109844511;3305.3389322135426;3437.6390478999847;3896.49345585075;3949.526056873179;3971.2653159165357;2757.7104404037486;3012.2469382654335;3483.4600636901323;3502.458918076064;3507.140750516114;5166.483351664841;5169.844904652867;5182.300389447451;5182.574791932211;5214.191800270964;3928.5678575356687;4345.566482769659;4466.486354069259;4585.418030779376;4603.618914325689;5452.945470545284;5494.670319780786;5518.806841760519;5519.972400138014;5542.725173210184;3822.789746563938;3843.3082045231845;3846.4807251021034;3863.2466668999555;3891.8248678809523;4936.80252789888;4938.481459446778;5354.544863686552;5900.645961242296;5939.168516407706;3161.119134115198;3754.267917756066;3776.6034566370686;3833.6838634580886;3838.2323535292494;3139.3250451153112;3841.9423708644395;3850.8652197172964;3889.7828237904123;3924.823382947774;4469.41897553316;4515.412996310459;4534.206513860061;4553.954460455353;4554.339620754972;5241.42344342122;5292.751253651254;5477.534409575151;5542.889142217133;5568.693721845257;1128.3342827731678;1182.438341787661;1292.1536393662166;1295.2358108715903;1325.4830616059744;764.3426653078349;799.808046068951;808.5916612110964;808.599743127144;810.9229623185788;4123.443335149716;4237.597428244312;4333.804978775963;4966.826161084345;5043.949560504421;4684.601808846286;4801.255805350176;4961.627877909099;5773.612054838269;5775.451332123442;5114.360704911886;5116.360454943132;5140;5166.018456493254;5224.216367544898;2267.240474441048;2553.731858154867;2759.668080471998;2953.394554116444;2977.0919869439595;4853.810816349946;4942.5057494251005;5274.7626356814035;5313.734313284321;5814.534837213013;5473.6463320772455;5723.971380143073;5747.6838773867;5778;5813.622114562624;5189.6646509070315;5487.847552014042;5802.912956305659;5809.489532721438;5839.737211825478;4765.833195838129;5381.408045115112;5399.83800485986;5475.780968761364;5671.971640141773;5145.948540514547;5219.50414710617;5537.501624853782;5554.444555544414;5609;5124.8206312778875;5860.948667966178;5869.593466678642;5892.1161825726485;5915.024021036481;3760.7555508891833;3796.9620303797715;3852.845886164522;3878.534575850916;3882.708796840192;4748.88127796794;5725.914111288251;5746.425357464387;5811.4479124483105;5817.331006934088;5076.593872490118;5389.0299746046085;5565.443455654402;5570.6657600544095;5592;4635.145941623377;5191.792328306826;5357.544608708147;5604.187392827936;5649.887002260014;4460.576245256695;4584;5064.392272927273;5394.865128371822;5463.562914966873;4743.525647435367;5040.143175660073;5490.313710786152;5546.889062218814;5555.833325000256;4235.407043014042;5386.380566234777;5391.29913111293;5448;5479.643823151642 11:47:30 INFO - PROCESS | 1943 | |5;yui.html;744.8547533231016;769.7459838253377;781.6229116945108;782.5343920367379;832.371559472598;15032.77450838237;15747.448839290628;15749.291281892314;15750.842491575095;15830;14325.426982920671;14335.569932902004;14335.569932902004;14341.784873226883;14595.781063284032;605.3250625552499;733.4902242941158;875.4047247871445;883.9734807955772;908.1735620585268;890.995545022274;896.4845214001942;902.9142231488031;903.746950853762;904.1410659873114;931.8881734191906;959.6928982725531;960.159860122393;963.0177219236375;963.9807203855919;749.6701451361383;774.31859963232;918.6049998500642;953.8569214617794;955.6894009446958;702.585474570001;706.3536863769644;706.4313227851577;706.6784613001072;708.0547469128719;15071.773923391158;15090.320935557926;15108.924455377763;15124;15160.696786064216;13588.864111358858;13687.384067716877;13981.580552583438;14012.92993535036;14605.415783368653;10602.257841951066;12734.80897786534;12752.362381880868;12816.679583010406;12866.228026318451;4431.734095954221;4491.842785502492;4494.775261236925;4671.182543054952;4694.50707675696;103238.45142322872;105380.4730976348;105980;106663.99999999923;107643;10738.463076846127;10776.892231077745;11002.614908478325;11079.833802492969;11323.603673871376;22292.66561001586;22311;22330.55338893213;22335.218267360888;22347;7753.6123193840085;7849.921500784919;7981.72063977758;8029.076656184497;8094.635741391651;4359.694821362537;4381.846635367747;4416.072624748775;4435;4543.34121552373;3490.6858382745677;3605.531280933462;3608.7473876828894;3862.7059934921554;4076.4496792932573;723.8045727653504;737.4137560639305;741.2106106996032;746.6042997211487;749.1459735901077;504.5786768048647;550.7604192176416;620.8665136995568;643.4225705079772;702.7470110760122;8492.108328625405;8605.397622166514;9233.445993240428;9320.166165020824;10406.687799366031;570.9486146246858;672.4049216443385;685.413971054751;688.6949972997219;697.5326531224092;3976.463177471004;4224.598663126998;4347.413099231627;4377.1683380157665;4679.672422930362;131020;133137;136651;136822;138548;72182.76479901576;78209.60895195602;90399;115496.42251788855;119422.8057719429;79854;81224;106642.46678766712;106927.4653626727;108056.91943080783;8159.388045896463;9251.491167985825;9276.62893484253;9712.805743885225;9813.607455701978;59966.400335996084;60775.69612151911;61686.69156654188;63011.684941575;66327;88868;90866.54566727125;91012;92179;93216.2035138947;12861.967665221142;13212.273324967218;13232.338383081;13653.931730341284;13897.0966887152;14641.26793660334;15253.237338132827;15285.923570382078;15357.385704571692;15565.299561519532;13418.93290533541;13472.932635337153;14271.928640356731;14363.21002344881;14534.781978270123;13540.526081587097;13677.179369237878;13825.308734563432;13964.162150271017;14179.858201418267;13703.383347749175;13749.587512374645;13776.966727495277;14014.789778153132;14059.086159399607;10450.790984180427;10474.895251047697;10619.681409557727;10637.308574942605;11075.612353567587;6631.436327911989;10076;10824.945875270574;10889.292196007234;10970.561177553127 11:47:30 INFO - PROCESS | 1943 | __end_tp_report 11:47:30 INFO - PROCESS | 1943 | __start_cc_report 11:47:30 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1885 11:47:30 INFO - PROCESS | 1943 | __end_cc_report 11:47:30 INFO - PROCESS | 1943 | __startTimestamp1476902850341__endTimestamp 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | ------- Summary: start ------- 11:47:30 INFO - PROCESS | 1943 | Number of tests: 6 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#0] dojo.html Cycles:165 Average:5609.13 Median:5772.71 stddev:665.53 (11.5%) stddev-sans-first:634.31 11:47:30 INFO - PROCESS | 1943 | Values: 2960.5 3232.2 3243.1 3275.2 3296.3 5282.6 5402.2 5524.2 5578.9 5612.8 5521.9 5604.4 5609.7 5611.4 5616.9 5085.0 5097.3 5101.5 5128.0 5160.5 5132.0 5156.1 5161.5 5206.9 5225.9 4366.1 5167.4 5168.9 5187.2 5233.7 5167.5 5171.7 5178.1 5183.4 5262.8 4049.8 4221.8 4915.1 5095.6 5121.2 5469.2 5500.5 5527.4 5542.0 5572.4 6114.5 6119.3 6166.8 6173.1 6197.8 6050.9 6070.1 6108.3 6112.7 6218.0 4719.8 4815.5 5686.2 5688.6 5730.9 6243.0 6246.9 6256.4 6278.2 6291.1 5832.7 5908.3 5909.7 6009.1 6018.7 5763.2 6076.2 6134.0 6147.0 6149.1 6073.1 6087.5 6092.4 6102.1 6129.5 5974.2 6027.8 6103.9 6117.6 6146.9 4824.3 4832.3 4867.8 4879.3 4906.2 4326.5 4335.5 4357.9 4393.9 4415.4 5627.1 5634.5 5638.6 5643.0 5650.2 5999.4 6102.2 6137.2 6214.7 6220.2 5552.1 5593.2 5598.1 5600.4 5602.1 5302.5 5307.4 5308.4 5309.2 5310.2 6229.2 6231.2 6237.9 6248.3 6268.4 6124.3 6151.7 6170.6 6226.0 6227.5 6235.8 6237.5 6260.4 6265.9 6272.0 6049.5 6062.7 6073.4 6090.1 6093.8 6005.2 6071.2 6074.6 6080.1 6138.0 5991.6 5994.0 6002.9 6021.3 6046.8 5812.3 5968.8 5978.9 5980.8 5993.9 5609.6 5687.7 5764.1 5772.7 5791.8 5847.6 5885.2 5893.5 5934.1 6014.5 5755.5 5768.5 5769.4 5777.8 5807.2 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#1] ext.html Cycles:180 Average:63548.29 Median:38140.21 stddev:135090.48 (354.2%) stddev-sans-first:135386.40 11:47:30 INFO - PROCESS | 1943 | Values: 457.4 508.2 519.3 526.2 547.1 26587.7 31560.0 31626.8 31659.8 31680.7 41791.8 42668.0 42845.0 44738.8 44775.6 9484.7 9666.7 9675.3 9677.7 9697.3 8651.7 8870.7 9228.1 9333.5 9342.5 11497.7 11771.9 11795.0 11898.2 11922.2 11538.8 11618.6 11619.8 11629.0 11635.1 8595.8 8793.2 11301.7 11320.6 11422.9 39761.8 39840.4 39878.6 39954.8 39954.8 3305.2 3372.7 3450.6 3986.5 4055.1 28035.3 28098.7 28107.4 28736.0 28812.0 3131.3 3161.1 3167.1 3176.4 3294.4 465243.0 472342.0 473714.0 474714.0 479961.0 49059.8 50148.7 57071.0 57339.4 57407.7 59450.0 60506.0 60772.7 62006.1 63535.7 14797.9 14843.4 14861.5 14862.1 14926.9 11610.4 11630.4 11671.8 11699.5 11700.6 13538.9 13804.1 14737.0 15103.8 15213.3 6707.6 6799.3 6852.8 6875.6 6907.0 875.2 916.1 917.9 1015.9 1037.9 28822.4 28925.7 29174.0 29952.4 30166.2 929.0 1036.5 1070.6 1080.0 1110.8 14728.5 15359.0 15595.5 15599.5 15747.8 615315.0 620099.0 621459.0 622503.0 624332.0 49012.5 50057.7 55793.7 57286.0 57517.4 1267.2 1316.1 1363.4 1364.8 1372.5 27381.9 30143.5 30316.8 30875.8 31099.7 50192.7 55662.0 55806.2 56692.0 57106.0 387799.0 401709.0 402120.0 402642.0 402844.8 21531.2 24586.3 24621.4 24651.6 24829.8 45557.8 46078.8 46641.3 47862.8 48240.0 37951.8 38329.0 38362.2 39081.0 39493.6 32703.2 36622.5 38328.6 38617.8 39433.0 37671.0 38329.8 38381.8 38489.4 38636.8 27261.5 31586.5 33971.7 34306.7 34397.0 22659.0 24769.9 25068.4 25444.5 25506.0 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#2] jquery.html Cycles:180 Average:32878.32 Median:2021.45 stddev:92801.44 (4590.8%) stddev-sans-first:93029.63 11:47:30 INFO - PROCESS | 1943 | Values: 350.7 372.9 374.9 380.4 400.0 14903.0 15009.3 15683.8 20241.4 20650.0 19104.8 19818.4 20277.4 20319.8 20490.9 5533.8 5726.4 6022.9 6023.5 6033.6 5576.4 5958.1 6020.2 6024.4 6028.3 5671.1 5863.2 5979.2 5995.3 6000.0 5845.0 5914.6 5920.6 5925.2 5928.7 5087.6 5141.0 5147.2 5351.5 5413.6 12903.7 12980.5 13011.3 13221.6 13229.9 1983.0 2115.9 2261.1 2272.2 2382.4 9487.2 9526.5 9656.3 10034.7 10193.7 1752.0 1809.2 1818.1 1849.3 1886.6 74148.6 122144.4 123044.0 123105.0 124506.8 9737.2 10797.4 10968.1 11093.8 11224.0 14272.6 14314.4 14316.9 14330.9 14345.3 6795.6 7915.8 8223.3 8359.9 8363.9 6968.2 7921.9 8040.2 8053.4 8228.2 3670.0 4394.5 4411.4 4477.6 4542.3 1890.9 1913.5 1931.5 1932.6 1939.4 871.8 920.7 945.5 1000.5 1021.4 19971.3 21256.3 21329.3 21395.2 21544.8 861.5 880.7 949.3 965.6 1001.1 10626.9 10978.4 11013.6 11090.3 11096.3 525723.0 551565.0 560673.4 566445.0 568586.0 87650.0 88944.1 129038.0 130705.0 133924.7 933.6 972.5 998.7 999.2 999.8 18259.8 18528.3 18740.8 18992.3 19073.5 54485.0 54623.0 55162.4 55889.0 56353.4 78794.6 79217.0 79701.2 79974.6 80464.0 7831.0 7977.7 8014.8 8088.1 8133.6 9165.9 12743.9 13080.4 13127.5 13252.7 11211.3 11225.3 11386.4 11399.4 11420.2 9632.0 10536.4 11179.9 11356.7 11453.8 11184.5 11356.9 11641.5 11644.2 11746.2 6611.3 7377.3 7487.0 8042.5 8307.3 10454.5 10810.1 11011.5 11273.5 11586.7 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#3] mootools.html Cycles:180 Average:5538.64 Median:4760.51 stddev:3128.27 (65.7%) stddev-sans-first:3121.50 11:47:30 INFO - PROCESS | 1943 | Values: 1387.6 1502.9 1543.5 1787.4 1962.5 7773.5 8238.3 8465.2 8856.7 8867.7 7536.5 8647.7 8701.3 8729.0 8768.4 2332.1 2362.8 2365.8 2366.7 2367.9 1999.5 2366.5 2369.9 2371.3 2376.8 2322.7 2353.5 2360.2 2361.0 2363.8 2305.6 2332.1 2339.9 2344.4 2350.1 2322.4 2323.9 2326.9 2328.8 2329.3 6456.7 8695.7 8720.0 8817.6 8861.6 5143.5 5331.9 5336.2 5346.4 5355.1 3320.6 3484.8 3530.8 3586.1 3596.6 1028.6 1062.4 1064.0 1079.6 1080.8 11386.4 11429.9 11498.3 11521.0 11535.1 3753.7 4302.0 4543.1 4546.5 4551.9 8861.9 8866.8 8871.2 8875.1 8882.9 5189.9 5193.5 5200.4 5203.6 5227.1 4378.8 4396.1 4560.3 4854.3 4891.4 3567.9 3636.9 3658.5 3725.5 3742.1 1137.4 1148.3 1152.2 1153.2 1160.3 910.1 913.8 922.5 940.1 946.6 4666.7 5524.9 5885.7 5990.1 6010.6 418.9 420.6 429.9 429.9 449.1 3814.8 3896.4 3908.8 4022.1 4037.1 10309.0 10456.9 10464.6 10556.9 10661.1 10419.9 10424.7 10425.4 10435.7 10669.8 8007.8 8059.5 8210.9 8215.0 8215.9 5683.7 5795.7 5800.8 5866.1 5898.9 9850.3 9888.4 9909.9 9933.0 10012.1 7256.7 9800.4 9803.2 9876.8 10115.2 6877.9 7115.5 7117.1 7246.5 7540.3 8068.6 8338.0 8345.0 8439.0 8443.5 7327.8 7421.0 7424.5 7509.6 7590.4 7203.0 7214.6 7246.6 7387.1 7396.8 6779.2 6931.5 6944.5 6946.5 6950.9 4902.2 4954.3 4981.6 5009.6 5059.2 3864.5 4477.5 4521.1 4576.1 4625.5 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#4] prototype.html Cycles:180 Average:4415.94 Median:5070.49 stddev:1391.40 (27.4%) stddev-sans-first:1360.54 11:47:30 INFO - PROCESS | 1943 | Values: 297.8 306.6 307.7 312.6 315.4 5270.9 5299.8 5306.3 5356.8 5397.3 5239.9 5253.8 5264.7 5297.7 5306.7 3335.7 3787.3 3794.8 3919.7 4001.2 3516.7 3712.1 3946.0 3959.0 4025.4 3083.7 3265.7 3410.5 3924.9 3937.8 3305.3 3437.6 3896.5 3949.5 3971.3 2757.7 3012.2 3483.5 3502.5 3507.1 5166.5 5169.8 5182.3 5182.6 5214.2 3928.6 4345.6 4466.5 4585.4 4603.6 5452.9 5494.7 5518.8 5520.0 5542.7 3822.8 3843.3 3846.5 3863.2 3891.8 4936.8 4938.5 5354.5 5900.6 5939.2 3161.1 3754.3 3776.6 3833.7 3838.2 3139.3 3841.9 3850.9 3889.8 3924.8 4469.4 4515.4 4534.2 4554.0 4554.3 5241.4 5292.8 5477.5 5542.9 5568.7 1128.3 1182.4 1292.2 1295.2 1325.5 764.3 799.8 808.6 808.6 810.9 4123.4 4237.6 4333.8 4966.8 5043.9 4684.6 4801.3 4961.6 5773.6 5775.5 5114.4 5116.4 5140.0 5166.0 5224.2 2267.2 2553.7 2759.7 2953.4 2977.1 4853.8 4942.5 5274.8 5313.7 5814.5 5473.6 5724.0 5747.7 5778.0 5813.6 5189.7 5487.8 5802.9 5809.5 5839.7 4765.8 5381.4 5399.8 5475.8 5672.0 5145.9 5219.5 5537.5 5554.4 5609.0 5124.8 5860.9 5869.6 5892.1 5915.0 3760.8 3797.0 3852.8 3878.5 3882.7 4748.9 5725.9 5746.4 5811.4 5817.3 5076.6 5389.0 5565.4 5570.7 5592.0 4635.1 5191.8 5357.5 5604.2 5649.9 4460.6 4584.0 5064.4 5394.9 5463.6 4743.5 5040.1 5490.3 5546.9 5555.8 4235.4 5386.4 5391.3 5448.0 5479.6 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - PROCESS | 1943 | [#5] yui.html Cycles:180 Average:23120.12 Median:4150.52 stddev:35271.71 (849.8%) stddev-sans-first:35330.65 11:47:30 INFO - PROCESS | 1943 | Values: 744.9 769.7 781.6 782.5 832.4 15032.8 15747.4 15749.3 15750.8 15830.0 14325.4 14335.6 14335.6 14341.8 14595.8 605.3 733.5 875.4 884.0 908.2 891.0 896.5 902.9 903.7 904.1 931.9 959.7 960.2 963.0 964.0 749.7 774.3 918.6 953.9 955.7 702.6 706.4 706.4 706.7 708.1 15071.8 15090.3 15108.9 15124.0 15160.7 13588.9 13687.4 13981.6 14012.9 14605.4 10602.3 12734.8 12752.4 12816.7 12866.2 4431.7 4491.8 4494.8 4671.2 4694.5 103238.5 105380.5 105980.0 106664.0 107643.0 10738.5 10776.9 11002.6 11079.8 11323.6 22292.7 22311.0 22330.6 22335.2 22347.0 7753.6 7849.9 7981.7 8029.1 8094.6 4359.7 4381.8 4416.1 4435.0 4543.3 3490.7 3605.5 3608.7 3862.7 4076.4 723.8 737.4 741.2 746.6 749.1 504.6 550.8 620.9 643.4 702.7 8492.1 8605.4 9233.4 9320.2 10406.7 570.9 672.4 685.4 688.7 697.5 3976.5 4224.6 4347.4 4377.2 4679.7 131020.0 133137.0 136651.0 136822.0 138548.0 72182.8 78209.6 90399.0 115496.4 119422.8 79854.0 81224.0 106642.5 106927.5 108056.9 8159.4 9251.5 9276.6 9712.8 9813.6 59966.4 60775.7 61686.7 63011.7 66327.0 88868.0 90866.5 91012.0 92179.0 93216.2 12862.0 13212.3 13232.3 13653.9 13897.1 14641.3 15253.2 15285.9 15357.4 15565.3 13418.9 13472.9 14271.9 14363.2 14534.8 13540.5 13677.2 13825.3 13964.2 14179.9 13703.4 13749.6 13777.0 14014.8 14059.1 10450.8 10474.9 10619.7 10637.3 11075.6 6631.4 10076.0 10824.9 10889.3 10970.6 11:47:30 INFO - PROCESS | 1943 | -------- Summary: end -------- 11:47:30 INFO - PROCESS | 1943 | 11:47:30 INFO - TEST-INFO | 1943: exit 0 11:47:30 INFO - TEST-OK | dromaeo_css | took 1119127ms 11:47:30 INFO - TEST-START | kraken 11:47:30 INFO - operating with platform_type : mac_ 11:47:30 INFO - Initialising browser for kraken test... 11:47:31 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpmmqH_G/profile http://localhost:50912/getInfo.html) 11:47:33 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 11:47:33 INFO - PROCESS | 1947 | colorDepth:24 11:47:33 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 11:47:33 INFO - PROCESS | 1947 | __metrics 11:47:33 INFO - PROCESS | 1947 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:47:33 INFO - PROCESS | 1947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:47:34 INFO - TEST-INFO | 1947: exit 0 11:47:34 INFO - Browser initialized. 11:47:34 INFO - Running cycle 1/1 for kraken test... 11:47:34 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpmmqH_G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:47:46 INFO - PROCESS | 1948 | RSS: Main: 239120384 11:47:46 INFO - PROCESS | 1948 | 11:47:48 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection) 11:47:49 INFO - PROCESS | 1948 | RSS: Main: 290582528 11:47:49 INFO - PROCESS | 1948 | 11:47:52 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-dft) 11:47:53 INFO - PROCESS | 1948 | RSS: Main: 289558528 11:47:53 INFO - PROCESS | 1948 | 11:47:56 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-fft) 11:47:57 INFO - PROCESS | 1948 | RSS: Main: 386187264 11:47:57 INFO - PROCESS | 1948 | 11:47:59 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator) 11:47:59 INFO - PROCESS | 1948 | RSS: Main: 285556736 11:47:59 INFO - PROCESS | 1948 | 11:48:01 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:02 INFO - PROCESS | 1948 | RSS: Main: 290365440 11:48:02 INFO - PROCESS | 1948 | 11:48:05 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:05 INFO - PROCESS | 1948 | RSS: Main: 328642560 11:48:05 INFO - PROCESS | 1948 | 11:48:08 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:09 INFO - PROCESS | 1948 | RSS: Main: 329801728 11:48:09 INFO - PROCESS | 1948 | 11:48:11 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial) 11:48:12 INFO - PROCESS | 1948 | RSS: Main: 325345280 11:48:12 INFO - PROCESS | 1948 | 11:48:14 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:14 INFO - PROCESS | 1948 | RSS: Main: 302944256 11:48:14 INFO - PROCESS | 1948 | 11:48:16 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:17 INFO - PROCESS | 1948 | RSS: Main: 307310592 11:48:17 INFO - PROCESS | 1948 | 11:48:19 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:48:20 INFO - PROCESS | 1948 | RSS: Main: 311173120 11:48:20 INFO - PROCESS | 1948 | 11:48:23 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:48:23 INFO - PROCESS | 1948 | RSS: Main: 326373376 11:48:23 INFO - PROCESS | 1948 | 11:48:26 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:48:27 INFO - PROCESS | 1948 | RSS: Main: 319541248 11:48:27 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:50912/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50912/tests/kraken/driver.html?testName=ai-astar) 11:48:29 INFO - PROCESS | 1948 | RSS: Main: 363560960 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | __start_tp_report 11:48:29 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 11:48:29 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 11:48:29 INFO - PROCESS | 1948 | |i|pagename|runs| 11:48:29 INFO - PROCESS | 1948 | |0;ai-astar;114;114;115;115;113;115;113;112;115;114 11:48:29 INFO - PROCESS | 1948 | |1;audio-beat-detection;149;112;111;150;136;135;137;100;172;135 11:48:29 INFO - PROCESS | 1948 | |2;audio-dft;184;183;184;186;186;182;182;183;182;183 11:48:29 INFO - PROCESS | 1948 | |3;audio-fft;77;57;59;89;61;59;58;58;80;56 11:48:29 INFO - PROCESS | 1948 | |4;audio-oscillator;84;72;91;68;68;69;91;67;68;91 11:48:29 INFO - PROCESS | 1948 | |5;imaging-gaussian-blur;89;86;89;87;89;86;89;87;88;85 11:48:29 INFO - PROCESS | 1948 | |6;imaging-darkroom;94;93;94;93;92;92;93;93;94;94 11:48:29 INFO - PROCESS | 1948 | |7;imaging-desaturate;82;83;80;78;84;84;83;84;82;82 11:48:29 INFO - PROCESS | 1948 | |8;json-parse-financial;58;56;54;53;53;55;54;54;54;53 11:48:29 INFO - PROCESS | 1948 | |9;json-stringify-tinderbox;71;70;65;76;73;69;70;70;70;71 11:48:29 INFO - PROCESS | 1948 | |10;stanford-crypto-aes;87;97;104;102;87;84;84;89;86;89 11:48:29 INFO - PROCESS | 1948 | |11;stanford-crypto-ccm;144;147;144;146;143;150;160;141;177;151 11:48:29 INFO - PROCESS | 1948 | |12;stanford-crypto-pbkdf2;236;184;171;171;170;170;171;168;170;172 11:48:29 INFO - PROCESS | 1948 | |13;stanford-crypto-sha256-iterative;69;70;72;84;85;84;82;70;69;83 11:48:29 INFO - PROCESS | 1948 | __end_tp_report 11:48:29 INFO - PROCESS | 1948 | __start_cc_report 11:48:29 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,2105 11:48:29 INFO - PROCESS | 1948 | __end_cc_report 11:48:29 INFO - PROCESS | 1948 | __startTimestamp1476902909617__endTimestamp 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | ------- Summary: start ------- 11:48:29 INFO - PROCESS | 1948 | Number of tests: 14 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.50 stddev:1.05 (0.9%) stddev-sans-first:1.12 11:48:29 INFO - PROCESS | 1948 | Values: 114.0 114.0 115.0 115.0 113.0 115.0 113.0 112.0 115.0 114.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:136.50 stddev:21.36 (15.7%) stddev-sans-first:21.93 11:48:29 INFO - PROCESS | 1948 | Values: 149.0 112.0 111.0 150.0 136.0 135.0 137.0 100.0 172.0 135.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.51 (0.8%) stddev-sans-first:1.59 11:48:29 INFO - PROCESS | 1948 | Values: 184.0 183.0 184.0 186.0 186.0 182.0 182.0 183.0 182.0 183.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:11.90 (19.8%) stddev-sans-first:11.86 11:48:29 INFO - PROCESS | 1948 | Values: 77.0 57.0 59.0 89.0 61.0 59.0 58.0 58.0 80.0 56.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.00 stddev:10.90 (14.0%) stddev-sans-first:11.25 11:48:29 INFO - PROCESS | 1948 | Values: 84.0 72.0 91.0 68.0 68.0 69.0 91.0 67.0 68.0 91.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.50 stddev:1.51 (1.7%) stddev-sans-first:1.50 11:48:29 INFO - PROCESS | 1948 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 89.0 87.0 88.0 85.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.50 stddev:0.79 (0.8%) stddev-sans-first:0.78 11:48:29 INFO - PROCESS | 1948 | Values: 94.0 93.0 94.0 93.0 92.0 92.0 93.0 93.0 94.0 94.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#7] imaging-desaturate Cycles:10 Average:82.20 Median:83.00 stddev:1.93 (2.3%) stddev-sans-first:2.05 11:48:29 INFO - PROCESS | 1948 | Values: 82.0 83.0 80.0 78.0 84.0 84.0 83.0 84.0 82.0 82.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:1.58 (2.9%) stddev-sans-first:1.00 11:48:29 INFO - PROCESS | 1948 | Values: 58.0 56.0 54.0 53.0 53.0 55.0 54.0 54.0 54.0 53.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:2.80 (4.0%) stddev-sans-first:2.96 11:48:29 INFO - PROCESS | 1948 | Values: 71.0 70.0 65.0 76.0 73.0 69.0 70.0 70.0 70.0 71.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.00 stddev:7.37 (8.5%) stddev-sans-first:7.68 11:48:29 INFO - PROCESS | 1948 | Values: 87.0 97.0 104.0 102.0 87.0 84.0 84.0 89.0 86.0 89.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:148.50 stddev:10.83 (7.3%) stddev-sans-first:11.25 11:48:29 INFO - PROCESS | 1948 | Values: 144.0 147.0 144.0 146.0 143.0 150.0 160.0 141.0 177.0 151.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:171.00 stddev:20.75 (12.1%) stddev-sans-first:4.68 11:48:29 INFO - PROCESS | 1948 | Values: 236.0 184.0 171.0 171.0 170.0 170.0 171.0 168.0 170.0 172.0 11:48:29 INFO - PROCESS | 1948 | 11:48:29 INFO - PROCESS | 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:82.50 stddev:7.25 (8.8%) stddev-sans-first:7.12 11:48:29 INFO - PROCESS | 1948 | Values: 69.0 70.0 72.0 84.0 85.0 84.0 82.0 70.0 69.0 83.0 11:48:29 INFO - PROCESS | 1948 | -------- Summary: end -------- 11:48:29 INFO - PROCESS | 1948 | 11:48:30 INFO - TEST-INFO | 1948: exit 0 11:48:30 INFO - TEST-OK | kraken | took 59526ms 11:48:30 INFO - SUITE-END | took 1178s 11:48:30 INFO - Completed test suite (00:19:38) 11:48:30 INFO - javascript benchmark 11:48:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2960.4967155583554, 3232.240423500477, 3243.0757234188263, 3275.197576593735, 3296.291297371065, 5282.577393808496, 5402.162664786964, 5524.1989911462815, 5578.888422231558, 5612.83161505155, 5521.934572958089, 5604.41153678864, 5609.691466969325, 5611.382747897728, 5616.887662246763, 5084.9745751271375, 5097.3373461449955, 5101.54086132248, 5128.0, 5160.535551800337, 5131.974340128294, 5156.097682905495, 5161.458046905077, 5206.869828254286, 5225.86935326618, 4366.0831225442535, 5167.4057483389515, 5168.870778230536, 5187.247849061884, 5233.685978841282, 5167.509086636764, 5171.741412929338, 5178.093833579146, 5183.352080989876, 5262.842114736564, 4049.8177582008584, 4221.767802770846, 4915.139850526144, 5095.617828662865, 5121.206213036985, 5469.179623056533, 5500.532454741352, 5527.39198688144, 5541.972290138524, 5572.4148964358565, 6114.541409394269, 6119.326874043852, 6166.84582885427, 6173.074038894157, 6197.81406557804, 6050.939490605082, 6070.119832624245, 6108.33519775278, 6112.724927378279, 6217.96891015542, 4719.834805781781, 4815.518448155227, 5686.2039314496005, 5688.630239034533, 5730.942690573124, 6243.032329988859, 6246.906296405557, 6256.4056414641445, 6278.246610406781, 6291.056341548804, 5832.650040997553, 5908.320543137508, 5909.734061967136, 6009.06859436788, 6018.699065046818, 5763.164341170591, 6076.179715738319, 6134.04922237054, 6147.016477363601, 6149.108379284979, 6073.1497590337385, 6087.482563982111, 6092.421219984094, 6102.146793736687, 6129.478994285447, 5974.163617093611, 6027.758889644453, 6103.908441373383, 6117.571769976059, 6146.877062458726, 4824.324594556765, 4832.34763306949, 4867.756612169448, 4879.292502587176, 4906.165951788204, 4326.545712700184, 4335.458067741532, 4357.9128417431475, 4393.934090988638, 4415.359772832985, 5627.071533197059, 5634.5210657093785, 5638.633488823213, 5643.01247281724, 5650.208970744099, 5999.430054144849, 6102.246889800143, 6137.232845894263, 6214.720337584819, 6220.191375121296, 5552.111662134039, 5593.244911936918, 5598.076317407582, 5600.439956004367, 5602.075657516546, 5302.522772950453, 5307.442718514578, 5308.416074231832, 5309.150535914312, 5310.176922577007, 6229.1590635264565, 6231.22109736292, 6237.875242495124, 6248.343923887926, 6268.373162683695, 6124.326324104256, 6151.7081412902, 6170.568060235741, 6226.0, 6227.532935029801, 6235.812925612239, 6237.5321850860455, 6260.436560709376, 6265.906011409741, 6272.027835685476, 6049.546284028804, 6062.696865156637, 6073.362296958756, 6090.056041313604, 6093.756249750138, 6005.189299444519, 6071.241094863142, 6074.635521868702, 6080.118382834553, 6137.96931015342, 5991.580589358703, 5994.0409534474275, 6002.879942401215, 6021.337652858095, 6046.848828779316, 5812.331581868139, 5968.820935371772, 5978.850528736816, 5980.790672326448, 5993.940060599512, 5609.579281553818, 5687.744051517609, 5764.07774756037, 5772.711364431678, 5791.797287094932, 5847.649141051551, 5885.17607534954, 5893.469587737124, 5934.109883517507, 6014.48876845473, 5755.510781583613, 5768.538516918553, 5769.365369809234, 5777.826665200051, 5807.157962095557], "value": 5564.322153997281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.4304990287092, 508.22749420880285, 519.2600544224482, 526.2343290512304, 547.0972894723706, 26587.734122658767, 31560.0, 31626.841865790695, 31659.841700791465, 31680.68319316806, 41791.79104104475, 42668.0, 42845.0, 44738.77630611842, 44775.55224447755, 9484.66803661872, 9666.661666841663, 9675.27435442341, 9677.661281855137, 9697.272704547151, 8651.65393384264, 8870.689525866595, 9228.077192280785, 9333.53332333384, 9342.486163261017, 11497.712557186054, 11771.882281177208, 11795.0, 11898.167128301022, 11922.225055371373, 11538.826917596201, 11618.59334923282, 11619.76760464786, 11629.0, 11635.069194464422, 8595.828083438359, 8793.164649358301, 11301.717457063556, 11320.603778867782, 11422.885771142266, 39761.80119099415, 39840.40239396411, 39878.60121398778, 39954.80022599868, 39954.80022599897, 3305.190228394059, 3372.6795954384293, 3450.6376830432696, 3986.5016872890887, 4055.087605288816, 28035.29911752202, 28098.719012809815, 28107.43785124286, 28736.0, 28811.991580294798, 3131.3111115554543, 3161.0832858471017, 3167.144870884871, 3176.444122278592, 3294.407006738786, 465243.0, 472342.0, 473714.0, 474714.0, 479961.0, 49059.754701226266, 50148.74925625422, 57071.0, 57339.42660573425, 57407.71296143576, 59450.0, 60506.0, 60772.69613651904, 62006.0699089514, 63535.68232158902, 14797.926010370094, 14843.406263749544, 14861.479848205261, 14862.108273503625, 14926.925365373103, 11610.419479026015, 11630.360330181751, 11671.824922626167, 11699.532018719327, 11700.648980530597, 13538.864611353958, 13804.102733322303, 14737.042092263968, 15103.848961510465, 15213.31540080699, 6707.5975441473665, 6799.252082270947, 6852.828679282959, 6875.58746475203, 6907.0, 875.1948207648851, 916.0610374366195, 917.9127982841619, 1015.8628585140912, 1037.901399367059, 28822.42355152927, 28925.710742892723, 29174.0, 29952.40095198084, 30166.245843854078, 928.9874037299376, 1036.533559898049, 1070.6306324318098, 1079.9784004320027, 1110.838928355384, 14728.484503042342, 15359.0, 15595.454159104376, 15599.512328630652, 15747.763783543256, 615315.0, 620099.0, 621459.0, 622503.0, 624332.0, 49012.509874900796, 50057.749711251214, 55793.7210313954, 57286.0, 57517.42482575121, 1267.2333238391009, 1316.059017802276, 1363.3932899859665, 1364.8157498737544, 1372.5333386648588, 27381.86309068442, 30143.547846781876, 30316.84841575778, 30875.845620771754, 31099.68900310968, 50192.74903625459, 55662.0, 55806.162907557235, 56692.0, 57106.0, 387799.0, 401709.0, 402120.0, 402642.0, 402844.7866409972, 21531.246406375703, 24586.262412127664, 24621.38446538851, 24651.630225546272, 24829.751702482743, 45557.772211138734, 46078.769606151756, 46641.30038049364, 47862.80342991453, 48240.03519929652, 37951.81024094862, 38329.041773955876, 38362.23275534529, 39081.0, 39493.60506394899, 32703.182420438727, 36622.450663240605, 38328.6167138325, 38617.806910965264, 39433.01417464586, 37671.0, 38329.80835095807, 38381.80809095937, 38489.422658660704, 38636.80681596686, 27261.500617465437, 31586.52620210653, 33971.66028339784, 34306.656933431346, 34397.0, 22659.0, 24769.876150619133, 25068.373290667878, 25444.491110178064, 25506.0], "value": 19312.325529354945, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7001513705783, 372.9179580492292, 374.88191219765787, 380.4198597139362, 399.95000624921886, 14903.0, 15009.32458039388, 15683.84316156838, 20241.39275821724, 20650.0, 19104.808951910476, 19818.40544783655, 20277.391678249674, 20319.796802031975, 20490.89754551229, 5533.750981205855, 5726.427357264271, 6022.909656355137, 6023.548233882454, 6033.637981721089, 5576.386597474275, 5958.106284057383, 6020.217371741667, 6024.397560243962, 6028.3067447243475, 5671.120976248668, 5863.179154918314, 5979.1629171915965, 5995.340512543638, 5999.970000149993, 5844.970775146118, 5914.556408269375, 5920.5559583031445, 5925.20009798676, 5928.703564821785, 5087.56755675768, 5140.974295128538, 5147.1507201312115, 5351.518363347317, 5413.648112872651, 12903.741925161537, 12980.480780768758, 13011.349432528335, 13221.603351899459, 13229.867701323055, 1982.9702554461694, 2115.862468939514, 2261.0729600863565, 2272.159301058619, 2382.3567636738157, 9487.241020718326, 9526.523673816351, 9656.27577931658, 10034.749131271703, 10193.694189174337, 1752.0, 1809.1587411853552, 1818.1272988965238, 1849.3434830635065, 1886.5849513107232, 74148.62925685337, 122144.38927805393, 123044.0, 123105.0, 124506.77466126658, 9737.172340351008, 10797.436845840853, 10968.122550195965, 11093.778124437466, 11224.045956093823, 14272.571822845332, 14314.355853986595, 14316.928415357857, 14330.925180611495, 14345.282735863166, 6795.558288711218, 7915.841683166304, 8223.259906608433, 8359.916400835913, 8363.916360836314, 6968.233494315621, 7921.890935268952, 8040.15578364275, 8053.395995300376, 8228.177182281725, 3669.981650091786, 4394.472663280363, 4411.426514553087, 4477.589559288814, 4542.273236282245, 1890.886546807196, 1913.4929243750416, 1931.5364312564884, 1932.6327997680396, 1939.3503176436052, 871.8430682477089, 920.663957655451, 945.4610871803006, 1000.5097502223858, 1021.4228960637216, 19971.301004464774, 21256.25603103884, 21329.253476128262, 21395.16429014259, 21544.78455215459, 861.5089399042611, 880.6785523284084, 949.3070058857073, 965.5751469353462, 1001.1340190735108, 10626.893731062744, 10978.396188209565, 11013.614523491638, 11090.334579925231, 11096.33421994683, 525723.0, 551565.0, 560673.3932660621, 566445.0, 568586.0, 87650.0, 88944.11055889487, 129038.0, 130705.0, 133924.66075339122, 933.5845548730749, 972.4845831709206, 998.7353351361895, 999.2206079258191, 999.7500624843934, 18259.81740182581, 18528.258869645604, 18740.78184917976, 18992.31766936014, 19073.523161921064, 54485.0, 54623.0, 55162.448375517335, 55889.0, 56353.43646563482, 78794.6060269695, 79217.0, 79701.20298796937, 79974.600126999, 80464.0, 7831.0, 7977.680892764224, 8014.759557213293, 8088.069871964799, 8133.593320333841, 9165.908340916505, 12743.93628031854, 13080.411381487665, 13127.474901003852, 13252.734945300846, 11211.327320360804, 11225.326480411202, 11386.43067846621, 11399.430028498376, 11420.200585958903, 9632.036796320312, 10536.367817930948, 11179.888201117885, 11356.716082098013, 11453.770924581628, 11184.496697648463, 11356.94321528387, 11641.53433862636, 11644.2431241969, 11746.17776755619, 6611.33886611335, 7377.299156580117, 7487.026686530716, 8042.477238979448, 8307.252347288773, 10454.477276136315, 10810.081143102925, 11011.522407458579, 11273.492692828679, 11586.71033224176], "value": 9182.496288326776, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.6114687887396, 1502.8947973641843, 1543.4906480861314, 1787.392286622548, 1962.5289930416704, 7773.494722843011, 8238.258556729901, 8465.23812856842, 8856.734297971072, 8867.73396798097, 7536.510126841751, 8647.69733059343, 8701.260392866614, 8728.956355218215, 8768.430052046613, 2332.1487657005155, 2362.751911049341, 2365.7515960824126, 2366.668666386707, 2367.917122900699, 1999.4501512084148, 2366.526694661066, 2369.8815059247054, 2371.2649078785544, 2376.8455050421667, 2322.686437330964, 2353.5292941411785, 2360.1975328388344, 2361.0319768894756, 2363.83453158279, 2305.5849947009533, 2332.125452955142, 2339.9415014624515, 2344.402177444758, 2350.106959355451, 2322.3613506285737, 2323.8954247058755, 2326.93019209424, 2328.7787660172426, 2329.3477826208687, 6456.709448074848, 8695.69565065226, 8719.956400218021, 8817.647294108228, 8861.645534178626, 5143.459936706629, 5331.946680533184, 5336.226247194174, 5346.4118946915805, 5355.062863998824, 3320.634730179678, 3484.756067075306, 3530.75284730069, 3586.103474131467, 3596.5684117905757, 1028.603987464824, 1062.389126252408, 1063.973400664982, 1079.5735684404694, 1080.77844041972, 11386.430678466044, 11429.885701142965, 11498.310101393943, 11521.0, 11535.077193824474, 3753.6621704046765, 4301.956980430218, 4543.091381723669, 4546.454425468899, 4551.886202844956, 8861.867071993925, 8866.77833054166, 8871.245944094695, 8875.112488751203, 8882.86675699865, 5189.870253243699, 5193.454687257784, 5200.401953775288, 5203.635745497779, 5227.059129356752, 4378.846740364072, 4396.142752163361, 4560.338750881104, 4854.296127061556, 4891.437484689306, 3567.8751243706347, 3636.890893273206, 3658.5061016762397, 3725.4784330193793, 3742.120601658608, 1137.4369687004985, 1148.2995372822572, 1152.2395219155317, 1153.1812413186565, 1160.2748282323548, 910.1262787723726, 913.7578541686458, 922.4926290540176, 940.0975063938696, 946.6213514594217, 4666.719996800135, 5524.917126243109, 5885.735141918624, 5990.131430942576, 6010.639361638316, 418.89318223852723, 420.6424539141766, 429.89897374117055, 429.91232182578125, 449.0569803412807, 3814.809259537012, 3896.4155376693725, 3908.8241029153755, 4022.1151346703678, 4037.1320166164414, 10309.0, 10456.947715261374, 10464.581416743396, 10556.947215264028, 10661.147108231324, 10419.895801041892, 10424.739381515372, 10425.374477531372, 10435.739106522398, 10669.786604268027, 8007.7998050048045, 8059.5164290142775, 8210.876836847452, 8215.0, 8215.917840821516, 5683.71581420919, 5795.681237531808, 5800.796972105956, 5866.090755932837, 5898.908701890164, 9850.31047826674, 9888.357256778287, 9909.900900990899, 9932.950335248566, 10012.148967337858, 7256.673449694882, 9800.411975281506, 9803.215742740422, 9876.753081173028, 10115.190784737057, 6877.931220687729, 7115.466340024415, 7117.110361204849, 7246.456515761444, 7540.283673051052, 8068.556229407439, 8337.95831020841, 8345.0, 8438.987321521457, 8443.49339039635, 7327.816804579928, 7421.0, 7424.480286380118, 7509.586972716553, 7590.354819840375, 7202.96398518004, 7214.6077949909395, 7246.6376681164675, 7387.1135463744695, 7396.778096657109, 6779.220389655055, 6931.480138989495, 6944.513884028272, 6946.513744038071, 6950.895736563854, 4902.240152776325, 4954.306397104337, 4981.576565991786, 5009.649324547247, 5059.19052951526, 3864.53625564934, 4477.507474177773, 4521.095780843779, 4576.084783043338, 4625.514320996247], "value": 4381.41006205711, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.82130721567063, 306.60601127551087, 307.7276610199973, 312.565533907868, 315.41805369094016, 5270.894582108355, 5299.8410047698535, 5306.283651707024, 5356.8125115620915, 5397.272872680744, 5239.895202095965, 5253.789848406059, 5264.710440925747, 5297.708626025567, 5306.681599104047, 3335.666433356669, 3787.2993496203153, 3794.842573015243, 3919.6864250860003, 4001.179758149587, 3516.6636678062428, 3712.1276500022473, 3945.9605403946025, 3958.960410395888, 4025.376066709665, 3083.7070478304527, 3265.7387409007224, 3410.493287433734, 3924.941125883114, 3937.803109844511, 3305.3389322135426, 3437.6390478999847, 3896.49345585075, 3949.526056873179, 3971.2653159165357, 2757.7104404037486, 3012.2469382654335, 3483.4600636901323, 3502.458918076064, 3507.140750516114, 5166.483351664841, 5169.844904652867, 5182.300389447451, 5182.574791932211, 5214.191800270964, 3928.5678575356687, 4345.566482769659, 4466.486354069259, 4585.418030779376, 4603.618914325689, 5452.945470545284, 5494.670319780786, 5518.806841760519, 5519.972400138014, 5542.725173210184, 3822.789746563938, 3843.3082045231845, 3846.4807251021034, 3863.2466668999555, 3891.8248678809523, 4936.80252789888, 4938.481459446778, 5354.544863686552, 5900.645961242296, 5939.168516407706, 3161.119134115198, 3754.267917756066, 3776.6034566370686, 3833.6838634580886, 3838.2323535292494, 3139.3250451153112, 3841.9423708644395, 3850.8652197172964, 3889.7828237904123, 3924.823382947774, 4469.41897553316, 4515.412996310459, 4534.206513860061, 4553.954460455353, 4554.339620754972, 5241.42344342122, 5292.751253651254, 5477.534409575151, 5542.889142217133, 5568.693721845257, 1128.3342827731678, 1182.438341787661, 1292.1536393662166, 1295.2358108715903, 1325.4830616059744, 764.3426653078349, 799.808046068951, 808.5916612110964, 808.599743127144, 810.9229623185788, 4123.443335149716, 4237.597428244312, 4333.804978775963, 4966.826161084345, 5043.949560504421, 4684.601808846286, 4801.255805350176, 4961.627877909099, 5773.612054838269, 5775.451332123442, 5114.360704911886, 5116.360454943132, 5140.0, 5166.018456493254, 5224.216367544898, 2267.240474441048, 2553.731858154867, 2759.668080471998, 2953.394554116444, 2977.0919869439595, 4853.810816349946, 4942.5057494251005, 5274.7626356814035, 5313.734313284321, 5814.534837213013, 5473.6463320772455, 5723.971380143073, 5747.6838773867, 5778.0, 5813.622114562624, 5189.6646509070315, 5487.847552014042, 5802.912956305659, 5809.489532721438, 5839.737211825478, 4765.833195838129, 5381.408045115112, 5399.83800485986, 5475.780968761364, 5671.971640141773, 5145.948540514547, 5219.50414710617, 5537.501624853782, 5554.444555544414, 5609.0, 5124.8206312778875, 5860.948667966178, 5869.593466678642, 5892.1161825726485, 5915.024021036481, 3760.7555508891833, 3796.9620303797715, 3852.845886164522, 3878.534575850916, 3882.708796840192, 4748.88127796794, 5725.914111288251, 5746.425357464387, 5811.4479124483105, 5817.331006934088, 5076.593872490118, 5389.0299746046085, 5565.443455654402, 5570.6657600544095, 5592.0, 4635.145941623377, 5191.792328306826, 5357.544608708147, 5604.187392827936, 5649.887002260014, 4460.576245256695, 4584.0, 5064.392272927273, 5394.865128371822, 5463.562914966873, 4743.525647435367, 5040.143175660073, 5490.313710786152, 5546.889062218814, 5555.833325000256, 4235.407043014042, 5386.380566234777, 5391.29913111293, 5448.0, 5479.643823151642], "value": 3969.32704828243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.8547533231016, 769.7459838253377, 781.6229116945108, 782.5343920367379, 832.371559472598, 15032.77450838237, 15747.448839290628, 15749.291281892314, 15750.842491575095, 15830.0, 14325.426982920671, 14335.569932902004, 14335.569932902004, 14341.784873226883, 14595.781063284032, 605.3250625552499, 733.4902242941158, 875.4047247871445, 883.9734807955772, 908.1735620585268, 890.995545022274, 896.4845214001942, 902.9142231488031, 903.746950853762, 904.1410659873114, 931.8881734191906, 959.6928982725531, 960.159860122393, 963.0177219236375, 963.9807203855919, 749.6701451361383, 774.31859963232, 918.6049998500642, 953.8569214617794, 955.6894009446958, 702.585474570001, 706.3536863769644, 706.4313227851577, 706.6784613001072, 708.0547469128719, 15071.773923391158, 15090.320935557926, 15108.924455377763, 15124.0, 15160.696786064216, 13588.864111358858, 13687.384067716877, 13981.580552583438, 14012.92993535036, 14605.415783368653, 10602.257841951066, 12734.80897786534, 12752.362381880868, 12816.679583010406, 12866.228026318451, 4431.734095954221, 4491.842785502492, 4494.775261236925, 4671.182543054952, 4694.50707675696, 103238.45142322872, 105380.4730976348, 105980.0, 106663.99999999923, 107643.0, 10738.463076846127, 10776.892231077745, 11002.614908478325, 11079.833802492969, 11323.603673871376, 22292.66561001586, 22311.0, 22330.55338893213, 22335.218267360888, 22347.0, 7753.6123193840085, 7849.921500784919, 7981.72063977758, 8029.076656184497, 8094.635741391651, 4359.694821362537, 4381.846635367747, 4416.072624748775, 4435.0, 4543.34121552373, 3490.6858382745677, 3605.531280933462, 3608.7473876828894, 3862.7059934921554, 4076.4496792932573, 723.8045727653504, 737.4137560639305, 741.2106106996032, 746.6042997211487, 749.1459735901077, 504.5786768048647, 550.7604192176416, 620.8665136995568, 643.4225705079772, 702.7470110760122, 8492.108328625405, 8605.397622166514, 9233.445993240428, 9320.166165020824, 10406.687799366031, 570.9486146246858, 672.4049216443385, 685.413971054751, 688.6949972997219, 697.5326531224092, 3976.463177471004, 4224.598663126998, 4347.413099231627, 4377.1683380157665, 4679.672422930362, 131020.0, 133137.0, 136651.0, 136822.0, 138548.0, 72182.76479901576, 78209.60895195602, 90399.0, 115496.42251788855, 119422.8057719429, 79854.0, 81224.0, 106642.46678766712, 106927.4653626727, 108056.91943080783, 8159.388045896463, 9251.491167985825, 9276.62893484253, 9712.805743885225, 9813.607455701978, 59966.400335996084, 60775.69612151911, 61686.69156654188, 63011.684941575, 66327.0, 88868.0, 90866.54566727125, 91012.0, 92179.0, 93216.2035138947, 12861.967665221142, 13212.273324967218, 13232.338383081, 13653.931730341284, 13897.0966887152, 14641.26793660334, 15253.237338132827, 15285.923570382078, 15357.385704571692, 15565.299561519532, 13418.93290533541, 13472.932635337153, 14271.928640356731, 14363.21002344881, 14534.781978270123, 13540.526081587097, 13677.179369237878, 13825.308734563432, 13964.162150271017, 14179.858201418267, 13703.383347749175, 13749.587512374645, 13776.966727495277, 14014.789778153132, 14059.086159399607, 10450.790984180427, 10474.895251047697, 10619.681409557727, 10637.308574942605, 11075.612353567587, 6631.436327911989, 10076.0, 10824.945875270574, 10889.292196007234, 10970.561177553127], "value": 7807.035302841427, "unit": "score"}], "name": "dromaeo_css", "value": 7153.392259318156, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 113.0, 115.0, 113.0, 112.0, 115.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 111.0, 150.0, 136.0, 135.0, 137.0, 100.0, 172.0, 135.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 186.0, 186.0, 182.0, 182.0, 183.0, 182.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 59.0, 89.0, 61.0, 59.0, 58.0, 58.0, 80.0, 56.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 91.0, 68.0, 68.0, 69.0, 91.0, 67.0, 68.0, 91.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 89.0, 87.0, 88.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 92.0, 92.0, 93.0, 93.0, 94.0, 94.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 78.0, 84.0, 84.0, 83.0, 84.0, 82.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 54.0, 53.0, 53.0, 55.0, 54.0, 54.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 65.0, 76.0, 73.0, 69.0, 70.0, 70.0, 70.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 97.0, 104.0, 102.0, 87.0, 84.0, 84.0, 89.0, 86.0, 89.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 144.0, 146.0, 143.0, 150.0, 160.0, 141.0, 177.0, 151.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 184.0, 171.0, 171.0, 170.0, 170.0, 171.0, 168.0, 170.0, 172.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 72.0, 84.0, 85.0, 84.0, 82.0, 70.0, 69.0, 83.0], "value": 76.8, "unit": "score"}], "name": "kraken", "value": 1457.6, "alertThreshold": 2.0}]} 11:48:30 INFO - Return code: 0 11:48:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:48:30 INFO - # TBPL SUCCESS # 11:48:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:48:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:48:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:48:30 INFO - 2016-10-19 11:48:30.917 screenresolution[1952:15168] starting screenresolution argv=screenresolution get 11:48:30 INFO - 2016-10-19 11:48:30.920 screenresolution[1952:15168] Display 0: 1600x1200x32@60 11:48:30 INFO - 2016-10-19 11:48:30.927 screenresolution[1953:15171] starting screenresolution argv=screenresolution list 11:48:30 INFO - Available Modes on Display 0 11:48:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:48:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:48:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:48:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:48:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:48:31 INFO - Intel Iris: 11:48:31 INFO - Chipset Model: Intel Iris 11:48:31 INFO - Type: GPU 11:48:31 INFO - Bus: Built-In 11:48:31 INFO - VRAM (Dynamic, Max): 1536 MB 11:48:31 INFO - Vendor: Intel (0x8086) 11:48:31 INFO - Device ID: 0x0a2e 11:48:31 INFO - Revision ID: 0x0009 11:48:31 INFO - Displays: 11:48:31 INFO - Display: 11:48:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:48:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:48:31 INFO - Display Serial Number: 1600x1200 60 11:48:31 INFO - Main Display: Yes 11:48:31 INFO - Mirror: Off 11:48:31 INFO - Online: Yes 11:48:31 INFO - Rotation: Supported 11:48:31 INFO - Return code: 0 11:48:31 INFO - Running post-action listener: _resource_record_post_action 11:48:31 INFO - [mozharness: 2016-10-19 18:48:31.162191Z] Finished run-tests step (success) 11:48:31 INFO - Running post-run listener: _resource_record_post_run 11:48:31 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 839680; Write bytes: 707909632; Read time: 9; Write time: 2019 11:48:31 INFO - TinderboxPrint: CPU usage
53.7% 11:48:31 INFO - TinderboxPrint: I/O read bytes / time
839,680 / 9 11:48:31 INFO - TinderboxPrint: I/O write bytes / time
707,909,632 / 2,019 11:48:31 INFO - TinderboxPrint: CPU idle
2,249.9 (46.3%) 11:48:31 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 11:48:31 INFO - TinderboxPrint: CPU user
1,628.2 (33.5%) 11:48:31 INFO - TinderboxPrint: Swap in / out
406,925,312 / 0 11:48:31 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390900224; Write bytes: 386817024; Read time: 29078; Write time: 766 11:48:31 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 839680; Write bytes: 297372672; Read time: 9; Write time: 1201 11:48:31 INFO - Running post-run listener: _upload_blobber_files 11:48:31 INFO - Blob upload gear active. 11:48:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:48:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:48:31 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'] 11:48:31 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 11:48:32 INFO - (blobuploader) - INFO - Open directory for files ... 11:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:48:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:32 INFO - (blobuploader) - INFO - Done attempting. 11:48:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:48:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:33 INFO - (blobuploader) - INFO - Done attempting. 11:48:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:48:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:48:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:48:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:48:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:48:36 INFO - (blobuploader) - INFO - Done attempting. 11:48:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:48:36 INFO - Return code: 0 11:48:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:48:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:48:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fde0283ed157f219afb9ff74964376f8d525c0023513e95ef879a2cd4238e3d656ada835e3d10261133919736f5251cad01cfd8a51e063ecb1de4e8cb877954", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a521b324b93ee93bb5f9ee47c341e5102feedfab5b2aecc51bae011238241d003e51744eed8f5fab86fce307e9d0e8b212af37cf3ac70e7d46ea13200431d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b280f23e599fba6d95417c9624dc94f249ff02336e75046d53c2c087354d52963aa3eb968de2bae4dee137d538acb2dfac066f81adb8da55c59f64bc651fbd3"} 11:48:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:48:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:48:36 INFO - Contents: 11:48:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fde0283ed157f219afb9ff74964376f8d525c0023513e95ef879a2cd4238e3d656ada835e3d10261133919736f5251cad01cfd8a51e063ecb1de4e8cb877954", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a521b324b93ee93bb5f9ee47c341e5102feedfab5b2aecc51bae011238241d003e51744eed8f5fab86fce307e9d0e8b212af37cf3ac70e7d46ea13200431d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b280f23e599fba6d95417c9624dc94f249ff02336e75046d53c2c087354d52963aa3eb968de2bae4dee137d538acb2dfac066f81adb8da55c59f64bc651fbd3"} 11:48:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:48:36 INFO - Copying logs to upload dir... 11:48:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:48:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.831346 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 11:48:36.325847) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 11:48:36.330304) ========= 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.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/4fde0283ed157f219afb9ff74964376f8d525c0023513e95ef879a2cd4238e3d656ada835e3d10261133919736f5251cad01cfd8a51e063ecb1de4e8cb877954", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a521b324b93ee93bb5f9ee47c341e5102feedfab5b2aecc51bae011238241d003e51744eed8f5fab86fce307e9d0e8b212af37cf3ac70e7d46ea13200431d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b280f23e599fba6d95417c9624dc94f249ff02336e75046d53c2c087354d52963aa3eb968de2bae4dee137d538acb2dfac066f81adb8da55c59f64bc651fbd3"} build_url:https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011515 build_url: 'https://queue.taskcluster.net/v1/task/B7KlRSKiQgakKJb3RpkW1Q/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/4fde0283ed157f219afb9ff74964376f8d525c0023513e95ef879a2cd4238e3d656ada835e3d10261133919736f5251cad01cfd8a51e063ecb1de4e8cb877954", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8a521b324b93ee93bb5f9ee47c341e5102feedfab5b2aecc51bae011238241d003e51744eed8f5fab86fce307e9d0e8b212af37cf3ac70e7d46ea13200431d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1b280f23e599fba6d95417c9624dc94f249ff02336e75046d53c2c087354d52963aa3eb968de2bae4dee137d538acb2dfac066f81adb8da55c59f64bc651fbd3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 11:48:36.362802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:48:36.366111) ========= 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.kX3HxnCax2/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.wG3XzWPHqE/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:48:36.414397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 11:48:36.414674) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 11:48:36.417977) ========= ========= Total master_lag: 0.27 =========