builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0160 starttime: 1492099304.15 results: success (0) buildid: 20170413075523 builduid: 28db367f326748c38cf2588bd78102ee revision: 66a6e0bb7fd45343cad3826af9092a2f77b110ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.152807) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.153460) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.153927) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.190048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.190555) ========= 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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-04-13 09:01:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.28M=0.001s 2017-04-13 09:01:44 (8.28 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.321802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.322121) ========= 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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.028868 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:44.372931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 09:01:44.373311) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66a6e0bb7fd45343cad3826af9092a2f77b110ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 66a6e0bb7fd45343cad3826af9092a2f77b110ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-04-13 09:01:44,478 truncating revision to first 12 chars 2017-04-13 09:01:44,478 Setting DEBUG logging. 2017-04-13 09:01:44,478 attempt 1/10 2017-04-13 09:01:44,478 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66a6e0bb7fd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 09:01:46,390 unpacking tar archive at: mozilla-central-66a6e0bb7fd4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.189740 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 09:01:46.659942) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.660248) ========= script_repo_revision: 66a6e0bb7fd45343cad3826af9092a2f77b110ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.660648) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.660915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 09:01:46.680801) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-13 09:01:46.681134) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:01:46 INFO - MultiFileLogger online at 20170413 09:01:46 in /builds/slave/test 09:01:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:01:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:46 INFO - {'append_to_log': False, 09:01:46 INFO - 'base_work_dir': '/builds/slave/test', 09:01:46 INFO - 'blob_upload_branch': 'Firefox', 09:01:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:46 INFO - 'branch': 'Firefox', 09:01:46 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:46 INFO - 'config_files': ('talos/mac_config.py',), 09:01:46 INFO - 'default_actions': ('clobber', 09:01:46 INFO - 'read-buildbot-config', 09:01:46 INFO - 'download-and-extract', 09:01:46 INFO - 'populate-webroot', 09:01:46 INFO - 'create-virtualenv', 09:01:46 INFO - 'install', 09:01:46 INFO - 'run-tests'), 09:01:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:46 INFO - 'download_minidump_stackwalk': True, 09:01:46 INFO - 'download_symbols': 'ondemand', 09:01:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:46 INFO - 'gecko_profile': False, 09:01:46 INFO - 'gecko_profile_interval': 0, 09:01:46 INFO - 'installer_path': 'installer.exe', 09:01:46 INFO - 'log_level': 'info', 09:01:46 INFO - 'log_name': 'talos', 09:01:46 INFO - 'log_to_console': True, 09:01:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:46 INFO - 'opt_config_files': (), 09:01:46 INFO - 'pip_index': False, 09:01:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:46 INFO - 'cmd': ('bash', 09:01:46 INFO - '-c', 09:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:46 INFO - 'enabled': True, 09:01:46 INFO - 'halt_on_failure': False, 09:01:46 INFO - 'name': 'check_screen_resolution'},), 09:01:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:46 INFO - 'cmd': ('bash', 09:01:46 INFO - '-c', 09:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:46 INFO - 'enabled': True, 09:01:46 INFO - 'halt_on_failure': False, 09:01:46 INFO - 'name': 'check_screen_resolution'},), 09:01:46 INFO - 'run_cmd_checks_enabled': True, 09:01:46 INFO - 'suite': 'dromaeojs', 09:01:46 INFO - 'system_bits': '32', 09:01:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:46 INFO - 'title': 't-yosemite-r7-0160', 09:01:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:46 INFO - 'use_talos_json': True, 09:01:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:46 INFO - 'work_dir': 'build'} 09:01:46 INFO - [mozharness: 2017-04-13 16:01:46.897949Z] Running clobber step. 09:01:46 INFO - Running pre-action listener: _resource_record_pre_action 09:01:46 INFO - Running main action method: clobber 09:01:46 INFO - rmtree: /builds/slave/test/build 09:01:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.386765Z] Finished clobber step (success) 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.386902Z] Running read-buildbot-config step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: read_buildbot_config 09:01:49 INFO - Using buildbot properties: 09:01:49 INFO - { 09:01:49 INFO - "project": "", 09:01:49 INFO - "product": "firefox", 09:01:49 INFO - "script_repo_revision": "production", 09:01:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:01:49 INFO - "repository": "", 09:01:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:01:49 INFO - "buildid": "20170413075523", 09:01:49 INFO - "pgo_build": "False", 09:01:49 INFO - "basedir": "/builds/slave/test", 09:01:49 INFO - "buildnumber": 14, 09:01:49 INFO - "slavename": "t-yosemite-r7-0160", 09:01:49 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:01:49 INFO - "platform": "yosemite_r7", 09:01:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:01:49 INFO - "branch": "mozilla-central", 09:01:49 INFO - "repo_path": "mozilla-central", 09:01:49 INFO - "revision": "66a6e0bb7fd45343cad3826af9092a2f77b110ef", 09:01:49 INFO - "stage_platform": "macosx64", 09:01:49 INFO - "builduid": "28db367f326748c38cf2588bd78102ee", 09:01:49 INFO - "slavebuilddir": "test" 09:01:49 INFO - } 09:01:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:01:49 INFO - Running post-action listener: _resource_record_post_action 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.391281Z] Finished read-buildbot-config step (success) 09:01:49 INFO - [mozharness: 2017-04-13 16:01:49.391388Z] Running download-and-extract step. 09:01:49 INFO - Running pre-action listener: _resource_record_pre_action 09:01:49 INFO - Running main action method: download_and_extract 09:01:49 INFO - mkdir: /builds/slave/test/build/tests 09:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:49 INFO - trying https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:01:49 INFO - Downloading https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:01:50 INFO - Downloaded 1325 bytes. 09:01:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:01:50 INFO - Using the following test package requirements: 09:01:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:01:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:01:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:01:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'jsshell-mac.zip'], 09:01:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:01:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:01:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:01:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:01:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:01:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:01:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:01:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:50 INFO - Fetch https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:01:52 INFO - Expected file size: 52595070 09:01:52 INFO - Obtained file size: 52595070 09:02:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:02:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:02:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:08 INFO - Fetch https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:02:10 INFO - Expected file size: 52595070 09:02:10 INFO - Obtained file size: 52595070 09:02:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:02:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:02:25 INFO - Fetch https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:02:26 INFO - Expected file size: 14407739 09:02:26 INFO - Obtained file size: 14407739 09:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:26 INFO - trying https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:26 INFO - Downloading https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:02:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:02:28 INFO - Downloaded 71067905 bytes. 09:02:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:28 INFO - mkdir: /builds/slave/test/properties 09:02:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:28 INFO - Contents: 09:02:28 INFO - build_url:https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - Running post-action listener: set_extra_try_arguments 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.501676Z] Finished download-and-extract step (success) 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.501803Z] Running populate-webroot step. 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: populate_webroot 09:02:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:29 INFO - '--tppagecycles', 09:02:29 INFO - '7'], 09:02:29 INFO - 'tests': ['tsvgm']}}, 09:02:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:02:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:02:29 INFO - 'bloom_basic_ref']}, 09:02:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart', 09:02:29 INFO - 'tsvg_static']}, 09:02:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart', 09:02:29 INFO - 'tsvg_static']}, 09:02:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o']}, 09:02:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:02:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s', 09:02:29 INFO - '--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}, 09:02:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:02:29 INFO - 'talos_options': ['--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}}, 09:02:29 INFO - 'talos.zip': {'path': '', 09:02:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.520980Z] Finished populate-webroot step (success) 09:02:29 INFO - [mozharness: 2017-04-13 16:02:29.521097Z] Running create-virtualenv step. 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: create_virtualenv 09:02:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:29 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 09:02:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:29 INFO - Using real prefix '/tools/python27' 09:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:33 INFO - Installing pip.................done. 09:02:33 INFO - Return code: 0 09:02:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:33 INFO - Reading from file tmpfile_stdout 09:02:33 INFO - Output received: 09:02:33 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) 09:02:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:33 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')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:33 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 09:02:33 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 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:33 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 09:02:33 INFO - Cleaning up... 09:02:33 INFO - Return code: 0 09:02:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:33 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')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:33 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 09:02:33 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 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:34 INFO - Downloading/unpacking psutil>=3.1.1 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:37 INFO - Installing collected packages: psutil 09:02:37 INFO - Running setup.py install for psutil 09:02:37 INFO - building 'psutil._psutil_osx' extension 09:02:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:02:38 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 09:02:38 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 09:02:38 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 09:02:38 INFO - building 'psutil._psutil_posix' extension 09:02:38 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 09:02:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:38 INFO - ^ 09:02:38 INFO - 1 warning generated. 09:02:38 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 09:02:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:38 INFO - Successfully installed psutil 09:02:38 INFO - Cleaning up... 09:02:38 INFO - Return code: 0 09:02:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:38 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')]} 09:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:39 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 09:02:39 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 09:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:39 INFO - 'HOME': '/Users/cltbld', 09:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:39 INFO - 'LOGNAME': 'cltbld', 09:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:39 INFO - 'MOZ_NO_REMOTE': '1', 09:02:39 INFO - 'NO_EM_RESTART': '1', 09:02:39 INFO - 'PAGER': '/bin/cat', 09:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:39 INFO - 'PWD': '/builds/slave/test', 09:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:39 INFO - 'SHELL': '/bin/bash', 09:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:39 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:39 INFO - 'USER': 'cltbld', 09:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:39 INFO - 'XPC_FLAGS': '0x0', 09:02:39 INFO - 'XPC_SERVICE_NAME': '0', 09:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:39 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 09:02:39 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 09:02:39 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 09:02:39 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 09:02:39 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 09:02:39 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 09:02:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:42 INFO - Installing collected packages: mozsystemmonitor 09:02:42 INFO - Running setup.py install for mozsystemmonitor 09:02:43 INFO - Successfully installed mozsystemmonitor 09:02:43 INFO - Cleaning up... 09:02:43 INFO - Return code: 0 09:02:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:43 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')]} 09:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:43 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')]} 09:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:43 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:43 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 09:02:43 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 09:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:43 INFO - 'HOME': '/Users/cltbld', 09:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:43 INFO - 'LOGNAME': 'cltbld', 09:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:43 INFO - 'MOZ_NO_REMOTE': '1', 09:02:43 INFO - 'NO_EM_RESTART': '1', 09:02:43 INFO - 'PAGER': '/bin/cat', 09:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:43 INFO - 'PWD': '/builds/slave/test', 09:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:43 INFO - 'SHELL': '/bin/bash', 09:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:43 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:43 INFO - 'USER': 'cltbld', 09:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:43 INFO - 'XPC_FLAGS': '0x0', 09:02:43 INFO - 'XPC_SERVICE_NAME': '0', 09:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:46 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 09:02:46 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 09:02:46 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 09:02:46 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 09:02:46 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 09:02:46 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 09:02:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:46 INFO - warning: no files found matching '*.txt' 09:02:46 INFO - no previously-included directories found matching 'build' 09:02:46 INFO - no previously-included directories found matching 'dist' 09:02:46 INFO - no previously-included directories found matching '.git*' 09:02:46 INFO - Installing collected packages: jsonschema, functools32 09:02:46 INFO - Running setup.py install for functools32 09:02:46 INFO - warning: no files found matching '*.txt' 09:02:46 INFO - no previously-included directories found matching 'build' 09:02:46 INFO - no previously-included directories found matching 'dist' 09:02:46 INFO - no previously-included directories found matching '.git*' 09:02:46 INFO - Successfully installed jsonschema functools32 09:02:46 INFO - Cleaning up... 09:02:46 INFO - Return code: 0 09:02:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', '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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02: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 functools32==3.2.3-2 09:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:46 INFO - 'HOME': '/Users/cltbld', 09:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:46 INFO - 'LOGNAME': 'cltbld', 09:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:46 INFO - 'MOZ_NO_REMOTE': '1', 09:02:46 INFO - 'NO_EM_RESTART': '1', 09:02:46 INFO - 'PAGER': '/bin/cat', 09:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:46 INFO - 'PWD': '/builds/slave/test', 09:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:46 INFO - 'SHELL': '/bin/bash', 09:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:46 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:46 INFO - 'USER': 'cltbld', 09:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:46 INFO - 'XPC_FLAGS': '0x0', 09:02:46 INFO - 'XPC_SERVICE_NAME': '0', 09:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:47 INFO - Cleaning up... 09:02:47 INFO - Return code: 0 09:02:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:47 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')]} 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:47 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')]} 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:47 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:47 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 09:02:47 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 09:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:47 INFO - 'HOME': '/Users/cltbld', 09:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:47 INFO - 'LOGNAME': 'cltbld', 09:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:47 INFO - 'MOZ_NO_REMOTE': '1', 09:02:47 INFO - 'NO_EM_RESTART': '1', 09:02:47 INFO - 'PAGER': '/bin/cat', 09:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:47 INFO - 'PWD': '/builds/slave/test', 09:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:47 INFO - 'SHELL': '/bin/bash', 09:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:47 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:47 INFO - 'USER': 'cltbld', 09:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:47 INFO - 'XPC_FLAGS': '0x0', 09:02:47 INFO - 'XPC_SERVICE_NAME': '0', 09:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:47 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:50 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 09:02:50 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 09:02:50 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 09:02:50 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 09:02:50 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 09:02:50 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 09:02:51 INFO - Downloading docopt-0.6.1.tar.gz 09:02:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:51 INFO - Running setup.py install for blobuploader 09:02:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:51 INFO - Running setup.py install for requests 09:02:51 INFO - Running setup.py install for docopt 09:02:52 INFO - Successfully installed blobuploader requests docopt 09:02:52 INFO - Cleaning up... 09:02:52 INFO - Return code: 0 09:02:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:52 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')]} 09:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:52 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')]} 09:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:52 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:52 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 09:02:52 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 09:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:52 INFO - 'HOME': '/Users/cltbld', 09:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:52 INFO - 'LOGNAME': 'cltbld', 09:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:52 INFO - 'MOZ_NO_REMOTE': '1', 09:02:52 INFO - 'NO_EM_RESTART': '1', 09:02:52 INFO - 'PAGER': '/bin/cat', 09:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:52 INFO - 'PWD': '/builds/slave/test', 09:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:52 INFO - 'SHELL': '/bin/bash', 09:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:52 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:52 INFO - 'USER': 'cltbld', 09:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:52 INFO - 'XPC_FLAGS': '0x0', 09:02:52 INFO - 'XPC_SERVICE_NAME': '0', 09:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-adnkLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Anyccd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-vc6ffC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-8J08p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-8bz9PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IF9pVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:52 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-g_IYI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-WUPorh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-UwuWhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-yVC5Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-C8ypkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-7FOSFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-V7EuYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ww6QAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-gybEj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:53 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-L7dsVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:54 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_vdZ2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:54 INFO - Running setup.py install for manifestparser 09:02:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Running setup.py install for mozcrash 09:02:54 INFO - Running setup.py install for mozdebug 09:02:54 INFO - Running setup.py install for mozdevice 09:02:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Running setup.py install for mozfile 09:02:54 INFO - Running setup.py install for mozhttpd 09:02:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:54 INFO - Running setup.py install for mozinfo 09:02:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozInstall 09:02:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozleak 09:02:55 INFO - Running setup.py install for mozlog 09:02:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for moznetwork 09:02:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:55 INFO - Running setup.py install for mozprocess 09:02:55 INFO - Running setup.py install for mozprofile 09:02:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Running setup.py install for mozrunner 09:02:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Running setup.py install for mozscreenshot 09:02:56 INFO - Running setup.py install for moztest 09:02:56 INFO - Running setup.py install for mozversion 09:02:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:56 INFO - Cleaning up... 09:02:56 INFO - Return code: 0 09:02:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:56 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:02:56 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 09:02:56 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 09:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:56 INFO - 'HOME': '/Users/cltbld', 09:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:56 INFO - 'LOGNAME': 'cltbld', 09:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:56 INFO - 'MOZ_NO_REMOTE': '1', 09:02:56 INFO - 'NO_EM_RESTART': '1', 09:02:56 INFO - 'PAGER': '/bin/cat', 09:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:56 INFO - 'PWD': '/builds/slave/test', 09:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:56 INFO - 'SHELL': '/bin/bash', 09:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:02:56 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:56 INFO - 'USER': 'cltbld', 09:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:56 INFO - 'XPC_FLAGS': '0x0', 09:02:56 INFO - 'XPC_SERVICE_NAME': '0', 09:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:56 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-MxeglT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-o1f9vK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-OKBbwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-fdeE_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-95vYg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-pvvS0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-lBsdbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-mzMpGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-mM19CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-VXgWdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:57 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)) 09:02:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:57 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ZVRQB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-iU7DlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-31onVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-IuttET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-HtYz83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-1voPh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:58 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)) 09:02:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:58 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Qa49NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 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)) 09:02:58 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:58 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:58 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 09:02:58 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 09:02:58 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 09:02:58 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 09:02:58 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 09:02:58 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 09:03:01 INFO - Downloading blessings-1.6.tar.gz 09:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:01 INFO - Installing collected packages: blessings 09:03:01 INFO - Running setup.py install for blessings 09:03:01 INFO - Successfully installed blessings 09:03:01 INFO - Cleaning up... 09:03:01 INFO - Return code: 0 09:03:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:01 INFO - Reading from file tmpfile_stdout 09:03:01 INFO - Current package versions: 09:03:01 INFO - blessings == 1.6 09:03:01 INFO - blobuploader == 1.2.4 09:03:01 INFO - docopt == 0.6.1 09:03:01 INFO - functools32 == 3.2.3-2 09:03:01 INFO - jsonschema == 2.5.1 09:03:01 INFO - manifestparser == 1.1 09:03:01 INFO - mozInstall == 1.12 09:03:01 INFO - mozcrash == 1.0 09:03:01 INFO - mozdebug == 0.1 09:03:01 INFO - mozdevice == 0.50 09:03:01 INFO - mozfile == 1.2 09:03:01 INFO - mozhttpd == 0.7 09:03:01 INFO - mozinfo == 0.9 09:03:01 INFO - mozleak == 0.1 09:03:01 INFO - mozlog == 3.4 09:03:01 INFO - moznetwork == 0.27 09:03:01 INFO - mozprocess == 0.25 09:03:01 INFO - mozprofile == 0.28 09:03:01 INFO - mozrunner == 6.13 09:03:01 INFO - mozscreenshot == 0.1 09:03:01 INFO - mozsystemmonitor == 0.3 09:03:01 INFO - moztest == 0.8 09:03:01 INFO - mozversion == 1.4 09:03:01 INFO - psutil == 3.1.1 09:03:01 INFO - requests == 1.2.3 09:03:01 INFO - wsgiref == 0.1.2 09:03:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:01 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')]} 09:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:01 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')]} 09:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:01 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:03:01 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 09:03:01 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 09:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:01 INFO - 'HOME': '/Users/cltbld', 09:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:01 INFO - 'LOGNAME': 'cltbld', 09:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:01 INFO - 'MOZ_NO_REMOTE': '1', 09:03:01 INFO - 'NO_EM_RESTART': '1', 09:03:01 INFO - 'PAGER': '/bin/cat', 09:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:01 INFO - 'PWD': '/builds/slave/test', 09:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:01 INFO - 'SHELL': '/bin/bash', 09:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:03:01 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:01 INFO - 'USER': 'cltbld', 09:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:01 INFO - 'XPC_FLAGS': '0x0', 09:03:01 INFO - 'XPC_SERVICE_NAME': '0', 09:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 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)) 09:03:02 INFO - Cleaning up... 09:03:02 INFO - Return code: 0 09:03:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:02 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')]} 09:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 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')]} 09:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:02 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 0x1092a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb7acae670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, '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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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 09:03:02 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 09:03:02 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 09:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:02 INFO - 'HOME': '/Users/cltbld', 09:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:02 INFO - 'LOGNAME': 'cltbld', 09:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:02 INFO - 'MOZ_NO_REMOTE': '1', 09:03:02 INFO - 'NO_EM_RESTART': '1', 09:03:02 INFO - 'PAGER': '/bin/cat', 09:03:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:02 INFO - 'PWD': '/builds/slave/test', 09:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:02 INFO - 'SHELL': '/bin/bash', 09:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:03:02 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:02 INFO - 'USER': 'cltbld', 09:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:02 INFO - 'XPC_FLAGS': '0x0', 09:03:02 INFO - 'XPC_SERVICE_NAME': '0', 09:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:02 INFO - Cleaning up... 09:03:02 INFO - Return code: 0 09:03:02 INFO - Running post-action listener: _resource_record_post_action 09:03:02 INFO - Running post-action listener: _start_resource_monitoring 09:03:02 INFO - Starting resource monitoring. 09:03:02 INFO - [mozharness: 2017-04-13 16:03:02.513671Z] Finished create-virtualenv step (success) 09:03:02 INFO - [mozharness: 2017-04-13 16:03:02.514629Z] Running install step. 09:03:02 INFO - Running pre-action listener: _resource_record_pre_action 09:03:02 INFO - Running main action method: install 09:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:02 INFO - Reading from file tmpfile_stdout 09:03:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:02 INFO - Reading from file tmpfile_stdout 09:03:02 INFO - Output received: 09:03:02 INFO - Usage: mozinstall [options] installer 09:03:02 INFO - Options: 09:03:02 INFO - -h, --help show this help message and exit 09:03:02 INFO - -d DEST, --destination=DEST 09:03:02 INFO - Directory to install application into. [default: 09:03:02 INFO - "/builds/slave/test"] 09:03:02 INFO - --app=APP Application being installed. [default: firefox] 09:03:02 INFO - mkdir: /builds/slave/test/build/application 09:03:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:03:22 INFO - Reading from file tmpfile_stdout 09:03:22 INFO - Output received: 09:03:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:22 INFO - Running post-action listener: _resource_record_post_action 09:03:22 INFO - [mozharness: 2017-04-13 16:03:22.627502Z] Finished install step (success) 09:03:22 INFO - [mozharness: 2017-04-13 16:03:22.627672Z] Running run-tests step. 09:03:22 INFO - Running pre-action listener: _resource_record_pre_action 09:03:22 INFO - Running main action method: run_tests 09:03:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:22 INFO - 2017-04-13 09:03:22.641 screenresolution[1622:7425] starting screenresolution argv=screenresolution get 09:03:22 INFO - 2017-04-13 09:03:22.644 screenresolution[1622:7425] Display 0: 1600x1200x32@60 09:03:22 INFO - 2017-04-13 09:03:22.650 screenresolution[1623:7428] starting screenresolution argv=screenresolution list 09:03:22 INFO - Available Modes on Display 0 09:03:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:22 INFO - Intel Iris: 09:03:22 INFO - Chipset Model: Intel Iris 09:03:22 INFO - Type: GPU 09:03:22 INFO - Bus: Built-In 09:03:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:22 INFO - Vendor: Intel (0x8086) 09:03:22 INFO - Device ID: 0x0a2e 09:03:22 INFO - Revision ID: 0x0009 09:03:22 INFO - Displays: 09:03:22 INFO - Display: 09:03:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:22 INFO - Display Serial Number: 1600x1200 60 09:03:22 INFO - Main Display: Yes 09:03:22 INFO - Mirror: Off 09:03:22 INFO - Online: Yes 09:03:22 INFO - Rotation: Supported 09:03:22 INFO - Return code: 0 09:03:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:22 INFO - Python 2.7.3 09:03:22 INFO - Return code: 0 09:03:22 INFO - grabbing minidump binary from tooltool 09:03: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')]} 09:03:22 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 0x109085d30>, '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 0x108facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10907abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:22 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 09:03:22 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 09:03:22 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 09:03:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:23 INFO - Return code: 0 09:03:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:23 INFO - ENV: RUST_BACKTRACE is now 1 09:03:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 09:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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 09:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GLtNVRFZPf/Render', 09:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:23 INFO - 'HOME': '/Users/cltbld', 09:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:23 INFO - 'LOGNAME': 'cltbld', 09:03:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:23 INFO - 'MOZ_NO_REMOTE': '1', 09:03:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:23 INFO - 'NO_EM_RESTART': '1', 09:03:23 INFO - 'PAGER': '/bin/cat', 09:03:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:23 INFO - 'PWD': '/builds/slave/test', 09:03:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:23 INFO - 'RUST_BACKTRACE': '1', 09:03:23 INFO - 'SHELL': '/bin/bash', 09:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MFDQ9Dmg0v/Listeners', 09:03:23 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 09:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:23 INFO - 'USER': 'cltbld', 09:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:23 INFO - 'XPC_FLAGS': '0x0', 09:03:23 INFO - 'XPC_SERVICE_NAME': '0', 09:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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 09:03:23 INFO - mozversion application_buildid: 20170413075523 09:03:23 INFO - mozversion application_changeset: 66a6e0bb7fd45343cad3826af9092a2f77b110ef 09:03:23 INFO - mozversion application_display_name: Nightly 09:03:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:23 INFO - mozversion application_name: Firefox 09:03:23 INFO - mozversion application_remotingname: firefox 09:03:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:03:23 INFO - mozversion application_vendor: Mozilla 09:03:23 INFO - mozversion application_version: 55.0a1 09:03:23 INFO - mozversion platform_buildid: 20170413075523 09:03:23 INFO - mozversion platform_changeset: 66a6e0bb7fd45343cad3826af9092a2f77b110ef 09:03:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:03:23 INFO - mozversion platform_version: 55.0a1 09:03:23 INFO - using testdate: 1492099403 09:03:23 INFO - actual date: 1492099403 09:03:23 INFO - starting webserver on 'localhost:49242' 09:03:23 INFO - SUITE-START | Running 2 tests 09:03:23 INFO - TEST-START | dromaeo_css 09:03:23 INFO - operating with platform_type : mac_ 09:03:23 INFO - Initialising browser for dromaeo_css test... 09:03:23 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpt93UfC/profile http://localhost:49242/getInfo.html) 09:03:26 INFO - PID 1633 | __metrics Screen width/height:1600/1200 09:03:26 INFO - PID 1633 | colorDepth:24 09:03:26 INFO - PID 1633 | Browser inner width/height: 1024/689 09:03:26 INFO - PID 1633 | __metrics 09:03:26 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:03:26 INFO - PID 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:27 INFO - TEST-INFO | 1633: exit 0 09:03:27 INFO - Browser initialized. 09:03:27 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:27 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpt93UfC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:39 INFO - PID 1635 | RSS: Main: 240783360 09:03:39 INFO - PID 1635 | 09:06:44 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:06:46 INFO - PID 1635 | RSS: Main: 427917312 09:06:46 INFO - PID 1635 | 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 09:08:29 INFO - PID 1635 | 09:08:29 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:29 INFO - PID 1635 | Full stack: 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 09:08:29 INFO - PID 1635 | 09:08:29 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:29 INFO - PID 1635 | Full stack: 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 09:08:29 INFO - PID 1635 | 09:08:29 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:29 INFO - PID 1635 | Full stack: 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | ************************* 09:08:29 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 09:08:29 INFO - PID 1635 | 09:08:29 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:29 INFO - PID 1635 | Full stack: 09:08:29 INFO - PID 1635 | ************************* 09:09:49 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:09:49 INFO - PID 1635 | RSS: Main: 366891008 09:09:49 INFO - PID 1635 | 09:12:52 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:12:52 INFO - PID 1635 | RSS: Main: 323964928 09:12:52 INFO - PID 1635 | 09:15:55 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:15:55 INFO - PID 1635 | RSS: Main: 361582592 09:15:55 INFO - PID 1635 | 09:18:59 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:18:59 INFO - PID 1635 | RSS: Main: 339345408 09:18:59 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:22:02 INFO - PID 1635 | RSS: Main: 375119872 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | __start_tp_report 09:22:02 INFO - PID 1635 | _x_x_mozilla_page_load 09:22:02 INFO - PID 1635 | _x_x_mozilla_page_load_details 09:22:02 INFO - PID 1635 | |i|pagename|runs| 09:22:02 INFO - PID 1635 | |0;dojo.html;3436.707879830214;3484.494748261501;3490.197254631433;3525.5593050868642;3543.503909452677;5893.263342082239;6102.145699602059;6172.351903050177;6180.227471566054;6185.907211391827;5965.671888046156;6041.275046994367;6041.637501749898;6053.727582258798;6074.726637301321;5218.47815218477;5228.686278823273;5252.159654455288;5254.632175747699;5380.596455265852;5324.973375133139;5335;5337.226102215196;5356.812511562096;5357.4106848246665;5334.119870221409;5339.866503337409;5344.7862085516535;5352.678839269637;5358.544523715489;5369.19462080689;5377.569794416438;5382.9730851345685;5408.567314614861;5450.29146210994;5283.920741188886;5366.060939335601;5373.811916582901;5382.757775900094;5400.243965844785;6156.6921653917125;6178.814635560941;6214.502839772853;6222.408871157233;6232.3144454110015;6299.401556852092;6342.4926005919415;6355.142055822497;6372.681365931685;6382.946813775721;4752.881177970544;6540.803775886731;6581.539292249497;6603.273639899608;6629.237637671681;6208.0998255253635;6209.565330426872;6220.25356957159;6222.408871157233;6227.564070515065;6504.252011018699;6614.86770264592;6623.304553021863;6789.049533065375;6793;6279.560430769893;6316.810495685137;6316.9368306317265;6333.96833015832;6335.334789847092;6439.066335381437;6516.771912983023;6570.868582628321;6591.57154784947;6612.437942774918;6538.640374779433;6545.509086818508;6558.344165583403;6559.934400656028;6560.803175904731;5398.865028374244;6562.704678289489;6574.441172500296;6577.408033277028;6580.341965803477;4388.5830846069575;4428.73427594339;5204;5241.109011468059;5266.157414813688;4709.293605959065;4713.269443236235;4713.717176969324;4720.45714742809;4735.810567577328;6252.812415627447;6257.499400047986;6264.718087686065;6265.154204182468;6267.780627678009;6609.239937407164;6625.238097618739;6631;6631;6647.268800431949;5925.170476133345;6148.354422785633;6160.969195153995;6162.26052873658;6215.06773983897;5789.392113828072;5796.797112101141;5801.883962320814;5813.098969659625;5841.4450627190345;6723.26044135135;6730.293319201609;6778.559393639398;6782.25395206517;6792.966035169792;6378.649174295459;6452.967525195946;6635.104260924906;6637.103990961352;6638.900416493853;6564.048213009182;6670.299618539983;6728.7981360557305;6748.628825414453;6749;6519.7392104315295;6523.510736694861;6542.476601871934;6546.345365463416;6548.639824809681;6388.55280130405;6513.055606936873;6587.341265873374;6662.800115996528;6670;6281.560290779602;6305.716242769178;6353.936460635335;6387.265464471645;6398.5201109916015;6403.167588213502;6434.613923164625;6441.1626488556185;6451.290358060516;6452.483801295979;6267.749290028348;6270.090836828543;6300;6305;6322;6222.315545290105;6284.811455656338;6295.307516173309;6354.332795056637;6410.358964103739;6246.625202487865;6269.310375858743;6281.623102613858;6283.654399007915;6289.842753931189 09:22:02 INFO - PID 1635 | |1;ext.html;773.0298475413356;825.0099880143825;1122.3266040375775;1149.0577726264466;1149.5861489863648;36170.819145904265;36918.815405922964;36991.26017479649;37157.62842371575;37254.813725931366;38964.83105506832;39242;39341.803290983575;39402.605973940255;39443;6171.660558669271;8451.02813176485;8600.741977740678;8604.225619694227;8620.827583448327;6196.473299769525;8255;8502.914970850274;8504.404691671585;8515.616797244138;8505.192006759347;8559.700410485602;8561.229489345958;8571.314294856396;8578;8488.702895398663;8548.529830859301;8551.871721924175;8553.01640311366;8553.914460855374;8438.957805210996;8498.660053597849;8500.532470714108;8505.489670619783;8507.489550626982;37352;37588;37589.24821503582;37611.811940940395;37620;3060.6633270340244;3558.4128618777872;3558.768680035789;3559.4126969050076;3563.875264365761;30338.84830575833;30367.544486832714;30373;30383.544246836314;30427.23931901698;3099.5660607514965;3135.67075457076;3162.0513845846385;3165.414398336292;3179.205198700325;262662;267337;267885;268286.31713683;270842;39086;39800.80099599483;48223.75888120537;48333.275000875015;48376.758116209196;41688.58311416908;48400.03199936052;48844.7557762216;49050.754746226754;49155;14024.929875350763;14114.858851411354;14125.646858828612;14170.716585668435;14208.005439619128;11522.827157592643;11540.24988375753;11558;11564.306141631529;11574.768504629861;9773.41359518431;10437;11145.996860282614;11819.468123934443;12010.519579216909;5114.974425127851;5134.717590532557;5152.974235128875;5153.1497302945345;5161.096808058574;1028.7993841200894;1036.2694300518117;1044.0916402729674;1073.8442925775719;1082.4695899009428;17711.380101696377;20266.89866550658;22254;22816.885915570318;24149;1127.7067962329854;1139.9031082357926;1168.4333098447246;1170.5142365918218;1172.9706757330964;11741.941290293495;12393.008559315233;12421.627351179479;12430.440630171664;12449.377531123408;360096;360567;360883;361056.1947190247;361139;52885.47114528805;53363;53389.932201355754;53393.733031334596;53568.73215633897;1502.699460107961;1506.314626844805;1550.3720992997853;1553.3475940104956;1557.3459147158221;23577.882110589337;23599.410014749083;23765.881170594035;23785.048598055884;23797.286081417584;36652.63347366492;36751.26497469982;36854;36968;37117.25765484729;266444;329799;329941;330134;331183;23828.404289892893;29111.272218194714;32367.676323236465;33019;33248.501272481415;42513.93715157146;43291.701248962585;43379.349309759746;43430;43554.34668479912;28041.859790700917;29404;29746;30003.54994675038;30196;19871.60256794885;23762.574245545322;27082;28005.73974171208;30042.699573004866;29736;35694;35853.565857365415;35919.74280900156;36039.45940810838;28930.566041509814;28952.855235724528;30065.838687783824;30675.693243068177;30793.538096928114;21886;23364;23729;23749.168779092648;23896.64155037641 09:22:02 INFO - PID 1635 | |2;jquery.html;430.2685434760907;433.4690004744191;440.83909373078825;441.32917964693655;464.8256903661127;19661.901690491544;21353;21966.90165491727;22100.22649207278;22144;21776.455588610293;22682.546349073007;23313.71774552399;23411;23674.64488032681;5976.790812321569;6032.638041717489;6268.968655156717;6271.686415679221;6279;5946.405359464041;6128.6322820630685;6156.7537298508005;6167.321594624587;6172.22847144107;5815.62198457101;6057.54568407369;6105.084237364409;6110.725017374207;6114.144019837227;6049.364816694249;6068.27180738312;6100.816975490742;6114.938850611504;6121.479674227696;5340.679559226459;5350.464953504657;5409.26974858393;5409.864753381157;5431;26529.602055969175;26576;26642.93428262867;26744.86627566869;26903.865480672666;2193.5941850757663;2230.9442263943533;2250.459889626478;2264.841461097724;2272.2047283450825;13763.587092387144;16690.58273543159;16727.414540491023;17275.913620431817;17424.825751742446;2094.350751267112;2143.839212059102;2173.7826217378292;2176.771438998914;2179.052112331141;133713.33143334318;133795;134214;134294;136113;9937.403755774676;11671.299722016705;12036.352002559423;12054.939725301492;12222.938885305517;16102.75845862313;16128.838711612969;16132.19339033067;16136;16176.838231617769;8409.747707568782;8669.696560620348;8713.869291960626;8841.292696584258;8894.110588941183;6667.733290668319;7988.281054705104;8268.751937441886;8325.458845175044;8407.495550266882;6484.967575162188;7508.587027713419;7531.246875312425;7537.585432801248;7538.924610753932;2044.959100818005;2058.475088852333;2060.9793902060787;2065.834733221339;2078.137572907257;848.3001523742937;933.3093510801957;952.842780941137;996.725900377402;1007.4541682795953;18991.715124273145;21092.78907210939;21478;21572.244971425924;21634.91825408723;1013.2299452416216;1029.1082059413072;1057.8571892794375;1057.9471026448646;1065.738893970982;8020.398470114765;10313.50454184139;10469.790604187874;10602.36385816838;10875.912408759219;664350;684097;685688;704880;710324;111211.33183002261;132993;137190;150446.49553504324;152548;1191.243560339191;1220.780259553289;1249.0819247852821;1255.491525931999;1258.3519487463964;19290.903545482182;20337;20893.582128357044;21275.148994040064;22158.667619986034;53954.19068714051;60540.69729651324;62095;62598.06102908551;63426.68286658537;65350;83361.58319208365;87287;88008;88616;8786;8849.690260841096;8930.642774289216;9012.369134160808;9031.548422578977;13659.453621855015;13734;13740.312984350543;14020.579382618538;14118.223497707724;11594.304341739522;11615.88384116148;11708.297502149899;11845.407729613657;11987.520499179935;8191.262786349257;8515.978082629876;9079.092090790868;9160.221381182408;9421.905780942378;11864.940675296568;11965.282083074695;11993.36146047868;12088.39558022113;12119.939400302943;7069;8710.259627931697;8760.12398760119;8877;8997.82004359896;11664.708382290512;12011.63965081049;12123.030157587196;12147.149699520949;12211.75576488483 09:22:02 INFO - PID 1635 | |3;mootools.html;1258.5595041735392;1333.6199183232777;1415.4126037694357;1428.735683898478;1438.7769895666174;11377.829332560019;11912.7617447651;11929.343886086263;11943.820842687366;11987.280763154205;11579.36313502757;11588.536458541648;12006.039516838653;12028.218165819215;12045.457954392052;2510.623406489023;2548.808839337048;2559;2563.4873025394904;2566.6150077488433;2456.312232574873;2456.6192240202795;2504.211173480357;2505.7619526144986;2517.3203235126507;2423.612222044473;2534.911278105267;2535.695716514021;2537.2134638262105;2541.885615147323;2291.3698732848434;2320.5590937721777;2321.860688358704;2371.976280237193;2535.657686212356;2175.3147758456034;2506.0852788732163;2536.239128261514;2541.606051062088;2551.6427700122;11633.534658613646;11923.105767067505;11937.104717146161;11955.880441195564;11956.103292253116;6517.608943463408;6784.7964561063245;6790.558613690095;6792.524523283372;6799.150106236721;4089.407035979766;4346.413234213373;4407.427034485529;4416.116776644684;4429.911401771947;1225.394732899904;1246.4266437438698;1299.5841330774156;1301.4078594239597;1303.2050449225965;15109.924450377677;15597.298121584556;16465.423710170086;16630.58423539409;16633.83366166347;4749.881252968704;5036.345275114211;5509.201165831012;5717.742701578439;5740.052891272977;12155.574554890536;12168.756624867454;12169.148159628741;12175.208611440228;12190.268583884996;5504.532114770209;6505.186851643544;6538.673066346663;6539.247986481616;6541.803745887631;5691.9146212806845;6200.286966998853;6219.689015549295;6222.62664240147;6228.408301211378;4284.485861696554;4361.672874534423;4373.1472362888935;4414.139242847678;4441.511433742286;1279.2068917271351;1285.3444743181044;1285.5115056278555;1292.6832925933206;1294.4239813282998;642.2902692524787;766.6971546239205;792.9167437419103;796.5499492786557;800.6677228950037;6768.526203165829;6806.353396427331;6857.931420685829;6898.689558969859;7012.228654847962;878.7627340617997;892.7554038454992;895.6148855991986;915.7023967210683;935.6631612619451;4071.75569465833;4160.252693868582;4173.457450531472;4264.616184543406;4289.978550107229;14850.2574871256;15453.150076745887;15520;15538;15571.688566228611;12382.318972456602;15152.924235378972;15233.542993710207;15312.234388280542;15438.691226175413;10774.94612526948;10977.670669880075;10982.780344393068;10987.230893837355;11019.283746556608;6351.618902865843;6424.38968298011;6714.932850671431;6746.9325306746305;6791.388775010273;13291.734165316833;13670.316484175553;13680.863191367958;13811.585652430444;13816.654583635489;13568.118072325267;13698.452061917811;14024.859751402355;14132.78800818009;14159.433622654979;8441.831163376575;8692.956535217283;8831.735047948572;8833.779155521164;8845.69040083594;10494;10631.255812093388;10636.30863993838;10640.627578034731;10640.787184256116;8891.894176316631;9109.544522773967;9185.816283674156;9214;9217;8924.286057115285;8977.775555611162;8986.14631609996;9090.863637045317;9095.954520227357;6819.761308354184;7928.881066784118;8123.918760812553;8142.796430089058;8145.551994640352;5937.495312898279;5972.731227094704;6049.78825741097;6137.294211165773;6158.630482171084;5814.5639077068545;5937.821865344046;5982;6089.117078023751;6284.905726414199 09:22:02 INFO - PID 1635 | |4;prototype.html;387.2912569996906;393.6712844774613;410.1694069509243;414.8008955701265;416.13028769871;4967.354243948286;5540.168974653804;5739.196512488255;5784.855378615537;5858.502027327677;5196.636235463519;5565.888682226353;5596.384397716248;5598.972005139979;5726.570507211965;3656.433252845813;4381.4961279452955;4426.712263702865;4429.5348988356245;4464.843730469418;4146.709730318879;4301.268784306675;4349.652027837781;4442.933355999663;4491.842785502508;4056.3307054336;4259;4262.996391695898;4273.4444522212225;4278.9786051069705;3100.069979006289;3611.7291203159607;3954.9209015819524;4164.895877603054;4261.3366645846345;3622.981885090584;3716.7212459065677;3722.1997270586953;3731.6828069613844;3737.103095257146;5612.045952188138;5614.3543492498475;5633.239512665778;5645.294338207724;5662.66024038559;4369.453818272716;4464.709793863389;4666.803239780761;4832.9758351208375;4985.354832905133;5449.727513624303;5640.8154287599655;6045.455908968214;6054.031354983182;6061.636301821861;4191.224623444642;4195.139996300782;4197.895052623679;4198.307279298912;4212.241796476633;6183.845403864894;6440.388163124496;6476.902846457307;6536.182977127859;6562.77030303937;3780.2628487445236;4085.9795701021303;4288.4210631564965;4291.485021797404;4294.849680261238;4336.588024137702;4347.543507931685;4355.60799528044;4373.2565463871215;4377.956220437819;4746.715197088186;4815.518448155157;5047.470015648304;5052.216906379517;5080.669756465818;5091.389033316026;5584.972075139598;5978.3124940631515;6210.223722034746;6233.283172435137;1258.6595276030998;1382.7718426459724;1418.7446259673152;1425.672095418039;1431.8330560593292;876.8202518483823;912.5893347993297;1000.3497726477848;1001.3627718680011;1006.411249419098;5316.893662126813;5940;5945.227120474397;5950.761969521257;5951.285845698458;5551.722413879371;5844.503217226584;6285.5229021179985;6390;6430.807075787734;5235.712035838065;5552.3892371839065;5909.940900591025;5920.2007728956905;5921.259842519685;3233.0763195898403;3333.999800060021;3337.3825842219267;3358.865645374158;3366.124807550055;6549.508786841006;6555.278919318871;6557.180352455943;6581.2760596334365;6608.372204640552;5859.062549992066;5880.618644279556;6506.707198176094;6517.185351831021;6527.510436717265;5175.301334319894;5963.403659634002;5986.46121849027;6084.39156084388;6419.454346380517;6077.11881777131;6083.270007599116;6383;6435.195600549931;6436.871262574628;5588.944110558842;5972.790952316648;6309.791572949431;6348.3651634838;6348.746050157942;5650.615599178228;6457.6448295342325;6578.309277525791;6607.240167380813;6610.4711623071;4150.522689890701;4241.448611680462;4265.232258193555;4265.2749032663905;4343.978280108579;4776.713397196179;5851.829634073288;5919.644821310735;6054.7275372607455;6367.554271200971;5088.414832294334;5916.73374698131;6017.247844019497;6224.253089629274;6242.65665388408;5591.30108736408;6107.267127944675;6166.537509686702;6167.629942203482;6187.298492914484;5268.288781014668;5855.180274761452;6006.33930267679;6055.184339282255;6064.575479716554;5099.812617114304;5887;6205.6586887721605;6214.471769899609;6271.874562508816;4207.338101449971;5791.16233045813;6090.817275481742;6098.725557349841;6115.7247923844425 09:22:02 INFO - PID 1635 | |5;yui.html;720.711715313874;856.5931182688223;868.7654333330001;875.2866413872697;883.2757139145903;13292;17941.372051978182;18139.4558163255;18574.16416261268;18650.906745466287;17387.13064346784;17499.212535435905;17882.642347153083;17909.10454477274;17942.820571794276;1003.6400677082576;1032.7469769906384;1033.2767063055853;1035.7514196592838;1036.098594223027;685.475611157465;713.414999700246;996.6180827821512;1031.5667419683714;1039.3296323871098;1084.8643919510062;1089.0416433538476;1089.199438412766;1090.5637744902024;1091.3342860854873;1077.6659235636898;1081.2431298091328;1081.5944020992129;1081.978360432787;1083.5882364701401;807.9757607271791;809.1463506001166;809.340387584117;809.6437567470294;809.8987626546682;16387.098709570968;16397.262123204484;16548.917255413646;17049.659006819795;17311.3941012064;16483.6703265934;16530.752038719427;16640.916795415946;16845.494635160965;16890.239939202642;13645.249511276876;13832.30838458083;14075.366608502642;14400.639984000483;14604.92697536516;3561.94657080144;3572.5712914450173;3572.6070132285427;3576.6065732769375;3582.8387722552548;110528;110945;112757;112995;113331;11310.151738619472;11671.88328116725;13051.934740326238;13376.598702038955;13422.731545369037;17593.29626814913;20104.89947550253;25118.74881251164;25151.993920242985;25160.37099072538;8837.248833849066;9043.59303831329;9150.313726470542;9175.357724959189;9177.357584969122;4763.285507173951;4820.108279968217;4832.323474713543;4845.200541910615;4852.44196917359;4236.343366778155;4400.559944005638;4403.427554417905;4405.647548196137;4407.559244075567;926.2913870888775;965.7440778193784;971.4948226921961;971.7910649210454;974.5614473486959;643.4112786800025;652.1912828093131;654.0875478707177;655.6819942327967;687.8589889072728;9180.357374983822;10900.291481053706;10930.890691093146;11832.88167118335;12047.156699030984;625.5152257000769;649.7823229218264;651.205529254309;664.3190729502298;673.2123415603755;5014.749262536859;5300.496452836975;5384.40771515133;5414.377346605112;5501.724913754297;106011;106175.93824061815;109738;110847;114234;94707;95322.85078619131;97321.51339243258;97450;99319.00680993243;66368.35421520544;75439.62280188674;76068.61965690246;76264.47471050659;76444;10508.579656813641;10682.519286632276;10754.601901752721;10786.568537258736;10946.507407166537;40125.197496050496;40735;40854.79572602118;40872.386914196904;41598.58401416068;54673;55184.72407638097;55387.33837984867;55668.44331556633;56210.31369058935;12459.065570082099;12709.682257943625;12827.679308017374;12896;13027.934860325639;12689.80965285503;15290.311935963131;15336.38654453854;15539.378424862878;15576.22118894028;14068.648283792578;14308.141511509342;14435.92782036083;14487.420503180176;14597.92701036488;14325.140491570539;14368.281585920453;14595.85404145945;14624.561263162122;14658.780118298448;13616.727665446833;13963.720725585228;13971;14108.858911410754;14124;10009.299349045497;10241.027102425258;10314.484275786332;10445.425501597181;10577.894221057692;11156.330620162817;11512.942435288105;13534.323283835965;13717.051111684419;13833.862968132751 09:22:02 INFO - PID 1635 | __end_tp_report 09:22:02 INFO - PID 1635 | __start_cc_report 09:22:02 INFO - PID 1635 | _x_x_mozilla_cycle_collect,2123 09:22:02 INFO - PID 1635 | __end_cc_report 09:22:02 INFO - PID 1635 | __startTimestamp1492100522669__endTimestamp 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | ------- Summary: start ------- 09:22:02 INFO - PID 1635 | Number of tests: 6 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6029.48 Median:6279.56 stddev:716.64 (11.4%) stddev-sans-first:689.37 09:22:02 INFO - PID 1635 | Values: 3436.7 3484.5 3490.2 3525.6 3543.5 5893.3 6102.1 6172.4 6180.2 6185.9 5965.7 6041.3 6041.6 6053.7 6074.7 5218.5 5228.7 5252.2 5254.6 5380.6 5325.0 5335.0 5337.2 5356.8 5357.4 5334.1 5339.9 5344.8 5352.7 5358.5 5369.2 5377.6 5383.0 5408.6 5450.3 5283.9 5366.1 5373.8 5382.8 5400.2 6156.7 6178.8 6214.5 6222.4 6232.3 6299.4 6342.5 6355.1 6372.7 6382.9 4752.9 6540.8 6581.5 6603.3 6629.2 6208.1 6209.6 6220.3 6222.4 6227.6 6504.3 6614.9 6623.3 6789.0 6793.0 6279.6 6316.8 6316.9 6334.0 6335.3 6439.1 6516.8 6570.9 6591.6 6612.4 6538.6 6545.5 6558.3 6559.9 6560.8 5398.9 6562.7 6574.4 6577.4 6580.3 4388.6 4428.7 5204.0 5241.1 5266.2 4709.3 4713.3 4713.7 4720.5 4735.8 6252.8 6257.5 6264.7 6265.2 6267.8 6609.2 6625.2 6631.0 6631.0 6647.3 5925.2 6148.4 6161.0 6162.3 6215.1 5789.4 5796.8 5801.9 5813.1 5841.4 6723.3 6730.3 6778.6 6782.3 6793.0 6378.6 6453.0 6635.1 6637.1 6638.9 6564.0 6670.3 6728.8 6748.6 6749.0 6519.7 6523.5 6542.5 6546.3 6548.6 6388.6 6513.1 6587.3 6662.8 6670.0 6281.6 6305.7 6353.9 6387.3 6398.5 6403.2 6434.6 6441.2 6451.3 6452.5 6267.7 6270.1 6300.0 6305.0 6322.0 6222.3 6284.8 6295.3 6354.3 6410.4 6246.6 6269.3 6281.6 6283.7 6289.8 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:45585.96 Median:181576.50 stddev:83740.52 (46.1%) stddev-sans-first:83907.84 09:22:02 INFO - PID 1635 | Values: 773.0 825.0 1122.3 1149.1 1149.6 36170.8 36918.8 36991.3 37157.6 37254.8 38964.8 39242.0 39341.8 39402.6 39443.0 6171.7 8451.0 8600.7 8604.2 8620.8 6196.5 8255.0 8502.9 8504.4 8515.6 8505.2 8559.7 8561.2 8571.3 8578.0 8488.7 8548.5 8551.9 8553.0 8553.9 8439.0 8498.7 8500.5 8505.5 8507.5 37352.0 37588.0 37589.2 37611.8 37620.0 3060.7 3558.4 3558.8 3559.4 3563.9 30338.8 30367.5 30373.0 30383.5 30427.2 3099.6 3135.7 3162.1 3165.4 3179.2 262662.0 267337.0 267885.0 268286.3 270842.0 39086.0 39800.8 48223.8 48333.3 48376.8 41688.6 48400.0 48844.8 49050.8 49155.0 14024.9 14114.9 14125.6 14170.7 14208.0 11522.8 11540.2 11558.0 11564.3 11574.8 9773.4 10437.0 11146.0 11819.5 12010.5 5115.0 5134.7 5153.0 5153.1 5161.1 1028.8 1036.3 1044.1 1073.8 1082.5 17711.4 20266.9 22254.0 22816.9 24149.0 1127.7 1139.9 1168.4 1170.5 1173.0 11741.9 12393.0 12421.6 12430.4 12449.4 360096.0 360567.0 360883.0 361056.2 361139.0 52885.5 53363.0 53389.9 53393.7 53568.7 1502.7 1506.3 1550.4 1553.3 1557.3 23577.9 23599.4 23765.9 23785.0 23797.3 36652.6 36751.3 36854.0 36968.0 37117.3 266444.0 329799.0 329941.0 330134.0 331183.0 23828.4 29111.3 32367.7 33019.0 33248.5 42513.9 43291.7 43379.3 43430.0 43554.3 28041.9 29404.0 29746.0 30003.5 30196.0 19871.6 23762.6 27082.0 28005.7 30042.7 29736.0 35694.0 35853.6 35919.7 36039.5 28930.6 28952.9 30065.8 30675.7 30793.5 21886.0 23364.0 23729.0 23749.2 23896.6 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:39194.98 Median:12792.96 stddev:114950.97 (898.5%) stddev-sans-first:115236.59 09:22:02 INFO - PID 1635 | Values: 430.3 433.5 440.8 441.3 464.8 19661.9 21353.0 21966.9 22100.2 22144.0 21776.5 22682.5 23313.7 23411.0 23674.6 5976.8 6032.6 6269.0 6271.7 6279.0 5946.4 6128.6 6156.8 6167.3 6172.2 5815.6 6057.5 6105.1 6110.7 6114.1 6049.4 6068.3 6100.8 6114.9 6121.5 5340.7 5350.5 5409.3 5409.9 5431.0 26529.6 26576.0 26642.9 26744.9 26903.9 2193.6 2230.9 2250.5 2264.8 2272.2 13763.6 16690.6 16727.4 17275.9 17424.8 2094.4 2143.8 2173.8 2176.8 2179.1 133713.3 133795.0 134214.0 134294.0 136113.0 9937.4 11671.3 12036.4 12054.9 12222.9 16102.8 16128.8 16132.2 16136.0 16176.8 8409.7 8669.7 8713.9 8841.3 8894.1 6667.7 7988.3 8268.8 8325.5 8407.5 6485.0 7508.6 7531.2 7537.6 7538.9 2045.0 2058.5 2061.0 2065.8 2078.1 848.3 933.3 952.8 996.7 1007.5 18991.7 21092.8 21478.0 21572.2 21634.9 1013.2 1029.1 1057.9 1057.9 1065.7 8020.4 10313.5 10469.8 10602.4 10875.9 664350.0 684097.0 685688.0 704880.0 710324.0 111211.3 132993.0 137190.0 150446.5 152548.0 1191.2 1220.8 1249.1 1255.5 1258.4 19290.9 20337.0 20893.6 21275.1 22158.7 53954.2 60540.7 62095.0 62598.1 63426.7 65350.0 83361.6 87287.0 88008.0 88616.0 8786.0 8849.7 8930.6 9012.4 9031.5 13659.5 13734.0 13740.3 14020.6 14118.2 11594.3 11615.9 11708.3 11845.4 11987.5 8191.3 8516.0 9079.1 9160.2 9421.9 11864.9 11965.3 11993.4 12088.4 12119.9 7069.0 8710.3 8760.1 8877.0 8997.8 11664.7 12011.6 12123.0 12147.1 12211.8 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7186.14 Median:2550.23 stddev:4570.03 (179.2%) stddev-sans-first:4561.14 09:22:02 INFO - PID 1635 | Values: 1258.6 1333.6 1415.4 1428.7 1438.8 11377.8 11912.8 11929.3 11943.8 11987.3 11579.4 11588.5 12006.0 12028.2 12045.5 2510.6 2548.8 2559.0 2563.5 2566.6 2456.3 2456.6 2504.2 2505.8 2517.3 2423.6 2534.9 2535.7 2537.2 2541.9 2291.4 2320.6 2321.9 2372.0 2535.7 2175.3 2506.1 2536.2 2541.6 2551.6 11633.5 11923.1 11937.1 11955.9 11956.1 6517.6 6784.8 6790.6 6792.5 6799.2 4089.4 4346.4 4407.4 4416.1 4429.9 1225.4 1246.4 1299.6 1301.4 1303.2 15109.9 15597.3 16465.4 16630.6 16633.8 4749.9 5036.3 5509.2 5717.7 5740.1 12155.6 12168.8 12169.1 12175.2 12190.3 5504.5 6505.2 6538.7 6539.2 6541.8 5691.9 6200.3 6219.7 6222.6 6228.4 4284.5 4361.7 4373.1 4414.1 4441.5 1279.2 1285.3 1285.5 1292.7 1294.4 642.3 766.7 792.9 796.5 800.7 6768.5 6806.4 6857.9 6898.7 7012.2 878.8 892.8 895.6 915.7 935.7 4071.8 4160.3 4173.5 4264.6 4290.0 14850.3 15453.2 15520.0 15538.0 15571.7 12382.3 15152.9 15233.5 15312.2 15438.7 10774.9 10977.7 10982.8 10987.2 11019.3 6351.6 6424.4 6714.9 6746.9 6791.4 13291.7 13670.3 13680.9 13811.6 13816.7 13568.1 13698.5 14024.9 14132.8 14159.4 8441.8 8693.0 8831.7 8833.8 8845.7 10494.0 10631.3 10636.3 10640.6 10640.8 8891.9 9109.5 9185.8 9214.0 9217.0 8924.3 8977.8 8986.1 9090.9 9096.0 6819.8 7928.9 8123.9 8142.8 8145.6 5937.5 5972.7 6049.8 6137.3 6158.6 5814.6 5937.8 5982.0 6089.1 6284.9 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:4950.43 Median:5590.12 stddev:1535.54 (27.5%) stddev-sans-first:1501.16 09:22:02 INFO - PID 1635 | Values: 387.3 393.7 410.2 414.8 416.1 4967.4 5540.2 5739.2 5784.9 5858.5 5196.6 5565.9 5596.4 5599.0 5726.6 3656.4 4381.5 4426.7 4429.5 4464.8 4146.7 4301.3 4349.7 4442.9 4491.8 4056.3 4259.0 4263.0 4273.4 4279.0 3100.1 3611.7 3954.9 4164.9 4261.3 3623.0 3716.7 3722.2 3731.7 3737.1 5612.0 5614.4 5633.2 5645.3 5662.7 4369.5 4464.7 4666.8 4833.0 4985.4 5449.7 5640.8 6045.5 6054.0 6061.6 4191.2 4195.1 4197.9 4198.3 4212.2 6183.8 6440.4 6476.9 6536.2 6562.8 3780.3 4086.0 4288.4 4291.5 4294.8 4336.6 4347.5 4355.6 4373.3 4378.0 4746.7 4815.5 5047.5 5052.2 5080.7 5091.4 5585.0 5978.3 6210.2 6233.3 1258.7 1382.8 1418.7 1425.7 1431.8 876.8 912.6 1000.3 1001.4 1006.4 5316.9 5940.0 5945.2 5950.8 5951.3 5551.7 5844.5 6285.5 6390.0 6430.8 5235.7 5552.4 5909.9 5920.2 5921.3 3233.1 3334.0 3337.4 3358.9 3366.1 6549.5 6555.3 6557.2 6581.3 6608.4 5859.1 5880.6 6506.7 6517.2 6527.5 5175.3 5963.4 5986.5 6084.4 6419.5 6077.1 6083.3 6383.0 6435.2 6436.9 5588.9 5972.8 6309.8 6348.4 6348.7 5650.6 6457.6 6578.3 6607.2 6610.5 4150.5 4241.4 4265.2 4265.3 4344.0 4776.7 5851.8 5919.6 6054.7 6367.6 5088.4 5916.7 6017.2 6224.3 6242.7 5591.3 6107.3 6166.5 6167.6 6187.3 5268.3 5855.2 6006.3 6055.2 6064.6 5099.8 5887.0 6205.7 6214.5 6271.9 4207.3 5791.2 6090.8 6098.7 6115.7 09:22:02 INFO - PID 1635 | 09:22:02 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21026.85 Median:17180.53 stddev:29894.55 (174.0%) stddev-sans-first:29939.53 09:22:02 INFO - PID 1635 | Values: 720.7 856.6 868.8 875.3 883.3 13292.0 17941.4 18139.5 18574.2 18650.9 17387.1 17499.2 17882.6 17909.1 17942.8 1003.6 1032.7 1033.3 1035.8 1036.1 685.5 713.4 996.6 1031.6 1039.3 1084.9 1089.0 1089.2 1090.6 1091.3 1077.7 1081.2 1081.6 1082.0 1083.6 808.0 809.1 809.3 809.6 809.9 16387.1 16397.3 16548.9 17049.7 17311.4 16483.7 16530.8 16640.9 16845.5 16890.2 13645.2 13832.3 14075.4 14400.6 14604.9 3561.9 3572.6 3572.6 3576.6 3582.8 110528.0 110945.0 112757.0 112995.0 113331.0 11310.2 11671.9 13051.9 13376.6 13422.7 17593.3 20104.9 25118.7 25152.0 25160.4 8837.2 9043.6 9150.3 9175.4 9177.4 4763.3 4820.1 4832.3 4845.2 4852.4 4236.3 4400.6 4403.4 4405.6 4407.6 926.3 965.7 971.5 971.8 974.6 643.4 652.2 654.1 655.7 687.9 9180.4 10900.3 10930.9 11832.9 12047.2 625.5 649.8 651.2 664.3 673.2 5014.7 5300.5 5384.4 5414.4 5501.7 106011.0 106175.9 109738.0 110847.0 114234.0 94707.0 95322.9 97321.5 97450.0 99319.0 66368.4 75439.6 76068.6 76264.5 76444.0 10508.6 10682.5 10754.6 10786.6 10946.5 40125.2 40735.0 40854.8 40872.4 41598.6 54673.0 55184.7 55387.3 55668.4 56210.3 12459.1 12709.7 12827.7 12896.0 13027.9 12689.8 15290.3 15336.4 15539.4 15576.2 14068.6 14308.1 14435.9 14487.4 14597.9 14325.1 14368.3 14595.9 14624.6 14658.8 13616.7 13963.7 13971.0 14108.9 14124.0 10009.3 10241.0 10314.5 10445.4 10577.9 11156.3 11512.9 13534.3 13717.1 13833.9 09:22:02 INFO - PID 1635 | -------- Summary: end -------- 09:22:02 INFO - PID 1635 | 09:22:03 INFO - TEST-INFO | 1635: exit 0 09:22:03 INFO - TEST-OK | dromaeo_css | took 1120111ms 09:22:03 INFO - TEST-START | kraken 09:22:03 INFO - operating with platform_type : mac_ 09:22:03 INFO - Initialising browser for kraken test... 09:22:03 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpbd3r2A/profile http://localhost:49242/getInfo.html) 09:22:05 INFO - PID 1647 | __metrics Screen width/height:1600/1200 09:22:05 INFO - PID 1647 | colorDepth:24 09:22:05 INFO - PID 1647 | Browser inner width/height: 1024/689 09:22:05 INFO - PID 1647 | __metrics 09:22:06 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:22:06 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:06 INFO - TEST-INFO | 1647: exit 0 09:22:06 INFO - Browser initialized. 09:22:06 INFO - Running cycle 1/1 for kraken test... 09:22:06 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpbd3r2A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:19 INFO - PID 1648 | RSS: Main: 241152000 09:22:19 INFO - PID 1648 | 09:22:21 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:22 INFO - PID 1648 | RSS: Main: 291401728 09:22:22 INFO - PID 1648 | 09:22:25 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:22:26 INFO - PID 1648 | RSS: Main: 291127296 09:22:26 INFO - PID 1648 | 09:22:29 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:22:30 INFO - PID 1648 | RSS: Main: 386183168 09:22:30 INFO - PID 1648 | 09:22:32 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:22:32 INFO - PID 1648 | RSS: Main: 289189888 09:22:32 INFO - PID 1648 | 09:22:34 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:35 INFO - PID 1648 | RSS: Main: 291749888 09:22:35 INFO - PID 1648 | 09:22:38 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:39 INFO - PID 1648 | RSS: Main: 333103104 09:22:39 INFO - PID 1648 | 09:22:41 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:42 INFO - PID 1648 | RSS: Main: 341630976 09:22:42 INFO - PID 1648 | 09:22:45 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:22:46 INFO - PID 1648 | RSS: Main: 343511040 09:22:46 INFO - PID 1648 | 09:22:47 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:48 INFO - PID 1648 | RSS: Main: 310173696 09:22:48 INFO - PID 1648 | 09:22:50 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:50 INFO - PID 1648 | RSS: Main: 313180160 09:22:50 INFO - PID 1648 | 09:22:52 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:53 INFO - PID 1648 | RSS: Main: 316301312 09:22:53 INFO - PID 1648 | 09:22:56 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:57 INFO - PID 1648 | RSS: Main: 330166272 09:22:57 INFO - PID 1648 | 09:23:00 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:00 INFO - PID 1648 | RSS: Main: 323407872 09:23:00 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:23:02 INFO - PID 1648 | RSS: Main: 384868352 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | __start_tp_report 09:23:02 INFO - PID 1648 | _x_x_mozilla_page_load 09:23:02 INFO - PID 1648 | _x_x_mozilla_page_load_details 09:23:02 INFO - PID 1648 | |i|pagename|runs| 09:23:02 INFO - PID 1648 | |0;ai-astar;110;110;110;110;111;111;110;110;108;108 09:23:02 INFO - PID 1648 | |1;audio-beat-detection;137;145;141;182;110;112;93;113;164;144 09:23:02 INFO - PID 1648 | |2;audio-dft;182;183;182;183;186;182;181;182;184;185 09:23:02 INFO - PID 1648 | |3;audio-fft;80;59;61;93;62;85;83;87;98;56 09:23:02 INFO - PID 1648 | |4;audio-oscillator;89;68;90;70;70;67;86;68;67;83 09:23:02 INFO - PID 1648 | |5;imaging-gaussian-blur;86;85;86;85;86;85;86;86;86;86 09:23:02 INFO - PID 1648 | |6;imaging-darkroom;94;93;93;94;93;93;93;94;93;92 09:23:02 INFO - PID 1648 | |7;imaging-desaturate;83;83;82;85;83;83;84;83;86;85 09:23:02 INFO - PID 1648 | |8;json-parse-financial;51;50;51;50;51;51;51;49;51;49 09:23:02 INFO - PID 1648 | |9;json-stringify-tinderbox;54;61;64;64;61;57;57;58;60;59 09:23:02 INFO - PID 1648 | |10;stanford-crypto-aes;83;82;83;83;83;81;83;81;82;82 09:23:02 INFO - PID 1648 | |11;stanford-crypto-ccm;141;136;152;130;137;136;135;139;186;142 09:23:02 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;177;185;185;220;233;191;185;185;184;183 09:23:02 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;68;67;67;68;67;68;67;68;67;67 09:23:02 INFO - PID 1648 | __end_tp_report 09:23:02 INFO - PID 1648 | __start_cc_report 09:23:02 INFO - PID 1648 | _x_x_mozilla_cycle_collect,2158 09:23:02 INFO - PID 1648 | __end_cc_report 09:23:02 INFO - PID 1648 | __startTimestamp1492100582883__endTimestamp 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | ------- Summary: start ------- 09:23:02 INFO - PID 1648 | Number of tests: 14 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:1.03 (0.9%) stddev-sans-first:1.09 09:23:02 INFO - PID 1648 | Values: 110.0 110.0 110.0 110.0 111.0 111.0 110.0 110.0 108.0 108.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:144.50 stddev:27.21 (18.8%) stddev-sans-first:28.84 09:23:02 INFO - PID 1648 | Values: 137.0 145.0 141.0 182.0 110.0 112.0 93.0 113.0 164.0 144.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.56 (0.9%) stddev-sans-first:1.62 09:23:02 INFO - PID 1648 | Values: 182.0 183.0 182.0 183.0 186.0 182.0 181.0 182.0 184.0 185.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:76.40 Median:84.00 stddev:15.45 (18.4%) stddev-sans-first:16.33 09:23:02 INFO - PID 1648 | Values: 80.0 59.0 61.0 93.0 62.0 85.0 83.0 87.0 98.0 56.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.50 stddev:9.86 (12.9%) stddev-sans-first:9.23 09:23:02 INFO - PID 1648 | Values: 89.0 68.0 90.0 70.0 70.0 67.0 86.0 68.0 67.0 83.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 09:23:02 INFO - PID 1648 | Values: 86.0 85.0 86.0 85.0 86.0 85.0 86.0 86.0 86.0 86.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 09:23:02 INFO - PID 1648 | Values: 94.0 93.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 92.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:83.50 stddev:1.25 (1.5%) stddev-sans-first:1.30 09:23:02 INFO - PID 1648 | Values: 83.0 83.0 82.0 85.0 83.0 83.0 84.0 83.0 86.0 85.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:0.84 (1.7%) stddev-sans-first:0.87 09:23:02 INFO - PID 1648 | Values: 51.0 50.0 51.0 50.0 51.0 51.0 51.0 49.0 51.0 49.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.50 stddev:3.17 (5.2%) stddev-sans-first:2.67 09:23:02 INFO - PID 1648 | Values: 54.0 61.0 64.0 64.0 61.0 57.0 57.0 58.0 60.0 59.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 09:23:02 INFO - PID 1648 | Values: 83.0 82.0 83.0 83.0 83.0 81.0 83.0 81.0 82.0 82.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:140.00 stddev:16.04 (11.5%) stddev-sans-first:16.99 09:23:02 INFO - PID 1648 | Values: 141.0 136.0 152.0 130.0 137.0 136.0 135.0 139.0 186.0 142.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:185.00 stddev:18.34 (9.9%) stddev-sans-first:18.53 09:23:02 INFO - PID 1648 | Values: 177.0 185.0 185.0 220.0 233.0 191.0 185.0 185.0 184.0 183.0 09:23:02 INFO - PID 1648 | 09:23:02 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 09:23:02 INFO - PID 1648 | Values: 68.0 67.0 67.0 68.0 67.0 68.0 67.0 68.0 67.0 67.0 09:23:02 INFO - PID 1648 | -------- Summary: end -------- 09:23:02 INFO - PID 1648 | 09:23:03 INFO - TEST-INFO | 1648: exit 0 09:23:03 INFO - TEST-OK | kraken | took 60626ms 09:23:03 INFO - SUITE-END | took 1180s 09:23:04 INFO - Completed test suite (00:19:40) 09:23:04 INFO - javascript benchmark 09:23:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7706.023709089491, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3436.707879830214, 3484.494748261501, 3490.197254631433, 3525.5593050868642, 3543.503909452677, 5893.263342082239, 6102.145699602059, 6172.351903050177, 6180.227471566054, 6185.907211391827, 5965.671888046156, 6041.275046994367, 6041.637501749898, 6053.727582258798, 6074.726637301321, 5218.47815218477, 5228.686278823273, 5252.159654455288, 5254.632175747699, 5380.596455265852, 5324.973375133139, 5335.0, 5337.226102215196, 5356.812511562096, 5357.4106848246665, 5334.119870221409, 5339.866503337409, 5344.7862085516535, 5352.678839269637, 5358.544523715489, 5369.19462080689, 5377.569794416438, 5382.9730851345685, 5408.567314614861, 5450.29146210994, 5283.920741188886, 5366.060939335601, 5373.811916582901, 5382.757775900094, 5400.243965844785, 6156.6921653917125, 6178.814635560941, 6214.502839772853, 6222.408871157233, 6232.3144454110015, 6299.401556852092, 6342.4926005919415, 6355.142055822497, 6372.681365931685, 6382.946813775721, 4752.881177970544, 6540.803775886731, 6581.539292249497, 6603.273639899608, 6629.237637671681, 6208.0998255253635, 6209.565330426872, 6220.25356957159, 6222.408871157233, 6227.564070515065, 6504.252011018699, 6614.86770264592, 6623.304553021863, 6789.049533065375, 6793.0, 6279.560430769893, 6316.810495685137, 6316.9368306317265, 6333.96833015832, 6335.334789847092, 6439.066335381437, 6516.771912983023, 6570.868582628321, 6591.57154784947, 6612.437942774918, 6538.640374779433, 6545.509086818508, 6558.344165583403, 6559.934400656028, 6560.803175904731, 5398.865028374244, 6562.704678289489, 6574.441172500296, 6577.408033277028, 6580.341965803477, 4388.5830846069575, 4428.73427594339, 5204.0, 5241.109011468059, 5266.157414813688, 4709.293605959065, 4713.269443236235, 4713.717176969324, 4720.45714742809, 4735.810567577328, 6252.812415627447, 6257.499400047986, 6264.718087686065, 6265.154204182468, 6267.780627678009, 6609.239937407164, 6625.238097618739, 6631.0, 6631.0, 6647.268800431949, 5925.170476133345, 6148.354422785633, 6160.969195153995, 6162.26052873658, 6215.06773983897, 5789.392113828072, 5796.797112101141, 5801.883962320814, 5813.098969659625, 5841.4450627190345, 6723.26044135135, 6730.293319201609, 6778.559393639398, 6782.25395206517, 6792.966035169792, 6378.649174295459, 6452.967525195946, 6635.104260924906, 6637.103990961352, 6638.900416493853, 6564.048213009182, 6670.299618539983, 6728.7981360557305, 6748.628825414453, 6749.0, 6519.7392104315295, 6523.510736694861, 6542.476601871934, 6546.345365463416, 6548.639824809681, 6388.55280130405, 6513.055606936873, 6587.341265873374, 6662.800115996528, 6670.0, 6281.560290779602, 6305.716242769178, 6353.936460635335, 6387.265464471645, 6398.5201109916015, 6403.167588213502, 6434.613923164625, 6441.1626488556185, 6451.290358060516, 6452.483801295979, 6267.749290028348, 6270.090836828543, 6300.0, 6305.0, 6322.0, 6222.315545290105, 6284.811455656338, 6295.307516173309, 6354.332795056637, 6410.358964103739, 6246.625202487865, 6269.310375858743, 6281.623102613858, 6283.654399007915, 6289.842753931189], "value": 5982.050372255248, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.0298475413356, 825.0099880143825, 1122.3266040375775, 1149.0577726264466, 1149.5861489863648, 36170.819145904265, 36918.815405922964, 36991.26017479649, 37157.62842371575, 37254.813725931366, 38964.83105506832, 39242.0, 39341.803290983575, 39402.605973940255, 39443.0, 6171.660558669271, 8451.02813176485, 8600.741977740678, 8604.225619694227, 8620.827583448327, 6196.473299769525, 8255.0, 8502.914970850274, 8504.404691671585, 8515.616797244138, 8505.192006759347, 8559.700410485602, 8561.229489345958, 8571.314294856396, 8578.0, 8488.702895398663, 8548.529830859301, 8551.871721924175, 8553.01640311366, 8553.914460855374, 8438.957805210996, 8498.660053597849, 8500.532470714108, 8505.489670619783, 8507.489550626982, 37352.0, 37588.0, 37589.24821503582, 37611.811940940395, 37620.0, 3060.6633270340244, 3558.4128618777872, 3558.768680035789, 3559.4126969050076, 3563.875264365761, 30338.84830575833, 30367.544486832714, 30373.0, 30383.544246836314, 30427.23931901698, 3099.5660607514965, 3135.67075457076, 3162.0513845846385, 3165.414398336292, 3179.205198700325, 262662.0, 267337.0, 267885.0, 268286.31713683, 270842.0, 39086.0, 39800.80099599483, 48223.75888120537, 48333.275000875015, 48376.758116209196, 41688.58311416908, 48400.03199936052, 48844.7557762216, 49050.754746226754, 49155.0, 14024.929875350763, 14114.858851411354, 14125.646858828612, 14170.716585668435, 14208.005439619128, 11522.827157592643, 11540.24988375753, 11558.0, 11564.306141631529, 11574.768504629861, 9773.41359518431, 10437.0, 11145.996860282614, 11819.468123934443, 12010.519579216909, 5114.974425127851, 5134.717590532557, 5152.974235128875, 5153.1497302945345, 5161.096808058574, 1028.7993841200894, 1036.2694300518117, 1044.0916402729674, 1073.8442925775719, 1082.4695899009428, 17711.380101696377, 20266.89866550658, 22254.0, 22816.885915570318, 24149.0, 1127.7067962329854, 1139.9031082357926, 1168.4333098447246, 1170.5142365918218, 1172.9706757330964, 11741.941290293495, 12393.008559315233, 12421.627351179479, 12430.440630171664, 12449.377531123408, 360096.0, 360567.0, 360883.0, 361056.1947190247, 361139.0, 52885.47114528805, 53363.0, 53389.932201355754, 53393.733031334596, 53568.73215633897, 1502.699460107961, 1506.314626844805, 1550.3720992997853, 1553.3475940104956, 1557.3459147158221, 23577.882110589337, 23599.410014749083, 23765.881170594035, 23785.048598055884, 23797.286081417584, 36652.63347366492, 36751.26497469982, 36854.0, 36968.0, 37117.25765484729, 266444.0, 329799.0, 329941.0, 330134.0, 331183.0, 23828.404289892893, 29111.272218194714, 32367.676323236465, 33019.0, 33248.501272481415, 42513.93715157146, 43291.701248962585, 43379.349309759746, 43430.0, 43554.34668479912, 28041.859790700917, 29404.0, 29746.0, 30003.54994675038, 30196.0, 19871.60256794885, 23762.574245545322, 27082.0, 28005.73974171208, 30042.699573004866, 29736.0, 35694.0, 35853.565857365415, 35919.74280900156, 36039.45940810838, 28930.566041509814, 28952.855235724528, 30065.838687783824, 30675.693243068177, 30793.538096928114, 21886.0, 23364.0, 23729.0, 23749.168779092648, 23896.64155037641], "value": 17094.75988076294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.2685434760907, 433.4690004744191, 440.83909373078825, 441.32917964693655, 464.8256903661127, 19661.901690491544, 21353.0, 21966.90165491727, 22100.22649207278, 22144.0, 21776.455588610293, 22682.546349073007, 23313.71774552399, 23411.0, 23674.64488032681, 5976.790812321569, 6032.638041717489, 6268.968655156717, 6271.686415679221, 6279.0, 5946.405359464041, 6128.6322820630685, 6156.7537298508005, 6167.321594624587, 6172.22847144107, 5815.62198457101, 6057.54568407369, 6105.084237364409, 6110.725017374207, 6114.144019837227, 6049.364816694249, 6068.27180738312, 6100.816975490742, 6114.938850611504, 6121.479674227696, 5340.679559226459, 5350.464953504657, 5409.26974858393, 5409.864753381157, 5431.0, 26529.602055969175, 26576.0, 26642.93428262867, 26744.86627566869, 26903.865480672666, 2193.5941850757663, 2230.9442263943533, 2250.459889626478, 2264.841461097724, 2272.2047283450825, 13763.587092387144, 16690.58273543159, 16727.414540491023, 17275.913620431817, 17424.825751742446, 2094.350751267112, 2143.839212059102, 2173.7826217378292, 2176.771438998914, 2179.052112331141, 133713.33143334318, 133795.0, 134214.0, 134294.0, 136113.0, 9937.403755774676, 11671.299722016705, 12036.352002559423, 12054.939725301492, 12222.938885305517, 16102.75845862313, 16128.838711612969, 16132.19339033067, 16136.0, 16176.838231617769, 8409.747707568782, 8669.696560620348, 8713.869291960626, 8841.292696584258, 8894.110588941183, 6667.733290668319, 7988.281054705104, 8268.751937441886, 8325.458845175044, 8407.495550266882, 6484.967575162188, 7508.587027713419, 7531.246875312425, 7537.585432801248, 7538.924610753932, 2044.959100818005, 2058.475088852333, 2060.9793902060787, 2065.834733221339, 2078.137572907257, 848.3001523742937, 933.3093510801957, 952.842780941137, 996.725900377402, 1007.4541682795953, 18991.715124273145, 21092.78907210939, 21478.0, 21572.244971425924, 21634.91825408723, 1013.2299452416216, 1029.1082059413072, 1057.8571892794375, 1057.9471026448646, 1065.738893970982, 8020.398470114765, 10313.50454184139, 10469.790604187874, 10602.36385816838, 10875.912408759219, 664350.0, 684097.0, 685688.0, 704880.0, 710324.0, 111211.33183002261, 132993.0, 137190.0, 150446.49553504324, 152548.0, 1191.243560339191, 1220.780259553289, 1249.0819247852821, 1255.491525931999, 1258.3519487463964, 19290.903545482182, 20337.0, 20893.582128357044, 21275.148994040064, 22158.667619986034, 53954.19068714051, 60540.69729651324, 62095.0, 62598.06102908551, 63426.68286658537, 65350.0, 83361.58319208365, 87287.0, 88008.0, 88616.0, 8786.0, 8849.690260841096, 8930.642774289216, 9012.369134160808, 9031.548422578977, 13659.453621855015, 13734.0, 13740.312984350543, 14020.579382618538, 14118.223497707724, 11594.304341739522, 11615.88384116148, 11708.297502149899, 11845.407729613657, 11987.520499179935, 8191.262786349257, 8515.978082629876, 9079.092090790868, 9160.221381182408, 9421.905780942378, 11864.940675296568, 11965.282083074695, 11993.36146047868, 12088.39558022113, 12119.939400302943, 7069.0, 8710.259627931697, 8760.12398760119, 8877.0, 8997.82004359896, 11664.708382290512, 12011.63965081049, 12123.030157587196, 12147.149699520949, 12211.75576488483], "value": 10362.933434598543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.5595041735392, 1333.6199183232777, 1415.4126037694357, 1428.735683898478, 1438.7769895666174, 11377.829332560019, 11912.7617447651, 11929.343886086263, 11943.820842687366, 11987.280763154205, 11579.36313502757, 11588.536458541648, 12006.039516838653, 12028.218165819215, 12045.457954392052, 2510.623406489023, 2548.808839337048, 2559.0, 2563.4873025394904, 2566.6150077488433, 2456.312232574873, 2456.6192240202795, 2504.211173480357, 2505.7619526144986, 2517.3203235126507, 2423.612222044473, 2534.911278105267, 2535.695716514021, 2537.2134638262105, 2541.885615147323, 2291.3698732848434, 2320.5590937721777, 2321.860688358704, 2371.976280237193, 2535.657686212356, 2175.3147758456034, 2506.0852788732163, 2536.239128261514, 2541.606051062088, 2551.6427700122, 11633.534658613646, 11923.105767067505, 11937.104717146161, 11955.880441195564, 11956.103292253116, 6517.608943463408, 6784.7964561063245, 6790.558613690095, 6792.524523283372, 6799.150106236721, 4089.407035979766, 4346.413234213373, 4407.427034485529, 4416.116776644684, 4429.911401771947, 1225.394732899904, 1246.4266437438698, 1299.5841330774156, 1301.4078594239597, 1303.2050449225965, 15109.924450377677, 15597.298121584556, 16465.423710170086, 16630.58423539409, 16633.83366166347, 4749.881252968704, 5036.345275114211, 5509.201165831012, 5717.742701578439, 5740.052891272977, 12155.574554890536, 12168.756624867454, 12169.148159628741, 12175.208611440228, 12190.268583884996, 5504.532114770209, 6505.186851643544, 6538.673066346663, 6539.247986481616, 6541.803745887631, 5691.9146212806845, 6200.286966998853, 6219.689015549295, 6222.62664240147, 6228.408301211378, 4284.485861696554, 4361.672874534423, 4373.1472362888935, 4414.139242847678, 4441.511433742286, 1279.2068917271351, 1285.3444743181044, 1285.5115056278555, 1292.6832925933206, 1294.4239813282998, 642.2902692524787, 766.6971546239205, 792.9167437419103, 796.5499492786557, 800.6677228950037, 6768.526203165829, 6806.353396427331, 6857.931420685829, 6898.689558969859, 7012.228654847962, 878.7627340617997, 892.7554038454992, 895.6148855991986, 915.7023967210683, 935.6631612619451, 4071.75569465833, 4160.252693868582, 4173.457450531472, 4264.616184543406, 4289.978550107229, 14850.2574871256, 15453.150076745887, 15520.0, 15538.0, 15571.688566228611, 12382.318972456602, 15152.924235378972, 15233.542993710207, 15312.234388280542, 15438.691226175413, 10774.94612526948, 10977.670669880075, 10982.780344393068, 10987.230893837355, 11019.283746556608, 6351.618902865843, 6424.38968298011, 6714.932850671431, 6746.9325306746305, 6791.388775010273, 13291.734165316833, 13670.316484175553, 13680.863191367958, 13811.585652430444, 13816.654583635489, 13568.118072325267, 13698.452061917811, 14024.859751402355, 14132.78800818009, 14159.433622654979, 8441.831163376575, 8692.956535217283, 8831.735047948572, 8833.779155521164, 8845.69040083594, 10494.0, 10631.255812093388, 10636.30863993838, 10640.627578034731, 10640.787184256116, 8891.894176316631, 9109.544522773967, 9185.816283674156, 9214.0, 9217.0, 8924.286057115285, 8977.775555611162, 8986.14631609996, 9090.863637045317, 9095.954520227357, 6819.761308354184, 7928.881066784118, 8123.918760812553, 8142.796430089058, 8145.551994640352, 5937.495312898279, 5972.731227094704, 6049.78825741097, 6137.294211165773, 6158.630482171084, 5814.5639077068545, 5937.821865344046, 5982.0, 6089.117078023751, 6284.905726414199], "value": 5406.653325206653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.2912569996906, 393.6712844774613, 410.1694069509243, 414.8008955701265, 416.13028769871, 4967.354243948286, 5540.168974653804, 5739.196512488255, 5784.855378615537, 5858.502027327677, 5196.636235463519, 5565.888682226353, 5596.384397716248, 5598.972005139979, 5726.570507211965, 3656.433252845813, 4381.4961279452955, 4426.712263702865, 4429.5348988356245, 4464.843730469418, 4146.709730318879, 4301.268784306675, 4349.652027837781, 4442.933355999663, 4491.842785502508, 4056.3307054336, 4259.0, 4262.996391695898, 4273.4444522212225, 4278.9786051069705, 3100.069979006289, 3611.7291203159607, 3954.9209015819524, 4164.895877603054, 4261.3366645846345, 3622.981885090584, 3716.7212459065677, 3722.1997270586953, 3731.6828069613844, 3737.103095257146, 5612.045952188138, 5614.3543492498475, 5633.239512665778, 5645.294338207724, 5662.66024038559, 4369.453818272716, 4464.709793863389, 4666.803239780761, 4832.9758351208375, 4985.354832905133, 5449.727513624303, 5640.8154287599655, 6045.455908968214, 6054.031354983182, 6061.636301821861, 4191.224623444642, 4195.139996300782, 4197.895052623679, 4198.307279298912, 4212.241796476633, 6183.845403864894, 6440.388163124496, 6476.902846457307, 6536.182977127859, 6562.77030303937, 3780.2628487445236, 4085.9795701021303, 4288.4210631564965, 4291.485021797404, 4294.849680261238, 4336.588024137702, 4347.543507931685, 4355.60799528044, 4373.2565463871215, 4377.956220437819, 4746.715197088186, 4815.518448155157, 5047.470015648304, 5052.216906379517, 5080.669756465818, 5091.389033316026, 5584.972075139598, 5978.3124940631515, 6210.223722034746, 6233.283172435137, 1258.6595276030998, 1382.7718426459724, 1418.7446259673152, 1425.672095418039, 1431.8330560593292, 876.8202518483823, 912.5893347993297, 1000.3497726477848, 1001.3627718680011, 1006.411249419098, 5316.893662126813, 5940.0, 5945.227120474397, 5950.761969521257, 5951.285845698458, 5551.722413879371, 5844.503217226584, 6285.5229021179985, 6390.0, 6430.807075787734, 5235.712035838065, 5552.3892371839065, 5909.940900591025, 5920.2007728956905, 5921.259842519685, 3233.0763195898403, 3333.999800060021, 3337.3825842219267, 3358.865645374158, 3366.124807550055, 6549.508786841006, 6555.278919318871, 6557.180352455943, 6581.2760596334365, 6608.372204640552, 5859.062549992066, 5880.618644279556, 6506.707198176094, 6517.185351831021, 6527.510436717265, 5175.301334319894, 5963.403659634002, 5986.46121849027, 6084.39156084388, 6419.454346380517, 6077.11881777131, 6083.270007599116, 6383.0, 6435.195600549931, 6436.871262574628, 5588.944110558842, 5972.790952316648, 6309.791572949431, 6348.3651634838, 6348.746050157942, 5650.615599178228, 6457.6448295342325, 6578.309277525791, 6607.240167380813, 6610.4711623071, 4150.522689890701, 4241.448611680462, 4265.232258193555, 4265.2749032663905, 4343.978280108579, 4776.713397196179, 5851.829634073288, 5919.644821310735, 6054.7275372607455, 6367.554271200971, 5088.414832294334, 5916.73374698131, 6017.247844019497, 6224.253089629274, 6242.65665388408, 5591.30108736408, 6107.267127944675, 6166.537509686702, 6167.629942203482, 6187.298492914484, 5268.288781014668, 5855.180274761452, 6006.33930267679, 6055.184339282255, 6064.575479716554, 5099.812617114304, 5887.0, 6205.6586887721605, 6214.471769899609, 6271.874562508816, 4207.338101449971, 5791.16233045813, 6090.817275481742, 6098.725557349841, 6115.7247923844425], "value": 4479.323229338592, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.711715313874, 856.5931182688223, 868.7654333330001, 875.2866413872697, 883.2757139145903, 13292.0, 17941.372051978182, 18139.4558163255, 18574.16416261268, 18650.906745466287, 17387.13064346784, 17499.212535435905, 17882.642347153083, 17909.10454477274, 17942.820571794276, 1003.6400677082576, 1032.7469769906384, 1033.2767063055853, 1035.7514196592838, 1036.098594223027, 685.475611157465, 713.414999700246, 996.6180827821512, 1031.5667419683714, 1039.3296323871098, 1084.8643919510062, 1089.0416433538476, 1089.199438412766, 1090.5637744902024, 1091.3342860854873, 1077.6659235636898, 1081.2431298091328, 1081.5944020992129, 1081.978360432787, 1083.5882364701401, 807.9757607271791, 809.1463506001166, 809.340387584117, 809.6437567470294, 809.8987626546682, 16387.098709570968, 16397.262123204484, 16548.917255413646, 17049.659006819795, 17311.3941012064, 16483.6703265934, 16530.752038719427, 16640.916795415946, 16845.494635160965, 16890.239939202642, 13645.249511276876, 13832.30838458083, 14075.366608502642, 14400.639984000483, 14604.92697536516, 3561.94657080144, 3572.5712914450173, 3572.6070132285427, 3576.6065732769375, 3582.8387722552548, 110528.0, 110945.0, 112757.0, 112995.0, 113331.0, 11310.151738619472, 11671.88328116725, 13051.934740326238, 13376.598702038955, 13422.731545369037, 17593.29626814913, 20104.89947550253, 25118.74881251164, 25151.993920242985, 25160.37099072538, 8837.248833849066, 9043.59303831329, 9150.313726470542, 9175.357724959189, 9177.357584969122, 4763.285507173951, 4820.108279968217, 4832.323474713543, 4845.200541910615, 4852.44196917359, 4236.343366778155, 4400.559944005638, 4403.427554417905, 4405.647548196137, 4407.559244075567, 926.2913870888775, 965.7440778193784, 971.4948226921961, 971.7910649210454, 974.5614473486959, 643.4112786800025, 652.1912828093131, 654.0875478707177, 655.6819942327967, 687.8589889072728, 9180.357374983822, 10900.291481053706, 10930.890691093146, 11832.88167118335, 12047.156699030984, 625.5152257000769, 649.7823229218264, 651.205529254309, 664.3190729502298, 673.2123415603755, 5014.749262536859, 5300.496452836975, 5384.40771515133, 5414.377346605112, 5501.724913754297, 106011.0, 106175.93824061815, 109738.0, 110847.0, 114234.0, 94707.0, 95322.85078619131, 97321.51339243258, 97450.0, 99319.00680993243, 66368.35421520544, 75439.62280188674, 76068.61965690246, 76264.47471050659, 76444.0, 10508.579656813641, 10682.519286632276, 10754.601901752721, 10786.568537258736, 10946.507407166537, 40125.197496050496, 40735.0, 40854.79572602118, 40872.386914196904, 41598.58401416068, 54673.0, 55184.72407638097, 55387.33837984867, 55668.44331556633, 56210.31369058935, 12459.065570082099, 12709.682257943625, 12827.679308017374, 12896.0, 13027.934860325639, 12689.80965285503, 15290.311935963131, 15336.38654453854, 15539.378424862878, 15576.22118894028, 14068.648283792578, 14308.141511509342, 14435.92782036083, 14487.420503180176, 14597.92701036488, 14325.140491570539, 14368.281585920453, 14595.85404145945, 14624.561263162122, 14658.780118298448, 13616.727665446833, 13963.720725585228, 13971.0, 14108.858911410754, 14124.0, 10009.299349045497, 10241.027102425258, 10314.484275786332, 10445.425501597181, 10577.894221057692, 11156.330620162817, 11512.942435288105, 13534.323283835965, 13717.051111684419, 13833.862968132751], "value": 8158.5516827775045, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1437.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 110.0, 110.0, 108.0, 108.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 145.0, 141.0, 182.0, 110.0, 112.0, 93.0, 113.0, 164.0, 144.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 182.0, 183.0, 186.0, 182.0, 181.0, 182.0, 184.0, 185.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 61.0, 93.0, 62.0, 85.0, 83.0, 87.0, 98.0, 56.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 90.0, 70.0, 70.0, 67.0, 86.0, 68.0, 67.0, 83.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 86.0, 85.0, 86.0, 86.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 85.0, 83.0, 83.0, 84.0, 83.0, 86.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0, 49.0, 51.0, 49.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 64.0, 64.0, 61.0, 57.0, 57.0, 58.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 83.0, 81.0, 83.0, 81.0, 82.0, 82.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 152.0, 130.0, 137.0, 136.0, 135.0, 139.0, 186.0, 142.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 185.0, 185.0, 220.0, 233.0, 191.0, 185.0, 185.0, 184.0, 183.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 67.4, "unit": "score"}]}]} 09:23:04 INFO - Return code: 0 09:23:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:04 ERROR - # TBPL SUCCESS # 09:23:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:04 INFO - 2017-04-13 09:23:04.218 screenresolution[1652:9754] starting screenresolution argv=screenresolution get 09:23:04 INFO - 2017-04-13 09:23:04.220 screenresolution[1652:9754] Display 0: 1600x1200x32@60 09:23:04 INFO - 2017-04-13 09:23:04.226 screenresolution[1653:9758] starting screenresolution argv=screenresolution list 09:23:04 INFO - Available Modes on Display 0 09:23:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:04 INFO - Intel Iris: 09:23:04 INFO - Chipset Model: Intel Iris 09:23:04 INFO - Type: GPU 09:23:04 INFO - Bus: Built-In 09:23:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:04 INFO - Vendor: Intel (0x8086) 09:23:04 INFO - Device ID: 0x0a2e 09:23:04 INFO - Revision ID: 0x0009 09:23:04 INFO - Displays: 09:23:04 INFO - Display: 09:23:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:04 INFO - Display Serial Number: 1600x1200 60 09:23:04 INFO - Main Display: Yes 09:23:04 INFO - Mirror: Off 09:23:04 INFO - Online: Yes 09:23:04 INFO - Rotation: Supported 09:23:04 INFO - Return code: 0 09:23:04 INFO - Running post-action listener: _resource_record_post_action 09:23:04 INFO - [mozharness: 2017-04-13 16:23:04.478065Z] Finished run-tests step (success) 09:23:04 INFO - Running post-run listener: _resource_record_post_run 09:23:04 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 39019520; Write bytes: 515933696; Read time: 383; Write time: 4423 09:23:04 INFO - TinderboxPrint: CPU usage
53.2% 09:23:04 INFO - TinderboxPrint: I/O read bytes / time
39,019,520 / 383 09:23:04 INFO - TinderboxPrint: I/O write bytes / time
515,933,696 / 4,423 09:23:04 INFO - TinderboxPrint: CPU idle
2,248.6 (46.8%) 09:23:04 INFO - TinderboxPrint: CPU system
950.9 (19.8%) 09:23:04 INFO - TinderboxPrint: CPU user
1,606.2 (33.4%) 09:23:04 INFO - TinderboxPrint: Swap in / out
246,599,680 / 0 09:23:04 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209111552; Write bytes: 211138048; Read time: 15980; Write time: 657 09:23:04 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 34407424; Write bytes: 303296512; Read time: 345; Write time: 3760 09:23:04 INFO - Running post-run listener: _upload_blobber_files 09:23:04 INFO - Blob upload gear active. 09:23:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:04 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 09:23:04 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:04 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:06 INFO - (blobuploader) - INFO - Done attempting. 09:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:07 INFO - (blobuploader) - INFO - Done attempting. 09:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:08 INFO - (blobuploader) - INFO - Done attempting. 09:23:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:11 INFO - (blobuploader) - INFO - Done attempting. 09:23:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:11 INFO - Return code: 0 09:23:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ec9dfe8efb0e318ce36fc65f90d1b39fe73f6d3e49aadbe60186c6476ada0d3a4839632324696005f2390813aa926c673de5579153620fe0008270a2cca245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fafae4dc59567e837649ec7498d4d478208ecbd3a8a79f9cd03efe71aa5fa2a45cbfcc0bec692422eb0a0483908dcd36804f6085622bbb47c4fd4a5da6481d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11b1998e5e7d64f448763ba9d52e7038cb07e1e80f690d0fb5ca055480e598b35efff7d2347d5d852813c5df266ad4747db421636373bd9458fd63502ad69cce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fed789f08788816a03ea5d1553be2f6a199966128c4f0bb5e985d868c58998d2528b4c0636e9c24224d3d9c2cee2dc4f0c4abe6947279f802d1cea0d724eea7"} 09:23:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:11 INFO - Contents: 09:23:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ec9dfe8efb0e318ce36fc65f90d1b39fe73f6d3e49aadbe60186c6476ada0d3a4839632324696005f2390813aa926c673de5579153620fe0008270a2cca245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fafae4dc59567e837649ec7498d4d478208ecbd3a8a79f9cd03efe71aa5fa2a45cbfcc0bec692422eb0a0483908dcd36804f6085622bbb47c4fd4a5da6481d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11b1998e5e7d64f448763ba9d52e7038cb07e1e80f690d0fb5ca055480e598b35efff7d2347d5d852813c5df266ad4747db421636373bd9458fd63502ad69cce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fed789f08788816a03ea5d1553be2f6a199966128c4f0bb5e985d868c58998d2528b4c0636e9c24224d3d9c2cee2dc4f0c4abe6947279f802d1cea0d724eea7"} 09:23:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:11 INFO - Copying logs to upload dir... 09:23:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.883339 ========= master_lag: 2.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-13 09:23:14.202032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:14.209521) ========= 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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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/Firefox/sha512/39ec9dfe8efb0e318ce36fc65f90d1b39fe73f6d3e49aadbe60186c6476ada0d3a4839632324696005f2390813aa926c673de5579153620fe0008270a2cca245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fafae4dc59567e837649ec7498d4d478208ecbd3a8a79f9cd03efe71aa5fa2a45cbfcc0bec692422eb0a0483908dcd36804f6085622bbb47c4fd4a5da6481d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11b1998e5e7d64f448763ba9d52e7038cb07e1e80f690d0fb5ca055480e598b35efff7d2347d5d852813c5df266ad4747db421636373bd9458fd63502ad69cce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fed789f08788816a03ea5d1553be2f6a199966128c4f0bb5e985d868c58998d2528b4c0636e9c24224d3d9c2cee2dc4f0c4abe6947279f802d1cea0d724eea7"} build_url:https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/deywAJdGR-67Dgr8PDzZYw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39ec9dfe8efb0e318ce36fc65f90d1b39fe73f6d3e49aadbe60186c6476ada0d3a4839632324696005f2390813aa926c673de5579153620fe0008270a2cca245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2fafae4dc59567e837649ec7498d4d478208ecbd3a8a79f9cd03efe71aa5fa2a45cbfcc0bec692422eb0a0483908dcd36804f6085622bbb47c4fd4a5da6481d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11b1998e5e7d64f448763ba9d52e7038cb07e1e80f690d0fb5ca055480e598b35efff7d2347d5d852813c5df266ad4747db421636373bd9458fd63502ad69cce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fed789f08788816a03ea5d1553be2f6a199966128c4f0bb5e985d868c58998d2528b4c0636e9c24224d3d9c2cee2dc4f0c4abe6947279f802d1cea0d724eea7"}' ========= master_lag: 0.68 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:14.898884) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:14.899222) ========= 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.GLtNVRFZPf/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.MFDQ9Dmg0v/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.005131 ========= master_lag: 0.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 09:23:15.170699) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:23:15.171302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 09:23:15.172201) ========= ========= Total master_lag: 3.73 =========