builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0240 starttime: 1493718446.76 results: success (0) buildid: 20170502004016 builduid: beaa831cb42d4aeebd9c187d87c35332 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.759703) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.760110) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.760420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.787424) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.787789) ========= 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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-05-02 02:47:26-- 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% 18.4M=0.001s 2017-05-02 02:47:26 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132275 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.945202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.945585) ========= 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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.026816 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:26.994826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-02 02:47:26.995172) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-05-02 02:47:27,080 truncating revision to first 12 chars 2017-05-02 02:47:27,080 Setting DEBUG logging. 2017-05-02 02:47:27,080 attempt 1/10 2017-05-02 02:47:27,080 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-02 02:47:27,860 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.063080 ========= master_lag: 1.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-02 02:47:29.554493) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:29.554836) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:29.555218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:29.555480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 02:47:29.582095) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-05-02 02:47:29.582459) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:47:29 INFO - MultiFileLogger online at 20170502 02:47:29 in /builds/slave/test 02:47:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:47:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:47:29 INFO - {'append_to_log': False, 02:47:29 INFO - 'base_work_dir': '/builds/slave/test', 02:47:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:47:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:47:29 INFO - 'branch': 'Mozilla-Aurora', 02:47:29 INFO - 'buildbot_json_path': 'buildprops.json', 02:47:29 INFO - 'config_files': ('talos/mac_config.py',), 02:47:29 INFO - 'default_actions': ('clobber', 02:47:29 INFO - 'read-buildbot-config', 02:47:29 INFO - 'download-and-extract', 02:47:29 INFO - 'populate-webroot', 02:47:29 INFO - 'create-virtualenv', 02:47:29 INFO - 'install', 02:47:29 INFO - 'run-tests'), 02:47:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:47:29 INFO - 'download_minidump_stackwalk': True, 02:47:29 INFO - 'download_symbols': 'ondemand', 02:47:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:47:29 INFO - 'tooltool.py': '/tools/tooltool.py', 02:47:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:47:29 INFO - '/tools/misc-python/virtualenv.py')}, 02:47:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:47:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:47:29 INFO - 'gecko_profile': False, 02:47:29 INFO - 'gecko_profile_interval': 0, 02:47:29 INFO - 'installer_path': 'installer.exe', 02:47:29 INFO - 'log_level': 'info', 02:47:29 INFO - 'log_name': 'talos', 02:47:29 INFO - 'log_to_console': True, 02:47:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:47:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:47:29 INFO - 'opt_config_files': (), 02:47:29 INFO - 'pip_index': False, 02:47:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:29 INFO - 'cmd': ('bash', 02:47:29 INFO - '-c', 02:47:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:29 INFO - 'enabled': True, 02:47:29 INFO - 'halt_on_failure': False, 02:47:29 INFO - 'name': 'check_screen_resolution'},), 02:47:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:29 INFO - 'cmd': ('bash', 02:47:29 INFO - '-c', 02:47:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:29 INFO - 'enabled': True, 02:47:29 INFO - 'halt_on_failure': False, 02:47:29 INFO - 'name': 'check_screen_resolution'},), 02:47:29 INFO - 'run_cmd_checks_enabled': True, 02:47:29 INFO - 'suite': 'dromaeojs', 02:47:29 INFO - 'system_bits': '32', 02:47:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:47:29 INFO - 'title': 't-yosemite-r7-0240', 02:47:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:47:29 INFO - 'use_talos_json': True, 02:47:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:47:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:47:29 INFO - 'work_dir': 'build'} 02:47:29 INFO - [mozharness: 2017-05-02 09:47:29.776995Z] Running clobber step. 02:47:29 INFO - Running pre-action listener: _resource_record_pre_action 02:47:29 INFO - Running main action method: clobber 02:47:29 INFO - rmtree: /builds/slave/test/build 02:47:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:47:32 INFO - Running post-action listener: _resource_record_post_action 02:47:32 INFO - [mozharness: 2017-05-02 09:47:32.315157Z] Finished clobber step (success) 02:47:32 INFO - [mozharness: 2017-05-02 09:47:32.315280Z] Running read-buildbot-config step. 02:47:32 INFO - Running pre-action listener: _resource_record_pre_action 02:47:32 INFO - Running main action method: read_buildbot_config 02:47:32 INFO - Using buildbot properties: 02:47:32 INFO - { 02:47:32 INFO - "project": "", 02:47:32 INFO - "product": "firefox", 02:47:32 INFO - "script_repo_revision": "production", 02:47:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:47:32 INFO - "repository": "", 02:47:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:47:32 INFO - "buildid": "20170502004016", 02:47:32 INFO - "stage_platform": "macosx64", 02:47:32 INFO - "basedir": "/builds/slave/test", 02:47:32 INFO - "nightly_build": "True", 02:47:32 INFO - "buildnumber": 0, 02:47:32 INFO - "slavename": "t-yosemite-r7-0240", 02:47:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:47:32 INFO - "platform": "yosemite_r7", 02:47:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:47:32 INFO - "branch": "mozilla-aurora", 02:47:32 INFO - "repo_path": "releases/mozilla-aurora", 02:47:32 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:47:32 INFO - "pgo_build": "False", 02:47:32 INFO - "builduid": "beaa831cb42d4aeebd9c187d87c35332", 02:47:32 INFO - "slavebuilddir": "test" 02:47:32 INFO - } 02:47:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:47:32 INFO - Running post-action listener: _resource_record_post_action 02:47:32 INFO - [mozharness: 2017-05-02 09:47:32.318770Z] Finished read-buildbot-config step (success) 02:47:32 INFO - [mozharness: 2017-05-02 09:47:32.318909Z] Running download-and-extract step. 02:47:32 INFO - Running pre-action listener: _resource_record_pre_action 02:47:32 INFO - Running main action method: download_and_extract 02:47:32 INFO - mkdir: /builds/slave/test/build/tests 02:47: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')]} 02:47:32 INFO - trying https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:47:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:47:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:47:33 INFO - Downloaded 1199 bytes. 02:47:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:47:33 INFO - Using the following test package requirements: 02:47:33 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:47:33 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:47:33 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:47:33 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'jsshell-mac.zip'], 02:47:33 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:47:33 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:47:33 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:47:33 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:47:33 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:47:33 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:47:33 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:47:33 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:47:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:47:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:47:35 INFO - Expected file size: 51791450 02:47:35 INFO - Obtained file size: 51791450 02:47:51 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:47:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:47:54 INFO - Expected file size: 51791450 02:47:54 INFO - Obtained file size: 51791450 02:48:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:48:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:48:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:48:10 INFO - Expected file size: 14404424 02:48:10 INFO - Obtained file size: 14404424 02:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:10 INFO - trying https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:48:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:48:12 INFO - Downloaded 74780935 bytes. 02:48:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:48:12 INFO - mkdir: /builds/slave/test/properties 02:48:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:48:12 INFO - Writing to file /builds/slave/test/properties/build_url 02:48:12 INFO - Contents: 02:48:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:48:13 INFO - Running post-action listener: _resource_record_post_action 02:48:13 INFO - Running post-action listener: set_extra_try_arguments 02:48:13 INFO - [mozharness: 2017-05-02 09:48:13.321704Z] Finished download-and-extract step (success) 02:48:13 INFO - [mozharness: 2017-05-02 09:48:13.321828Z] Running populate-webroot step. 02:48:13 INFO - Running pre-action listener: _resource_record_pre_action 02:48:13 INFO - Running main action method: populate_webroot 02:48:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:48:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:48:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:48:13 INFO - '--tppagecycles', 02:48:13 INFO - '7'], 02:48:13 INFO - 'tests': ['tsvgm']}}, 02:48:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:48:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:48:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:48:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:48:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['damp', 'tps']}, 02:48:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'tests': ['damp', 'tps']}, 02:48:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['dromaeo_dom']}, 02:48:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:48:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['basic_compositor_video']}, 02:48:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:48:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'other-e10s': {'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['a11yr', 02:48:13 INFO - 'ts_paint', 02:48:13 INFO - 'tpaint', 02:48:13 INFO - 'sessionrestore', 02:48:13 INFO - 'sessionrestore_no_auto_restore', 02:48:13 INFO - 'tabpaint']}, 02:48:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['tsvgx', 02:48:13 INFO - 'tsvgr_opacity', 02:48:13 INFO - 'tart', 02:48:13 INFO - 'tscrollx', 02:48:13 INFO - 'cart', 02:48:13 INFO - 'tsvg_static']}, 02:48:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:48:13 INFO - 'tsvgr_opacity', 02:48:13 INFO - 'tart', 02:48:13 INFO - 'tscrollx', 02:48:13 INFO - 'cart', 02:48:13 INFO - 'tsvg_static']}, 02:48:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'talos_options': ['--disable-e10s'], 02:48:13 INFO - 'tests': ['tp5o']}, 02:48:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:48:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'talos_options': ['--disable-e10s', 02:48:13 INFO - '--xperf_path', 02:48:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:13 INFO - 'tests': ['tp5n']}, 02:48:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:48:13 INFO - 'talos_options': ['--xperf_path', 02:48:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:13 INFO - 'tests': ['tp5n']}}, 02:48:13 INFO - 'talos.zip': {'path': '', 02:48:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:48:13 INFO - Running post-action listener: _resource_record_post_action 02:48:13 INFO - [mozharness: 2017-05-02 09:48:13.340233Z] Finished populate-webroot step (success) 02:48:13 INFO - [mozharness: 2017-05-02 09:48:13.340352Z] Running create-virtualenv step. 02:48:13 INFO - Running pre-action listener: _resource_record_pre_action 02:48:13 INFO - Running main action method: create_virtualenv 02:48:13 INFO - Creating virtualenv /builds/slave/test/build/venv 02:48:13 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 02:48:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:48:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:48:13 INFO - Using real prefix '/tools/python27' 02:48:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:48:14 INFO - Installing distribute.............................................................................................................................................................................................done. 02:48:17 INFO - Installing pip.................done. 02:48:17 INFO - Return code: 0 02:48:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:48:17 INFO - Reading from file tmpfile_stdout 02:48:17 INFO - Output received: 02:48:17 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) 02:48:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:48:17 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')]} 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 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')]} 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:17 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 02:48:17 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 02:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:17 INFO - 'HOME': '/Users/cltbld', 02:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:17 INFO - 'LOGNAME': 'cltbld', 02:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:17 INFO - 'MOZ_NO_REMOTE': '1', 02:48:17 INFO - 'NO_EM_RESTART': '1', 02:48:17 INFO - 'PAGER': '/bin/cat', 02:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:17 INFO - 'PWD': '/builds/slave/test', 02:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:17 INFO - 'SHELL': '/bin/bash', 02:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:17 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:17 INFO - 'USER': 'cltbld', 02:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:17 INFO - 'XPC_FLAGS': '0x0', 02:48:17 INFO - 'XPC_SERVICE_NAME': '0', 02:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:17 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 02:48:17 INFO - Cleaning up... 02:48:17 INFO - Return code: 0 02:48:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:48:17 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')]} 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 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')]} 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:17 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 02:48:17 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 02:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:17 INFO - 'HOME': '/Users/cltbld', 02:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:17 INFO - 'LOGNAME': 'cltbld', 02:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:17 INFO - 'MOZ_NO_REMOTE': '1', 02:48:17 INFO - 'NO_EM_RESTART': '1', 02:48:17 INFO - 'PAGER': '/bin/cat', 02:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:17 INFO - 'PWD': '/builds/slave/test', 02:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:17 INFO - 'SHELL': '/bin/bash', 02:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:17 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:17 INFO - 'USER': 'cltbld', 02:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:17 INFO - 'XPC_FLAGS': '0x0', 02:48:17 INFO - 'XPC_SERVICE_NAME': '0', 02:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:17 INFO - Downloading/unpacking psutil>=3.1.1 02:48:17 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 02:48:17 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 02:48:17 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 02:48:17 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 02:48:17 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 02:48:17 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 02:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:48:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:21 INFO - Installing collected packages: psutil 02:48:21 INFO - Running setup.py install for psutil 02:48:21 INFO - building 'psutil._psutil_osx' extension 02:48:21 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 02:48:22 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 02:48:22 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 02:48:22 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 02:48:22 INFO - building 'psutil._psutil_posix' extension 02:48:22 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 02:48:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:48:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:48:22 INFO - ^ 02:48:22 INFO - 1 warning generated. 02:48:22 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 02:48:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:22 INFO - Successfully installed psutil 02:48:22 INFO - Cleaning up... 02:48:22 INFO - Return code: 0 02:48:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:23 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')]} 02:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:23 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:23 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 02:48:23 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 02:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:23 INFO - 'HOME': '/Users/cltbld', 02:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:23 INFO - 'LOGNAME': 'cltbld', 02:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:23 INFO - 'MOZ_NO_REMOTE': '1', 02:48:23 INFO - 'NO_EM_RESTART': '1', 02:48:23 INFO - 'PAGER': '/bin/cat', 02:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:23 INFO - 'PWD': '/builds/slave/test', 02:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:23 INFO - 'SHELL': '/bin/bash', 02:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:23 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:23 INFO - 'USER': 'cltbld', 02:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:23 INFO - 'XPC_FLAGS': '0x0', 02:48:23 INFO - 'XPC_SERVICE_NAME': '0', 02:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:23 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 02:48:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:48:27 INFO - Installing collected packages: mozsystemmonitor 02:48:27 INFO - Running setup.py install for mozsystemmonitor 02:48:27 INFO - Successfully installed mozsystemmonitor 02:48:27 INFO - Cleaning up... 02:48:27 INFO - Return code: 0 02:48:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:48:27 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')]} 02:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 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')]} 02:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:27 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 02:48:27 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 02:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:27 INFO - 'HOME': '/Users/cltbld', 02:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:27 INFO - 'LOGNAME': 'cltbld', 02:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:27 INFO - 'MOZ_NO_REMOTE': '1', 02:48:27 INFO - 'NO_EM_RESTART': '1', 02:48:27 INFO - 'PAGER': '/bin/cat', 02:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:27 INFO - 'PWD': '/builds/slave/test', 02:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:27 INFO - 'SHELL': '/bin/bash', 02:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:27 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:27 INFO - 'USER': 'cltbld', 02:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:27 INFO - 'XPC_FLAGS': '0x0', 02:48:27 INFO - 'XPC_SERVICE_NAME': '0', 02:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:27 INFO - Downloading/unpacking jsonschema==2.5.1 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:48:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:48:30 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 02:48:30 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 02:48:30 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 02:48:30 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 02:48:30 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 02:48:30 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 02:48:30 INFO - Downloading functools32-3.2.3-2.tar.gz 02:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:48:30 INFO - warning: no files found matching '*.txt' 02:48:30 INFO - no previously-included directories found matching 'build' 02:48:30 INFO - no previously-included directories found matching 'dist' 02:48:30 INFO - no previously-included directories found matching '.git*' 02:48:30 INFO - Installing collected packages: jsonschema, functools32 02:48:30 INFO - Running setup.py install for functools32 02:48:30 INFO - warning: no files found matching '*.txt' 02:48:30 INFO - no previously-included directories found matching 'build' 02:48:30 INFO - no previously-included directories found matching 'dist' 02:48:30 INFO - no previously-included directories found matching '.git*' 02:48:30 INFO - Successfully installed jsonschema functools32 02:48:30 INFO - Cleaning up... 02:48:31 INFO - Return code: 0 02:48:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:31 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:31 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 02:48:31 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 02:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:31 INFO - 'HOME': '/Users/cltbld', 02:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:31 INFO - 'LOGNAME': 'cltbld', 02:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:31 INFO - 'MOZ_NO_REMOTE': '1', 02:48:31 INFO - 'NO_EM_RESTART': '1', 02:48:31 INFO - 'PAGER': '/bin/cat', 02:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:31 INFO - 'PWD': '/builds/slave/test', 02:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:31 INFO - 'SHELL': '/bin/bash', 02:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:31 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:31 INFO - 'USER': 'cltbld', 02:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:31 INFO - 'XPC_FLAGS': '0x0', 02:48:31 INFO - 'XPC_SERVICE_NAME': '0', 02:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:48:31 INFO - Cleaning up... 02:48:31 INFO - Return code: 0 02:48:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:31 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:31 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 02:48:31 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 02:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:31 INFO - 'HOME': '/Users/cltbld', 02:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:31 INFO - 'LOGNAME': 'cltbld', 02:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:31 INFO - 'MOZ_NO_REMOTE': '1', 02:48:31 INFO - 'NO_EM_RESTART': '1', 02:48:31 INFO - 'PAGER': '/bin/cat', 02:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:31 INFO - 'PWD': '/builds/slave/test', 02:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:31 INFO - 'SHELL': '/bin/bash', 02:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:31 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:31 INFO - 'USER': 'cltbld', 02:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:31 INFO - 'XPC_FLAGS': '0x0', 02:48:31 INFO - 'XPC_SERVICE_NAME': '0', 02:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:31 INFO - Downloading/unpacking blobuploader==1.2.4 02:48:31 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 02:48:31 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 02:48:31 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 02:48:31 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 02:48:31 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 02:48:31 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 02:48:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:48:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:48:34 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 02:48:34 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 02:48:34 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 02:48:34 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 02:48:34 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 02:48:34 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 02:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:48:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:48:35 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 02:48:35 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 02:48:35 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 02:48:35 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 02:48:35 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 02:48:35 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 02:48:35 INFO - Downloading docopt-0.6.1.tar.gz 02:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:48:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:48:35 INFO - Running setup.py install for blobuploader 02:48:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:48:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:48:35 INFO - Running setup.py install for requests 02:48:36 INFO - Running setup.py install for docopt 02:48:36 INFO - Successfully installed blobuploader requests docopt 02:48:36 INFO - Cleaning up... 02:48:36 INFO - Return code: 0 02:48:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:36 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')]} 02:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:36 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')]} 02:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:36 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:36 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 02:48:36 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 02:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:36 INFO - 'HOME': '/Users/cltbld', 02:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:36 INFO - 'LOGNAME': 'cltbld', 02:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:36 INFO - 'MOZ_NO_REMOTE': '1', 02:48:36 INFO - 'NO_EM_RESTART': '1', 02:48:36 INFO - 'PAGER': '/bin/cat', 02:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:36 INFO - 'PWD': '/builds/slave/test', 02:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:36 INFO - 'SHELL': '/bin/bash', 02:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:36 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:36 INFO - 'USER': 'cltbld', 02:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:36 INFO - 'XPC_FLAGS': '0x0', 02:48:36 INFO - 'XPC_SERVICE_NAME': '0', 02:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:36 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-kHb5J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:36 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-slWrqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:36 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-4YZuWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:36 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-XbQ41Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-yPRTwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ayLsAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-MieF5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ultLNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-EzZxHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-AC93Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-7NZw0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-haJ3HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:37 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-XbQnnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-DCPT9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-DxTVKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-8EIwj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:38 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-hrIvfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:48:38 INFO - Running setup.py install for manifestparser 02:48:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:48:38 INFO - Running setup.py install for mozcrash 02:48:38 INFO - Running setup.py install for mozdebug 02:48:38 INFO - Running setup.py install for mozdevice 02:48:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Running setup.py install for mozfile 02:48:39 INFO - Running setup.py install for mozhttpd 02:48:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Running setup.py install for mozinfo 02:48:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Running setup.py install for mozInstall 02:48:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Running setup.py install for mozleak 02:48:39 INFO - Running setup.py install for mozlog 02:48:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:48:39 INFO - Running setup.py install for moznetwork 02:48:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:48:40 INFO - Running setup.py install for mozprocess 02:48:40 INFO - Running setup.py install for mozprofile 02:48:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:48:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:48:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:48:40 INFO - Running setup.py install for mozrunner 02:48:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:48:40 INFO - Running setup.py install for mozscreenshot 02:48:40 INFO - Running setup.py install for moztest 02:48:40 INFO - Running setup.py install for mozversion 02:48:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:48:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:48:41 INFO - Cleaning up... 02:48:41 INFO - Return code: 0 02:48:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:41 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:41 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 02:48:41 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 02:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:41 INFO - 'HOME': '/Users/cltbld', 02:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:41 INFO - 'LOGNAME': 'cltbld', 02:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:41 INFO - 'MOZ_NO_REMOTE': '1', 02:48:41 INFO - 'NO_EM_RESTART': '1', 02:48:41 INFO - 'PAGER': '/bin/cat', 02:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:41 INFO - 'PWD': '/builds/slave/test', 02:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:41 INFO - 'SHELL': '/bin/bash', 02:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:41 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:41 INFO - 'USER': 'cltbld', 02:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:41 INFO - 'XPC_FLAGS': '0x0', 02:48:41 INFO - 'XPC_SERVICE_NAME': '0', 02:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-D4eAU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:41 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ujo96M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:41 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-uu15jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:41 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-rMmPt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-dniMxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:41 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-FwI6uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:41 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)) 02:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:41 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ukuKvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-6BqjEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-xXKS5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-8daSc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-VUsBaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-uMs3L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-Sl7xRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-0ypEIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-RyBy33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:42 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)) 02:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:42 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-l1RNhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:43 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)) 02:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:43 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-QET2Ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:43 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)) 02:48:43 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)) 02:48:43 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)) 02:48:43 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)) 02:48:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:48:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:48:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:45 INFO - Downloading blessings-1.6.tar.gz 02:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:48:45 INFO - Installing collected packages: blessings 02:48:45 INFO - Running setup.py install for blessings 02:48:46 INFO - Successfully installed blessings 02:48:46 INFO - Cleaning up... 02:48:46 INFO - Return code: 0 02:48:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:48:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:46 INFO - Reading from file tmpfile_stdout 02:48:46 INFO - Current package versions: 02:48:46 INFO - blessings == 1.6 02:48:46 INFO - blobuploader == 1.2.4 02:48:46 INFO - docopt == 0.6.1 02:48:46 INFO - functools32 == 3.2.3-2 02:48:46 INFO - jsonschema == 2.5.1 02:48:46 INFO - manifestparser == 1.1 02:48:46 INFO - mozInstall == 1.12 02:48:46 INFO - mozcrash == 1.0 02:48:46 INFO - mozdebug == 0.1 02:48:46 INFO - mozdevice == 0.49 02:48:46 INFO - mozfile == 1.2 02:48:46 INFO - mozhttpd == 0.7 02:48:46 INFO - mozinfo == 0.9 02:48:46 INFO - mozleak == 0.1 02:48:46 INFO - mozlog == 3.4 02:48:46 INFO - moznetwork == 0.27 02:48:46 INFO - mozprocess == 0.25 02:48:46 INFO - mozprofile == 0.28 02:48:46 INFO - mozrunner == 6.13 02:48:46 INFO - mozscreenshot == 0.1 02:48:46 INFO - mozsystemmonitor == 0.3 02:48:46 INFO - moztest == 0.8 02:48:46 INFO - mozversion == 1.4 02:48:46 INFO - psutil == 3.1.1 02:48:46 INFO - requests == 1.2.3 02:48:46 INFO - wsgiref == 0.1.2 02:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:46 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')]} 02:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:46 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')]} 02:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:46 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:46 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 02:48:46 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 02:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:46 INFO - 'HOME': '/Users/cltbld', 02:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:46 INFO - 'LOGNAME': 'cltbld', 02:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:46 INFO - 'MOZ_NO_REMOTE': '1', 02:48:46 INFO - 'NO_EM_RESTART': '1', 02:48:46 INFO - 'PAGER': '/bin/cat', 02:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:46 INFO - 'PWD': '/builds/slave/test', 02:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:46 INFO - 'SHELL': '/bin/bash', 02:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:46 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:46 INFO - 'USER': 'cltbld', 02:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:46 INFO - 'XPC_FLAGS': '0x0', 02:48:46 INFO - 'XPC_SERVICE_NAME': '0', 02:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 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)) 02:48:46 INFO - Cleaning up... 02:48:46 INFO - Return code: 0 02:48:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:48:46 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')]} 02:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:46 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')]} 02:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:46 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 0x10fd2eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd31768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb094e7540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, '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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 02:48:46 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 02:48:46 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 02:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:46 INFO - 'HOME': '/Users/cltbld', 02:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:46 INFO - 'LOGNAME': 'cltbld', 02:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:46 INFO - 'MOZ_NO_REMOTE': '1', 02:48:46 INFO - 'NO_EM_RESTART': '1', 02:48:46 INFO - 'PAGER': '/bin/cat', 02:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:46 INFO - 'PWD': '/builds/slave/test', 02:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:46 INFO - 'SHELL': '/bin/bash', 02:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:48:46 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:46 INFO - 'USER': 'cltbld', 02:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:46 INFO - 'XPC_FLAGS': '0x0', 02:48:46 INFO - 'XPC_SERVICE_NAME': '0', 02:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:48:46 INFO - Cleaning up... 02:48:46 INFO - Return code: 0 02:48:46 INFO - Running post-action listener: _resource_record_post_action 02:48:46 INFO - Running post-action listener: _start_resource_monitoring 02:48:46 INFO - Starting resource monitoring. 02:48:46 INFO - [mozharness: 2017-05-02 09:48:46.986628Z] Finished create-virtualenv step (success) 02:48:46 INFO - [mozharness: 2017-05-02 09:48:46.987569Z] Running install step. 02:48:46 INFO - Running pre-action listener: _resource_record_pre_action 02:48:46 INFO - Running main action method: install 02:48:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:47 INFO - Reading from file tmpfile_stdout 02:48:47 INFO - Detecting whether we're running mozinstall >=1.0... 02:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:48:47 INFO - Reading from file tmpfile_stdout 02:48:47 INFO - Output received: 02:48:47 INFO - Usage: mozinstall [options] installer 02:48:47 INFO - Options: 02:48:47 INFO - -h, --help show this help message and exit 02:48:47 INFO - -d DEST, --destination=DEST 02:48:47 INFO - Directory to install application into. [default: 02:48:47 INFO - "/builds/slave/test"] 02:48:47 INFO - --app=APP Application being installed. [default: firefox] 02:48:47 INFO - mkdir: /builds/slave/test/build/application 02:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:49:07 INFO - Reading from file tmpfile_stdout 02:49:07 INFO - Output received: 02:49:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:49:07 INFO - Running post-action listener: _resource_record_post_action 02:49:07 INFO - [mozharness: 2017-05-02 09:49:07.491713Z] Finished install step (success) 02:49:07 INFO - [mozharness: 2017-05-02 09:49:07.491855Z] Running run-tests step. 02:49:07 INFO - Running pre-action listener: _resource_record_pre_action 02:49:07 INFO - Running main action method: run_tests 02:49:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:07 INFO - 2017-05-02 02:49:07.506 screenresolution[1630:7515] starting screenresolution argv=screenresolution get 02:49:07 INFO - 2017-05-02 02:49:07.509 screenresolution[1630:7515] Display 0: 1600x1200x32@60 02:49:07 INFO - 2017-05-02 02:49:07.514 screenresolution[1631:7518] starting screenresolution argv=screenresolution list 02:49:07 INFO - Available Modes on Display 0 02:49:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:07 INFO - Intel Iris: 02:49:07 INFO - Chipset Model: Intel Iris 02:49:07 INFO - Type: GPU 02:49:07 INFO - Bus: Built-In 02:49:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:07 INFO - Vendor: Intel (0x8086) 02:49:07 INFO - Device ID: 0x0a2e 02:49:07 INFO - Revision ID: 0x0009 02:49:07 INFO - Displays: 02:49:07 INFO - Display: 02:49:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:07 INFO - Display Serial Number: 1600x1200 60 02:49:07 INFO - Main Display: Yes 02:49:07 INFO - Mirror: Off 02:49:07 INFO - Online: Yes 02:49:07 INFO - Rotation: Supported 02:49:07 INFO - Return code: 0 02:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:07 INFO - Python 2.7.3 02:49:07 INFO - Return code: 0 02:49:07 INFO - grabbing minidump binary from tooltool 02:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:07 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 0x10fb0cd30>, '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 0x10fa33db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fb01be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:07 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 02:49:07 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 02:49:07 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 02:49:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:07 INFO - Return code: 0 02:49:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:07 INFO - ENV: RUST_BACKTRACE is now 1 02:49:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--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 02:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0240 --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 02:49:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HQ8sjqCDPu/Render', 02:49:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:07 INFO - 'HOME': '/Users/cltbld', 02:49:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:07 INFO - 'LOGNAME': 'cltbld', 02:49:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:07 INFO - 'MOZ_NO_REMOTE': '1', 02:49:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:07 INFO - 'NO_EM_RESTART': '1', 02:49:07 INFO - 'PAGER': '/bin/cat', 02:49:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:07 INFO - 'PWD': '/builds/slave/test', 02:49:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:07 INFO - 'RUST_BACKTRACE': '1', 02:49:07 INFO - 'SHELL': '/bin/bash', 02:49:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShBfDorYpg/Listeners', 02:49:07 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 02:49:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:07 INFO - 'USER': 'cltbld', 02:49:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:07 INFO - 'XPC_FLAGS': '0x0', 02:49:07 INFO - 'XPC_SERVICE_NAME': '0', 02:49:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--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 02:49:08 INFO - mozversion application_buildid: 20170502004016 02:49:08 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:49:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:49:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:08 INFO - mozversion application_name: Firefox 02:49:08 INFO - mozversion application_remotingname: firefox-dev 02:49:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:08 INFO - mozversion application_vendor: Mozilla 02:49:08 INFO - mozversion application_version: 54.0a2 02:49:08 INFO - mozversion platform_buildid: 20170502004016 02:49:08 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:49:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:08 INFO - mozversion platform_version: 54.0a2 02:49:08 INFO - using testdate: 1493718548 02:49:08 INFO - actual date: 1493718548 02:49:08 INFO - starting webserver on 'localhost:49234' 02:49:08 INFO - SUITE-START | Running 2 tests 02:49:08 INFO - TEST-START | dromaeo_css 02:49:08 INFO - operating with platform_type : mac_ 02:49:08 INFO - Initialising browser for dromaeo_css test... 02:49:08 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpJrZosV/profile http://localhost:49234/getInfo.html) 02:49:10 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 02:49:10 INFO - PROCESS | 1641 | colorDepth:24 02:49:10 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 02:49:10 INFO - PROCESS | 1641 | __metrics 02:49:10 INFO - PROCESS | 1641 | 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" 02:49:10 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:11 INFO - TEST-INFO | 1641: exit 0 02:49:11 INFO - Browser initialized. 02:49:11 INFO - Running cycle 1/1 for dromaeo_css test... 02:49:11 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpJrZosV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:23 INFO - PROCESS | 1644 | RSS: Main: 215109632 02:49:23 INFO - PROCESS | 1644 | 02:52:27 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 02:52:30 INFO - PROCESS | 1644 | RSS: Main: 397414400 02:52:30 INFO - PROCESS | 1644 | 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:54:13 INFO - PROCESS | 1644 | 02:54:13 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:54:13 INFO - PROCESS | 1644 | Full stack: 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:54:13 INFO - PROCESS | 1644 | 02:54:13 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:54:13 INFO - PROCESS | 1644 | Full stack: 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:54:13 INFO - PROCESS | 1644 | 02:54:13 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:54:13 INFO - PROCESS | 1644 | Full stack: 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | ************************* 02:54:13 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:54:13 INFO - PROCESS | 1644 | 02:54:13 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:54:13 INFO - PROCESS | 1644 | Full stack: 02:54:13 INFO - PROCESS | 1644 | ************************* 02:55:33 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 02:55:33 INFO - PROCESS | 1644 | RSS: Main: 343465984 02:55:33 INFO - PROCESS | 1644 | 02:58:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 02:58:36 INFO - PROCESS | 1644 | RSS: Main: 339390464 02:58:36 INFO - PROCESS | 1644 | 03:01:38 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 03:01:39 INFO - PROCESS | 1644 | RSS: Main: 339841024 03:01:39 INFO - PROCESS | 1644 | 03:04:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 03:04:44 INFO - PROCESS | 1644 | RSS: Main: 316719104 03:04:44 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 03:07:47 INFO - PROCESS | 1644 | RSS: Main: 557285376 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | __start_tp_report 03:07:47 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 03:07:47 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 03:07:47 INFO - PROCESS | 1644 | |i|pagename|runs| 03:07:47 INFO - PROCESS | 1644 | |0;dojo.html;3368.174797174692;3368.9663103368966;3380.847861846217;3382.661733826619;3429.2112814052766;5855.268091488564;5862.0913758367415;5873.148393482953;5883.558733095019;5939.851503712404;5884.529237660988;5958.28500579931;5967.462928336449;5974.940250597493;5987.461128498434;5298.258243845865;5326.786928522854;5344.278522399484;5389.9759045781375;5420.512153906147;5252.185911183773;5262.263283140344;5281.524662780349;5442.156465747796;5451.318585176853;5332.386775520826;5347.919781203285;5384.488473595002;5408.675479471244;5425.430329815372;5370.301860758115;5382.865428364322;5399.352077750656;5404.1083221268445;5409.891802163955;5150.356205474316;5260.552853007499;5326.7070311132875;5342;5401.054815407328;5725.599208055438;5746.856328591777;5803.042497987827;5848.3274423441;5867.794627188071;6250.9062364064575;6297.118403423525;6307.148534947769;6311.179546658951;6313.337099604567;6287.276963149251;6330.683465826736;6345.302016778151;6346.42882140605;6355.936440635581;5747.770089196385;5893.14549390336;5922.022866227025;5950.31571369298;6083.239595050619;6234.688265586793;6242.812715618538;6250.9062364064575;6254.312025677172;6256.0303153011355;6136.549713441251;6237.220347456568;6246.969250073687;6273.435390814849;6286.622802631857;6335.841603959846;6338.271098823694;6353.396427339396;6354.936450635527;6357.682115894187;6224.439800417894;6282.151909492251;6286.5599408041;6295.464885484785;6307.400796924377;6192.628442293477;6245.094461303085;6272.341404152498;6292.182016337847;6307;5019.899602007939;5027.547520723152;5050.090983622909;5087.97456012725;5142.357205349332;4501.077279157765;4506.414166158378;4525.683202175882;4543.7728113594185;4554.749488778083;5928.970355148197;5955.52355811534;5972.014617588049;5972.46247837688;5974.731137098841;6136.56222693796;6211.409916057967;6236.750529978841;6341.746330146742;6364.840878978062;5032.3961124665275;5928.644281343133;5943.613665111754;5957.49361304294;5972.880542389128;5570.3315602128005;5586.413426590149;5610.270664813548;5610.663360198319;5622.522085622686;6261.467775238973;6271.216097987752;6302.65335406557;6513.446357059608;6528.934710652833;5428.321459817523;6242.625442473466;6260.40526150015;6303.49572034227;6318.936810631835;6459.741610335722;6463.515236357198;6474.449671777763;6486.254080780771;6512.413882750575;6250.281217659845;6254.749810007549;6272.905906411499;6313.652749098844;6327.778527751507;5446.346438427414;6228.096925945803;6232.750689972531;6292.11910332563;6296.212973378328;6254.9687251563455;6310.5898116622275;6313.337099604475;6326.367363263821;6330.461910737639;6238.15784869055;6268.1851359323;6290.0879372491645;6293.811185664437;6314.8737025258315;5908.202392676933;5954.07711804671;6172.074188871705;6181.629102253879;6190.535709821871;6187.81436556904;6196.535259855439;6270.529710271657;6311.053341998737;6317.336679648754;5891;5917.733701983335;5975.970120149372;6107.969460152848;6112.847178820387 03:07:47 INFO - PROCESS | 1644 | |1;ext.html;715.8103102677791;814.3159745813517;871.5032431514036;891.1222445890801;891.3804905590613;38205.42691859621;38780.41829372558;40671.186576268454;40798;40804.59195408045;40175.397369039485;40412.39381409274;40445;40455.797721011426;40503.392449113286;7308.4518661100365;7495.2130026346995;9151.359404841647;9330.626774928995;9343.626254949828;10816.945915270413;11020.393878336688;11141.22011459194;11141.832872506919;11147.665570032912;11033.558657653684;11058;11078.335299881992;11088.778224435506;11104.55581776728;7368.521046131984;7543;11026.44867756613;11030.889691103066;11074.003339699386;7363.153237377716;7396.4822462427655;7406.148292946326;10633.096186824052;10905.127589792797;38239.80880095582;38630.613693862986;38675;38689.03277418059;38738.806305968574;3026.9243268918453;3072.830994295313;3075.4310452566347;3103.177657920653;3196.680331966808;26507.73492265072;26977.65111744405;27952;28350.432991340058;28653.85384584614;3070.9278227845653;3111.284404586935;3119.251379668886;3176.0630613968706;3233.401820663185;321323;323723;324787;327933;327972;41296.58703412987;42217.57782422198;43343.566564334586;50579.49420505822;50712;43475;51345.486545134816;52247;52569.737151313995;52582.47417525776;14562.490312839212;14579.781303280459;14613.19627420502;14638.560843174722;14644.341004654816;10984.286021408583;12383.318917459446;12617.684557885943;12637.87362126367;12648.6837829055;11726.765464690829;11731.296122232694;11738.354390508433;11808.94095529517;11819.349935753444;5688.687122208318;5693.971530142406;5695.572832037614;5702.6863522506665;5714.914276285859;831.8876951611576;983.8573406855966;986.3243678080538;1047.7537778622018;1073.9677809665566;12212.023038156925;14643.414263429557;14819.481318153812;15557.066576005476;16039.518814435585;655.8589903170841;1206.3968015992004;1227.7360367521026;1239.8884100431005;1260.0864373329446;13261.602151935458;13475.460981560824;13516.93241533786;13531.59405217845;13584.592462226148;275281;277885;278053;278288;278476;46967.29549056767;47007.76496117566;47058.529414705416;47120.5287947123;47133;1413.9293035348192;1451.658860167881;1462.488129154766;1467.325030485967;1468.0751126790053;23936;23997.520049598563;25209.873950630128;25244.242672719847;25357.239282821545;35689.64310356863;35730.392132354624;35734.28531429409;35829.28341433105;35838.64161358353;244290;246152;246750.1299947982;247484;247699;29289.121326360244;29883.402331952806;29947.101586952427;29948;30026.699733003265;36493.45259821052;36986;37255.6274437263;37295.06762330963;37448.62551374451;26113.869430652725;26971.595426069016;27184.592231116152;27505.31236719098;27722.306942326602;26748.662566871968;26965.73034269711;27190.91236350524;27253.59119613247;28280.010199642915;28252.43495130045;28540.57189142206;28589.857050714614;32034.519482208252;32147;21286.148554057665;26632.06787762419;29398.853005734836;29589.704102959557;29814.701852981194;22847.543049138592;22945.426364340357;23161.783008253366;23405.829708514164;23417.531649367258 03:07:47 INFO - PROCESS | 1644 | |2;jquery.html;367.8491818354473;398.91024519483113;403.5742291882064;416.1095256151834;436.8489031402254;11924.642260732171;19728.506787330323;21051.47371315718;21069.894650526745;21226.257081002168;15672.686546269068;16875.156242187903;22079.779202207974;22269.777302226972;22310.107595696194;6507.577007494521;6888.207856096538;6927.688254028581;6932.480063995221;6936.20233673129;6544.312847151052;6620.437262832665;6687.097241872358;6692.39768420842;6750.324967503259;6654.068430419745;6665.466762658975;6684.799456016327;6690.498212634047;6694.263631000586;7611.543307401574;7937.563434011127;8116.594170291521;8122.147174546647;8148.7147949822065;7625.313721765012;7864.606769661551;7962.681492740284;7965.482243654202;7971.800704982364;29669.554956675453;29971.850140749375;30319;30837.537436938463;30870.845645771853;3028.1369809604375;3035.1805012646682;3037.1040543039635;3044.4824379855477;3077.584526088972;17032.574185645335;17797.021163835983;17977.910110449495;18638.627227455374;18715.438536843918;1970.5369238229005;2049.641312770274;2059.0939986405933;2062.7937206279403;2075.0869617368307;139944;150776;157811.99999999886;157842;160991.1950440252;21108.050137743838;24372.903219355172;25097.372565686004;25339.61990570143;25777.226683199533;38584.61415385867;40395.99010024714;40886.79556602198;41760.37359439611;43869.78065109654;8818.33862460318;9225.815483690289;9384.436933783858;10036.548355324028;10076.49617519121;7666.8849967250535;8614.18165274298;8646.56767161652;8812.8678069829;8939.418937769024;9625.71122866315;9701.805963880824;9770.560324785401;9804.705858824247;9805.460699661444;3273.9032424137704;3348.5555544452686;4061.3907913813164;4155.293600087932;4173.727013260985;825.6037102190982;885.203317014692;887.9965638828212;907.8456662367413;915.0437792506847;20656.483587910123;21267;21347.573048539252;21368;21399.572008559742;827.8882350882674;866.2377108144792;921.9677311294073;927.1979737526991;936.7517607833929;10732.78534429307;11343.716407089889;11350.09199264057;11409.942950285196;11416.37209953444;729419;750806;762639;782129;796696;148099;153140;153648.99999999776;156203;163670;1200.237848965913;1210.8970737487236;1238.8203710461933;1239.60332693539;1240.4418011894504;17093.401730939357;18856.905715471334;18878.622427551647;18985.145668445228;19012.714809277597;63603.045954311645;64577.67711161414;64709.67645161744;64804.675976619816;64905.02642460461;89489.55255223943;89857;90698.54650726705;92468;93958.06041939766;11510.48202830858;11705.946464818207;12670.162826390306;12736.95505305422;12894.806577901527;13713.862861371259;14100.929495352457;14127.576172714835;14356.353964071433;14508.34712437964;10853.837192442277;11590.898864607849;11740.236884602815;11965.940170299093;12168;11371.43142842871;11404.543818247508;11409.315441073562;11501.942490287494;11844.76310473803;11909.285442873455;12156.939215303866;12211.206271592318;12232.388380581113;12301.384930753606;12910.289934053717;13128.474861005452;13173.736525269249;13509.797353039516;13546.05177637556;10973.902609738961;11215.775684486427;11576.594819181288;11611.709707257387;11990.340531270864 03:07:47 INFO - PROCESS | 1644 | |3;mootools.html;1501.9173945432997;1537.7308970930085;1555.3933965753358;1561.453491278052;1568.6156891561566;9479.857802132974;10614.46927653617;11185.776284474316;11202.15983801215;11215.607453739121;10727.409992450412;11112.277701949368;11254.043406310453;11257.211995160342;11268.323900565973;2302.0676625966466;2336.696229490164;2339.391758142878;2340.719113706358;2343.2150229672993;2239.3729755668437;2319.420144963759;2327.3832434404894;2327.487952650414;2331.825113116515;2251.6715138068535;2275.4425165834396;2300.4823914619246;2308.3190458814606;2317.8261630377706;2184.4648061224943;2289.1415719105335;2292.3810571145864;2297;2303.389601755536;2299.9880052776725;2301.7583153768783;2310.0066971202377;2311.0986715181093;2311.722593288799;11209.159313051554;11296.830547541793;11317.207795454322;11320.943395283053;11324.9807517323;5384.165454354581;5487.807926722586;5504.972475137639;5522.475364840334;5544.556435485151;3144.4811606085195;3263.151580589064;3317.5355450236984;3398.150462384404;3430.708389786871;1012.8430093335545;1202.2485946283573;1225.3689349984766;1237.9504819807196;1258.2890666773244;15258.771118433233;15277.38890444381;15408.075515468996;15415.997960132667;15417.997830141005;4125.793710314472;4311.202427550913;4404.383386325917;4410.955890441119;4423.248047831877;11355;11375.31748095117;11386.487608057492;11393.601223957288;11416;5427.402985671573;5427.592930530226;5449;5449.836504904859;5451.3458384994055;4831;4892.877678058006;4932.334134891816;5041.269015992661;5042.142835717937;3537.4340105583497;3579.9105022374647;3610.458431235283;3670.632936706308;3679.0802299425145;912.3339961827903;917.2845180759018;919.8758167647416;929.6699671616478;931.701855406277;917.2386918857333;939.3659279985982;941.2799208605422;946.8485042393185;984.0454758883872;5616.354119276254;6591.6374599397495;6649.335066493312;6666.733330666816;6696.129503164558;960.4189465373488;964.7781010367655;986.2947992185622;996.5565323431003;1003.2926786615419;4168.478940132483;4174.582541745801;4179.895502612399;4245.214635292481;4286.957130428718;13021.869781302066;13753.968452366113;13769.655758605915;13774.65563360924;13789.034767566374;11750.353730544903;12193.32936688473;13690.38393272305;13700.314984250748;13701.383437745326;7499.1001079869675;8532.061473237938;8798.20816126539;8946.955265223633;10246.48767561619;6659.234188068434;6688.899666505104;6747.426468750211;6818.693158807766;6936.7225310989015;12179.391030448265;12303.876961230273;12304.015678745858;12315.137940344448;12408.131430800118;12414.689632759253;12496.250524926336;12589.811152832897;12659.430325635185;12678.239305641691;8226.588670566329;8257.545834979133;8338.916610833814;8343.749687509384;8346.749597512084;7859.214078592095;8265.380096492667;8483.194096560817;8544.700935467228;9571.138597525978;7360.926390736024;7371.557706537625;7391.371733402721;7410.333070023724;7508.0990281166605;5799.565032622486;6987.930120698931;6995.265497122728;7063.682134304071;7088.326608972139;6321.209848768904;6577.309382514767;6804.82987925286;6824.93175068243;6951.895721564281;5109.8978020438635;5130.640855140254;5190.247414125006;5243.711595862264;5245.269061209715;5057.216131499624;5105.489451054865;5180.559652429435;5390.272313237662;5428.240046393587 03:07:47 INFO - PROCESS | 1644 | |4;prototype.html;362.7750794507404;367.1390589068634;376.47481762940697;378.91474418255876;381.25654972803034;4767.757924611263;5125.3342663634285;5140.306058682077;5157.896842063156;5222.9477705222935;4124.608162224584;4836.661433699634;4932.473281603653;4933.851984440464;4964.677295975759;4134;4172.958270417287;4178.352355384913;4186.79066046698;4264.765437900915;3716.2010167813914;4226.852060177895;4236.961944323645;4281.957180428187;4286.807093680776;4094.7133700640966;4128.752274863517;4209.968557703367;4222.282212023963;4238.7032907696475;3633.8001409922445;3688.723345749066;4143;4144.668426525871;4159.91680166398;3845.288621605012;3846.4038074098557;3846.807659617008;3850.3058654192064;3888.397298418721;4673.158831410345;4725.621950243973;4914.70511769295;4972.328735620681;4984.651074424792;4334.566543345672;4682.578567928868;4783.173461231036;4795.232762757942;4908;5404.702741349224;5417.810376636839;5440.510354068153;5450.972745136289;5461.972690136564;3569.303985722785;4366.585174408426;4410.162069206873;4414.271645178542;4421.823127074913;4648.442186937589;5405.162199859028;5462.5083742462975;5500.28496295479;5599.524040456526;3690.833912473945;3913.9217215655526;4078.490188726409;4166.500019997619;4224.978875105605;3936.3111455495173;4334.26317526015;4414.955850441519;4468.888277793016;4488.685791994594;4334.739915605074;4395.857077159962;4571.565701258375;4668.253079507331;4821.397325334333;4653.511381304982;4796.832110876103;5293.338332708411;5297.920531192035;5439.497830997706;1325.2181213084327;1415.476273778688;1416.5325442603917;1523.3830298729033;1549.3344654496461;1052.921030922684;1066.861308029951;1083.2146693647044;1138.476300901578;1163.4357336691698;4571.954280457219;5013.097642424399;5050.600964541887;5060.7216603086445;5126.4620613815705;4861.538153875376;5357.8392648220615;5415.106507426231;5602.691851948182;5620.747066382022;4386.451693538308;4740.905181896343;4846.127697014571;4914.262860570871;5039.84880453587;2630.395009147907;2776.2642899631614;2842.317843717493;2851.6435445569305;2868.5553739170464;5230.92153617696;5663.603547751618;5670.858228544237;5703.971480142573;5720.513756330759;4582.793774280165;5216;5481.232627432163;5775.249217601685;5832.987619271345;5202.959408118391;5559.499645031967;5739.101753614398;5887.705614719247;5897.311637248398;5238.476152384731;5281.57747380201;5646;5668.327843286158;5676.687782171855;4905.141600219876;5320.467953204648;5410.513053825174;5415.675059496442;5497.147942068986;5471.4831177658725;5475.972620136874;5661.264035675335;5682.261306030189;5781.682007489629;3968.265870813909;3971.7021223407783;4323.308270676676;4451.3545535896465;4494.6629002825575;4839.491853355347;5051.595872330278;5274.97362513185;5636.549076073986;5644.491995720405;4745.786439610157;4791.808327666854;5216.582673386044;5248.422673505835;5391;4800.855974320776;5055.16589762685;5297.708626025452;5387.973060134675;5402.460298814819;4609.769511524478;4841.975790121027;4853.466118726867;4906.8773280668265;5323.973380133075;5377.548062023154;5381.48875856793;5385.892282154413;5387.676739395649;5388.434214407431;4409.4488188976375;4542.364069030405;4731.29030645406;4782.402199725035;5056.191009438472 03:07:47 INFO - PROCESS | 1644 | |5;yui.html;622.6662429490744;627.3162253144072;700.457145712073;713.357977819962;714.8212946763309;11986.880131198686;13619.659508512292;14467.493637722682;14488.855111448882;14512.346944387515;13427.127236729606;14632.195229262386;15153;15619.140947247897;15646.530604081867;773.7485317271895;948.9620415183384;951.7763325618474;953.3945944325334;954.9140577348037;926.5274709897967;945.6170251048302;946.7869729310919;947.0340252941995;952.1478276942132;1001.9148372388356;1005.2962925951826;1005.8591797148405;1006.5369929832286;1007.450939238117;996.5216695985895;997.4813097189493;1000.9499525023756;1002.2382988928395;1002.9749256268578;744.8100734312716;745.1356426545182;745.2770812312103;745.5191401546027;746.4140649590046;14172.362243698955;14544.854551454457;14658.267086645625;14700.411983520646;14717;12711.936440317739;13069.869301307055;13353.198808071547;13423.407702854294;13435.462581496728;10318.226133040052;10527.526261318259;10651.25441219115;10902.454877256105;10982;3601.58581763098;3629.1834337274167;3699.5930447650735;3715.2569486102616;3732.4401339798974;107147.46426267819;112313.81127572867;112696.43651781688;114116.42941785321;114711;10358.741031474123;10437.112845408074;10481.790364192673;10536.736581585368;10714.732131696614;21283.78716212818;21582.136714531556;21768.346949591538;21801.236956706758;21864.672029919562;7518.511296765692;7558.622068896533;7590.620468976529;7593.868514584159;7698;3962.227365663668;3979.980100099539;4060.715749897479;4072.38914162872;4074.796260186979;3192.824394658316;3279.655636158169;3280.4587243105098;3291.6050073991273;3306.40484712754;514.5909002343146;576.2623841482908;584.4622946888787;587.3187102960545;598.9850253743604;663.5221058668872;689.9068625735462;693.7225109956058;696.3210869402393;732.1945859554447;9792.314537982273;10512.947435262928;10770.407627580558;10933.617323393642;11003.834942475869;588.2411688921244;589.0545674192945;596.8925593393144;604.8518113062328;727.3453891497588;4034.9798251008556;4234.449521562177;4247.957520424756;4312.137572485515;4335.913281734411;56033;73642.63178684072;87290.56354718313;87436;100588.99411005943;74667.62666186634;86924.56537717397;87997.5600122008;90977.63533547003;91077;62520;63457.04814427787;66153;68617.65691171512;70616.6469167651;8368.832623347376;10267.7433064171;10386.532606032595;10519.474026298807;10576.36541807494;48287.5171248283;48866.267005994225;49541.752291238314;49688.50311496983;51436.74281628568;62392;64879.026814598765;65005.34994649993;65228;65664.67167664132;11541.538338466367;11600.6519804406;11753.823692644446;11770.58802941893;11823.408829558659;12684.492620295454;12690.746185076432;13392.866071339162;13815.65196619973;13981.161130332213;11844.407779610812;11989.160758747154;12596;12881.420336085086;12956;11347.319160850375;11719.824202637137;11828.940855296012;11833.408329583313;12313.507459701512;11775.293482391084;12063.336516491327;12304.200226985575;12630.873691263338;12691.301978391277;8629.266512346521;9109.817803644022;9166.541672916193;9202;9225.815483690154;8619.612117454855;9181.31462882203;9889.802203956024;10451.111655509063;10593.735156621146 03:07:47 INFO - PROCESS | 1644 | __end_tp_report 03:07:47 INFO - PROCESS | 1644 | __start_cc_report 03:07:47 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2057 03:07:47 INFO - PROCESS | 1644 | __end_cc_report 03:07:47 INFO - PROCESS | 1644 | __startTimestamp1493719667502__endTimestamp 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | ------- Summary: start ------- 03:07:47 INFO - PROCESS | 1644 | Number of tests: 6 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5853.33 Median:6107.97 stddev:638.55 (10.5%) stddev-sans-first:610.02 03:07:47 INFO - PROCESS | 1644 | Values: 3368.2 3369.0 3380.8 3382.7 3429.2 5855.3 5862.1 5873.1 5883.6 5939.9 5884.5 5958.3 5967.5 5974.9 5987.5 5298.3 5326.8 5344.3 5390.0 5420.5 5252.2 5262.3 5281.5 5442.2 5451.3 5332.4 5347.9 5384.5 5408.7 5425.4 5370.3 5382.9 5399.4 5404.1 5409.9 5150.4 5260.6 5326.7 5342.0 5401.1 5725.6 5746.9 5803.0 5848.3 5867.8 6250.9 6297.1 6307.1 6311.2 6313.3 6287.3 6330.7 6345.3 6346.4 6355.9 5747.8 5893.1 5922.0 5950.3 6083.2 6234.7 6242.8 6250.9 6254.3 6256.0 6136.5 6237.2 6247.0 6273.4 6286.6 6335.8 6338.3 6353.4 6354.9 6357.7 6224.4 6282.2 6286.6 6295.5 6307.4 6192.6 6245.1 6272.3 6292.2 6307.0 5019.9 5027.5 5050.1 5088.0 5142.4 4501.1 4506.4 4525.7 4543.8 4554.7 5929.0 5955.5 5972.0 5972.5 5974.7 6136.6 6211.4 6236.8 6341.7 6364.8 5032.4 5928.6 5943.6 5957.5 5972.9 5570.3 5586.4 5610.3 5610.7 5622.5 6261.5 6271.2 6302.7 6513.4 6528.9 5428.3 6242.6 6260.4 6303.5 6318.9 6459.7 6463.5 6474.4 6486.3 6512.4 6250.3 6254.7 6272.9 6313.7 6327.8 5446.3 6228.1 6232.8 6292.1 6296.2 6255.0 6310.6 6313.3 6326.4 6330.5 6238.2 6268.2 6290.1 6293.8 6314.9 5908.2 5954.1 6172.1 6181.6 6190.5 6187.8 6196.5 6270.5 6311.1 6317.3 5891.0 5917.7 5976.0 6108.0 6112.8 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:42588.82 Median:28971.49 stddev:74719.58 (257.9%) stddev-sans-first:74863.05 03:07:47 INFO - PROCESS | 1644 | Values: 715.8 814.3 871.5 891.1 891.4 38205.4 38780.4 40671.2 40798.0 40804.6 40175.4 40412.4 40445.0 40455.8 40503.4 7308.5 7495.2 9151.4 9330.6 9343.6 10816.9 11020.4 11141.2 11141.8 11147.7 11033.6 11058.0 11078.3 11088.8 11104.6 7368.5 7543.0 11026.4 11030.9 11074.0 7363.2 7396.5 7406.1 10633.1 10905.1 38239.8 38630.6 38675.0 38689.0 38738.8 3026.9 3072.8 3075.4 3103.2 3196.7 26507.7 26977.7 27952.0 28350.4 28653.9 3070.9 3111.3 3119.3 3176.1 3233.4 321323.0 323723.0 324787.0 327933.0 327972.0 41296.6 42217.6 43343.6 50579.5 50712.0 43475.0 51345.5 52247.0 52569.7 52582.5 14562.5 14579.8 14613.2 14638.6 14644.3 10984.3 12383.3 12617.7 12637.9 12648.7 11726.8 11731.3 11738.4 11808.9 11819.3 5688.7 5694.0 5695.6 5702.7 5714.9 831.9 983.9 986.3 1047.8 1074.0 12212.0 14643.4 14819.5 15557.1 16039.5 655.9 1206.4 1227.7 1239.9 1260.1 13261.6 13475.5 13516.9 13531.6 13584.6 275281.0 277885.0 278053.0 278288.0 278476.0 46967.3 47007.8 47058.5 47120.5 47133.0 1413.9 1451.7 1462.5 1467.3 1468.1 23936.0 23997.5 25209.9 25244.2 25357.2 35689.6 35730.4 35734.3 35829.3 35838.6 244290.0 246152.0 246750.1 247484.0 247699.0 29289.1 29883.4 29947.1 29948.0 30026.7 36493.5 36986.0 37255.6 37295.1 37448.6 26113.9 26971.6 27184.6 27505.3 27722.3 26748.7 26965.7 27190.9 27253.6 28280.0 28252.4 28540.6 28589.9 32034.5 32147.0 21286.1 26632.1 29398.9 29589.7 29814.7 22847.5 22945.4 23161.8 23405.8 23417.5 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:44302.78 Median:25558.42 stddev:127405.81 (498.5%) stddev-sans-first:127720.51 03:07:47 INFO - PROCESS | 1644 | Values: 367.8 398.9 403.6 416.1 436.8 11924.6 19728.5 21051.5 21069.9 21226.3 15672.7 16875.2 22079.8 22269.8 22310.1 6507.6 6888.2 6927.7 6932.5 6936.2 6544.3 6620.4 6687.1 6692.4 6750.3 6654.1 6665.5 6684.8 6690.5 6694.3 7611.5 7937.6 8116.6 8122.1 8148.7 7625.3 7864.6 7962.7 7965.5 7971.8 29669.6 29971.9 30319.0 30837.5 30870.8 3028.1 3035.2 3037.1 3044.5 3077.6 17032.6 17797.0 17977.9 18638.6 18715.4 1970.5 2049.6 2059.1 2062.8 2075.1 139944.0 150776.0 157812.0 157842.0 160991.2 21108.1 24372.9 25097.4 25339.6 25777.2 38584.6 40396.0 40886.8 41760.4 43869.8 8818.3 9225.8 9384.4 10036.5 10076.5 7666.9 8614.2 8646.6 8812.9 8939.4 9625.7 9701.8 9770.6 9804.7 9805.5 3273.9 3348.6 4061.4 4155.3 4173.7 825.6 885.2 888.0 907.8 915.0 20656.5 21267.0 21347.6 21368.0 21399.6 827.9 866.2 922.0 927.2 936.8 10732.8 11343.7 11350.1 11409.9 11416.4 729419.0 750806.0 762639.0 782129.0 796696.0 148099.0 153140.0 153649.0 156203.0 163670.0 1200.2 1210.9 1238.8 1239.6 1240.4 17093.4 18856.9 18878.6 18985.1 19012.7 63603.0 64577.7 64709.7 64804.7 64905.0 89489.6 89857.0 90698.5 92468.0 93958.1 11510.5 11705.9 12670.2 12737.0 12894.8 13713.9 14100.9 14127.6 14356.4 14508.3 10853.8 11590.9 11740.2 11965.9 12168.0 11371.4 11404.5 11409.3 11501.9 11844.8 11909.3 12156.9 12211.2 12232.4 12301.4 12910.3 13128.5 13173.7 13509.8 13546.1 10973.9 11215.8 11576.6 11611.7 11990.3 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6389.34 Median:3902.44 stddev:4141.61 (106.1%) stddev-sans-first:4136.95 03:07:47 INFO - PROCESS | 1644 | Values: 1501.9 1537.7 1555.4 1561.5 1568.6 9479.9 10614.5 11185.8 11202.2 11215.6 10727.4 11112.3 11254.0 11257.2 11268.3 2302.1 2336.7 2339.4 2340.7 2343.2 2239.4 2319.4 2327.4 2327.5 2331.8 2251.7 2275.4 2300.5 2308.3 2317.8 2184.5 2289.1 2292.4 2297.0 2303.4 2300.0 2301.8 2310.0 2311.1 2311.7 11209.2 11296.8 11317.2 11320.9 11325.0 5384.2 5487.8 5505.0 5522.5 5544.6 3144.5 3263.2 3317.5 3398.2 3430.7 1012.8 1202.2 1225.4 1238.0 1258.3 15258.8 15277.4 15408.1 15416.0 15418.0 4125.8 4311.2 4404.4 4411.0 4423.2 11355.0 11375.3 11386.5 11393.6 11416.0 5427.4 5427.6 5449.0 5449.8 5451.3 4831.0 4892.9 4932.3 5041.3 5042.1 3537.4 3579.9 3610.5 3670.6 3679.1 912.3 917.3 919.9 929.7 931.7 917.2 939.4 941.3 946.8 984.0 5616.4 6591.6 6649.3 6666.7 6696.1 960.4 964.8 986.3 996.6 1003.3 4168.5 4174.6 4179.9 4245.2 4287.0 13021.9 13754.0 13769.7 13774.7 13789.0 11750.4 12193.3 13690.4 13700.3 13701.4 7499.1 8532.1 8798.2 8947.0 10246.5 6659.2 6688.9 6747.4 6818.7 6936.7 12179.4 12303.9 12304.0 12315.1 12408.1 12414.7 12496.3 12589.8 12659.4 12678.2 8226.6 8257.5 8338.9 8343.7 8346.7 7859.2 8265.4 8483.2 8544.7 9571.1 7360.9 7371.6 7391.4 7410.3 7508.1 5799.6 6987.9 6995.3 7063.7 7088.3 6321.2 6577.3 6804.8 6824.9 6951.9 5109.9 5130.6 5190.2 5243.7 5245.3 5057.2 5105.5 5180.6 5390.3 5428.2 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4483.46 Median:4798.84 stddev:1252.07 (26.1%) stddev-sans-first:1216.79 03:07:47 INFO - PROCESS | 1644 | Values: 362.8 367.1 376.5 378.9 381.3 4767.8 5125.3 5140.3 5157.9 5222.9 4124.6 4836.7 4932.5 4933.9 4964.7 4134.0 4173.0 4178.4 4186.8 4264.8 3716.2 4226.9 4237.0 4282.0 4286.8 4094.7 4128.8 4210.0 4222.3 4238.7 3633.8 3688.7 4143.0 4144.7 4159.9 3845.3 3846.4 3846.8 3850.3 3888.4 4673.2 4725.6 4914.7 4972.3 4984.7 4334.6 4682.6 4783.2 4795.2 4908.0 5404.7 5417.8 5440.5 5451.0 5462.0 3569.3 4366.6 4410.2 4414.3 4421.8 4648.4 5405.2 5462.5 5500.3 5599.5 3690.8 3913.9 4078.5 4166.5 4225.0 3936.3 4334.3 4415.0 4468.9 4488.7 4334.7 4395.9 4571.6 4668.3 4821.4 4653.5 4796.8 5293.3 5297.9 5439.5 1325.2 1415.5 1416.5 1523.4 1549.3 1052.9 1066.9 1083.2 1138.5 1163.4 4572.0 5013.1 5050.6 5060.7 5126.5 4861.5 5357.8 5415.1 5602.7 5620.7 4386.5 4740.9 4846.1 4914.3 5039.8 2630.4 2776.3 2842.3 2851.6 2868.6 5230.9 5663.6 5670.9 5704.0 5720.5 4582.8 5216.0 5481.2 5775.2 5833.0 5203.0 5559.5 5739.1 5887.7 5897.3 5238.5 5281.6 5646.0 5668.3 5676.7 4905.1 5320.5 5410.5 5415.7 5497.1 5471.5 5476.0 5661.3 5682.3 5781.7 3968.3 3971.7 4323.3 4451.4 4494.7 4839.5 5051.6 5275.0 5636.5 5644.5 4745.8 4791.8 5216.6 5248.4 5391.0 4800.9 5055.2 5297.7 5388.0 5402.5 4609.8 4842.0 4853.5 4906.9 5324.0 5377.5 5381.5 5385.9 5387.7 5388.4 4409.4 4542.4 4731.3 4782.4 5056.2 03:07:47 INFO - PROCESS | 1644 | 03:07:47 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19174.66 Median:3664.39 stddev:27687.43 (755.6%) stddev-sans-first:27730.06 03:07:47 INFO - PROCESS | 1644 | Values: 622.7 627.3 700.5 713.4 714.8 11986.9 13619.7 14467.5 14488.9 14512.3 13427.1 14632.2 15153.0 15619.1 15646.5 773.7 949.0 951.8 953.4 954.9 926.5 945.6 946.8 947.0 952.1 1001.9 1005.3 1005.9 1006.5 1007.5 996.5 997.5 1000.9 1002.2 1003.0 744.8 745.1 745.3 745.5 746.4 14172.4 14544.9 14658.3 14700.4 14717.0 12711.9 13069.9 13353.2 13423.4 13435.5 10318.2 10527.5 10651.3 10902.5 10982.0 3601.6 3629.2 3699.6 3715.3 3732.4 107147.5 112313.8 112696.4 114116.4 114711.0 10358.7 10437.1 10481.8 10536.7 10714.7 21283.8 21582.1 21768.3 21801.2 21864.7 7518.5 7558.6 7590.6 7593.9 7698.0 3962.2 3980.0 4060.7 4072.4 4074.8 3192.8 3279.7 3280.5 3291.6 3306.4 514.6 576.3 584.5 587.3 599.0 663.5 689.9 693.7 696.3 732.2 9792.3 10512.9 10770.4 10933.6 11003.8 588.2 589.1 596.9 604.9 727.3 4035.0 4234.4 4248.0 4312.1 4335.9 56033.0 73642.6 87290.6 87436.0 100589.0 74667.6 86924.6 87997.6 90977.6 91077.0 62520.0 63457.0 66153.0 68617.7 70616.6 8368.8 10267.7 10386.5 10519.5 10576.4 48287.5 48866.3 49541.8 49688.5 51436.7 62392.0 64879.0 65005.3 65228.0 65664.7 11541.5 11600.7 11753.8 11770.6 11823.4 12684.5 12690.7 13392.9 13815.7 13981.2 11844.4 11989.2 12596.0 12881.4 12956.0 11347.3 11719.8 11828.9 11833.4 12313.5 11775.3 12063.3 12304.2 12630.9 12691.3 8629.3 9109.8 9166.5 9202.0 9225.8 8619.6 9181.3 9889.8 10451.1 10593.7 03:07:47 INFO - PROCESS | 1644 | -------- Summary: end -------- 03:07:47 INFO - PROCESS | 1644 | 03:07:48 INFO - TEST-INFO | 1644: exit 0 03:07:48 INFO - TEST-OK | dromaeo_css | took 1120064ms 03:07:48 INFO - TEST-START | kraken 03:07:48 INFO - operating with platform_type : mac_ 03:07:48 INFO - Initialising browser for kraken test... 03:07:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpYO4LFO/profile http://localhost:49234/getInfo.html) 03:07:50 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 03:07:50 INFO - PROCESS | 1654 | colorDepth:24 03:07:50 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 03:07:50 INFO - PROCESS | 1654 | __metrics 03:07:50 INFO - PROCESS | 1654 | 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" 03:07:50 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:07:51 INFO - TEST-INFO | 1654: exit 0 03:07:51 INFO - Browser initialized. 03:07:51 INFO - Running cycle 1/1 for kraken test... 03:07:51 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpYO4LFO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:08:03 INFO - PROCESS | 1656 | RSS: Main: 210788352 03:08:03 INFO - PROCESS | 1656 | 03:08:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 03:08:07 INFO - PROCESS | 1656 | RSS: Main: 265023488 03:08:07 INFO - PROCESS | 1656 | 03:08:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 03:08:10 INFO - PROCESS | 1656 | RSS: Main: 278237184 03:08:10 INFO - PROCESS | 1656 | 03:08:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 03:08:14 INFO - PROCESS | 1656 | RSS: Main: 374599680 03:08:14 INFO - PROCESS | 1656 | 03:08:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 03:08:17 INFO - PROCESS | 1656 | RSS: Main: 279064576 03:08:17 INFO - PROCESS | 1656 | 03:08:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:08:19 INFO - PROCESS | 1656 | RSS: Main: 280424448 03:08:19 INFO - PROCESS | 1656 | 03:08:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 03:08:23 INFO - PROCESS | 1656 | RSS: Main: 320438272 03:08:23 INFO - PROCESS | 1656 | 03:08:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 03:08:26 INFO - PROCESS | 1656 | RSS: Main: 320851968 03:08:26 INFO - PROCESS | 1656 | 03:08:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 03:08:30 INFO - PROCESS | 1656 | RSS: Main: 319787008 03:08:30 INFO - PROCESS | 1656 | 03:08:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:08:32 INFO - PROCESS | 1656 | RSS: Main: 296390656 03:08:32 INFO - PROCESS | 1656 | 03:08:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:08:35 INFO - PROCESS | 1656 | RSS: Main: 298885120 03:08:35 INFO - PROCESS | 1656 | 03:08:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:08:37 INFO - PROCESS | 1656 | RSS: Main: 306700288 03:08:37 INFO - PROCESS | 1656 | 03:08:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:08:41 INFO - PROCESS | 1656 | RSS: Main: 320647168 03:08:41 INFO - PROCESS | 1656 | 03:08:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:08:44 INFO - PROCESS | 1656 | RSS: Main: 314081280 03:08:44 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 03:08:46 INFO - PROCESS | 1656 | RSS: Main: 358969344 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | __start_tp_report 03:08:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 03:08:46 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 03:08:46 INFO - PROCESS | 1656 | |i|pagename|runs| 03:08:46 INFO - PROCESS | 1656 | |0;ai-astar;107;116;116;115;116;116;120;171;156;115 03:08:46 INFO - PROCESS | 1656 | |1;audio-beat-detection;140;107;108;140;109;133;107;108;131;132 03:08:46 INFO - PROCESS | 1656 | |2;audio-dft;186;184;183;183;186;186;197;231;258;231 03:08:46 INFO - PROCESS | 1656 | |3;audio-fft;81;61;61;89;63;63;77;79;86;61 03:08:46 INFO - PROCESS | 1656 | |4;audio-oscillator;88;71;86;70;69;68;83;68;69;83 03:08:46 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;88;88;88;90;88;89;87;88;88 03:08:46 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;96;95;95;95;95;96;96;95;95 03:08:46 INFO - PROCESS | 1656 | |7;imaging-desaturate;83;83;85;84;85;84;85;85;85;84 03:08:46 INFO - PROCESS | 1656 | |8;json-parse-financial;61;61;59;60;64;64;64;63;63;64 03:08:46 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;56;63;66;62;59;57;58;59;58;58 03:08:46 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;81;81;86;83;81;84;86;83;88;83 03:08:46 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;138;136;138;138;134;130;142;146;195;161 03:08:46 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;154;167;164;162;163;163;163;163;161;163 03:08:46 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;67;66;65;65;64;65;63;64;63 03:08:46 INFO - PROCESS | 1656 | __end_tp_report 03:08:46 INFO - PROCESS | 1656 | __start_cc_report 03:08:46 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2104 03:08:46 INFO - PROCESS | 1656 | __end_cc_report 03:08:46 INFO - PROCESS | 1656 | __startTimestamp1493719726876__endTimestamp 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | ------- Summary: start ------- 03:08:46 INFO - PROCESS | 1656 | Number of tests: 14 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:124.80 Median:116.00 stddev:20.95 (18.1%) stddev-sans-first:21.21 03:08:46 INFO - PROCESS | 1656 | Values: 107.0 116.0 116.0 115.0 116.0 116.0 120.0 171.0 156.0 115.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:131.50 stddev:14.75 (11.2%) stddev-sans-first:14.05 03:08:46 INFO - PROCESS | 1656 | Values: 140.0 107.0 108.0 140.0 109.0 133.0 107.0 108.0 131.0 132.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:202.50 Median:191.50 stddev:27.19 (14.2%) stddev-sans-first:28.18 03:08:46 INFO - PROCESS | 1656 | Values: 186.0 184.0 183.0 183.0 186.0 186.0 197.0 231.0 258.0 231.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:72.10 Median:78.00 stddev:11.38 (14.6%) stddev-sans-first:11.60 03:08:46 INFO - PROCESS | 1656 | Values: 81.0 61.0 61.0 89.0 63.0 63.0 77.0 79.0 86.0 61.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:77.00 stddev:8.34 (10.8%) stddev-sans-first:7.52 03:08:46 INFO - PROCESS | 1656 | Values: 88.0 71.0 86.0 70.0 69.0 68.0 83.0 68.0 69.0 83.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 03:08:46 INFO - PROCESS | 1656 | Values: 89.0 88.0 88.0 88.0 90.0 88.0 89.0 87.0 88.0 88.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 03:08:46 INFO - PROCESS | 1656 | Values: 94.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.73 03:08:46 INFO - PROCESS | 1656 | Values: 83.0 83.0 85.0 84.0 85.0 84.0 85.0 85.0 85.0 84.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.50 stddev:1.89 (3.0%) stddev-sans-first:1.94 03:08:46 INFO - PROCESS | 1656 | Values: 61.0 61.0 59.0 60.0 64.0 64.0 64.0 63.0 63.0 64.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:3.10 (5.3%) stddev-sans-first:3.00 03:08:46 INFO - PROCESS | 1656 | Values: 56.0 63.0 66.0 62.0 59.0 57.0 58.0 59.0 58.0 58.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.50 stddev:2.41 (2.9%) stddev-sans-first:2.37 03:08:46 INFO - PROCESS | 1656 | Values: 81.0 81.0 86.0 83.0 81.0 84.0 86.0 83.0 88.0 83.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:140.00 stddev:19.25 (13.7%) stddev-sans-first:20.21 03:08:46 INFO - PROCESS | 1656 | Values: 138.0 136.0 138.0 138.0 134.0 130.0 142.0 146.0 195.0 161.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:3.30 (2.0%) stddev-sans-first:1.64 03:08:46 INFO - PROCESS | 1656 | Values: 154.0 167.0 164.0 162.0 163.0 163.0 163.0 163.0 161.0 163.0 03:08:46 INFO - PROCESS | 1656 | 03:08:46 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.32 (2.0%) stddev-sans-first:1.32 03:08:46 INFO - PROCESS | 1656 | Values: 66.0 67.0 66.0 65.0 65.0 64.0 65.0 63.0 64.0 63.0 03:08:46 INFO - PROCESS | 1656 | -------- Summary: end -------- 03:08:46 INFO - PROCESS | 1656 | 03:08:47 INFO - TEST-INFO | 1656: exit 0 03:08:47 INFO - TEST-OK | kraken | took 59627ms 03:08:47 INFO - SUITE-END | took 1179s 03:08:48 INFO - Completed test suite (00:19:39) 03:08:48 INFO - javascript benchmark 03:08:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7434.142032143249, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.174797174692, 3368.9663103368966, 3380.847861846217, 3382.661733826619, 3429.2112814052766, 5855.268091488564, 5862.0913758367415, 5873.148393482953, 5883.558733095019, 5939.851503712404, 5884.529237660988, 5958.28500579931, 5967.462928336449, 5974.940250597493, 5987.461128498434, 5298.258243845865, 5326.786928522854, 5344.278522399484, 5389.9759045781375, 5420.512153906147, 5252.185911183773, 5262.263283140344, 5281.524662780349, 5442.156465747796, 5451.318585176853, 5332.386775520826, 5347.919781203285, 5384.488473595002, 5408.675479471244, 5425.430329815372, 5370.301860758115, 5382.865428364322, 5399.352077750656, 5404.1083221268445, 5409.891802163955, 5150.356205474316, 5260.552853007499, 5326.7070311132875, 5342.0, 5401.054815407328, 5725.599208055438, 5746.856328591777, 5803.042497987827, 5848.3274423441, 5867.794627188071, 6250.9062364064575, 6297.118403423525, 6307.148534947769, 6311.179546658951, 6313.337099604567, 6287.276963149251, 6330.683465826736, 6345.302016778151, 6346.42882140605, 6355.936440635581, 5747.770089196385, 5893.14549390336, 5922.022866227025, 5950.31571369298, 6083.239595050619, 6234.688265586793, 6242.812715618538, 6250.9062364064575, 6254.312025677172, 6256.0303153011355, 6136.549713441251, 6237.220347456568, 6246.969250073687, 6273.435390814849, 6286.622802631857, 6335.841603959846, 6338.271098823694, 6353.396427339396, 6354.936450635527, 6357.682115894187, 6224.439800417894, 6282.151909492251, 6286.5599408041, 6295.464885484785, 6307.400796924377, 6192.628442293477, 6245.094461303085, 6272.341404152498, 6292.182016337847, 6307.0, 5019.899602007939, 5027.547520723152, 5050.090983622909, 5087.97456012725, 5142.357205349332, 4501.077279157765, 4506.414166158378, 4525.683202175882, 4543.7728113594185, 4554.749488778083, 5928.970355148197, 5955.52355811534, 5972.014617588049, 5972.46247837688, 5974.731137098841, 6136.56222693796, 6211.409916057967, 6236.750529978841, 6341.746330146742, 6364.840878978062, 5032.3961124665275, 5928.644281343133, 5943.613665111754, 5957.49361304294, 5972.880542389128, 5570.3315602128005, 5586.413426590149, 5610.270664813548, 5610.663360198319, 5622.522085622686, 6261.467775238973, 6271.216097987752, 6302.65335406557, 6513.446357059608, 6528.934710652833, 5428.321459817523, 6242.625442473466, 6260.40526150015, 6303.49572034227, 6318.936810631835, 6459.741610335722, 6463.515236357198, 6474.449671777763, 6486.254080780771, 6512.413882750575, 6250.281217659845, 6254.749810007549, 6272.905906411499, 6313.652749098844, 6327.778527751507, 5446.346438427414, 6228.096925945803, 6232.750689972531, 6292.11910332563, 6296.212973378328, 6254.9687251563455, 6310.5898116622275, 6313.337099604475, 6326.367363263821, 6330.461910737639, 6238.15784869055, 6268.1851359323, 6290.0879372491645, 6293.811185664437, 6314.8737025258315, 5908.202392676933, 5954.07711804671, 6172.074188871705, 6181.629102253879, 6190.535709821871, 6187.81436556904, 6196.535259855439, 6270.529710271657, 6311.053341998737, 6317.336679648754, 5891.0, 5917.733701983335, 5975.970120149372, 6107.969460152848, 6112.847178820387], "value": 5812.669531587635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8103102677791, 814.3159745813517, 871.5032431514036, 891.1222445890801, 891.3804905590613, 38205.42691859621, 38780.41829372558, 40671.186576268454, 40798.0, 40804.59195408045, 40175.397369039485, 40412.39381409274, 40445.0, 40455.797721011426, 40503.392449113286, 7308.4518661100365, 7495.2130026346995, 9151.359404841647, 9330.626774928995, 9343.626254949828, 10816.945915270413, 11020.393878336688, 11141.22011459194, 11141.832872506919, 11147.665570032912, 11033.558657653684, 11058.0, 11078.335299881992, 11088.778224435506, 11104.55581776728, 7368.521046131984, 7543.0, 11026.44867756613, 11030.889691103066, 11074.003339699386, 7363.153237377716, 7396.4822462427655, 7406.148292946326, 10633.096186824052, 10905.127589792797, 38239.80880095582, 38630.613693862986, 38675.0, 38689.03277418059, 38738.806305968574, 3026.9243268918453, 3072.830994295313, 3075.4310452566347, 3103.177657920653, 3196.680331966808, 26507.73492265072, 26977.65111744405, 27952.0, 28350.432991340058, 28653.85384584614, 3070.9278227845653, 3111.284404586935, 3119.251379668886, 3176.0630613968706, 3233.401820663185, 321323.0, 323723.0, 324787.0, 327933.0, 327972.0, 41296.58703412987, 42217.57782422198, 43343.566564334586, 50579.49420505822, 50712.0, 43475.0, 51345.486545134816, 52247.0, 52569.737151313995, 52582.47417525776, 14562.490312839212, 14579.781303280459, 14613.19627420502, 14638.560843174722, 14644.341004654816, 10984.286021408583, 12383.318917459446, 12617.684557885943, 12637.87362126367, 12648.6837829055, 11726.765464690829, 11731.296122232694, 11738.354390508433, 11808.94095529517, 11819.349935753444, 5688.687122208318, 5693.971530142406, 5695.572832037614, 5702.6863522506665, 5714.914276285859, 831.8876951611576, 983.8573406855966, 986.3243678080538, 1047.7537778622018, 1073.9677809665566, 12212.023038156925, 14643.414263429557, 14819.481318153812, 15557.066576005476, 16039.518814435585, 655.8589903170841, 1206.3968015992004, 1227.7360367521026, 1239.8884100431005, 1260.0864373329446, 13261.602151935458, 13475.460981560824, 13516.93241533786, 13531.59405217845, 13584.592462226148, 275281.0, 277885.0, 278053.0, 278288.0, 278476.0, 46967.29549056767, 47007.76496117566, 47058.529414705416, 47120.5287947123, 47133.0, 1413.9293035348192, 1451.658860167881, 1462.488129154766, 1467.325030485967, 1468.0751126790053, 23936.0, 23997.520049598563, 25209.873950630128, 25244.242672719847, 25357.239282821545, 35689.64310356863, 35730.392132354624, 35734.28531429409, 35829.28341433105, 35838.64161358353, 244290.0, 246152.0, 246750.1299947982, 247484.0, 247699.0, 29289.121326360244, 29883.402331952806, 29947.101586952427, 29948.0, 30026.699733003265, 36493.45259821052, 36986.0, 37255.6274437263, 37295.06762330963, 37448.62551374451, 26113.869430652725, 26971.595426069016, 27184.592231116152, 27505.31236719098, 27722.306942326602, 26748.662566871968, 26965.73034269711, 27190.91236350524, 27253.59119613247, 28280.010199642915, 28252.43495130045, 28540.57189142206, 28589.857050714614, 32034.519482208252, 32147.0, 21286.148554057665, 26632.06787762419, 29398.853005734836, 29589.704102959557, 29814.701852981194, 22847.543049138592, 22945.426364340357, 23161.783008253366, 23405.829708514164, 23417.531649367258], "value": 16883.14436217783, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.8491818354473, 398.91024519483113, 403.5742291882064, 416.1095256151834, 436.8489031402254, 11924.642260732171, 19728.506787330323, 21051.47371315718, 21069.894650526745, 21226.257081002168, 15672.686546269068, 16875.156242187903, 22079.779202207974, 22269.777302226972, 22310.107595696194, 6507.577007494521, 6888.207856096538, 6927.688254028581, 6932.480063995221, 6936.20233673129, 6544.312847151052, 6620.437262832665, 6687.097241872358, 6692.39768420842, 6750.324967503259, 6654.068430419745, 6665.466762658975, 6684.799456016327, 6690.498212634047, 6694.263631000586, 7611.543307401574, 7937.563434011127, 8116.594170291521, 8122.147174546647, 8148.7147949822065, 7625.313721765012, 7864.606769661551, 7962.681492740284, 7965.482243654202, 7971.800704982364, 29669.554956675453, 29971.850140749375, 30319.0, 30837.537436938463, 30870.845645771853, 3028.1369809604375, 3035.1805012646682, 3037.1040543039635, 3044.4824379855477, 3077.584526088972, 17032.574185645335, 17797.021163835983, 17977.910110449495, 18638.627227455374, 18715.438536843918, 1970.5369238229005, 2049.641312770274, 2059.0939986405933, 2062.7937206279403, 2075.0869617368307, 139944.0, 150776.0, 157811.99999999886, 157842.0, 160991.1950440252, 21108.050137743838, 24372.903219355172, 25097.372565686004, 25339.61990570143, 25777.226683199533, 38584.61415385867, 40395.99010024714, 40886.79556602198, 41760.37359439611, 43869.78065109654, 8818.33862460318, 9225.815483690289, 9384.436933783858, 10036.548355324028, 10076.49617519121, 7666.8849967250535, 8614.18165274298, 8646.56767161652, 8812.8678069829, 8939.418937769024, 9625.71122866315, 9701.805963880824, 9770.560324785401, 9804.705858824247, 9805.460699661444, 3273.9032424137704, 3348.5555544452686, 4061.3907913813164, 4155.293600087932, 4173.727013260985, 825.6037102190982, 885.203317014692, 887.9965638828212, 907.8456662367413, 915.0437792506847, 20656.483587910123, 21267.0, 21347.573048539252, 21368.0, 21399.572008559742, 827.8882350882674, 866.2377108144792, 921.9677311294073, 927.1979737526991, 936.7517607833929, 10732.78534429307, 11343.716407089889, 11350.09199264057, 11409.942950285196, 11416.37209953444, 729419.0, 750806.0, 762639.0, 782129.0, 796696.0, 148099.0, 153140.0, 153648.99999999776, 156203.0, 163670.0, 1200.237848965913, 1210.8970737487236, 1238.8203710461933, 1239.60332693539, 1240.4418011894504, 17093.401730939357, 18856.905715471334, 18878.622427551647, 18985.145668445228, 19012.714809277597, 63603.045954311645, 64577.67711161414, 64709.67645161744, 64804.675976619816, 64905.02642460461, 89489.55255223943, 89857.0, 90698.54650726705, 92468.0, 93958.06041939766, 11510.48202830858, 11705.946464818207, 12670.162826390306, 12736.95505305422, 12894.806577901527, 13713.862861371259, 14100.929495352457, 14127.576172714835, 14356.353964071433, 14508.34712437964, 10853.837192442277, 11590.898864607849, 11740.236884602815, 11965.940170299093, 12168.0, 11371.43142842871, 11404.543818247508, 11409.315441073562, 11501.942490287494, 11844.76310473803, 11909.285442873455, 12156.939215303866, 12211.206271592318, 12232.388380581113, 12301.384930753606, 12910.289934053717, 13128.474861005452, 13173.736525269249, 13509.797353039516, 13546.05177637556, 10973.902609738961, 11215.775684486427, 11576.594819181288, 11611.709707257387, 11990.340531270864], "value": 11943.833767917991, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.9173945432997, 1537.7308970930085, 1555.3933965753358, 1561.453491278052, 1568.6156891561566, 9479.857802132974, 10614.46927653617, 11185.776284474316, 11202.15983801215, 11215.607453739121, 10727.409992450412, 11112.277701949368, 11254.043406310453, 11257.211995160342, 11268.323900565973, 2302.0676625966466, 2336.696229490164, 2339.391758142878, 2340.719113706358, 2343.2150229672993, 2239.3729755668437, 2319.420144963759, 2327.3832434404894, 2327.487952650414, 2331.825113116515, 2251.6715138068535, 2275.4425165834396, 2300.4823914619246, 2308.3190458814606, 2317.8261630377706, 2184.4648061224943, 2289.1415719105335, 2292.3810571145864, 2297.0, 2303.389601755536, 2299.9880052776725, 2301.7583153768783, 2310.0066971202377, 2311.0986715181093, 2311.722593288799, 11209.159313051554, 11296.830547541793, 11317.207795454322, 11320.943395283053, 11324.9807517323, 5384.165454354581, 5487.807926722586, 5504.972475137639, 5522.475364840334, 5544.556435485151, 3144.4811606085195, 3263.151580589064, 3317.5355450236984, 3398.150462384404, 3430.708389786871, 1012.8430093335545, 1202.2485946283573, 1225.3689349984766, 1237.9504819807196, 1258.2890666773244, 15258.771118433233, 15277.38890444381, 15408.075515468996, 15415.997960132667, 15417.997830141005, 4125.793710314472, 4311.202427550913, 4404.383386325917, 4410.955890441119, 4423.248047831877, 11355.0, 11375.31748095117, 11386.487608057492, 11393.601223957288, 11416.0, 5427.402985671573, 5427.592930530226, 5449.0, 5449.836504904859, 5451.3458384994055, 4831.0, 4892.877678058006, 4932.334134891816, 5041.269015992661, 5042.142835717937, 3537.4340105583497, 3579.9105022374647, 3610.458431235283, 3670.632936706308, 3679.0802299425145, 912.3339961827903, 917.2845180759018, 919.8758167647416, 929.6699671616478, 931.701855406277, 917.2386918857333, 939.3659279985982, 941.2799208605422, 946.8485042393185, 984.0454758883872, 5616.354119276254, 6591.6374599397495, 6649.335066493312, 6666.733330666816, 6696.129503164558, 960.4189465373488, 964.7781010367655, 986.2947992185622, 996.5565323431003, 1003.2926786615419, 4168.478940132483, 4174.582541745801, 4179.895502612399, 4245.214635292481, 4286.957130428718, 13021.869781302066, 13753.968452366113, 13769.655758605915, 13774.65563360924, 13789.034767566374, 11750.353730544903, 12193.32936688473, 13690.38393272305, 13700.314984250748, 13701.383437745326, 7499.1001079869675, 8532.061473237938, 8798.20816126539, 8946.955265223633, 10246.48767561619, 6659.234188068434, 6688.899666505104, 6747.426468750211, 6818.693158807766, 6936.7225310989015, 12179.391030448265, 12303.876961230273, 12304.015678745858, 12315.137940344448, 12408.131430800118, 12414.689632759253, 12496.250524926336, 12589.811152832897, 12659.430325635185, 12678.239305641691, 8226.588670566329, 8257.545834979133, 8338.916610833814, 8343.749687509384, 8346.749597512084, 7859.214078592095, 8265.380096492667, 8483.194096560817, 8544.700935467228, 9571.138597525978, 7360.926390736024, 7371.557706537625, 7391.371733402721, 7410.333070023724, 7508.0990281166605, 5799.565032622486, 6987.930120698931, 6995.265497122728, 7063.682134304071, 7088.326608972139, 6321.209848768904, 6577.309382514767, 6804.82987925286, 6824.93175068243, 6951.895721564281, 5109.8978020438635, 5130.640855140254, 5190.247414125006, 5243.711595862264, 5245.269061209715, 5057.216131499624, 5105.489451054865, 5180.559652429435, 5390.272313237662, 5428.240046393587], "value": 4861.593210778253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.7750794507404, 367.1390589068634, 376.47481762940697, 378.91474418255876, 381.25654972803034, 4767.757924611263, 5125.3342663634285, 5140.306058682077, 5157.896842063156, 5222.9477705222935, 4124.608162224584, 4836.661433699634, 4932.473281603653, 4933.851984440464, 4964.677295975759, 4134.0, 4172.958270417287, 4178.352355384913, 4186.79066046698, 4264.765437900915, 3716.2010167813914, 4226.852060177895, 4236.961944323645, 4281.957180428187, 4286.807093680776, 4094.7133700640966, 4128.752274863517, 4209.968557703367, 4222.282212023963, 4238.7032907696475, 3633.8001409922445, 3688.723345749066, 4143.0, 4144.668426525871, 4159.91680166398, 3845.288621605012, 3846.4038074098557, 3846.807659617008, 3850.3058654192064, 3888.397298418721, 4673.158831410345, 4725.621950243973, 4914.70511769295, 4972.328735620681, 4984.651074424792, 4334.566543345672, 4682.578567928868, 4783.173461231036, 4795.232762757942, 4908.0, 5404.702741349224, 5417.810376636839, 5440.510354068153, 5450.972745136289, 5461.972690136564, 3569.303985722785, 4366.585174408426, 4410.162069206873, 4414.271645178542, 4421.823127074913, 4648.442186937589, 5405.162199859028, 5462.5083742462975, 5500.28496295479, 5599.524040456526, 3690.833912473945, 3913.9217215655526, 4078.490188726409, 4166.500019997619, 4224.978875105605, 3936.3111455495173, 4334.26317526015, 4414.955850441519, 4468.888277793016, 4488.685791994594, 4334.739915605074, 4395.857077159962, 4571.565701258375, 4668.253079507331, 4821.397325334333, 4653.511381304982, 4796.832110876103, 5293.338332708411, 5297.920531192035, 5439.497830997706, 1325.2181213084327, 1415.476273778688, 1416.5325442603917, 1523.3830298729033, 1549.3344654496461, 1052.921030922684, 1066.861308029951, 1083.2146693647044, 1138.476300901578, 1163.4357336691698, 4571.954280457219, 5013.097642424399, 5050.600964541887, 5060.7216603086445, 5126.4620613815705, 4861.538153875376, 5357.8392648220615, 5415.106507426231, 5602.691851948182, 5620.747066382022, 4386.451693538308, 4740.905181896343, 4846.127697014571, 4914.262860570871, 5039.84880453587, 2630.395009147907, 2776.2642899631614, 2842.317843717493, 2851.6435445569305, 2868.5553739170464, 5230.92153617696, 5663.603547751618, 5670.858228544237, 5703.971480142573, 5720.513756330759, 4582.793774280165, 5216.0, 5481.232627432163, 5775.249217601685, 5832.987619271345, 5202.959408118391, 5559.499645031967, 5739.101753614398, 5887.705614719247, 5897.311637248398, 5238.476152384731, 5281.57747380201, 5646.0, 5668.327843286158, 5676.687782171855, 4905.141600219876, 5320.467953204648, 5410.513053825174, 5415.675059496442, 5497.147942068986, 5471.4831177658725, 5475.972620136874, 5661.264035675335, 5682.261306030189, 5781.682007489629, 3968.265870813909, 3971.7021223407783, 4323.308270676676, 4451.3545535896465, 4494.6629002825575, 4839.491853355347, 5051.595872330278, 5274.97362513185, 5636.549076073986, 5644.491995720405, 4745.786439610157, 4791.808327666854, 5216.582673386044, 5248.422673505835, 5391.0, 4800.855974320776, 5055.16589762685, 5297.708626025452, 5387.973060134675, 5402.460298814819, 4609.769511524478, 4841.975790121027, 4853.466118726867, 4906.8773280668265, 5323.973380133075, 5377.548062023154, 5381.48875856793, 5385.892282154413, 5387.676739395649, 5388.434214407431, 4409.4488188976375, 4542.364069030405, 4731.29030645406, 4782.402199725035, 5056.191009438472], "value": 4115.732912472157, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.6662429490744, 627.3162253144072, 700.457145712073, 713.357977819962, 714.8212946763309, 11986.880131198686, 13619.659508512292, 14467.493637722682, 14488.855111448882, 14512.346944387515, 13427.127236729606, 14632.195229262386, 15153.0, 15619.140947247897, 15646.530604081867, 773.7485317271895, 948.9620415183384, 951.7763325618474, 953.3945944325334, 954.9140577348037, 926.5274709897967, 945.6170251048302, 946.7869729310919, 947.0340252941995, 952.1478276942132, 1001.9148372388356, 1005.2962925951826, 1005.8591797148405, 1006.5369929832286, 1007.450939238117, 996.5216695985895, 997.4813097189493, 1000.9499525023756, 1002.2382988928395, 1002.9749256268578, 744.8100734312716, 745.1356426545182, 745.2770812312103, 745.5191401546027, 746.4140649590046, 14172.362243698955, 14544.854551454457, 14658.267086645625, 14700.411983520646, 14717.0, 12711.936440317739, 13069.869301307055, 13353.198808071547, 13423.407702854294, 13435.462581496728, 10318.226133040052, 10527.526261318259, 10651.25441219115, 10902.454877256105, 10982.0, 3601.58581763098, 3629.1834337274167, 3699.5930447650735, 3715.2569486102616, 3732.4401339798974, 107147.46426267819, 112313.81127572867, 112696.43651781688, 114116.42941785321, 114711.0, 10358.741031474123, 10437.112845408074, 10481.790364192673, 10536.736581585368, 10714.732131696614, 21283.78716212818, 21582.136714531556, 21768.346949591538, 21801.236956706758, 21864.672029919562, 7518.511296765692, 7558.622068896533, 7590.620468976529, 7593.868514584159, 7698.0, 3962.227365663668, 3979.980100099539, 4060.715749897479, 4072.38914162872, 4074.796260186979, 3192.824394658316, 3279.655636158169, 3280.4587243105098, 3291.6050073991273, 3306.40484712754, 514.5909002343146, 576.2623841482908, 584.4622946888787, 587.3187102960545, 598.9850253743604, 663.5221058668872, 689.9068625735462, 693.7225109956058, 696.3210869402393, 732.1945859554447, 9792.314537982273, 10512.947435262928, 10770.407627580558, 10933.617323393642, 11003.834942475869, 588.2411688921244, 589.0545674192945, 596.8925593393144, 604.8518113062328, 727.3453891497588, 4034.9798251008556, 4234.449521562177, 4247.957520424756, 4312.137572485515, 4335.913281734411, 56033.0, 73642.63178684072, 87290.56354718313, 87436.0, 100588.99411005943, 74667.62666186634, 86924.56537717397, 87997.5600122008, 90977.63533547003, 91077.0, 62520.0, 63457.04814427787, 66153.0, 68617.65691171512, 70616.6469167651, 8368.832623347376, 10267.7433064171, 10386.532606032595, 10519.474026298807, 10576.36541807494, 48287.5171248283, 48866.267005994225, 49541.752291238314, 49688.50311496983, 51436.74281628568, 62392.0, 64879.026814598765, 65005.34994649993, 65228.0, 65664.67167664132, 11541.538338466367, 11600.6519804406, 11753.823692644446, 11770.58802941893, 11823.408829558659, 12684.492620295454, 12690.746185076432, 13392.866071339162, 13815.65196619973, 13981.161130332213, 11844.407779610812, 11989.160758747154, 12596.0, 12881.420336085086, 12956.0, 11347.319160850375, 11719.824202637137, 11828.940855296012, 11833.408329583313, 12313.507459701512, 11775.293482391084, 12063.336516491327, 12304.200226985575, 12630.873691263338, 12691.301978391277, 8629.266512346521, 9109.817803644022, 9166.541672916193, 9202.0, 9225.815483690154, 8619.612117454855, 9181.31462882203, 9889.802203956024, 10451.111655509063, 10593.735156621146], "value": 7196.894482423689, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 116.0, 115.0, 116.0, 116.0, 120.0, 171.0, 156.0, 115.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 108.0, 140.0, 109.0, 133.0, 107.0, 108.0, 131.0, 132.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 183.0, 183.0, 186.0, 186.0, 197.0, 231.0, 258.0, 231.0], "value": 202.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 61.0, 89.0, 63.0, 63.0, 77.0, 79.0, 86.0, 61.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 86.0, 70.0, 69.0, 68.0, 83.0, 68.0, 69.0, 83.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 90.0, 88.0, 89.0, 87.0, 88.0, 88.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 84.0, 85.0, 84.0, 85.0, 85.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 60.0, 64.0, 64.0, 64.0, 63.0, 63.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 66.0, 62.0, 59.0, 57.0, 58.0, 59.0, 58.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 86.0, 83.0, 81.0, 84.0, 86.0, 83.0, 88.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 138.0, 138.0, 134.0, 130.0, 142.0, 146.0, 195.0, 161.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 167.0, 164.0, 162.0, 163.0, 163.0, 163.0, 163.0, 161.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 65.0, 64.0, 65.0, 63.0, 64.0, 63.0], "value": 64.8, "unit": "score"}]}]} 03:08:48 INFO - Return code: 0 03:08:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:08:48 ERROR - # TBPL SUCCESS # 03:08:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:08:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:08:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:08:48 INFO - 2017-05-02 03:08:48.190 screenresolution[1660:9935] starting screenresolution argv=screenresolution get 03:08:48 INFO - 2017-05-02 03:08:48.193 screenresolution[1660:9935] Display 0: 1600x1200x32@60 03:08:48 INFO - 2017-05-02 03:08:48.199 screenresolution[1661:9938] starting screenresolution argv=screenresolution list 03:08:48 INFO - Available Modes on Display 0 03:08:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:08:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:08:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:08:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:08:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:08:48 INFO - Intel Iris: 03:08:48 INFO - Chipset Model: Intel Iris 03:08:48 INFO - Type: GPU 03:08:48 INFO - Bus: Built-In 03:08:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:08:48 INFO - Vendor: Intel (0x8086) 03:08:48 INFO - Device ID: 0x0a2e 03:08:48 INFO - Revision ID: 0x0009 03:08:48 INFO - Displays: 03:08:48 INFO - Display: 03:08:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:08:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:08:48 INFO - Display Serial Number: 1600x1200 60 03:08:48 INFO - Main Display: Yes 03:08:48 INFO - Mirror: Off 03:08:48 INFO - Online: Yes 03:08:48 INFO - Rotation: Supported 03:08:48 INFO - Return code: 0 03:08:48 INFO - Running post-action listener: _resource_record_post_action 03:08:48 INFO - [mozharness: 2017-05-02 10:08:48.434449Z] Finished run-tests step (success) 03:08:48 INFO - Running post-run listener: _resource_record_post_run 03:08:48 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38487040; Write bytes: 507356160; Read time: 355; Write time: 1540 03:08:48 INFO - TinderboxPrint: CPU usage
53.9% 03:08:48 INFO - TinderboxPrint: I/O read bytes / time
38,487,040 / 355 03:08:48 INFO - TinderboxPrint: I/O write bytes / time
507,356,160 / 1,540 03:08:48 INFO - TinderboxPrint: CPU idle
2,213.6 (46.1%) 03:08:48 INFO - TinderboxPrint: CPU system
954.5 (19.9%) 03:08:48 INFO - TinderboxPrint: CPU user
1,633.4 (34.0%) 03:08:48 INFO - TinderboxPrint: Swap in / out
246,501,376 / 0 03:08:48 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203919872; Write bytes: 200205824; Read time: 15694; Write time: 419 03:08:48 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36451328; Write bytes: 296353280; Read time: 328; Write time: 1086 03:08:48 INFO - Running post-run listener: _upload_blobber_files 03:08:48 INFO - Blob upload gear active. 03:08:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:48 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 03:08:48 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:08:48 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:08:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:08:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:08:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:50 INFO - (blobuploader) - INFO - Done attempting. 03:08:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:08:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:51 INFO - (blobuploader) - INFO - Done attempting. 03:08:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:08:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:52 INFO - (blobuploader) - INFO - Done attempting. 03:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:54 INFO - (blobuploader) - INFO - Done attempting. 03:08:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:54 INFO - Return code: 0 03:08:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ed7fbb005a0e260d62cb4bf5b035c1ab162fd5a23f463d6b4ff840d3dfff290a719f4607b892de853b95a7f825d50cef6a77bc74afea111fbd392164395ca80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d30e779a353ba07c25caa932ff839dd0a5f10498d4cf327856393dafb6b319e7950965158a6a8a6e9c505c23beffa0717ed4c62fe33904a4d827122f8ac84b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ee64dd1c2c744f8b509fb97ef6279a0687daa3e0ed705f400d37e8ee01f07aa2fa31a5705f5859db3233b1881cc9b336a6bd63e1a68910ae901748b4fecd33", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff68117c6749492cef625d7165c245f6acecf4fc56096a47237aedc3843a86092138e6f783a30702c08209fbed9d72e73b4091b12f042387de8bf8b34a57de99"} 03:08:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:54 INFO - Contents: 03:08:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ed7fbb005a0e260d62cb4bf5b035c1ab162fd5a23f463d6b4ff840d3dfff290a719f4607b892de853b95a7f825d50cef6a77bc74afea111fbd392164395ca80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d30e779a353ba07c25caa932ff839dd0a5f10498d4cf327856393dafb6b319e7950965158a6a8a6e9c505c23beffa0717ed4c62fe33904a4d827122f8ac84b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ee64dd1c2c744f8b509fb97ef6279a0687daa3e0ed705f400d37e8ee01f07aa2fa31a5705f5859db3233b1881cc9b336a6bd63e1a68910ae901748b4fecd33", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff68117c6749492cef625d7165c245f6acecf4fc56096a47237aedc3843a86092138e6f783a30702c08209fbed9d72e73b4091b12f042387de8bf8b34a57de99"} 03:08:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:54 INFO - Copying logs to upload dir... 03:08:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.185918 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-05-02 03:08:54.809544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 03:08:54.812972) ========= 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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-Aurora/sha512/3ed7fbb005a0e260d62cb4bf5b035c1ab162fd5a23f463d6b4ff840d3dfff290a719f4607b892de853b95a7f825d50cef6a77bc74afea111fbd392164395ca80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d30e779a353ba07c25caa932ff839dd0a5f10498d4cf327856393dafb6b319e7950965158a6a8a6e9c505c23beffa0717ed4c62fe33904a4d827122f8ac84b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ee64dd1c2c744f8b509fb97ef6279a0687daa3e0ed705f400d37e8ee01f07aa2fa31a5705f5859db3233b1881cc9b336a6bd63e1a68910ae901748b4fecd33", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff68117c6749492cef625d7165c245f6acecf4fc56096a47237aedc3843a86092138e6f783a30702c08209fbed9d72e73b4091b12f042387de8bf8b34a57de99"} build_url:https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/Juzzx2hnTKqPCp8esoOHRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3ed7fbb005a0e260d62cb4bf5b035c1ab162fd5a23f463d6b4ff840d3dfff290a719f4607b892de853b95a7f825d50cef6a77bc74afea111fbd392164395ca80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d30e779a353ba07c25caa932ff839dd0a5f10498d4cf327856393dafb6b319e7950965158a6a8a6e9c505c23beffa0717ed4c62fe33904a4d827122f8ac84b4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ee64dd1c2c744f8b509fb97ef6279a0687daa3e0ed705f400d37e8ee01f07aa2fa31a5705f5859db3233b1881cc9b336a6bd63e1a68910ae901748b4fecd33", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff68117c6749492cef625d7165c245f6acecf4fc56096a47237aedc3843a86092138e6f783a30702c08209fbed9d72e73b4091b12f042387de8bf8b34a57de99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 03:08:54.848641) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 03:08:54.848988) ========= 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.HQ8sjqCDPu/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.ShBfDorYpg/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.005109 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 03:08:54.897841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 03:08:54.898187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 03:08:54.898510) ========= ========= Total master_lag: 1.65 =========