builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1487901814.51 results: success (0) buildid: 20170223154122 builduid: 93c69c4dbd6549e4980e7d7cd80ea940 revision: dfab6e1129c8e430a6b1f360fc35644fc7d92591 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.510664) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.511161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.511474) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.535725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.536163) ========= 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.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-23 18:03:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.96M=0.001s 2017-02-23 18:03:34 (7.96 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080601 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.639697) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.640064) ========= 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.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.020630 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:34.689675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 18:03:34.690088) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dfab6e1129c8e430a6b1f360fc35644fc7d92591 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dfab6e1129c8e430a6b1f360fc35644fc7d92591 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-23 18:03:34,784 truncating revision to first 12 chars 2017-02-23 18:03:34,784 Setting DEBUG logging. 2017-02-23 18:03:34,784 attempt 1/10 2017-02-23 18:03:34,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/dfab6e1129c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 18:03:35,875 unpacking tar archive at: mozilla-beta-dfab6e1129c8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.341424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 18:03:36.055740) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:36.056142) ========= script_repo_revision: dfab6e1129c8e430a6b1f360fc35644fc7d92591 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:36.056677) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:36.057000) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 18:03:36.075962) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-23 18:03:36.076344) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:03:36 INFO - MultiFileLogger online at 20170223 18:03:36 in /builds/slave/test 18:03:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:03:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:36 INFO - {'append_to_log': False, 18:03:36 INFO - 'base_work_dir': '/builds/slave/test', 18:03:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:03:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:36 INFO - 'branch': 'Mozilla-Beta', 18:03:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:36 INFO - 'config_files': ('talos/mac_config.py',), 18:03:36 INFO - 'default_actions': ('clobber', 18:03:36 INFO - 'read-buildbot-config', 18:03:36 INFO - 'download-and-extract', 18:03:36 INFO - 'populate-webroot', 18:03:36 INFO - 'create-virtualenv', 18:03:36 INFO - 'install', 18:03:36 INFO - 'run-tests'), 18:03:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:36 INFO - 'download_minidump_stackwalk': True, 18:03:36 INFO - 'download_symbols': 'ondemand', 18:03:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:03:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:03:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:03:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:03:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:36 INFO - 'installer_path': 'installer.exe', 18:03:36 INFO - 'log_level': 'info', 18:03:36 INFO - 'log_name': 'talos', 18:03:36 INFO - 'log_to_console': True, 18:03:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:03:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:03:36 INFO - 'opt_config_files': (), 18:03:36 INFO - 'pip_index': False, 18:03:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:36 INFO - 'cmd': ('bash', 18:03:36 INFO - '-c', 18:03:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:36 INFO - 'enabled': True, 18:03:36 INFO - 'halt_on_failure': False, 18:03:36 INFO - 'name': 'check_screen_resolution'},), 18:03:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:36 INFO - 'cmd': ('bash', 18:03:36 INFO - '-c', 18:03:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:36 INFO - 'enabled': True, 18:03:36 INFO - 'halt_on_failure': False, 18:03:36 INFO - 'name': 'check_screen_resolution'},), 18:03:36 INFO - 'run_cmd_checks_enabled': True, 18:03:36 INFO - 'sps_profile': False, 18:03:36 INFO - 'sps_profile_interval': 0, 18:03:36 INFO - 'suite': 'dromaeojs', 18:03:36 INFO - 'system_bits': '32', 18:03:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:36 INFO - 'title': 't-yosemite-r7-0029', 18:03:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:36 INFO - 'use_talos_json': True, 18:03:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:36 INFO - 'work_dir': 'build'} 18:03:36 INFO - [mozharness: 2017-02-24 02:03:36.296709Z] Running clobber step. 18:03:36 INFO - Running pre-action listener: _resource_record_pre_action 18:03:36 INFO - Running main action method: clobber 18:03:36 INFO - rmtree: /builds/slave/test/build 18:03:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:39 INFO - Running post-action listener: _resource_record_post_action 18:03:39 INFO - [mozharness: 2017-02-24 02:03:39.450531Z] Finished clobber step (success) 18:03:39 INFO - [mozharness: 2017-02-24 02:03:39.450658Z] Running read-buildbot-config step. 18:03:39 INFO - Running pre-action listener: _resource_record_pre_action 18:03:39 INFO - Running main action method: read_buildbot_config 18:03:39 INFO - Using buildbot properties: 18:03:39 INFO - { 18:03:39 INFO - "project": "", 18:03:39 INFO - "product": "firefox", 18:03:39 INFO - "script_repo_revision": "production", 18:03:39 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 18:03:39 INFO - "repository": "", 18:03:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 18:03:39 INFO - "buildid": "20170223154122", 18:03:39 INFO - "pgo_build": "False", 18:03:39 INFO - "basedir": "/builds/slave/test", 18:03:39 INFO - "buildnumber": 7, 18:03:39 INFO - "slavename": "t-yosemite-r7-0029", 18:03:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:03:39 INFO - "platform": "yosemite_r7", 18:03:39 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:03:39 INFO - "branch": "mozilla-beta", 18:03:39 INFO - "repo_path": "releases/mozilla-beta", 18:03:39 INFO - "revision": "dfab6e1129c8e430a6b1f360fc35644fc7d92591", 18:03:39 INFO - "stage_platform": "macosx64", 18:03:39 INFO - "builduid": "93c69c4dbd6549e4980e7d7cd80ea940", 18:03:39 INFO - "slavebuilddir": "test" 18:03:39 INFO - } 18:03:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 18:03:39 INFO - Running post-action listener: _resource_record_post_action 18:03:39 INFO - [mozharness: 2017-02-24 02:03:39.455548Z] Finished read-buildbot-config step (success) 18:03:39 INFO - [mozharness: 2017-02-24 02:03:39.455727Z] Running download-and-extract step. 18:03:39 INFO - Running pre-action listener: _resource_record_pre_action 18:03:39 INFO - Running main action method: download_and_extract 18:03:39 INFO - mkdir: /builds/slave/test/build/tests 18:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:39 INFO - trying https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 18:03:39 INFO - Downloading https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 18:03:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 18:03:40 INFO - Downloaded 1165 bytes. 18:03:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 18:03:40 INFO - Using the following test package requirements: 18:03:40 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 18:03:40 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 18:03:40 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 18:03:40 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:03:40 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 18:03:40 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 18:03:40 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 18:03:40 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 18:03:40 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 18:03:40 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 18:03:40 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 18:03:40 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 18:03:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 18:03:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 18:03:40 INFO - Fetch https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 18:03:41 INFO - Http code: 200 18:03:41 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 18:03:41 INFO - x-amz-cf-id: hnyQMzz6NTlSQqtS9-YJKWLj7VqqQtDBHgIWXg771CnfpxyCiwzMlw== 18:03:41 INFO - x-amz-version-id: 9a3eNkAuE6MpYjQLCx2QgPIFC5qxZ4B2 18:03:41 INFO - Expected file size: 16093399 18:03:41 INFO - Obtained file size: 16093399 18:03:48 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 18:03:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 18:03:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 18:03:48 INFO - Fetch https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 18:03:48 INFO - Http code: 200 18:03:48 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 18:03:48 INFO - x-amz-cf-id: 2ifsOmCvVT9dODCoZPYB9DrJJnI9TyAOeLMWMR7DfQ93jZTdDS3oIg== 18:03:48 INFO - x-amz-version-id: 9a3eNkAuE6MpYjQLCx2QgPIFC5qxZ4B2 18:03:49 INFO - Expected file size: 16093399 18:03:49 INFO - Obtained file size: 16093399 18:03:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 18:03:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 18:03:55 INFO - Fetch https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 18:03:55 INFO - Http code: 200 18:03:55 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 18:03:55 INFO - x-amz-cf-id: cXI6jjspmfTmsqBi-Njrescgqiu09PyThUYnXgrr4N7IKdQdESKBIw== 18:03:55 INFO - x-amz-version-id: HuNVPXAz4ld8njyVNxFEfPTStD.tmtcs 18:03:56 INFO - Expected file size: 14402992 18:03:56 INFO - Obtained file size: 14402992 18:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:56 INFO - trying https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:03:56 INFO - Downloading https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 18:03:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 18:03:58 INFO - Downloaded 82111369 bytes. 18:03:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:03:58 INFO - mkdir: /builds/slave/test/properties 18:03:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:03:58 INFO - Writing to file /builds/slave/test/properties/build_url 18:03:58 INFO - Contents: 18:03:58 INFO - build_url:https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 18:03:59 INFO - Running post-action listener: _resource_record_post_action 18:03:59 INFO - Running post-action listener: set_extra_try_arguments 18:03:59 INFO - [mozharness: 2017-02-24 02:03:59.472017Z] Finished download-and-extract step (success) 18:03:59 INFO - [mozharness: 2017-02-24 02:03:59.472140Z] Running populate-webroot step. 18:03:59 INFO - Running pre-action listener: _resource_record_pre_action 18:03:59 INFO - Running main action method: populate_webroot 18:03:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:03:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:03:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:03:59 INFO - '--tppagecycles', 18:03:59 INFO - '7'], 18:03:59 INFO - 'tests': ['tsvgm']}}, 18:03:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:03:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:03:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:03:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:03:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['damp', 'tps']}, 18:03:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'tests': ['damp', 'tps']}, 18:03:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['dromaeo_dom']}, 18:03:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:03:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['basic_compositor_video']}, 18:03:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:03:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'other-e10s': {'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['a11yr', 18:03:59 INFO - 'ts_paint', 18:03:59 INFO - 'tpaint', 18:03:59 INFO - 'sessionrestore', 18:03:59 INFO - 'sessionrestore_no_auto_restore', 18:03:59 INFO - 'tabpaint']}, 18:03:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['tsvgx', 18:03:59 INFO - 'tsvgr_opacity', 18:03:59 INFO - 'tart', 18:03:59 INFO - 'tscrollx', 18:03:59 INFO - 'cart']}, 18:03:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:03:59 INFO - 'tsvgr_opacity', 18:03:59 INFO - 'tart', 18:03:59 INFO - 'tscrollx', 18:03:59 INFO - 'cart']}, 18:03:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'talos_options': ['--disable-e10s'], 18:03:59 INFO - 'tests': ['tp5o']}, 18:03:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'tests': ['tp5o']}, 18:03:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'talos_options': ['--disable-e10s', 18:03:59 INFO - '--xperf_path', 18:03:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:59 INFO - 'tests': ['tp5n']}, 18:03:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:03:59 INFO - 'talos_options': ['--xperf_path', 18:03:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:59 INFO - 'tests': ['tp5n']}}, 18:03:59 INFO - 'talos.zip': {'path': '', 18:03:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:03:59 INFO - Running post-action listener: _resource_record_post_action 18:03:59 INFO - [mozharness: 2017-02-24 02:03:59.491254Z] Finished populate-webroot step (success) 18:03:59 INFO - [mozharness: 2017-02-24 02:03:59.491384Z] Running create-virtualenv step. 18:03:59 INFO - Running pre-action listener: _resource_record_pre_action 18:03:59 INFO - Running main action method: create_virtualenv 18:03:59 INFO - Creating virtualenv /builds/slave/test/build/venv 18:03:59 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 18:03:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:03:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:03:59 INFO - Using real prefix '/tools/python27' 18:03:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:00 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:03 INFO - Installing pip.................done. 18:04:03 INFO - Return code: 0 18:04:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:04:03 INFO - Reading from file tmpfile_stdout 18:04:03 INFO - Output received: 18:04:03 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) 18:04:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:03 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 18:04:03 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 18:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:03 INFO - 'HOME': '/Users/cltbld', 18:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:03 INFO - 'LOGNAME': 'cltbld', 18:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:03 INFO - 'MOZ_NO_REMOTE': '1', 18:04:03 INFO - 'NO_EM_RESTART': '1', 18:04:03 INFO - 'PAGER': '/bin/cat', 18:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:03 INFO - 'PWD': '/builds/slave/test', 18:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:03 INFO - 'SHELL': '/bin/bash', 18:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:03 INFO - 'USER': 'cltbld', 18:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:03 INFO - 'XPC_FLAGS': '0x0', 18:04:03 INFO - 'XPC_SERVICE_NAME': '0', 18:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:03 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 18:04:03 INFO - Cleaning up... 18:04:03 INFO - Return code: 0 18:04:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:03 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:03 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 18:04:03 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 18:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:03 INFO - 'HOME': '/Users/cltbld', 18:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:03 INFO - 'LOGNAME': 'cltbld', 18:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:03 INFO - 'MOZ_NO_REMOTE': '1', 18:04:03 INFO - 'NO_EM_RESTART': '1', 18:04:03 INFO - 'PAGER': '/bin/cat', 18:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:03 INFO - 'PWD': '/builds/slave/test', 18:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:03 INFO - 'SHELL': '/bin/bash', 18:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:03 INFO - 'USER': 'cltbld', 18:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:03 INFO - 'XPC_FLAGS': '0x0', 18:04:03 INFO - 'XPC_SERVICE_NAME': '0', 18:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:03 INFO - Downloading/unpacking psutil>=3.1.1 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04: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 18:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:07 INFO - Installing collected packages: psutil 18:04:07 INFO - Running setup.py install for psutil 18:04:07 INFO - building 'psutil._psutil_osx' extension 18:04:07 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 18:04:07 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 18:04:08 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 18:04:08 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 18:04:08 INFO - building 'psutil._psutil_posix' extension 18:04:08 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 18:04:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:04:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:04:08 INFO - ^ 18:04:08 INFO - 1 warning generated. 18:04:08 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 18:04:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:08 INFO - Successfully installed psutil 18:04:08 INFO - Cleaning up... 18:04:08 INFO - Return code: 0 18:04:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:08 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:08 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 18:04:08 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 18:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:08 INFO - 'HOME': '/Users/cltbld', 18:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:08 INFO - 'LOGNAME': 'cltbld', 18:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:08 INFO - 'MOZ_NO_REMOTE': '1', 18:04:08 INFO - 'NO_EM_RESTART': '1', 18:04:08 INFO - 'PAGER': '/bin/cat', 18:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:08 INFO - 'PWD': '/builds/slave/test', 18:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:08 INFO - 'SHELL': '/bin/bash', 18:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:08 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:08 INFO - 'USER': 'cltbld', 18:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:08 INFO - 'XPC_FLAGS': '0x0', 18:04:08 INFO - 'XPC_SERVICE_NAME': '0', 18:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:04:08 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 18:04:08 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 18:04:08 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 18:04:08 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 18:04:08 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 18:04:08 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 18:04:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:04:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:04:11 INFO - Installing collected packages: mozsystemmonitor 18:04:11 INFO - Running setup.py install for mozsystemmonitor 18:04:11 INFO - Successfully installed mozsystemmonitor 18:04:11 INFO - Cleaning up... 18:04:11 INFO - Return code: 0 18:04:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:11 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:11 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 18:04:11 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 18:04:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:11 INFO - 'HOME': '/Users/cltbld', 18:04:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:11 INFO - 'LOGNAME': 'cltbld', 18:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:11 INFO - 'MOZ_NO_REMOTE': '1', 18:04:11 INFO - 'NO_EM_RESTART': '1', 18:04:11 INFO - 'PAGER': '/bin/cat', 18:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:11 INFO - 'PWD': '/builds/slave/test', 18:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:11 INFO - 'SHELL': '/bin/bash', 18:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:11 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:11 INFO - 'USER': 'cltbld', 18:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:11 INFO - 'XPC_FLAGS': '0x0', 18:04:11 INFO - 'XPC_SERVICE_NAME': '0', 18:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:11 INFO - Downloading/unpacking jsonschema==2.5.1 18:04:11 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 18:04:11 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 18:04:11 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 18:04:11 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 18:04:11 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 18:04:11 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 18:04:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:04:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:14 INFO - Downloading functools32-3.2.3-2.tar.gz 18:04:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:04:14 INFO - warning: no files found matching '*.txt' 18:04:15 INFO - no previously-included directories found matching 'build' 18:04:15 INFO - no previously-included directories found matching 'dist' 18:04:15 INFO - no previously-included directories found matching '.git*' 18:04:15 INFO - Installing collected packages: jsonschema, functools32 18:04:15 INFO - Running setup.py install for functools32 18:04:15 INFO - warning: no files found matching '*.txt' 18:04:15 INFO - no previously-included directories found matching 'build' 18:04:15 INFO - no previously-included directories found matching 'dist' 18:04:15 INFO - no previously-included directories found matching '.git*' 18:04:15 INFO - Successfully installed jsonschema functools32 18:04:15 INFO - Cleaning up... 18:04:15 INFO - Return code: 0 18:04:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:04: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')]} 18:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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')]} 18:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:15 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:15 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 18:04:15 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 18:04:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:15 INFO - 'HOME': '/Users/cltbld', 18:04:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:15 INFO - 'LOGNAME': 'cltbld', 18:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:15 INFO - 'MOZ_NO_REMOTE': '1', 18:04:15 INFO - 'NO_EM_RESTART': '1', 18:04:15 INFO - 'PAGER': '/bin/cat', 18:04:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:15 INFO - 'PWD': '/builds/slave/test', 18:04:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:15 INFO - 'SHELL': '/bin/bash', 18:04:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:15 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:15 INFO - 'USER': 'cltbld', 18:04:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:15 INFO - 'XPC_FLAGS': '0x0', 18:04:15 INFO - 'XPC_SERVICE_NAME': '0', 18:04:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:04:15 INFO - Cleaning up... 18:04:15 INFO - Return code: 0 18:04:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04: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')]} 18:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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')]} 18:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:15 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:15 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 18:04:15 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 18:04:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:15 INFO - 'HOME': '/Users/cltbld', 18:04:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:15 INFO - 'LOGNAME': 'cltbld', 18:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:15 INFO - 'MOZ_NO_REMOTE': '1', 18:04:15 INFO - 'NO_EM_RESTART': '1', 18:04:15 INFO - 'PAGER': '/bin/cat', 18:04:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:15 INFO - 'PWD': '/builds/slave/test', 18:04:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:15 INFO - 'SHELL': '/bin/bash', 18:04:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:15 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:15 INFO - 'USER': 'cltbld', 18:04:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:15 INFO - 'XPC_FLAGS': '0x0', 18:04:15 INFO - 'XPC_SERVICE_NAME': '0', 18:04:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:15 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 18:04:15 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 18:04:15 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 18:04:15 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 18:04:15 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 18:04:15 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 18:04:18 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:19 INFO - Downloading docopt-0.6.1.tar.gz 18:04:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:19 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:19 INFO - Running setup.py install for blobuploader 18:04:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:19 INFO - Running setup.py install for requests 18:04:20 INFO - Running setup.py install for docopt 18:04:20 INFO - Successfully installed blobuploader requests docopt 18:04:20 INFO - Cleaning up... 18:04:20 INFO - Return code: 0 18:04:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:20 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:20 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 18:04:20 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 18:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:20 INFO - 'HOME': '/Users/cltbld', 18:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:20 INFO - 'LOGNAME': 'cltbld', 18:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:20 INFO - 'MOZ_NO_REMOTE': '1', 18:04:20 INFO - 'NO_EM_RESTART': '1', 18:04:20 INFO - 'PAGER': '/bin/cat', 18:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:20 INFO - 'PWD': '/builds/slave/test', 18:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:20 INFO - 'SHELL': '/bin/bash', 18:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:20 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:20 INFO - 'USER': 'cltbld', 18:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:20 INFO - 'XPC_FLAGS': '0x0', 18:04:20 INFO - 'XPC_SERVICE_NAME': '0', 18:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ECnEZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-pik4ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4sCfR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mVkMcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-chYhDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jmQKSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-26nirL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8142qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-85gLOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:21 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-WLTFBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-hQGndK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b92IHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1Ctyzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-i0cG8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cdNP3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_qcq24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:22 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-M66Bo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:04:22 INFO - Running setup.py install for manifestparser 18:04:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:04:22 INFO - Running setup.py install for mozcrash 18:04:23 INFO - Running setup.py install for mozdebug 18:04:23 INFO - Running setup.py install for mozdevice 18:04:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Running setup.py install for mozfile 18:04:23 INFO - Running setup.py install for mozhttpd 18:04:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Running setup.py install for mozinfo 18:04:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Running setup.py install for mozInstall 18:04:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:04:23 INFO - Running setup.py install for mozleak 18:04:24 INFO - Running setup.py install for mozlog 18:04:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Running setup.py install for moznetwork 18:04:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Running setup.py install for mozprocess 18:04:24 INFO - Running setup.py install for mozprofile 18:04:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Running setup.py install for mozrunner 18:04:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:04:24 INFO - Running setup.py install for mozscreenshot 18:04:25 INFO - Running setup.py install for moztest 18:04:25 INFO - Running setup.py install for mozversion 18:04:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:04:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:04:25 INFO - Cleaning up... 18:04:25 INFO - Return code: 0 18:04:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:25 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:25 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 18:04:25 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 18:04:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:25 INFO - 'HOME': '/Users/cltbld', 18:04:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:25 INFO - 'LOGNAME': 'cltbld', 18:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:25 INFO - 'MOZ_NO_REMOTE': '1', 18:04:25 INFO - 'NO_EM_RESTART': '1', 18:04:25 INFO - 'PAGER': '/bin/cat', 18:04:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:25 INFO - 'PWD': '/builds/slave/test', 18:04:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:25 INFO - 'SHELL': '/bin/bash', 18:04:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:25 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:25 INFO - 'USER': 'cltbld', 18:04:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:25 INFO - 'XPC_FLAGS': '0x0', 18:04:25 INFO - 'XPC_SERVICE_NAME': '0', 18:04:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MAB8DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:25 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)) 18:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AWwm3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MWe2B5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:25 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)) 18:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sQ_Cbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-e9maGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EgNLWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3AR6R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fxJZHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iwo2WY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XWtzMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_5pw57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Ag5Kpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:26 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)) 18:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:26 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AlPzWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:27 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)) 18:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wz_39U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TcTNf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:04:27 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)) 18:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:04:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LtTImY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:04:27 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5bf06_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:04:27 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)) 18:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:04:27 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)) 18:04:27 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)) 18:04:27 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)) 18:04:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:30 INFO - Downloading blessings-1.6.tar.gz 18:04:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:04:30 INFO - Installing collected packages: blessings 18:04:30 INFO - Running setup.py install for blessings 18:04:30 INFO - Successfully installed blessings 18:04:30 INFO - Cleaning up... 18:04:30 INFO - Return code: 0 18:04:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:04:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:30 INFO - Reading from file tmpfile_stdout 18:04:30 INFO - Current package versions: 18:04:30 INFO - blessings == 1.6 18:04:30 INFO - blobuploader == 1.2.4 18:04:30 INFO - docopt == 0.6.1 18:04:30 INFO - functools32 == 3.2.3-2 18:04:30 INFO - jsonschema == 2.5.1 18:04:30 INFO - manifestparser == 1.1 18:04:30 INFO - mozInstall == 1.12 18:04:30 INFO - mozcrash == 1.0 18:04:30 INFO - mozdebug == 0.1 18:04:30 INFO - mozdevice == 0.48 18:04:30 INFO - mozfile == 1.2 18:04:30 INFO - mozhttpd == 0.7 18:04:30 INFO - mozinfo == 0.9 18:04:30 INFO - mozleak == 0.1 18:04:30 INFO - mozlog == 3.4 18:04:30 INFO - moznetwork == 0.27 18:04:30 INFO - mozprocess == 0.23 18:04:30 INFO - mozprofile == 0.28 18:04:30 INFO - mozrunner == 6.13 18:04:30 INFO - mozscreenshot == 0.1 18:04:30 INFO - mozsystemmonitor == 0.3 18:04:30 INFO - moztest == 0.8 18:04:30 INFO - mozversion == 1.4 18:04:30 INFO - psutil == 3.1.1 18:04:30 INFO - requests == 1.2.3 18:04:30 INFO - wsgiref == 0.1.2 18:04:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:31 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 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:31 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 18:04:31 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 18:04:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:31 INFO - 'HOME': '/Users/cltbld', 18:04:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:31 INFO - 'LOGNAME': 'cltbld', 18:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:31 INFO - 'MOZ_NO_REMOTE': '1', 18:04:31 INFO - 'NO_EM_RESTART': '1', 18:04:31 INFO - 'PAGER': '/bin/cat', 18:04:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:31 INFO - 'PWD': '/builds/slave/test', 18:04:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:31 INFO - 'SHELL': '/bin/bash', 18:04:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:31 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:31 INFO - 'USER': 'cltbld', 18:04:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:31 INFO - 'XPC_FLAGS': '0x0', 18:04:31 INFO - 'XPC_SERVICE_NAME': '0', 18:04:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 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)) 18:04:32 INFO - Cleaning up... 18:04:32 INFO - Return code: 0 18:04:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101aac490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a22570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaf0c69270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, '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.NlapZy0NoW/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.zIk0b4FqNh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:32 INFO - 'HOME': '/Users/cltbld', 18:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:32 INFO - 'LOGNAME': 'cltbld', 18:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:32 INFO - 'MOZ_NO_REMOTE': '1', 18:04:32 INFO - 'NO_EM_RESTART': '1', 18:04:32 INFO - 'PAGER': '/bin/cat', 18:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:32 INFO - 'PWD': '/builds/slave/test', 18:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:32 INFO - 'SHELL': '/bin/bash', 18:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:32 INFO - 'USER': 'cltbld', 18:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:32 INFO - 'XPC_FLAGS': '0x0', 18:04:32 INFO - 'XPC_SERVICE_NAME': '0', 18:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:04:32 INFO - Cleaning up... 18:04:32 INFO - Return code: 0 18:04:32 INFO - Running post-action listener: _resource_record_post_action 18:04:32 INFO - Running post-action listener: _start_resource_monitoring 18:04:32 INFO - Starting resource monitoring. 18:04:32 INFO - [mozharness: 2017-02-24 02:04:32.749432Z] Finished create-virtualenv step (success) 18:04:32 INFO - [mozharness: 2017-02-24 02:04:32.750440Z] Running install step. 18:04:32 INFO - Running pre-action listener: _resource_record_pre_action 18:04:32 INFO - Running main action method: install 18:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:04:33 INFO - Reading from file tmpfile_stdout 18:04:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:04:33 INFO - Reading from file tmpfile_stdout 18:04:33 INFO - Output received: 18:04:33 INFO - Usage: mozinstall [options] installer 18:04:33 INFO - Options: 18:04:33 INFO - -h, --help show this help message and exit 18:04:33 INFO - -d DEST, --destination=DEST 18:04:33 INFO - Directory to install application into. [default: 18:04:33 INFO - "/builds/slave/test"] 18:04:33 INFO - --app=APP Application being installed. [default: firefox] 18:04:33 INFO - mkdir: /builds/slave/test/build/application 18:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 18:04:54 INFO - Reading from file tmpfile_stdout 18:04:54 INFO - Output received: 18:04:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:04:54 INFO - Running post-action listener: _resource_record_post_action 18:04:54 INFO - [mozharness: 2017-02-24 02:04:54.720263Z] Finished install step (success) 18:04:54 INFO - [mozharness: 2017-02-24 02:04:54.720400Z] Running run-tests step. 18:04:54 INFO - Running pre-action listener: _resource_record_pre_action 18:04:54 INFO - Running main action method: run_tests 18:04:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:04:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:04:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:04:54 INFO - 2017-02-23 18:04:54.735 screenresolution[1959:10273] starting screenresolution argv=screenresolution get 18:04:54 INFO - 2017-02-23 18:04:54.737 screenresolution[1959:10273] Display 0: 1600x1200x32@60 18:04:54 INFO - 2017-02-23 18:04:54.743 screenresolution[1960:10276] starting screenresolution argv=screenresolution list 18:04:54 INFO - Available Modes on Display 0 18:04:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:04:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:04:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:04:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:04:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:04:54 INFO - Intel Iris: 18:04:54 INFO - Chipset Model: Intel Iris 18:04:54 INFO - Type: GPU 18:04:54 INFO - Bus: Built-In 18:04:54 INFO - VRAM (Dynamic, Max): 1536 MB 18:04:54 INFO - Vendor: Intel (0x8086) 18:04:54 INFO - Device ID: 0x0a2e 18:04:54 INFO - Revision ID: 0x0009 18:04:54 INFO - Displays: 18:04:54 INFO - Display: 18:04:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:04:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:04:54 INFO - Display Serial Number: 1600x1200 60 18:04:54 INFO - Main Display: Yes 18:04:54 INFO - Mirror: Off 18:04:54 INFO - Online: Yes 18:04:54 INFO - Rotation: Supported 18:04:54 INFO - Return code: 0 18:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:04:54 INFO - Python 2.7.3 18:04:54 INFO - Return code: 0 18:04:54 INFO - grabbing minidump binary from tooltool 18:04: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')]} 18:04:54 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 0x1018c0d30>, '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 0x101726db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:04:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:04:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:04:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:04:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:04:55 INFO - Return code: 0 18:04:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:04:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:04:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:04:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:04:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:04:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NlapZy0NoW/Render', 18:04:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:55 INFO - 'HOME': '/Users/cltbld', 18:04:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:55 INFO - 'LOGNAME': 'cltbld', 18:04:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:04:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:55 INFO - 'MOZ_NO_REMOTE': '1', 18:04:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:04:55 INFO - 'NO_EM_RESTART': '1', 18:04:55 INFO - 'PAGER': '/bin/cat', 18:04:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:55 INFO - 'PWD': '/builds/slave/test', 18:04:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:04:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:55 INFO - 'SHELL': '/bin/bash', 18:04:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zIk0b4FqNh/Listeners', 18:04:55 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:04:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:55 INFO - 'USER': 'cltbld', 18:04:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:55 INFO - 'XPC_FLAGS': '0x0', 18:04:55 INFO - 'XPC_SERVICE_NAME': '0', 18:04:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:04:55 INFO - mozversion application_buildid: 20170223154122 18:04:55 INFO - mozversion application_changeset: dfab6e1129c8e430a6b1f360fc35644fc7d92591 18:04:55 INFO - mozversion application_display_name: Firefox 18:04:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:04:55 INFO - mozversion application_name: Firefox 18:04:55 INFO - mozversion application_remotingname: firefox 18:04:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:04:55 INFO - mozversion application_vendor: Mozilla 18:04:55 INFO - mozversion application_version: 52.0 18:04:55 INFO - mozversion platform_buildid: 20170223154122 18:04:55 INFO - mozversion platform_changeset: dfab6e1129c8e430a6b1f360fc35644fc7d92591 18:04:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:04:55 INFO - mozversion platform_version: 52.0 18:04:55 INFO - using testdate: 1487901895 18:04:55 INFO - actual date: 1487901895 18:04:55 INFO - starting webserver on 'localhost:49353' 18:04:55 INFO - SUITE-START | Running 2 tests 18:04:55 INFO - TEST-START | dromaeo_css 18:04:55 INFO - operating with platform_type : mac_ 18:04:55 INFO - Initialising browser for dromaeo_css test... 18:04:55 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmprrJPIn/profile http://localhost:49353/getInfo.html) 18:04:57 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 18:04:57 INFO - PROCESS | 1970 | colorDepth:24 18:04:57 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 18:04:57 INFO - PROCESS | 1970 | __metrics 18:04:57 INFO - PROCESS | 1970 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:04:57 INFO - PROCESS | 1970 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:04:58 INFO - TEST-INFO | 1970: exit 0 18:04:58 INFO - Browser initialized. 18:04:58 INFO - Running cycle 1/1 for dromaeo_css test... 18:04:58 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmprrJPIn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:10 INFO - PROCESS | 1973 | RSS: Main: 167616512 18:05:10 INFO - PROCESS | 1973 | 18:08:14 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49353/tests/dromaeo/cssquery-ext.html) 18:08:15 INFO - PROCESS | 1973 | RSS: Main: 260112384 18:08:15 INFO - PROCESS | 1973 | 18:11:18 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-ext.html (next: http://localhost:49353/tests/dromaeo/cssquery-jquery.html) 18:11:18 INFO - PROCESS | 1973 | RSS: Main: 302923776 18:11:18 INFO - PROCESS | 1973 | 18:14:21 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49353/tests/dromaeo/cssquery-mootools.html) 18:14:21 INFO - PROCESS | 1973 | RSS: Main: 298786816 18:14:21 INFO - PROCESS | 1973 | 18:17:24 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49353/tests/dromaeo/cssquery-prototype.html) 18:17:24 INFO - PROCESS | 1973 | RSS: Main: 283590656 18:17:24 INFO - PROCESS | 1973 | 18:20:27 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49353/tests/dromaeo/cssquery-yui.html) 18:20:28 INFO - PROCESS | 1973 | RSS: Main: 256217088 18:20:28 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49353/tests/dromaeo/cssquery-yui.html (next: http://localhost:49353/tests/dromaeo/cssquery-dojo.html) 18:23:31 INFO - PROCESS | 1973 | RSS: Main: 396222464 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | __start_tp_report 18:23:31 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 18:23:31 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 18:23:31 INFO - PROCESS | 1973 | |i|pagename|runs| 18:23:31 INFO - PROCESS | 1973 | |0;dojo.html;3395.083327501575;3432.5366075579805;3445.276491936693;3445.8277086145695;3497.650234976503;5647.5764317676185;5707.942920570793;5709.971450142754;5729.770809167629;5731.570132240078;5731.398203188668;5851.882962340751;5856.2094117294155;5860.208871802305;5861.384554621767;5194.117000109981;5228.398734145584;5248.186531087687;5251.894962100756;5306.655067420625;4391.86824395267;5185.792568297264;5188.688678679272;5193.376794784631;5332.813351532697;4368.716033457831;5219.686818790885;5293.841184764463;5295.391030031538;5325.094733895247;5310.8141215057485;5320.015797077553;5321.813736519242;5332.8400147995235;5336.946630533684;5117.5394214520875;5172.301739265189;5256.053910296183;5263.710495922761;5289.629725919187;5706.144078388275;5713.857153571153;5774.5091667208335;5821.708914554255;5830.154627578977;6230.626162430269;6248.781292654735;6274.811755647338;6284.654344011077;6287.497000240016;6254.124422580842;6268.937310626881;6279.968600157015;6282.151909492206;6287.717052732638;5709.200711900379;5831.650100993869;5835.79574714883;5866.941330586725;5943.4056594340655;6272.435480806749;6300.527460440485;6302.716377763011;6307.590006649553;6312.147860038928;6265.749370025239;6266.749330026839;6274.40393162658;6275.748970041147;6300.9684951574945;5804.2744656917885;6238.75044998195;6242.687865606701;6245.75016999324;6273.278572964076;6222.502199824004;6255.280642726054;6264.906026409608;6326.873462530723;6331.3352098029045;5218.712970786643;5631.690257035821;5893.911591326134;6170.197874276405;6282.905756413657;4941.876453088702;5027.095122877844;5038.647294689336;5043.268726034705;5043.293938848564;4425.867223983286;4464.308032255006;4473.4408198975125;4474.060447306102;4517.028838799673;5968.134620480093;5971.164037034819;5975;5979.19280897082;5979.820605381846;6235.812925612239;6246.094316324107;6248.156498872685;6254.937450625435;6265.780697675651;5831.329397119301;5892.911606325909;5956.225690660273;5960.344362120163;5964.761409543657;5501.14732216507;5523.226748255248;5531.502164805106;5555.111182210827;5566.944330556643;6404.295527492065;6420.74317027323;6421.422072013541;6446.323136070832;6453.870922581616;4739.360186374795;6179.84550386244;6206.875862482815;6246.593971391845;6263.3736626337;6329.145565348619;6362.395572420613;6364.936350636434;6443.484521238383;6460.967695161494;6236.563440559117;6271.811845644638;6298.275698294571;6299.401556852092;6309.2744334400295;5415.296011518478;6194.163787888758;6207.720652570555;6236.469900058546;6263.56155069159;6112.989854784201;6119.776756597957;6148.661823599745;6177.876442471215;6266.717997690206;6239;6244.500439964701;6244.843878903063;6258.186435763321;6260.373962603885;6062.636241825505;6070.1501789750355;6119.265688117454;6135.417135372132;6138.416850399205;6095.872263631242;6127.953177891068;6138;6169.753209871555;6219.067139928866;6117.705611546597;6131.264248290234;6137.9693101535995;6138.940218748475;6160.969195153995 18:23:31 INFO - PROCESS | 1973 | |1;ext.html;528.4583302115334;532.8028629407119;533.5384892068361;551.4430425270475;610.7038086528033;31672.524912126337;40746.98132546688;40835.591644083586;42857.14285714284;42973.71078867639;42681.35977960333;43003.569964300346;43058.35412468816;43070;43095.138097238036;9524.8571271431;9683.03169683033;9701.611935522571;9705.369151005198;9729.075737804897;9429.151376376125;9552.761180970461;9556.187724043464;9558.80882382352;9583.760405989871;9538.332316737831;9566.378185417925;9577.808443831154;9585.2811039172;9593.47235902025;9489.383190092656;9558.996305387904;9565.665201717977;9568.760780980461;9582.185514231334;9512.001239869784;9610.90389096114;9615.759606009835;9618.951905240428;9621.18219951305;40490.59509404927;40522.18955620901;40864;40875.1824963502;40937.181256375006;3195.1532843796413;3269.3788180245915;3281.5405843181975;3302.768806183568;3337.7329813615097;29563.70436295631;29586.556201656993;29800.552991705143;30943.535846962313;31788.36423271545;2945.867435965387;3142.7485801135854;3164.47786115293;3315.187778994162;3421.8460169292193;503428;504359.99999999633;504818;505438;505544;57239.14141287884;57528;57564;57744.71127644335;57756.71121644365;62093.68953155205;62098;62163.689181554706;62182;62213.68893155505;14439.35022923971;16525.33898644065;16734.832651673572;16754.74867876983;16776;12998;13056.21662700241;13077.476900923857;13083.41124649393;13090.869091308965;15330.616734581501;15889.682206355808;16003.679926401406;16018.75971860423;16030.599235019217;5709.172170035405;6822.283660215705;6831.419329357061;6878.65606719672;6895.965520172367;830.4228561149943;869.482657818597;927.5547737086237;1057.735566108473;1117.3854380090918;29239.415211696072;29375.55936660952;29825.254368640522;30046.54930176049;30108.84945575258;1036.2849633752687;1051.953306460077;1067.989320106789;1096.4517741129434;1117.284937639911;14760.778588321184;14804.259787010606;15433.922830385776;15498.63218104949;15902.443414480438;641936;647231;650615;651229;654023;58921.41078589245;59150.70424647849;59175.704121479976;59205.51986200379;59326.40673593209;1235.092207227687;1255.4162314523974;1257.3838818978818;1364.4405793624567;1387.555982085723;29324.706752932198;31016.534751978288;31588.052358429282;32269.838650806596;32318.030459086265;54238.644033899465;54716.45283547114;54828.45171548233;54978.725106374215;55093;588551;595642;595848;596375;597090;32554.61195325653;34597;35506.467402989496;39595.2080958385;39898.2020359597;39678.801605991786;43510.56489435192;45429.54570454385;46774;47155.76422117867;38246;38312.61687383202;38462.46030319385;38617.61382386253;39361.606383935796;37432.438513422865;37772.489100435676;38062.85811425662;38837.80581097076;39084.609153908095;37855.43216851694;38099.42850857295;38222.80888595539;38355.616443835206;38407.23185536218;32224.03327900167;33701.3259734799;34327;34332.656673432946;34402.82798585991;25125.623115653645;25157.87421062956;25236.24291271265;25322.075078362883;25833.095841645452 18:23:31 INFO - PROCESS | 1973 | |2;jquery.html;342.2299825392866;387.26033276441996;396.61132090551257;406.1430381894201;420.4450125833899;18492.445226643205;19863.801361986374;21007.684884726743;21266.787332126674;21267.25564605237;12281.140320177568;12416.751664966718;14954;20016.79983200171;21087.68368474474;5307.460836357462;5372.623916325859;5574.052411090104;5581.05122129239;5586.8603284918;5462.535684466825;5616.241807356016;5617.494425501704;5621.662700237978;5622.971885140569;5361.946380536203;5601.327840659126;5614.494695477406;5618.971905140468;5620.100783874581;5466.070767969455;5574.247476590649;5574.721263936787;5582.86042848928;5588.469095435927;5265.394479634852;5419.485148910847;5426.294581704393;5438.619296649236;5505.504504594605;13521.121127126706;13802.585922422344;13833.861661383358;13837.377318020714;18666.719999199886;1968.5964377302619;2119.73503312086;2144.1101942693763;2165.447810808234;2225.3991422316044;9755.170810481117;10204.438755868425;10476.1095306899;10719.571217151304;10721.571137154504;1839.613681126965;1852.97220541692;1907.1036612791966;1916.6645836978612;1968.8326492248177;100916;113964;123230;129600.70399295981;132157.6784232155;9232.21526170283;10920.577166247524;11406.942965285287;11498.770024599462;11850.407479625985;15238.714192870937;15253.847461525243;15261.084334939938;15293.235338233044;15302.846971530365;8592.441491303045;8938.50838203892;8941.59762810675;9063.592138353659;9084.000759916404;7991.800204994805;8298.96262967129;8377.120402357665;8683.739487815375;8894.73315800527;4519.79660915253;4593.28804035375;4593.770311484413;4668.136394766979;4677.859664210079;1761.8678599104862;1845.6493266279633;1961.5292329840736;1962.6271008508338;1963.8428925685912;638.7636574467476;695.5617960684838;722.2227748873421;856.9143085691381;870.0125357718908;20553.48616284605;21263.149474021175;21319.78680213209;21523.67714484284;21604.67592986075;797.4378063465243;903.1194585279432;916.1937495004487;975.9316847820585;986.7681094942683;10575.20685948557;11129.998300152864;11266.774664506827;11273.718157045976;11292.830607540893;595983;599618;617333;618967;626888;130212.69787302196;135822.641773583;138589.32339603957;140039.29980350236;142137;940.2947789158131;942.5522876633681;965.4834663455018;966.4104896013426;968.2592816495387;17745;17939.910300448937;18446.815531845048;18498.907505462925;18599.16303766361;51732;54126;54150.45849541454;54203.186952196535;54741.726291368286;62763;76257;81806.13678521223;83647;86729;10979.890201097887;11510.079193664655;12220.816687749868;12684.492620295454;12964.416601252778;13292.136011159244;13809.723805524034;13815.654608634864;14002.229877356436;14453.277336132942;11583.6524904253;11766.470508827295;12035.75928481443;12136.817947730968;12147.39263036827;11544.365059921785;11593.362365069665;11666.241694289844;11891.167618266638;12183.573574924834;11790.997765189712;11844;12022.879771202175;12124.454399552218;12340.81488777651;12211.75576488483;12531.561395351118;12744.61766147017;12939.288339141061;12996.090273680751;10118.59525618967;11880.762384752084;12075.283061311486;12087.153899226969;12120.09099317537 18:23:31 INFO - PROCESS | 1973 | |3;mootools.html;1488.240997091483;1529.2965235991444;1718.4844546636014;1817.2730907636935;1898.8955607441585;7790.961045194773;8223.259906608411;8230.42387032908;8232.46488978216;8234.588270586477;6856.417204537608;7497.662605182766;7685.077790665128;8254.793630159249;8269.83460330793;2036.9694454583193;2347.7417484076736;2383.9761602384015;2395.4370722880108;2397.844140130886;2388.319328991238;2410.6143017117265;2432.8905199266073;2434.123715462441;2436.573599620059;2357.7524359942217;2367.1714899785024;2381.1070848431837;2400.579898517761;2407.337982054931;2368.786809187173;2371.1700904683394;2393.581123303415;2396.2212281008656;2396.940076498084;2087.5094352827073;2385.0817435287368;2411.517696460715;2412.0592968742203;2414.396400899775;8415.242628163494;8467.576621168979;8472.78818029548;8482.36382271326;8484.618192181366;5086.77109530072;5314.123169676998;5321.840344789663;5323.680579165262;5334.1732031535175;3545.1137215696076;3576.910577235564;3578.1949061461223;3594.155373487228;3654.4335627977707;1060.6181774561153;1074.2587614546017;1103.1395511501041;1103.189155970361;1110.7778444311089;11020.834687479693;11080.279781814237;11088.445577721162;11099;11116.44417779116;4464.933026004613;4640.1879671058105;4725.669203155746;4727.527247275314;4779.115863565252;7845.725399610987;9409.106134917172;9476.620935162517;9481.09929556691;9499.715008549754;5273.077211487975;5273.525382715574;5274.156135018378;5277.261183434322;5280.81517146898;4387.319965405367;4389.495208051051;4848.854534363974;4864.391951006124;4874.2201247800895;3857.61423857612;3908.863189788344;3970.841166353371;4007.8597249096724;4015.578364271768;1264.5447638850007;1297.6042307096238;1297.8961683065331;1306.3272414706435;1308.0974127851753;823.6705317872899;894.8120894612074;903.1104362203224;903.4308385716956;934.3973137326386;4474.903648606112;5605.747741351649;5631.2960879890015;5649.943500565024;5913.142594323799;444.16718652526515;453.08476876708863;453.8479609330845;457.11776271795753;460.9331646911246;3782.659560639556;3815.637514436124;3820.522434695663;3878.2243551289853;3897.2719096631904;9155.496447695441;9886.25853061009;10189.337693049929;10318.742031449123;10333.896661033445;9618.567164477616;10145.188384929188;10295.845562316572;10310.896891031143;10317;7788.493747906368;7877.7636670899965;7948.284654381134;8010.519368837889;8029.076656184497;4961.305417241661;5632.211490391431;5665.801696940587;5667.773289068391;5853.326867410302;9411.52942352866;9544;9561.9043809561;9569.234461243223;9580.329376943826;9234.1689247968;9542.997985211452;9556.522173891417;9698.51507424612;9717.56270967794;6677.332266773284;6872.072270243453;6880.759173428906;7245.818854528468;7405.925940740523;7685.615719214129;7762.495437796525;7937.126916039347;8047.557384343917;8067.757967260757;6994.895076573754;7201.279872012757;7217.494775365674;7255.673494692646;7277.636118194175;6851.9657401712675;6931.341522555349;6974.954526374792;7088.432925365855;7196.5682059074525;6718.2609912908565;6723.2940541244425;6807.863842723019;6808.897866531907;6818;4702.882427939329;4725.929111063406;4780.330753694555;4814.277858321279;4859.416869975626;4798.928016079692;4882.975585122052;4906.901861962812;4982.978489409735;5016.697365859508 18:23:31 INFO - PROCESS | 1973 | |4;prototype.html;304.2985917460254;305.8363775380171;313.5751057317335;318.83420621276935;321.4246498767207;4316.43886294782;4681.555252251035;5091.498008087614;5123.231515272711;5196.2592531502;3990.620891015356;4150.198615934248;4928.260760885869;4985.376827896513;5010.874728131789;3252.67473252674;3786.5645450773236;3839.6544311011935;3854.1906199698096;3915.6671682906986;3899.688024958011;3921.3333733265413;3922.9803850980707;3950.2297052074855;3962.623550762673;3400.336934297813;3709.2766910452474;3905.785181814999;3913.710479369572;3940.6847452203756;3232.0788575256174;3733.9813300933456;3902.122022544933;3912.37402015679;3918.647321741057;2665.9066932657265;2886.682464928856;3369.073504786179;3417.325510499862;3428.468587368962;4055.6958228133008;4481.708688935209;4840.927386089212;4966.602671786249;5069.543741063322;3793.525809273841;4481.633101903918;4486.528914463967;4509.564834989102;4512.887177820548;5387.272718183034;5396.217548455491;5417.674939503603;5418.214358917974;5434.538064264542;2927.536231884058;3748.475213470116;3756.3050835595504;3770.96298517907;3802.714796390254;5047.592874809489;5401.43284955078;5518.972405137989;5573.721313934287;5665.945897357816;3810.428435734662;3812.4281357796;3821.9044523887123;3822.0125534721788;3830.559485659129;3895.5130608673917;3906.2968665640456;3911.9071296856614;3919.333713268751;3919.6080391960577;3972.4239985201984;4333.674974376934;4458.598726114666;4504.761190672526;4528.320751887178;4765.309030190672;5364.490373414519;5374.838754837361;5405.918911216335;5415.83752487426;1057.9312344697723;1088.5101704232968;1143.5540139345662;1254.8494180698374;1256.8114782782473;712.8574285142992;796.9641366138537;817.5340056168046;842.613900134288;852.9488230706178;4667.556582124693;4697.953020469751;4870.342503761947;4873.733713823019;4890.608751299887;4655.580997710223;5224.03355379256;5226.451222621646;5268.947310526923;5348.358197016383;4272;4283.686809123101;4474.822875758183;4753.623188405797;4824.552634209793;2333.7432882382923;2533.049551845069;2583.948321033569;2629.421527263999;2749.649050062208;4489.685721999429;5126.846194614168;5371.3822910365025;5396.1905714143195;5462.590305727086;4081.9591804082174;4175.394567787654;4735.981763920774;4854.320395144682;5223.682961319513;5204.089284375219;5392.595555333366;5405.945840561131;5413.242146099628;5430.402655707869;4412.646988240997;4471.642268618568;4484.417025786627;4893.962480953299;4907.803687852446;4418.624416924469;4653.418322709661;4770;5121.874422114781;5130.48695130484;4201.248182440507;4808.567228949411;5413.99523264592;5456.91814622773;5462.590305727166;3587.8652201939803;3607.4408466687705;3636.836342364547;3639.3449179148006;3642.7085833132755;4874.926876096933;5176.50823171798;5237.895242095061;5260.028678347823;5603.243562119062;5435.945640543544;5439.688132543574;5442.755076021509;5443;5451.64564303319;5154;5434.212039254365;5454.2091396746455;5454.836354909359;5485.9725701371235;5132.640715150053;5185.688858668492;5313.601479889101;5353.420740881527;5363.517283444508;5277.102892508359;5338.599605029716;5478.369987451494;5483.725813709379;5485.670859748428;4515.367848501278;4679.2045352289515;5328;5355.973220133875;5377.257598624651 18:23:31 INFO - PROCESS | 1973 | |5;yui.html;597.5578072226552;757.1681909657877;772.5133166105353;784.6116172494612;806.4234072638064;14334.139951602898;15237.77143342848;15413.229338533085;15434.69130617387;15455.61360965975;13774.793378099337;14397.496087636935;14904.701905961874;15066.84933150668;15087.62280942977;859.4800145911727;883.1301168349171;885.2696525366566;885.4642941020686;885.8848349714528;689.0456717446348;710.3535782437982;839.4459656626627;856.610242339737;858.909814469481;904.1094521895927;919.72868003939;920.4937284493535;921.5760750054983;921.6681994481982;918.3433844800966;920.2637889688223;920.8618707193974;921.3366376209152;921.5576523268868;675.3651567526509;677.0352248046563;678.2640834694363;678.7115475922762;679.5447050476192;14668.973171877973;14915.70168596622;15075.170865602387;15085.773713394308;15246.313915873812;13414.86585134146;13420.194788312732;13576;13916.791248131287;14068.859311406857;9904.900950990543;11159.665210043711;11393.145514086476;11665.475053622607;12280.877191228063;3798.8480460781534;3811.866584669551;3836.5396152461603;3843.13529455873;3959.2675355059405;93970.12059758767;94833;94988;95983.08033839354;96400.55399169089;10482.580696772196;10634.946825265824;10753.677389678322;10807.945960270148;10857.619983300547;20410.795892041187;20633;20854.27010054671;20863.582728345347;20932.895335523226;7936.880946785803;7949.562774047367;7949.960250198827;8084.595770211466;8215.7946051348;4306.117245964632;4414.602685758297;4417.3815665806815;4539.84110556129;4571.2000399929875;3192.4732419151046;3279.32773781379;3296.357210344008;3301.339732053551;3309.073459431363;628.3684896678809;629.9181106456132;652.7225928980164;656.3534918105662;657.1588366890388;588.109014842514;590.9940900591025;600.7777122464717;658.868226354731;675.3449154320301;8970.86543701845;9249.445033298023;9264.11990860867;9369.109934556207;9408.764780880532;631.2235949781794;637.4613451633362;648.3710800523579;682.7712716240115;683.480554778372;4344.761038142869;4573.748443835554;4582.152301824173;4644.744539050317;4713.34013238147;109946;111175;115177.4241128789;116450.41774791072;118582.8141718589;66125.66937165284;78909;101076;103895;104254;61591.69204153951;64679.67660161669;64755.35244647682;73510.63244683742;90392.09607903837;9076.863847042432;9301.488418136796;9578.664746733832;9975.401475911469;10054.34646747959;49245.50754492409;49351;51074.97850043052;51173.744131279105;51240;70321.64839175771;71238.64380678271;73017;74722.62638686989;75141.6242918782;12185.756284874431;12517.374131293582;12733;12827.871721282667;13069;14117.576472705836;14191.219482928538;14230.430782768573;14336.71326573442;14381.92809035948;13014.15407998477;13029.934850325688;13141.934290328487;13748.312584370542;13952.930235348758;12607.87392126067;12976.15654982423;13359.730825571554;13507.932460338028;13677;12723.363831808558;12763.361831908182;13252.668683282995;13294.933525332312;13450;10449.791004179722;10702.571897124339;11456.19806613529;11603.245789023686;11718.003969662674;10599.89400105989;10704;10986.945065274942;11002.930803238913;11258.718532036764 18:23:31 INFO - PROCESS | 1973 | __end_tp_report 18:23:31 INFO - PROCESS | 1973 | __start_cc_report 18:23:31 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,1590 18:23:31 INFO - PROCESS | 1973 | __end_cc_report 18:23:31 INFO - PROCESS | 1973 | __startTimestamp1487903011719__endTimestamp 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | ------- Summary: start ------- 18:23:31 INFO - PROCESS | 1973 | Number of tests: 6 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#0] dojo.html Cycles:165 Average:5788.09 Median:6070.15 stddev:655.67 (10.8%) stddev-sans-first:630.23 18:23:31 INFO - PROCESS | 1973 | Values: 3395.1 3432.5 3445.3 3445.8 3497.7 5647.6 5707.9 5710.0 5729.8 5731.6 5731.4 5851.9 5856.2 5860.2 5861.4 5194.1 5228.4 5248.2 5251.9 5306.7 4391.9 5185.8 5188.7 5193.4 5332.8 4368.7 5219.7 5293.8 5295.4 5325.1 5310.8 5320.0 5321.8 5332.8 5336.9 5117.5 5172.3 5256.1 5263.7 5289.6 5706.1 5713.9 5774.5 5821.7 5830.2 6230.6 6248.8 6274.8 6284.7 6287.5 6254.1 6268.9 6280.0 6282.2 6287.7 5709.2 5831.7 5835.8 5866.9 5943.4 6272.4 6300.5 6302.7 6307.6 6312.1 6265.7 6266.7 6274.4 6275.7 6301.0 5804.3 6238.8 6242.7 6245.8 6273.3 6222.5 6255.3 6264.9 6326.9 6331.3 5218.7 5631.7 5893.9 6170.2 6282.9 4941.9 5027.1 5038.6 5043.3 5043.3 4425.9 4464.3 4473.4 4474.1 4517.0 5968.1 5971.2 5975.0 5979.2 5979.8 6235.8 6246.1 6248.2 6254.9 6265.8 5831.3 5892.9 5956.2 5960.3 5964.8 5501.1 5523.2 5531.5 5555.1 5566.9 6404.3 6420.7 6421.4 6446.3 6453.9 4739.4 6179.8 6206.9 6246.6 6263.4 6329.1 6362.4 6364.9 6443.5 6461.0 6236.6 6271.8 6298.3 6299.4 6309.3 5415.3 6194.2 6207.7 6236.5 6263.6 6113.0 6119.8 6148.7 6177.9 6266.7 6239.0 6244.5 6244.8 6258.2 6260.4 6062.6 6070.2 6119.3 6135.4 6138.4 6095.9 6128.0 6138.0 6169.8 6219.1 6117.7 6131.3 6138.0 6138.9 6161.0 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#1] ext.html Cycles:180 Average:71595.01 Median:40634.59 stddev:156574.43 (385.3%) stddev-sans-first:156922.74 18:23:31 INFO - PROCESS | 1973 | Values: 528.5 532.8 533.5 551.4 610.7 31672.5 40747.0 40835.6 42857.1 42973.7 42681.4 43003.6 43058.4 43070.0 43095.1 9524.9 9683.0 9701.6 9705.4 9729.1 9429.2 9552.8 9556.2 9558.8 9583.8 9538.3 9566.4 9577.8 9585.3 9593.5 9489.4 9559.0 9565.7 9568.8 9582.2 9512.0 9610.9 9615.8 9619.0 9621.2 40490.6 40522.2 40864.0 40875.2 40937.2 3195.2 3269.4 3281.5 3302.8 3337.7 29563.7 29586.6 29800.6 30943.5 31788.4 2945.9 3142.7 3164.5 3315.2 3421.8 503428.0 504360.0 504818.0 505438.0 505544.0 57239.1 57528.0 57564.0 57744.7 57756.7 62093.7 62098.0 62163.7 62182.0 62213.7 14439.4 16525.3 16734.8 16754.7 16776.0 12998.0 13056.2 13077.5 13083.4 13090.9 15330.6 15889.7 16003.7 16018.8 16030.6 5709.2 6822.3 6831.4 6878.7 6896.0 830.4 869.5 927.6 1057.7 1117.4 29239.4 29375.6 29825.3 30046.5 30108.8 1036.3 1052.0 1068.0 1096.5 1117.3 14760.8 14804.3 15433.9 15498.6 15902.4 641936.0 647231.0 650615.0 651229.0 654023.0 58921.4 59150.7 59175.7 59205.5 59326.4 1235.1 1255.4 1257.4 1364.4 1387.6 29324.7 31016.5 31588.1 32269.8 32318.0 54238.6 54716.5 54828.5 54978.7 55093.0 588551.0 595642.0 595848.0 596375.0 597090.0 32554.6 34597.0 35506.5 39595.2 39898.2 39678.8 43510.6 45429.5 46774.0 47155.8 38246.0 38312.6 38462.5 38617.6 39361.6 37432.4 37772.5 38062.9 38837.8 39084.6 37855.4 38099.4 38222.8 38355.6 38407.2 32224.0 33701.3 34327.0 34332.7 34402.8 25125.6 25157.9 25236.2 25322.1 25833.1 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#2] jquery.html Cycles:180 Average:35671.40 Median:20780.59 stddev:102314.23 (492.4%) stddev-sans-first:102566.86 18:23:31 INFO - PROCESS | 1973 | Values: 342.2 387.3 396.6 406.1 420.4 18492.4 19863.8 21007.7 21266.8 21267.3 12281.1 12416.8 14954.0 20016.8 21087.7 5307.5 5372.6 5574.1 5581.1 5586.9 5462.5 5616.2 5617.5 5621.7 5623.0 5361.9 5601.3 5614.5 5619.0 5620.1 5466.1 5574.2 5574.7 5582.9 5588.5 5265.4 5419.5 5426.3 5438.6 5505.5 13521.1 13802.6 13833.9 13837.4 18666.7 1968.6 2119.7 2144.1 2165.4 2225.4 9755.2 10204.4 10476.1 10719.6 10721.6 1839.6 1853.0 1907.1 1916.7 1968.8 100916.0 113964.0 123230.0 129600.7 132157.7 9232.2 10920.6 11406.9 11498.8 11850.4 15238.7 15253.8 15261.1 15293.2 15302.8 8592.4 8938.5 8941.6 9063.6 9084.0 7991.8 8299.0 8377.1 8683.7 8894.7 4519.8 4593.3 4593.8 4668.1 4677.9 1761.9 1845.6 1961.5 1962.6 1963.8 638.8 695.6 722.2 856.9 870.0 20553.5 21263.1 21319.8 21523.7 21604.7 797.4 903.1 916.2 975.9 986.8 10575.2 11130.0 11266.8 11273.7 11292.8 595983.0 599618.0 617333.0 618967.0 626888.0 130212.7 135822.6 138589.3 140039.3 142137.0 940.3 942.6 965.5 966.4 968.3 17745.0 17939.9 18446.8 18498.9 18599.2 51732.0 54126.0 54150.5 54203.2 54741.7 62763.0 76257.0 81806.1 83647.0 86729.0 10979.9 11510.1 12220.8 12684.5 12964.4 13292.1 13809.7 13815.7 14002.2 14453.3 11583.7 11766.5 12035.8 12136.8 12147.4 11544.4 11593.4 11666.2 11891.2 12183.6 11791.0 11844.0 12022.9 12124.5 12340.8 12211.8 12531.6 12744.6 12939.3 12996.1 10118.6 11880.8 12075.3 12087.2 12120.1 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#3] mootools.html Cycles:180 Average:5466.78 Median:4934.10 stddev:2990.54 (60.6%) stddev-sans-first:2983.98 18:23:31 INFO - PROCESS | 1973 | Values: 1488.2 1529.3 1718.5 1817.3 1898.9 7791.0 8223.3 8230.4 8232.5 8234.6 6856.4 7497.7 7685.1 8254.8 8269.8 2037.0 2347.7 2384.0 2395.4 2397.8 2388.3 2410.6 2432.9 2434.1 2436.6 2357.8 2367.2 2381.1 2400.6 2407.3 2368.8 2371.2 2393.6 2396.2 2396.9 2087.5 2385.1 2411.5 2412.1 2414.4 8415.2 8467.6 8472.8 8482.4 8484.6 5086.8 5314.1 5321.8 5323.7 5334.2 3545.1 3576.9 3578.2 3594.2 3654.4 1060.6 1074.3 1103.1 1103.2 1110.8 11020.8 11080.3 11088.4 11099.0 11116.4 4464.9 4640.2 4725.7 4727.5 4779.1 7845.7 9409.1 9476.6 9481.1 9499.7 5273.1 5273.5 5274.2 5277.3 5280.8 4387.3 4389.5 4848.9 4864.4 4874.2 3857.6 3908.9 3970.8 4007.9 4015.6 1264.5 1297.6 1297.9 1306.3 1308.1 823.7 894.8 903.1 903.4 934.4 4474.9 5605.7 5631.3 5649.9 5913.1 444.2 453.1 453.8 457.1 460.9 3782.7 3815.6 3820.5 3878.2 3897.3 9155.5 9886.3 10189.3 10318.7 10333.9 9618.6 10145.2 10295.8 10310.9 10317.0 7788.5 7877.8 7948.3 8010.5 8029.1 4961.3 5632.2 5665.8 5667.8 5853.3 9411.5 9544.0 9561.9 9569.2 9580.3 9234.2 9543.0 9556.5 9698.5 9717.6 6677.3 6872.1 6880.8 7245.8 7405.9 7685.6 7762.5 7937.1 8047.6 8067.8 6994.9 7201.3 7217.5 7255.7 7277.6 6852.0 6931.3 6975.0 7088.4 7196.6 6718.3 6723.3 6807.9 6808.9 6818.0 4702.9 4725.9 4780.3 4814.3 4859.4 4798.9 4883.0 4906.9 4983.0 5016.7 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#4] prototype.html Cycles:180 Average:4252.28 Median:4680.38 stddev:1304.12 (27.9%) stddev-sans-first:1273.67 18:23:31 INFO - PROCESS | 1973 | Values: 304.3 305.8 313.6 318.8 321.4 4316.4 4681.6 5091.5 5123.2 5196.3 3990.6 4150.2 4928.3 4985.4 5010.9 3252.7 3786.6 3839.7 3854.2 3915.7 3899.7 3921.3 3923.0 3950.2 3962.6 3400.3 3709.3 3905.8 3913.7 3940.7 3232.1 3734.0 3902.1 3912.4 3918.6 2665.9 2886.7 3369.1 3417.3 3428.5 4055.7 4481.7 4840.9 4966.6 5069.5 3793.5 4481.6 4486.5 4509.6 4512.9 5387.3 5396.2 5417.7 5418.2 5434.5 2927.5 3748.5 3756.3 3771.0 3802.7 5047.6 5401.4 5519.0 5573.7 5665.9 3810.4 3812.4 3821.9 3822.0 3830.6 3895.5 3906.3 3911.9 3919.3 3919.6 3972.4 4333.7 4458.6 4504.8 4528.3 4765.3 5364.5 5374.8 5405.9 5415.8 1057.9 1088.5 1143.6 1254.8 1256.8 712.9 797.0 817.5 842.6 852.9 4667.6 4698.0 4870.3 4873.7 4890.6 4655.6 5224.0 5226.5 5268.9 5348.4 4272.0 4283.7 4474.8 4753.6 4824.6 2333.7 2533.0 2583.9 2629.4 2749.6 4489.7 5126.8 5371.4 5396.2 5462.6 4082.0 4175.4 4736.0 4854.3 5223.7 5204.1 5392.6 5405.9 5413.2 5430.4 4412.6 4471.6 4484.4 4894.0 4907.8 4418.6 4653.4 4770.0 5121.9 5130.5 4201.2 4808.6 5414.0 5456.9 5462.6 3587.9 3607.4 3636.8 3639.3 3642.7 4874.9 5176.5 5237.9 5260.0 5603.2 5435.9 5439.7 5442.8 5443.0 5451.6 5154.0 5434.2 5454.2 5454.8 5486.0 5132.6 5185.7 5313.6 5353.4 5363.5 5277.1 5338.6 5478.4 5483.7 5485.7 4515.4 4679.2 5328.0 5356.0 5377.3 18:23:31 INFO - PROCESS | 1973 | 18:23:31 INFO - PROCESS | 1973 | [#5] yui.html Cycles:180 Average:20401.79 Median:4415.99 stddev:29726.41 (673.2%) stddev-sans-first:29772.60 18:23:31 INFO - PROCESS | 1973 | Values: 597.6 757.2 772.5 784.6 806.4 14334.1 15237.8 15413.2 15434.7 15455.6 13774.8 14397.5 14904.7 15066.8 15087.6 859.5 883.1 885.3 885.5 885.9 689.0 710.4 839.4 856.6 858.9 904.1 919.7 920.5 921.6 921.7 918.3 920.3 920.9 921.3 921.6 675.4 677.0 678.3 678.7 679.5 14669.0 14915.7 15075.2 15085.8 15246.3 13414.9 13420.2 13576.0 13916.8 14068.9 9904.9 11159.7 11393.1 11665.5 12280.9 3798.8 3811.9 3836.5 3843.1 3959.3 93970.1 94833.0 94988.0 95983.1 96400.6 10482.6 10634.9 10753.7 10807.9 10857.6 20410.8 20633.0 20854.3 20863.6 20932.9 7936.9 7949.6 7950.0 8084.6 8215.8 4306.1 4414.6 4417.4 4539.8 4571.2 3192.5 3279.3 3296.4 3301.3 3309.1 628.4 629.9 652.7 656.4 657.2 588.1 591.0 600.8 658.9 675.3 8970.9 9249.4 9264.1 9369.1 9408.8 631.2 637.5 648.4 682.8 683.5 4344.8 4573.7 4582.2 4644.7 4713.3 109946.0 111175.0 115177.4 116450.4 118582.8 66125.7 78909.0 101076.0 103895.0 104254.0 61591.7 64679.7 64755.4 73510.6 90392.1 9076.9 9301.5 9578.7 9975.4 10054.3 49245.5 49351.0 51075.0 51173.7 51240.0 70321.6 71238.6 73017.0 74722.6 75141.6 12185.8 12517.4 12733.0 12827.9 13069.0 14117.6 14191.2 14230.4 14336.7 14381.9 13014.2 13029.9 13141.9 13748.3 13952.9 12607.9 12976.2 13359.7 13507.9 13677.0 12723.4 12763.4 13252.7 13294.9 13450.0 10449.8 10702.6 11456.2 11603.2 11718.0 10599.9 10704.0 10986.9 11002.9 11258.7 18:23:31 INFO - PROCESS | 1973 | -------- Summary: end -------- 18:23:31 INFO - PROCESS | 1973 | 18:23:32 INFO - TEST-INFO | 1973: exit 0 18:23:32 INFO - TEST-OK | dromaeo_css | took 1117033ms 18:23:32 INFO - TEST-START | kraken 18:23:32 INFO - operating with platform_type : mac_ 18:23:32 INFO - Initialising browser for kraken test... 18:23:32 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpVDbOtA/profile http://localhost:49353/getInfo.html) 18:23:34 INFO - PROCESS | 1976 | __metrics Screen width/height:1600/1200 18:23:34 INFO - PROCESS | 1976 | colorDepth:24 18:23:34 INFO - PROCESS | 1976 | Browser inner width/height: 1024/689 18:23:34 INFO - PROCESS | 1976 | __metrics 18:23:34 INFO - PROCESS | 1976 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:23:34 INFO - PROCESS | 1976 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:23:35 INFO - TEST-INFO | 1976: exit 0 18:23:35 INFO - Browser initialized. 18:23:35 INFO - Running cycle 1/1 for kraken test... 18:23:35 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpVDbOtA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:47 INFO - PROCESS | 1978 | RSS: Main: 167673856 18:23:47 INFO - PROCESS | 1978 | 18:23:50 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-beat-detection) 18:23:51 INFO - PROCESS | 1978 | RSS: Main: 218517504 18:23:51 INFO - PROCESS | 1978 | 18:23:54 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-dft) 18:23:54 INFO - PROCESS | 1978 | RSS: Main: 231555072 18:23:54 INFO - PROCESS | 1978 | 18:23:58 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-fft) 18:23:58 INFO - PROCESS | 1978 | RSS: Main: 330498048 18:23:58 INFO - PROCESS | 1978 | 18:24:00 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49353/tests/kraken/driver.html?testName=audio-oscillator) 18:24:01 INFO - PROCESS | 1978 | RSS: Main: 232976384 18:24:01 INFO - PROCESS | 1978 | 18:24:03 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:04 INFO - PROCESS | 1978 | RSS: Main: 234258432 18:24:04 INFO - PROCESS | 1978 | 18:24:06 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:07 INFO - PROCESS | 1978 | RSS: Main: 273838080 18:24:07 INFO - PROCESS | 1978 | 18:24:10 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49353/tests/kraken/driver.html?testName=imaging-desaturate) 18:24:10 INFO - PROCESS | 1978 | RSS: Main: 273469440 18:24:10 INFO - PROCESS | 1978 | 18:24:13 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49353/tests/kraken/driver.html?testName=json-parse-financial) 18:24:14 INFO - PROCESS | 1978 | RSS: Main: 270315520 18:24:14 INFO - PROCESS | 1978 | 18:24:15 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49353/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:24:16 INFO - PROCESS | 1978 | RSS: Main: 249733120 18:24:16 INFO - PROCESS | 1978 | 18:24:18 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:24:18 INFO - PROCESS | 1978 | RSS: Main: 250785792 18:24:18 INFO - PROCESS | 1978 | 18:24:21 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:24:21 INFO - PROCESS | 1978 | RSS: Main: 253349888 18:24:21 INFO - PROCESS | 1978 | 18:24:24 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:24:25 INFO - PROCESS | 1978 | RSS: Main: 272674816 18:24:25 INFO - PROCESS | 1978 | 18:24:28 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:24:28 INFO - PROCESS | 1978 | RSS: Main: 266354688 18:24:28 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49353/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49353/tests/kraken/driver.html?testName=ai-astar) 18:24:30 INFO - PROCESS | 1978 | RSS: Main: 310673408 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | __start_tp_report 18:24:30 INFO - PROCESS | 1978 | _x_x_mozilla_page_load 18:24:30 INFO - PROCESS | 1978 | _x_x_mozilla_page_load_details 18:24:30 INFO - PROCESS | 1978 | |i|pagename|runs| 18:24:30 INFO - PROCESS | 1978 | |0;ai-astar;102;114;114;114;114;113;114;114;115;114 18:24:30 INFO - PROCESS | 1978 | |1;audio-beat-detection;142;107;121;167;107;124;129;132;138;107 18:24:30 INFO - PROCESS | 1978 | |2;audio-dft;184;185;186;182;185;203;183;182;183;187 18:24:30 INFO - PROCESS | 1978 | |3;audio-fft;81;58;60;88;61;59;59;58;85;56 18:24:30 INFO - PROCESS | 1978 | |4;audio-oscillator;85;71;93;95;92;94;90;67;68;91 18:24:30 INFO - PROCESS | 1978 | |5;imaging-gaussian-blur;90;87;90;84;128;126;90;87;89;86 18:24:30 INFO - PROCESS | 1978 | |6;imaging-darkroom;94;96;95;95;96;95;96;95;95;95 18:24:30 INFO - PROCESS | 1978 | |7;imaging-desaturate;82;83;82;75;82;81;85;83;82;82 18:24:30 INFO - PROCESS | 1978 | |8;json-parse-financial;53;52;52;53;56;55;57;56;56;55 18:24:30 INFO - PROCESS | 1978 | |9;json-stringify-tinderbox;65;76;65;76;72;68;69;69;69;72 18:24:30 INFO - PROCESS | 1978 | |10;stanford-crypto-aes;80;93;96;80;84;82;80;82;82;82 18:24:30 INFO - PROCESS | 1978 | |11;stanford-crypto-ccm;138;137;136;137;136;154;147;151;192;146 18:24:30 INFO - PROCESS | 1978 | |12;stanford-crypto-pbkdf2;161;206;161;161;164;210;151;194;214;192 18:24:30 INFO - PROCESS | 1978 | |13;stanford-crypto-sha256-iterative;67;65;66;65;65;66;63;64;66;78 18:24:30 INFO - PROCESS | 1978 | __end_tp_report 18:24:30 INFO - PROCESS | 1978 | __start_cc_report 18:24:30 INFO - PROCESS | 1978 | _x_x_mozilla_cycle_collect,2090 18:24:30 INFO - PROCESS | 1978 | __end_cc_report 18:24:30 INFO - PROCESS | 1978 | __startTimestamp1487903070861__endTimestamp 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | ------- Summary: start ------- 18:24:30 INFO - PROCESS | 1978 | Number of tests: 14 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.82 (3.4%) stddev-sans-first:0.50 18:24:30 INFO - PROCESS | 1978 | Values: 102.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 115.0 114.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:130.50 stddev:18.91 (14.5%) stddev-sans-first:19.31 18:24:30 INFO - PROCESS | 1978 | Values: 142.0 107.0 121.0 167.0 107.0 124.0 129.0 132.0 138.0 107.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#2] audio-dft Cycles:10 Average:186.00 Median:185.00 stddev:6.20 (3.4%) stddev-sans-first:6.53 18:24:30 INFO - PROCESS | 1978 | Values: 184.0 185.0 186.0 182.0 185.0 203.0 183.0 182.0 183.0 187.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#3] audio-fft Cycles:10 Average:66.50 Median:60.50 stddev:12.71 (21.0%) stddev-sans-first:12.35 18:24:30 INFO - PROCESS | 1978 | Values: 81.0 58.0 60.0 88.0 61.0 59.0 59.0 58.0 85.0 56.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:91.50 stddev:11.36 (12.4%) stddev-sans-first:12.05 18:24:30 INFO - PROCESS | 1978 | Values: 85.0 71.0 93.0 95.0 92.0 94.0 90.0 67.0 68.0 91.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:88.00 stddev:16.62 (18.9%) stddev-sans-first:17.50 18:24:30 INFO - PROCESS | 1978 | Values: 90.0 87.0 90.0 84.0 128.0 126.0 90.0 87.0 89.0 86.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 18:24:30 INFO - PROCESS | 1978 | Values: 94.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#7] imaging-desaturate Cycles:10 Average:81.70 Median:82.00 stddev:2.58 (3.2%) stddev-sans-first:2.74 18:24:30 INFO - PROCESS | 1978 | Values: 82.0 83.0 82.0 75.0 82.0 81.0 85.0 83.0 82.0 82.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.50 stddev:1.84 (3.3%) stddev-sans-first:1.87 18:24:30 INFO - PROCESS | 1978 | Values: 53.0 52.0 52.0 53.0 56.0 55.0 57.0 56.0 56.0 55.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:3.90 (5.5%) stddev-sans-first:3.67 18:24:30 INFO - PROCESS | 1978 | Values: 65.0 76.0 65.0 76.0 72.0 68.0 69.0 69.0 69.0 72.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:82.00 stddev:5.67 (6.9%) stddev-sans-first:5.81 18:24:30 INFO - PROCESS | 1978 | Values: 80.0 93.0 96.0 80.0 84.0 82.0 80.0 82.0 82.0 82.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:146.50 stddev:17.04 (11.6%) stddev-sans-first:17.73 18:24:30 INFO - PROCESS | 1978 | Values: 138.0 137.0 136.0 137.0 136.0 154.0 147.0 151.0 192.0 146.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:193.00 stddev:24.11 (12.5%) stddev-sans-first:24.42 18:24:30 INFO - PROCESS | 1978 | Values: 161.0 206.0 161.0 161.0 164.0 210.0 151.0 194.0 214.0 192.0 18:24:30 INFO - PROCESS | 1978 | 18:24:30 INFO - PROCESS | 1978 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.00 stddev:4.20 (6.4%) stddev-sans-first:4.45 18:24:30 INFO - PROCESS | 1978 | Values: 67.0 65.0 66.0 65.0 65.0 66.0 63.0 64.0 66.0 78.0 18:24:30 INFO - PROCESS | 1978 | -------- Summary: end -------- 18:24:30 INFO - PROCESS | 1978 | 18:24:31 INFO - TEST-INFO | 1978: exit 0 18:24:31 INFO - TEST-OK | kraken | took 59576ms 18:24:31 INFO - SUITE-END | took 1176s 18:24:31 INFO - Completed test suite (00:19:36) 18:24:32 INFO - javascript benchmark 18:24:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.083327501575, 3432.5366075579805, 3445.276491936693, 3445.8277086145695, 3497.650234976503, 5647.5764317676185, 5707.942920570793, 5709.971450142754, 5729.770809167629, 5731.570132240078, 5731.398203188668, 5851.882962340751, 5856.2094117294155, 5860.208871802305, 5861.384554621767, 5194.117000109981, 5228.398734145584, 5248.186531087687, 5251.894962100756, 5306.655067420625, 4391.86824395267, 5185.792568297264, 5188.688678679272, 5193.376794784631, 5332.813351532697, 4368.716033457831, 5219.686818790885, 5293.841184764463, 5295.391030031538, 5325.094733895247, 5310.8141215057485, 5320.015797077553, 5321.813736519242, 5332.8400147995235, 5336.946630533684, 5117.5394214520875, 5172.301739265189, 5256.053910296183, 5263.710495922761, 5289.629725919187, 5706.144078388275, 5713.857153571153, 5774.5091667208335, 5821.708914554255, 5830.154627578977, 6230.626162430269, 6248.781292654735, 6274.811755647338, 6284.654344011077, 6287.497000240016, 6254.124422580842, 6268.937310626881, 6279.968600157015, 6282.151909492206, 6287.717052732638, 5709.200711900379, 5831.650100993869, 5835.79574714883, 5866.941330586725, 5943.4056594340655, 6272.435480806749, 6300.527460440485, 6302.716377763011, 6307.590006649553, 6312.147860038928, 6265.749370025239, 6266.749330026839, 6274.40393162658, 6275.748970041147, 6300.9684951574945, 5804.2744656917885, 6238.75044998195, 6242.687865606701, 6245.75016999324, 6273.278572964076, 6222.502199824004, 6255.280642726054, 6264.906026409608, 6326.873462530723, 6331.3352098029045, 5218.712970786643, 5631.690257035821, 5893.911591326134, 6170.197874276405, 6282.905756413657, 4941.876453088702, 5027.095122877844, 5038.647294689336, 5043.268726034705, 5043.293938848564, 4425.867223983286, 4464.308032255006, 4473.4408198975125, 4474.060447306102, 4517.028838799673, 5968.134620480093, 5971.164037034819, 5975.0, 5979.19280897082, 5979.820605381846, 6235.812925612239, 6246.094316324107, 6248.156498872685, 6254.937450625435, 6265.780697675651, 5831.329397119301, 5892.911606325909, 5956.225690660273, 5960.344362120163, 5964.761409543657, 5501.14732216507, 5523.226748255248, 5531.502164805106, 5555.111182210827, 5566.944330556643, 6404.295527492065, 6420.74317027323, 6421.422072013541, 6446.323136070832, 6453.870922581616, 4739.360186374795, 6179.84550386244, 6206.875862482815, 6246.593971391845, 6263.3736626337, 6329.145565348619, 6362.395572420613, 6364.936350636434, 6443.484521238383, 6460.967695161494, 6236.563440559117, 6271.811845644638, 6298.275698294571, 6299.401556852092, 6309.2744334400295, 5415.296011518478, 6194.163787888758, 6207.720652570555, 6236.469900058546, 6263.56155069159, 6112.989854784201, 6119.776756597957, 6148.661823599745, 6177.876442471215, 6266.717997690206, 6239.0, 6244.500439964701, 6244.843878903063, 6258.186435763321, 6260.373962603885, 6062.636241825505, 6070.1501789750355, 6119.265688117454, 6135.417135372132, 6138.416850399205, 6095.872263631242, 6127.953177891068, 6138.0, 6169.753209871555, 6219.067139928866, 6117.705611546597, 6131.264248290234, 6137.9693101535995, 6138.940218748475, 6160.969195153995], "value": 5747.196535614725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.4583302115334, 532.8028629407119, 533.5384892068361, 551.4430425270475, 610.7038086528033, 31672.524912126337, 40746.98132546688, 40835.591644083586, 42857.14285714284, 42973.71078867639, 42681.35977960333, 43003.569964300346, 43058.35412468816, 43070.0, 43095.138097238036, 9524.8571271431, 9683.03169683033, 9701.611935522571, 9705.369151005198, 9729.075737804897, 9429.151376376125, 9552.761180970461, 9556.187724043464, 9558.80882382352, 9583.760405989871, 9538.332316737831, 9566.378185417925, 9577.808443831154, 9585.2811039172, 9593.47235902025, 9489.383190092656, 9558.996305387904, 9565.665201717977, 9568.760780980461, 9582.185514231334, 9512.001239869784, 9610.90389096114, 9615.759606009835, 9618.951905240428, 9621.18219951305, 40490.59509404927, 40522.18955620901, 40864.0, 40875.1824963502, 40937.181256375006, 3195.1532843796413, 3269.3788180245915, 3281.5405843181975, 3302.768806183568, 3337.7329813615097, 29563.70436295631, 29586.556201656993, 29800.552991705143, 30943.535846962313, 31788.36423271545, 2945.867435965387, 3142.7485801135854, 3164.47786115293, 3315.187778994162, 3421.8460169292193, 503428.0, 504359.99999999633, 504818.0, 505438.0, 505544.0, 57239.14141287884, 57528.0, 57564.0, 57744.71127644335, 57756.71121644365, 62093.68953155205, 62098.0, 62163.689181554706, 62182.0, 62213.68893155505, 14439.35022923971, 16525.33898644065, 16734.832651673572, 16754.74867876983, 16776.0, 12998.0, 13056.21662700241, 13077.476900923857, 13083.41124649393, 13090.869091308965, 15330.616734581501, 15889.682206355808, 16003.679926401406, 16018.75971860423, 16030.599235019217, 5709.172170035405, 6822.283660215705, 6831.419329357061, 6878.65606719672, 6895.965520172367, 830.4228561149943, 869.482657818597, 927.5547737086237, 1057.735566108473, 1117.3854380090918, 29239.415211696072, 29375.55936660952, 29825.254368640522, 30046.54930176049, 30108.84945575258, 1036.2849633752687, 1051.953306460077, 1067.989320106789, 1096.4517741129434, 1117.284937639911, 14760.778588321184, 14804.259787010606, 15433.922830385776, 15498.63218104949, 15902.443414480438, 641936.0, 647231.0, 650615.0, 651229.0, 654023.0, 58921.41078589245, 59150.70424647849, 59175.704121479976, 59205.51986200379, 59326.40673593209, 1235.092207227687, 1255.4162314523974, 1257.3838818978818, 1364.4405793624567, 1387.555982085723, 29324.706752932198, 31016.534751978288, 31588.052358429282, 32269.838650806596, 32318.030459086265, 54238.644033899465, 54716.45283547114, 54828.45171548233, 54978.725106374215, 55093.0, 588551.0, 595642.0, 595848.0, 596375.0, 597090.0, 32554.61195325653, 34597.0, 35506.467402989496, 39595.2080958385, 39898.2020359597, 39678.801605991786, 43510.56489435192, 45429.54570454385, 46774.0, 47155.76422117867, 38246.0, 38312.61687383202, 38462.46030319385, 38617.61382386253, 39361.606383935796, 37432.438513422865, 37772.489100435676, 38062.85811425662, 38837.80581097076, 39084.609153908095, 37855.43216851694, 38099.42850857295, 38222.80888595539, 38355.616443835206, 38407.23185536218, 32224.03327900167, 33701.3259734799, 34327.0, 34332.656673432946, 34402.82798585991, 25125.623115653645, 25157.87421062956, 25236.24291271265, 25322.075078362883, 25833.095841645452], "value": 20045.931690411675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.2299825392866, 387.26033276441996, 396.61132090551257, 406.1430381894201, 420.4450125833899, 18492.445226643205, 19863.801361986374, 21007.684884726743, 21266.787332126674, 21267.25564605237, 12281.140320177568, 12416.751664966718, 14954.0, 20016.79983200171, 21087.68368474474, 5307.460836357462, 5372.623916325859, 5574.052411090104, 5581.05122129239, 5586.8603284918, 5462.535684466825, 5616.241807356016, 5617.494425501704, 5621.662700237978, 5622.971885140569, 5361.946380536203, 5601.327840659126, 5614.494695477406, 5618.971905140468, 5620.100783874581, 5466.070767969455, 5574.247476590649, 5574.721263936787, 5582.86042848928, 5588.469095435927, 5265.394479634852, 5419.485148910847, 5426.294581704393, 5438.619296649236, 5505.504504594605, 13521.121127126706, 13802.585922422344, 13833.861661383358, 13837.377318020714, 18666.719999199886, 1968.5964377302619, 2119.73503312086, 2144.1101942693763, 2165.447810808234, 2225.3991422316044, 9755.170810481117, 10204.438755868425, 10476.1095306899, 10719.571217151304, 10721.571137154504, 1839.613681126965, 1852.97220541692, 1907.1036612791966, 1916.6645836978612, 1968.8326492248177, 100916.0, 113964.0, 123230.0, 129600.70399295981, 132157.6784232155, 9232.21526170283, 10920.577166247524, 11406.942965285287, 11498.770024599462, 11850.407479625985, 15238.714192870937, 15253.847461525243, 15261.084334939938, 15293.235338233044, 15302.846971530365, 8592.441491303045, 8938.50838203892, 8941.59762810675, 9063.592138353659, 9084.000759916404, 7991.800204994805, 8298.96262967129, 8377.120402357665, 8683.739487815375, 8894.73315800527, 4519.79660915253, 4593.28804035375, 4593.770311484413, 4668.136394766979, 4677.859664210079, 1761.8678599104862, 1845.6493266279633, 1961.5292329840736, 1962.6271008508338, 1963.8428925685912, 638.7636574467476, 695.5617960684838, 722.2227748873421, 856.9143085691381, 870.0125357718908, 20553.48616284605, 21263.149474021175, 21319.78680213209, 21523.67714484284, 21604.67592986075, 797.4378063465243, 903.1194585279432, 916.1937495004487, 975.9316847820585, 986.7681094942683, 10575.20685948557, 11129.998300152864, 11266.774664506827, 11273.718157045976, 11292.830607540893, 595983.0, 599618.0, 617333.0, 618967.0, 626888.0, 130212.69787302196, 135822.641773583, 138589.32339603957, 140039.29980350236, 142137.0, 940.2947789158131, 942.5522876633681, 965.4834663455018, 966.4104896013426, 968.2592816495387, 17745.0, 17939.910300448937, 18446.815531845048, 18498.907505462925, 18599.16303766361, 51732.0, 54126.0, 54150.45849541454, 54203.186952196535, 54741.726291368286, 62763.0, 76257.0, 81806.13678521223, 83647.0, 86729.0, 10979.890201097887, 11510.079193664655, 12220.816687749868, 12684.492620295454, 12964.416601252778, 13292.136011159244, 13809.723805524034, 13815.654608634864, 14002.229877356436, 14453.277336132942, 11583.6524904253, 11766.470508827295, 12035.75928481443, 12136.817947730968, 12147.39263036827, 11544.365059921785, 11593.362365069665, 11666.241694289844, 11891.167618266638, 12183.573574924834, 11790.997765189712, 11844.0, 12022.879771202175, 12124.454399552218, 12340.81488777651, 12211.75576488483, 12531.561395351118, 12744.61766147017, 12939.288339141061, 12996.090273680751, 10118.59525618967, 11880.762384752084, 12075.283061311486, 12087.153899226969, 12120.09099317537], "value": 9599.291802162821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.240997091483, 1529.2965235991444, 1718.4844546636014, 1817.2730907636935, 1898.8955607441585, 7790.961045194773, 8223.259906608411, 8230.42387032908, 8232.46488978216, 8234.588270586477, 6856.417204537608, 7497.662605182766, 7685.077790665128, 8254.793630159249, 8269.83460330793, 2036.9694454583193, 2347.7417484076736, 2383.9761602384015, 2395.4370722880108, 2397.844140130886, 2388.319328991238, 2410.6143017117265, 2432.8905199266073, 2434.123715462441, 2436.573599620059, 2357.7524359942217, 2367.1714899785024, 2381.1070848431837, 2400.579898517761, 2407.337982054931, 2368.786809187173, 2371.1700904683394, 2393.581123303415, 2396.2212281008656, 2396.940076498084, 2087.5094352827073, 2385.0817435287368, 2411.517696460715, 2412.0592968742203, 2414.396400899775, 8415.242628163494, 8467.576621168979, 8472.78818029548, 8482.36382271326, 8484.618192181366, 5086.77109530072, 5314.123169676998, 5321.840344789663, 5323.680579165262, 5334.1732031535175, 3545.1137215696076, 3576.910577235564, 3578.1949061461223, 3594.155373487228, 3654.4335627977707, 1060.6181774561153, 1074.2587614546017, 1103.1395511501041, 1103.189155970361, 1110.7778444311089, 11020.834687479693, 11080.279781814237, 11088.445577721162, 11099.0, 11116.44417779116, 4464.933026004613, 4640.1879671058105, 4725.669203155746, 4727.527247275314, 4779.115863565252, 7845.725399610987, 9409.106134917172, 9476.620935162517, 9481.09929556691, 9499.715008549754, 5273.077211487975, 5273.525382715574, 5274.156135018378, 5277.261183434322, 5280.81517146898, 4387.319965405367, 4389.495208051051, 4848.854534363974, 4864.391951006124, 4874.2201247800895, 3857.61423857612, 3908.863189788344, 3970.841166353371, 4007.8597249096724, 4015.578364271768, 1264.5447638850007, 1297.6042307096238, 1297.8961683065331, 1306.3272414706435, 1308.0974127851753, 823.6705317872899, 894.8120894612074, 903.1104362203224, 903.4308385716956, 934.3973137326386, 4474.903648606112, 5605.747741351649, 5631.2960879890015, 5649.943500565024, 5913.142594323799, 444.16718652526515, 453.08476876708863, 453.8479609330845, 457.11776271795753, 460.9331646911246, 3782.659560639556, 3815.637514436124, 3820.522434695663, 3878.2243551289853, 3897.2719096631904, 9155.496447695441, 9886.25853061009, 10189.337693049929, 10318.742031449123, 10333.896661033445, 9618.567164477616, 10145.188384929188, 10295.845562316572, 10310.896891031143, 10317.0, 7788.493747906368, 7877.7636670899965, 7948.284654381134, 8010.519368837889, 8029.076656184497, 4961.305417241661, 5632.211490391431, 5665.801696940587, 5667.773289068391, 5853.326867410302, 9411.52942352866, 9544.0, 9561.9043809561, 9569.234461243223, 9580.329376943826, 9234.1689247968, 9542.997985211452, 9556.522173891417, 9698.51507424612, 9717.56270967794, 6677.332266773284, 6872.072270243453, 6880.759173428906, 7245.818854528468, 7405.925940740523, 7685.615719214129, 7762.495437796525, 7937.126916039347, 8047.557384343917, 8067.757967260757, 6994.895076573754, 7201.279872012757, 7217.494775365674, 7255.673494692646, 7277.636118194175, 6851.9657401712675, 6931.341522555349, 6974.954526374792, 7088.432925365855, 7196.5682059074525, 6718.2609912908565, 6723.2940541244425, 6807.863842723019, 6808.897866531907, 6818.0, 4702.882427939329, 4725.929111063406, 4780.330753694555, 4814.277858321279, 4859.416869975626, 4798.928016079692, 4882.975585122052, 4906.901861962812, 4982.978489409735, 5016.697365859508], "value": 4382.594737002305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.2985917460254, 305.8363775380171, 313.5751057317335, 318.83420621276935, 321.4246498767207, 4316.43886294782, 4681.555252251035, 5091.498008087614, 5123.231515272711, 5196.2592531502, 3990.620891015356, 4150.198615934248, 4928.260760885869, 4985.376827896513, 5010.874728131789, 3252.67473252674, 3786.5645450773236, 3839.6544311011935, 3854.1906199698096, 3915.6671682906986, 3899.688024958011, 3921.3333733265413, 3922.9803850980707, 3950.2297052074855, 3962.623550762673, 3400.336934297813, 3709.2766910452474, 3905.785181814999, 3913.710479369572, 3940.6847452203756, 3232.0788575256174, 3733.9813300933456, 3902.122022544933, 3912.37402015679, 3918.647321741057, 2665.9066932657265, 2886.682464928856, 3369.073504786179, 3417.325510499862, 3428.468587368962, 4055.6958228133008, 4481.708688935209, 4840.927386089212, 4966.602671786249, 5069.543741063322, 3793.525809273841, 4481.633101903918, 4486.528914463967, 4509.564834989102, 4512.887177820548, 5387.272718183034, 5396.217548455491, 5417.674939503603, 5418.214358917974, 5434.538064264542, 2927.536231884058, 3748.475213470116, 3756.3050835595504, 3770.96298517907, 3802.714796390254, 5047.592874809489, 5401.43284955078, 5518.972405137989, 5573.721313934287, 5665.945897357816, 3810.428435734662, 3812.4281357796, 3821.9044523887123, 3822.0125534721788, 3830.559485659129, 3895.5130608673917, 3906.2968665640456, 3911.9071296856614, 3919.333713268751, 3919.6080391960577, 3972.4239985201984, 4333.674974376934, 4458.598726114666, 4504.761190672526, 4528.320751887178, 4765.309030190672, 5364.490373414519, 5374.838754837361, 5405.918911216335, 5415.83752487426, 1057.9312344697723, 1088.5101704232968, 1143.5540139345662, 1254.8494180698374, 1256.8114782782473, 712.8574285142992, 796.9641366138537, 817.5340056168046, 842.613900134288, 852.9488230706178, 4667.556582124693, 4697.953020469751, 4870.342503761947, 4873.733713823019, 4890.608751299887, 4655.580997710223, 5224.03355379256, 5226.451222621646, 5268.947310526923, 5348.358197016383, 4272.0, 4283.686809123101, 4474.822875758183, 4753.623188405797, 4824.552634209793, 2333.7432882382923, 2533.049551845069, 2583.948321033569, 2629.421527263999, 2749.649050062208, 4489.685721999429, 5126.846194614168, 5371.3822910365025, 5396.1905714143195, 5462.590305727086, 4081.9591804082174, 4175.394567787654, 4735.981763920774, 4854.320395144682, 5223.682961319513, 5204.089284375219, 5392.595555333366, 5405.945840561131, 5413.242146099628, 5430.402655707869, 4412.646988240997, 4471.642268618568, 4484.417025786627, 4893.962480953299, 4907.803687852446, 4418.624416924469, 4653.418322709661, 4770.0, 5121.874422114781, 5130.48695130484, 4201.248182440507, 4808.567228949411, 5413.99523264592, 5456.91814622773, 5462.590305727166, 3587.8652201939803, 3607.4408466687705, 3636.836342364547, 3639.3449179148006, 3642.7085833132755, 4874.926876096933, 5176.50823171798, 5237.895242095061, 5260.028678347823, 5603.243562119062, 5435.945640543544, 5439.688132543574, 5442.755076021509, 5443.0, 5451.64564303319, 5154.0, 5434.212039254365, 5454.2091396746455, 5454.836354909359, 5485.9725701371235, 5132.640715150053, 5185.688858668492, 5313.601479889101, 5353.420740881527, 5363.517283444508, 5277.102892508359, 5338.599605029716, 5478.369987451494, 5483.725813709379, 5485.670859748428, 4515.367848501278, 4679.2045352289515, 5328.0, 5355.973220133875, 5377.257598624651], "value": 3837.775312239092, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.5578072226552, 757.1681909657877, 772.5133166105353, 784.6116172494612, 806.4234072638064, 14334.139951602898, 15237.77143342848, 15413.229338533085, 15434.69130617387, 15455.61360965975, 13774.793378099337, 14397.496087636935, 14904.701905961874, 15066.84933150668, 15087.62280942977, 859.4800145911727, 883.1301168349171, 885.2696525366566, 885.4642941020686, 885.8848349714528, 689.0456717446348, 710.3535782437982, 839.4459656626627, 856.610242339737, 858.909814469481, 904.1094521895927, 919.72868003939, 920.4937284493535, 921.5760750054983, 921.6681994481982, 918.3433844800966, 920.2637889688223, 920.8618707193974, 921.3366376209152, 921.5576523268868, 675.3651567526509, 677.0352248046563, 678.2640834694363, 678.7115475922762, 679.5447050476192, 14668.973171877973, 14915.70168596622, 15075.170865602387, 15085.773713394308, 15246.313915873812, 13414.86585134146, 13420.194788312732, 13576.0, 13916.791248131287, 14068.859311406857, 9904.900950990543, 11159.665210043711, 11393.145514086476, 11665.475053622607, 12280.877191228063, 3798.8480460781534, 3811.866584669551, 3836.5396152461603, 3843.13529455873, 3959.2675355059405, 93970.12059758767, 94833.0, 94988.0, 95983.08033839354, 96400.55399169089, 10482.580696772196, 10634.946825265824, 10753.677389678322, 10807.945960270148, 10857.619983300547, 20410.795892041187, 20633.0, 20854.27010054671, 20863.582728345347, 20932.895335523226, 7936.880946785803, 7949.562774047367, 7949.960250198827, 8084.595770211466, 8215.7946051348, 4306.117245964632, 4414.602685758297, 4417.3815665806815, 4539.84110556129, 4571.2000399929875, 3192.4732419151046, 3279.32773781379, 3296.357210344008, 3301.339732053551, 3309.073459431363, 628.3684896678809, 629.9181106456132, 652.7225928980164, 656.3534918105662, 657.1588366890388, 588.109014842514, 590.9940900591025, 600.7777122464717, 658.868226354731, 675.3449154320301, 8970.86543701845, 9249.445033298023, 9264.11990860867, 9369.109934556207, 9408.764780880532, 631.2235949781794, 637.4613451633362, 648.3710800523579, 682.7712716240115, 683.480554778372, 4344.761038142869, 4573.748443835554, 4582.152301824173, 4644.744539050317, 4713.34013238147, 109946.0, 111175.0, 115177.4241128789, 116450.41774791072, 118582.8141718589, 66125.66937165284, 78909.0, 101076.0, 103895.0, 104254.0, 61591.69204153951, 64679.67660161669, 64755.35244647682, 73510.63244683742, 90392.09607903837, 9076.863847042432, 9301.488418136796, 9578.664746733832, 9975.401475911469, 10054.34646747959, 49245.50754492409, 49351.0, 51074.97850043052, 51173.744131279105, 51240.0, 70321.64839175771, 71238.64380678271, 73017.0, 74722.62638686989, 75141.6242918782, 12185.756284874431, 12517.374131293582, 12733.0, 12827.871721282667, 13069.0, 14117.576472705836, 14191.219482928538, 14230.430782768573, 14336.71326573442, 14381.92809035948, 13014.15407998477, 13029.934850325688, 13141.934290328487, 13748.312584370542, 13952.930235348758, 12607.87392126067, 12976.15654982423, 13359.730825571554, 13507.932460338028, 13677.0, 12723.363831808558, 12763.361831908182, 13252.668683282995, 13294.933525332312, 13450.0, 10449.791004179722, 10702.571897124339, 11456.19806613529, 11603.245789023686, 11718.003969662674, 10599.89400105989, 10704.0, 10986.945065274942, 11002.930803238913, 11258.718532036764], "value": 7420.8887654145055, "unit": "score"}], "name": "dromaeo_css", "value": 7189.077650565757, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 121.0, 167.0, 107.0, 124.0, 129.0, 132.0, 138.0, 107.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 182.0, 185.0, 203.0, 183.0, 182.0, 183.0, 187.0], "value": 186.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 88.0, 61.0, 59.0, 59.0, 58.0, 85.0, 56.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 93.0, 95.0, 92.0, 94.0, 90.0, 67.0, 68.0, 91.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 84.0, 128.0, 126.0, 90.0, 87.0, 89.0, 86.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 75.0, 82.0, 81.0, 85.0, 83.0, 82.0, 82.0], "value": 81.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 56.0, 55.0, 57.0, 56.0, 56.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 65.0, 76.0, 72.0, 68.0, 69.0, 69.0, 69.0, 72.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 93.0, 96.0, 80.0, 84.0, 82.0, 80.0, 82.0, 82.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 137.0, 136.0, 154.0, 147.0, 151.0, 192.0, 146.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 206.0, 161.0, 161.0, 164.0, 210.0, 151.0, 194.0, 214.0, 192.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 65.0, 65.0, 66.0, 63.0, 64.0, 66.0, 78.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1453.9000000000003, "alertThreshold": 2.0}]} 18:24:32 INFO - Return code: 0 18:24:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:24:32 INFO - # TBPL SUCCESS # 18:24:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:24:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:24:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:24:32 INFO - 2017-02-23 18:24:32.168 screenresolution[1982:12242] starting screenresolution argv=screenresolution get 18:24:32 INFO - 2017-02-23 18:24:32.171 screenresolution[1982:12242] Display 0: 1600x1200x32@60 18:24:32 INFO - 2017-02-23 18:24:32.177 screenresolution[1983:12245] starting screenresolution argv=screenresolution list 18:24:32 INFO - Available Modes on Display 0 18:24:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:24:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:24:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:24:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:24:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:24:32 INFO - Intel Iris: 18:24:32 INFO - Chipset Model: Intel Iris 18:24:32 INFO - Type: GPU 18:24:32 INFO - Bus: Built-In 18:24:32 INFO - VRAM (Dynamic, Max): 1536 MB 18:24:32 INFO - Vendor: Intel (0x8086) 18:24:32 INFO - Device ID: 0x0a2e 18:24:32 INFO - Revision ID: 0x0009 18:24:32 INFO - Displays: 18:24:32 INFO - Display: 18:24:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:24:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:24:32 INFO - Display Serial Number: 1600x1200 60 18:24:32 INFO - Main Display: Yes 18:24:32 INFO - Mirror: Off 18:24:32 INFO - Online: Yes 18:24:32 INFO - Rotation: Supported 18:24:32 INFO - Return code: 0 18:24:32 INFO - Running post-action listener: _resource_record_post_action 18:24:32 INFO - [mozharness: 2017-02-24 02:24:32.426121Z] Finished run-tests step (success) 18:24:32 INFO - Running post-run listener: _resource_record_post_run 18:24:32 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 561152; Write bytes: 501482496; Read time: 9; Write time: 1633 18:24:32 INFO - TinderboxPrint: CPU usage
53.5% 18:24:32 INFO - TinderboxPrint: I/O read bytes / time
561,152 / 9 18:24:32 INFO - TinderboxPrint: I/O write bytes / time
501,482,496 / 1,633 18:24:32 INFO - TinderboxPrint: CPU idle
2,231.0 (46.5%) 18:24:32 INFO - TinderboxPrint: CPU system
959.8 (20.0%) 18:24:32 INFO - TinderboxPrint: CPU user
1,606.9 (33.5%) 18:24:32 INFO - TinderboxPrint: Swap in / out
192,331,776 / 0 18:24:32 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 177048064; Write bytes: 178665472; Read time: 16818; Write time: 431 18:24:32 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 561152; Write bytes: 305081344; Read time: 9; Write time: 1149 18:24:32 INFO - Running post-run listener: _upload_blobber_files 18:24:32 INFO - Blob upload gear active. 18:24:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:24:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:24:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:24:33 INFO - (blobuploader) - INFO - Open directory for files ... 18:24:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:24:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:34 INFO - (blobuploader) - INFO - Done attempting. 18:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:35 INFO - (blobuploader) - INFO - Done attempting. 18:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:24:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:24:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:24:37 INFO - (blobuploader) - INFO - Done attempting. 18:24:37 INFO - (blobuploader) - INFO - Iteration through files over. 18:24:37 INFO - Return code: 0 18:24:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:24:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:24:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/305175082f1f4ca70bae0df75ae3d749ea3bbd5126162c9d86c870363f600283448d0ba8fb62a76899b685b5b52765994388d7f472e883daeafac26e3591687f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e4c4a1791c0fead92071fa92cb031c768b8e50c35d7da84f9380387447d1e472850f396de8d8aafb45485593394e2ec9af4362a887c0f6bed151e6f9deabc78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1f78e515c360986823fb6c6f9a1d9314ced65af71d251cc71b83e489af5aad9a6a2ae12542c88c63b0f17739022d7b195256d048fa46f204940031ed423e1e"} 18:24:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:24:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:24:37 INFO - Contents: 18:24:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/305175082f1f4ca70bae0df75ae3d749ea3bbd5126162c9d86c870363f600283448d0ba8fb62a76899b685b5b52765994388d7f472e883daeafac26e3591687f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e4c4a1791c0fead92071fa92cb031c768b8e50c35d7da84f9380387447d1e472850f396de8d8aafb45485593394e2ec9af4362a887c0f6bed151e6f9deabc78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1f78e515c360986823fb6c6f9a1d9314ced65af71d251cc71b83e489af5aad9a6a2ae12542c88c63b0f17739022d7b195256d048fa46f204940031ed423e1e"} 18:24:37 INFO - Running post-run listener: copy_logs_to_upload_dir 18:24:37 INFO - Copying logs to upload dir... 18:24:37 INFO - mkdir: /builds/slave/test/build/upload/logs 18:24:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.753073 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-23 18:24:37.873739) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 18:24:37.877473) ========= 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.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/305175082f1f4ca70bae0df75ae3d749ea3bbd5126162c9d86c870363f600283448d0ba8fb62a76899b685b5b52765994388d7f472e883daeafac26e3591687f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e4c4a1791c0fead92071fa92cb031c768b8e50c35d7da84f9380387447d1e472850f396de8d8aafb45485593394e2ec9af4362a887c0f6bed151e6f9deabc78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1f78e515c360986823fb6c6f9a1d9314ced65af71d251cc71b83e489af5aad9a6a2ae12542c88c63b0f17739022d7b195256d048fa46f204940031ed423e1e"} build_url:https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011007 build_url: 'https://queue.taskcluster.net/v1/task/EB8DS4vjQmeOudgCiqLBJQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/305175082f1f4ca70bae0df75ae3d749ea3bbd5126162c9d86c870363f600283448d0ba8fb62a76899b685b5b52765994388d7f472e883daeafac26e3591687f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e4c4a1791c0fead92071fa92cb031c768b8e50c35d7da84f9380387447d1e472850f396de8d8aafb45485593394e2ec9af4362a887c0f6bed151e6f9deabc78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b1f78e515c360986823fb6c6f9a1d9314ced65af71d251cc71b83e489af5aad9a6a2ae12542c88c63b0f17739022d7b195256d048fa46f204940031ed423e1e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 18:24:37.911006) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:24:37.911578) ========= 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.NlapZy0NoW/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.zIk0b4FqNh/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005149 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 18:24:37.961062) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 18:24:37.961469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 18:24:37.961858) ========= ========= Total master_lag: 0.19 =========