builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0152 starttime: 1483372316.04 results: success (0) buildid: 20170102070419 builduid: ec9c0554ccde4d66ab8d77e55761c956 revision: 495c17aa9f9919ee8982af861101e6cf0a5a8883 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.035901) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.042016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.042348) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.147538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.147846) ========= 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.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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-01-02 07:51:56-- 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% 15.7M=0.001s 2017-01-02 07:51:56 (15.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180227 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.352448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.352825) ========= 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.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.028765 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:56.403121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-02 07:51:56.403497) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 495c17aa9f9919ee8982af861101e6cf0a5a8883 --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 495c17aa9f9919ee8982af861101e6cf0a5a8883 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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-01-02 07:51:56,499 truncating revision to first 12 chars 2017-01-02 07:51:56,499 Setting DEBUG logging. 2017-01-02 07:51:56,499 attempt 1/10 2017-01-02 07:51:56,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/495c17aa9f99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 07:51:58,457 unpacking tar archive at: mozilla-central-495c17aa9f99/testing/mozharness/ program finished with exit code 0 elapsedTime=2.236290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-02 07:51:58.663721) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:58.664053) ========= script_repo_revision: 495c17aa9f9919ee8982af861101e6cf0a5a8883 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:58.664459) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:58.664723) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 07:51:58.685613) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-02 07:51:58.686033) ========= /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.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:51:58 INFO - MultiFileLogger online at 20170102 07:51:58 in /builds/slave/test 07:51:58 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 07:51:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:51:58 INFO - {'append_to_log': False, 07:51:58 INFO - 'base_work_dir': '/builds/slave/test', 07:51:58 INFO - 'blob_upload_branch': 'Firefox', 07:51:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:51:58 INFO - 'branch': 'Firefox', 07:51:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:51:58 INFO - 'config_files': ('talos/mac_config.py',), 07:51:58 INFO - 'default_actions': ('clobber', 07:51:58 INFO - 'read-buildbot-config', 07:51:58 INFO - 'download-and-extract', 07:51:58 INFO - 'populate-webroot', 07:51:58 INFO - 'create-virtualenv', 07:51:58 INFO - 'install', 07:51:58 INFO - 'run-tests'), 07:51:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:51:58 INFO - 'download_minidump_stackwalk': True, 07:51:58 INFO - 'download_symbols': 'ondemand', 07:51:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:51:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:51:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:51:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:51:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:51:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:51:58 INFO - 'installer_path': 'installer.exe', 07:51:58 INFO - 'log_level': 'info', 07:51:58 INFO - 'log_name': 'talos', 07:51:58 INFO - 'log_to_console': True, 07:51:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:51:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:51:58 INFO - 'opt_config_files': (), 07:51:58 INFO - 'pip_index': False, 07:51:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:58 INFO - 'cmd': ('bash', 07:51:58 INFO - '-c', 07:51:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:58 INFO - 'enabled': True, 07:51:58 INFO - 'halt_on_failure': False, 07:51:58 INFO - 'name': 'check_screen_resolution'},), 07:51:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:58 INFO - 'cmd': ('bash', 07:51:58 INFO - '-c', 07:51:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:58 INFO - 'enabled': True, 07:51:58 INFO - 'halt_on_failure': False, 07:51:58 INFO - 'name': 'check_screen_resolution'},), 07:51:58 INFO - 'run_cmd_checks_enabled': True, 07:51:58 INFO - 'sps_profile': False, 07:51:58 INFO - 'sps_profile_interval': 0, 07:51:58 INFO - 'suite': 'dromaeojs', 07:51:58 INFO - 'system_bits': '32', 07:51:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:51:58 INFO - 'title': 't-yosemite-r7-0152', 07:51:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:51:58 INFO - 'use_talos_json': True, 07:51:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:51:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:51:58 INFO - 'work_dir': 'build'} 07:51:58 INFO - [mozharness: 2017-01-02 15:51:58.891036Z] Running clobber step. 07:51:58 INFO - Running pre-action listener: _resource_record_pre_action 07:51:58 INFO - Running main action method: clobber 07:51:58 INFO - rmtree: /builds/slave/test/build 07:51:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:52:01 INFO - Running post-action listener: _resource_record_post_action 07:52:01 INFO - [mozharness: 2017-01-02 15:52:01.004569Z] Finished clobber step (success) 07:52:01 INFO - [mozharness: 2017-01-02 15:52:01.004742Z] Running read-buildbot-config step. 07:52:01 INFO - Running pre-action listener: _resource_record_pre_action 07:52:01 INFO - Running main action method: read_buildbot_config 07:52:01 INFO - Using buildbot properties: 07:52:01 INFO - { 07:52:01 INFO - "project": "", 07:52:01 INFO - "product": "firefox", 07:52:01 INFO - "script_repo_revision": "production", 07:52:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:52:01 INFO - "repository": "", 07:52:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:52:01 INFO - "buildid": "20170102070419", 07:52:01 INFO - "pgo_build": "False", 07:52:01 INFO - "basedir": "/builds/slave/test", 07:52:01 INFO - "buildnumber": 19, 07:52:01 INFO - "slavename": "t-yosemite-r7-0152", 07:52:01 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:52:01 INFO - "platform": "yosemite_r7", 07:52:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:52:01 INFO - "branch": "mozilla-central", 07:52:01 INFO - "repo_path": "mozilla-central", 07:52:01 INFO - "revision": "495c17aa9f9919ee8982af861101e6cf0a5a8883", 07:52:01 INFO - "stage_platform": "macosx64", 07:52:01 INFO - "builduid": "ec9c0554ccde4d66ab8d77e55761c956", 07:52:01 INFO - "slavebuilddir": "test" 07:52:01 INFO - } 07:52:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:52:01 INFO - Running post-action listener: _resource_record_post_action 07:52:01 INFO - [mozharness: 2017-01-02 15:52:01.009296Z] Finished read-buildbot-config step (success) 07:52:01 INFO - [mozharness: 2017-01-02 15:52:01.009410Z] Running download-and-extract step. 07:52:01 INFO - Running pre-action listener: _resource_record_pre_action 07:52:01 INFO - Running main action method: download_and_extract 07:52:01 INFO - mkdir: /builds/slave/test/build/tests 07:52: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')]} 07:52:01 INFO - trying https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:52:01 INFO - Downloading https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:52:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:52:02 INFO - Downloaded 1199 bytes. 07:52:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:52:02 INFO - Using the following test package requirements: 07:52:02 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:52:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:52:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:52:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'jsshell-mac.zip'], 07:52:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:52:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:52:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:52:02 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:52:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:52:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:52:02 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:52:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:52:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:52:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:52:02 INFO - Fetch https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:52:03 INFO - Expected file size: 16300330 07:52:03 INFO - Obtained file size: 16300330 07:52:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:52:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:52:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:52:10 INFO - Fetch https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:52:10 INFO - Expected file size: 16300330 07:52:10 INFO - Obtained file size: 16300330 07:52:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:52:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:52:17 INFO - Fetch https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:52:18 INFO - Expected file size: 14409776 07:52:18 INFO - Obtained file size: 14409776 07:52:18 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')]} 07:52:18 INFO - trying https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:52:20 INFO - Downloaded 69756727 bytes. 07:52:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:52:20 INFO - mkdir: /builds/slave/test/properties 07:52:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:52:20 INFO - Writing to file /builds/slave/test/properties/build_url 07:52:20 INFO - Contents: 07:52:20 INFO - build_url:https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:52:21 INFO - Running post-action listener: _resource_record_post_action 07:52:21 INFO - Running post-action listener: set_extra_try_arguments 07:52:21 INFO - [mozharness: 2017-01-02 15:52:21.084053Z] Finished download-and-extract step (success) 07:52:21 INFO - [mozharness: 2017-01-02 15:52:21.084205Z] Running populate-webroot step. 07:52:21 INFO - Running pre-action listener: _resource_record_pre_action 07:52:21 INFO - Running main action method: populate_webroot 07:52:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:52:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:52:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:52:21 INFO - '--tppagecycles', 07:52:21 INFO - '7'], 07:52:21 INFO - 'tests': ['tsvgm']}}, 07:52:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:52:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:52:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:52:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:52:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['damp', 'tps']}, 07:52:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'tests': ['damp', 'tps']}, 07:52:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['dromaeo_dom']}, 07:52:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:52:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['basic_compositor_video']}, 07:52:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:52:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'other-e10s': {'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['a11yr', 07:52:21 INFO - 'ts_paint', 07:52:21 INFO - 'tpaint', 07:52:21 INFO - 'sessionrestore', 07:52:21 INFO - 'sessionrestore_no_auto_restore', 07:52:21 INFO - 'tabpaint']}, 07:52:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['tsvgx', 07:52:21 INFO - 'tsvgr_opacity', 07:52:21 INFO - 'tart', 07:52:21 INFO - 'tscrollx', 07:52:21 INFO - 'cart', 07:52:21 INFO - 'tsvg_static']}, 07:52:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:52:21 INFO - 'tsvgr_opacity', 07:52:21 INFO - 'tart', 07:52:21 INFO - 'tscrollx', 07:52:21 INFO - 'cart', 07:52:21 INFO - 'tsvg_static']}, 07:52:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'talos_options': ['--disable-e10s'], 07:52:21 INFO - 'tests': ['tp5o']}, 07:52:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:52:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'talos_options': ['--disable-e10s', 07:52:21 INFO - '--xperf_path', 07:52:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:21 INFO - 'tests': ['tp5n']}, 07:52:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:52:21 INFO - 'talos_options': ['--xperf_path', 07:52:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:21 INFO - 'tests': ['tp5n']}}, 07:52:21 INFO - 'talos.zip': {'path': '', 07:52:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:52:21 INFO - Running post-action listener: _resource_record_post_action 07:52:21 INFO - [mozharness: 2017-01-02 15:52:21.102256Z] Finished populate-webroot step (success) 07:52:21 INFO - [mozharness: 2017-01-02 15:52:21.102370Z] Running create-virtualenv step. 07:52:21 INFO - Running pre-action listener: _resource_record_pre_action 07:52:21 INFO - Running main action method: create_virtualenv 07:52:21 INFO - Creating virtualenv /builds/slave/test/build/venv 07:52:21 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 07:52:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:52:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:52:21 INFO - Using real prefix '/tools/python27' 07:52:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:52:21 INFO - Installing distribute.............................................................................................................................................................................................done. 07:52:24 INFO - Installing pip.................done. 07:52:24 INFO - Return code: 0 07:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:52:25 INFO - Reading from file tmpfile_stdout 07:52:25 INFO - Output received: 07:52:25 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) 07:52:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:25 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:25 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 07:52:25 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 07:52:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:25 INFO - 'HOME': '/Users/cltbld', 07:52:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:25 INFO - 'LOGNAME': 'cltbld', 07:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:25 INFO - 'MOZ_NO_REMOTE': '1', 07:52:25 INFO - 'NO_EM_RESTART': '1', 07:52:25 INFO - 'PAGER': '/bin/cat', 07:52:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:25 INFO - 'PWD': '/builds/slave/test', 07:52:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:25 INFO - 'SHELL': '/bin/bash', 07:52:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:25 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:25 INFO - 'USER': 'cltbld', 07:52:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:25 INFO - 'XPC_FLAGS': '0x0', 07:52:25 INFO - 'XPC_SERVICE_NAME': '0', 07:52:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:25 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 07:52:25 INFO - Cleaning up... 07:52:25 INFO - Return code: 0 07:52:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:25 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:25 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 07:52:25 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 07:52:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:25 INFO - 'HOME': '/Users/cltbld', 07:52:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:25 INFO - 'LOGNAME': 'cltbld', 07:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:25 INFO - 'MOZ_NO_REMOTE': '1', 07:52:25 INFO - 'NO_EM_RESTART': '1', 07:52:25 INFO - 'PAGER': '/bin/cat', 07:52:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:25 INFO - 'PWD': '/builds/slave/test', 07:52:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:25 INFO - 'SHELL': '/bin/bash', 07:52:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:25 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:25 INFO - 'USER': 'cltbld', 07:52:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:25 INFO - 'XPC_FLAGS': '0x0', 07:52:25 INFO - 'XPC_SERVICE_NAME': '0', 07:52:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:25 INFO - Downloading/unpacking psutil>=3.1.1 07:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:52:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:29 INFO - Installing collected packages: psutil 07:52:29 INFO - Running setup.py install for psutil 07:52:29 INFO - building 'psutil._psutil_osx' extension 07:52:29 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 07:52:30 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 07:52:30 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 07:52:30 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 07:52:30 INFO - building 'psutil._psutil_posix' extension 07:52:30 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 07:52:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:52:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:52:30 INFO - ^ 07:52:30 INFO - 1 warning generated. 07:52:30 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 07:52:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:30 INFO - Successfully installed psutil 07:52:30 INFO - Cleaning up... 07:52:30 INFO - Return code: 0 07:52:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:30 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:30 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 07:52:30 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 07:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:30 INFO - 'HOME': '/Users/cltbld', 07:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:30 INFO - 'LOGNAME': 'cltbld', 07:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:30 INFO - 'MOZ_NO_REMOTE': '1', 07:52:30 INFO - 'NO_EM_RESTART': '1', 07:52:30 INFO - 'PAGER': '/bin/cat', 07:52:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:30 INFO - 'PWD': '/builds/slave/test', 07:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:30 INFO - 'SHELL': '/bin/bash', 07:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:30 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:30 INFO - 'USER': 'cltbld', 07:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:30 INFO - 'XPC_FLAGS': '0x0', 07:52:30 INFO - 'XPC_SERVICE_NAME': '0', 07:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:52:33 INFO - Installing collected packages: mozsystemmonitor 07:52:33 INFO - Running setup.py install for mozsystemmonitor 07:52:33 INFO - Successfully installed mozsystemmonitor 07:52:33 INFO - Cleaning up... 07:52:33 INFO - Return code: 0 07:52:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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', '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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:52: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 jsonschema==2.5.1 07:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:33 INFO - 'HOME': '/Users/cltbld', 07:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:33 INFO - 'LOGNAME': 'cltbld', 07:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:33 INFO - 'MOZ_NO_REMOTE': '1', 07:52:33 INFO - 'NO_EM_RESTART': '1', 07:52:33 INFO - 'PAGER': '/bin/cat', 07:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:33 INFO - 'PWD': '/builds/slave/test', 07:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:33 INFO - 'SHELL': '/bin/bash', 07:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:33 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:33 INFO - 'USER': 'cltbld', 07:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:33 INFO - 'XPC_FLAGS': '0x0', 07:52:33 INFO - 'XPC_SERVICE_NAME': '0', 07:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:34 INFO - Downloading/unpacking jsonschema==2.5.1 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:52:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:52:36 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 07:52:36 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 07:52:36 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 07:52:36 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 07:52:36 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 07:52:36 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 07:52:37 INFO - Downloading functools32-3.2.3-2.tar.gz 07:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:52:37 INFO - warning: no files found matching '*.txt' 07:52:37 INFO - no previously-included directories found matching 'build' 07:52:37 INFO - no previously-included directories found matching 'dist' 07:52:37 INFO - no previously-included directories found matching '.git*' 07:52:37 INFO - Installing collected packages: jsonschema, functools32 07:52:37 INFO - Running setup.py install for functools32 07:52:37 INFO - warning: no files found matching '*.txt' 07:52:37 INFO - no previously-included directories found matching 'build' 07:52:37 INFO - no previously-included directories found matching 'dist' 07:52:37 INFO - no previously-included directories found matching '.git*' 07:52:37 INFO - Successfully installed jsonschema functools32 07:52:37 INFO - Cleaning up... 07:52:37 INFO - Return code: 0 07:52:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:52:37 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')]} 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 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')]} 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:37 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 07:52:37 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 07:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:37 INFO - 'HOME': '/Users/cltbld', 07:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:37 INFO - 'LOGNAME': 'cltbld', 07:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:37 INFO - 'MOZ_NO_REMOTE': '1', 07:52:37 INFO - 'NO_EM_RESTART': '1', 07:52:37 INFO - 'PAGER': '/bin/cat', 07:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:37 INFO - 'PWD': '/builds/slave/test', 07:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:37 INFO - 'SHELL': '/bin/bash', 07:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:37 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:37 INFO - 'USER': 'cltbld', 07:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:37 INFO - 'XPC_FLAGS': '0x0', 07:52:37 INFO - 'XPC_SERVICE_NAME': '0', 07:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:52:37 INFO - Cleaning up... 07:52:37 INFO - Return code: 0 07:52:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:52:37 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')]} 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 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')]} 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:37 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 07:52:37 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 07:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:37 INFO - 'HOME': '/Users/cltbld', 07:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:37 INFO - 'LOGNAME': 'cltbld', 07:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:37 INFO - 'MOZ_NO_REMOTE': '1', 07:52:37 INFO - 'NO_EM_RESTART': '1', 07:52:37 INFO - 'PAGER': '/bin/cat', 07:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:37 INFO - 'PWD': '/builds/slave/test', 07:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:37 INFO - 'SHELL': '/bin/bash', 07:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:37 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:37 INFO - 'USER': 'cltbld', 07:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:37 INFO - 'XPC_FLAGS': '0x0', 07:52:37 INFO - 'XPC_SERVICE_NAME': '0', 07:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:37 INFO - Downloading/unpacking blobuploader==1.2.4 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:40 INFO - Downloading blobuploader-1.2.4.tar.gz 07:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:52:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:52:40 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 07:52:40 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 07:52:40 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 07:52:40 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 07:52:40 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 07:52:40 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 07:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:52:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:52:41 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 07:52:41 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 07:52:41 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 07:52:41 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 07:52:41 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 07:52:41 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 07:52:41 INFO - Downloading docopt-0.6.1.tar.gz 07:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:52:41 INFO - Installing collected packages: blobuploader, requests, docopt 07:52:41 INFO - Running setup.py install for blobuploader 07:52:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:52:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:52:41 INFO - Running setup.py install for requests 07:52:42 INFO - Running setup.py install for docopt 07:52:42 INFO - Successfully installed blobuploader requests docopt 07:52:42 INFO - Cleaning up... 07:52:42 INFO - Return code: 0 07:52:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:42 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')]} 07:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:42 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')]} 07:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:42 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:42 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 07:52:42 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 07:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:42 INFO - 'HOME': '/Users/cltbld', 07:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:42 INFO - 'LOGNAME': 'cltbld', 07:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:42 INFO - 'MOZ_NO_REMOTE': '1', 07:52:42 INFO - 'NO_EM_RESTART': '1', 07:52:42 INFO - 'PAGER': '/bin/cat', 07:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:42 INFO - 'PWD': '/builds/slave/test', 07:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:42 INFO - 'SHELL': '/bin/bash', 07:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:42 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:42 INFO - 'USER': 'cltbld', 07:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:42 INFO - 'XPC_FLAGS': '0x0', 07:52:42 INFO - 'XPC_SERVICE_NAME': '0', 07:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-hdXN5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-x43MCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-48DXY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-0yEf2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-xny_3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-2zF225-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-NNxart-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yVkpnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-x0FY8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:43 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-BXA9K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-m8x7i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-93cpz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-WDco5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-hjVGHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-bwfel2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-0sT8oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:44 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-RJ1FaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:52:44 INFO - Running setup.py install for manifestparser 07:52:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:52:44 INFO - Running setup.py install for mozcrash 07:52:45 INFO - Running setup.py install for mozdebug 07:52:45 INFO - Running setup.py install for mozdevice 07:52:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozfile 07:52:45 INFO - Running setup.py install for mozhttpd 07:52:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozinfo 07:52:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozInstall 07:52:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:52:45 INFO - Running setup.py install for mozleak 07:52:46 INFO - Running setup.py install for mozlog 07:52:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Running setup.py install for moznetwork 07:52:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Running setup.py install for mozprocess 07:52:46 INFO - Running setup.py install for mozprofile 07:52:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Running setup.py install for mozrunner 07:52:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:52:46 INFO - Running setup.py install for mozscreenshot 07:52:47 INFO - Running setup.py install for moztest 07:52:47 INFO - Running setup.py install for mozversion 07:52:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:52:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:52:47 INFO - Cleaning up... 07:52:47 INFO - Return code: 0 07:52:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:47 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:47 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 07:52:47 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 07:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:47 INFO - 'HOME': '/Users/cltbld', 07:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:47 INFO - 'LOGNAME': 'cltbld', 07:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:47 INFO - 'MOZ_NO_REMOTE': '1', 07:52:47 INFO - 'NO_EM_RESTART': '1', 07:52:47 INFO - 'PAGER': '/bin/cat', 07:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:47 INFO - 'PWD': '/builds/slave/test', 07:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:47 INFO - 'SHELL': '/bin/bash', 07:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:47 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:47 INFO - 'USER': 'cltbld', 07:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:47 INFO - 'XPC_FLAGS': '0x0', 07:52:47 INFO - 'XPC_SERVICE_NAME': '0', 07:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:47 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-lm07IQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:47 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-djcoHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:47 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-pKuEPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:47 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)) 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:47 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-0KazlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-UeGrW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-AmiVK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-O6pdeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-N8VYZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IEmuFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-NbEDYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-g391xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Swm3vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Uryc1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:48 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)) 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:48 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yAkagU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:49 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)) 07:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:49 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-LtlstU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:49 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)) 07:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:49 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-33Ji02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:49 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)) 07:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:49 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-1d9yk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:49 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)) 07:52:49 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)) 07:52:49 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)) 07:52:49 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)) 07:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:51 INFO - Downloading blessings-1.6.tar.gz 07:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:52:52 INFO - Installing collected packages: blessings 07:52:52 INFO - Running setup.py install for blessings 07:52:52 INFO - Successfully installed blessings 07:52:52 INFO - Cleaning up... 07:52:52 INFO - Return code: 0 07:52:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:52:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:52 INFO - Reading from file tmpfile_stdout 07:52:52 INFO - Current package versions: 07:52:52 INFO - blessings == 1.6 07:52:52 INFO - blobuploader == 1.2.4 07:52:52 INFO - docopt == 0.6.1 07:52:52 INFO - functools32 == 3.2.3-2 07:52:52 INFO - jsonschema == 2.5.1 07:52:52 INFO - manifestparser == 1.1 07:52:52 INFO - mozInstall == 1.12 07:52:52 INFO - mozcrash == 1.0 07:52:52 INFO - mozdebug == 0.1 07:52:52 INFO - mozdevice == 0.49 07:52:52 INFO - mozfile == 1.2 07:52:52 INFO - mozhttpd == 0.7 07:52:52 INFO - mozinfo == 0.9 07:52:52 INFO - mozleak == 0.1 07:52:52 INFO - mozlog == 3.4 07:52:52 INFO - moznetwork == 0.27 07:52:52 INFO - mozprocess == 0.23 07:52:52 INFO - mozprofile == 0.28 07:52:52 INFO - mozrunner == 6.13 07:52:52 INFO - mozscreenshot == 0.1 07:52:52 INFO - mozsystemmonitor == 0.3 07:52:52 INFO - moztest == 0.8 07:52:52 INFO - mozversion == 1.4 07:52:52 INFO - psutil == 3.1.1 07:52:52 INFO - requests == 1.2.3 07:52:52 INFO - wsgiref == 0.1.2 07:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:52 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:52 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 07:52:52 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 07:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:52 INFO - 'HOME': '/Users/cltbld', 07:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:52 INFO - 'LOGNAME': 'cltbld', 07:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:52 INFO - 'MOZ_NO_REMOTE': '1', 07:52:52 INFO - 'NO_EM_RESTART': '1', 07:52:52 INFO - 'PAGER': '/bin/cat', 07:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:52 INFO - 'PWD': '/builds/slave/test', 07:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:52 INFO - 'SHELL': '/bin/bash', 07:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:52 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:52 INFO - 'USER': 'cltbld', 07:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:52 INFO - 'XPC_FLAGS': '0x0', 07:52:52 INFO - 'XPC_SERVICE_NAME': '0', 07:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 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)) 07:52:52 INFO - Cleaning up... 07:52:52 INFO - Return code: 0 07:52:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:52 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 0x10d448b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce48cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, '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.g4HSihGS60/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.6PpAadyRki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 07:52:52 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 07:52:52 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 07:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:52 INFO - 'HOME': '/Users/cltbld', 07:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:52 INFO - 'LOGNAME': 'cltbld', 07:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:52 INFO - 'MOZ_NO_REMOTE': '1', 07:52:52 INFO - 'NO_EM_RESTART': '1', 07:52:52 INFO - 'PAGER': '/bin/cat', 07:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:52 INFO - 'PWD': '/builds/slave/test', 07:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:52 INFO - 'SHELL': '/bin/bash', 07:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:52:52 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:52 INFO - 'USER': 'cltbld', 07:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:52 INFO - 'XPC_FLAGS': '0x0', 07:52:52 INFO - 'XPC_SERVICE_NAME': '0', 07:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:52:53 INFO - Cleaning up... 07:52:53 INFO - Return code: 0 07:52:53 INFO - Running post-action listener: _resource_record_post_action 07:52:53 INFO - Running post-action listener: _start_resource_monitoring 07:52:53 INFO - Starting resource monitoring. 07:52:53 INFO - [mozharness: 2017-01-02 15:52:53.157776Z] Finished create-virtualenv step (success) 07:52:53 INFO - [mozharness: 2017-01-02 15:52:53.158552Z] Running install step. 07:52:53 INFO - Running pre-action listener: _resource_record_pre_action 07:52:53 INFO - Running main action method: install 07:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:53 INFO - Reading from file tmpfile_stdout 07:52:53 INFO - Detecting whether we're running mozinstall >=1.0... 07:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:52:53 INFO - Reading from file tmpfile_stdout 07:52:53 INFO - Output received: 07:52:53 INFO - Usage: mozinstall [options] installer 07:52:53 INFO - Options: 07:52:53 INFO - -h, --help show this help message and exit 07:52:53 INFO - -d DEST, --destination=DEST 07:52:53 INFO - Directory to install application into. [default: 07:52:53 INFO - "/builds/slave/test"] 07:52:53 INFO - --app=APP Application being installed. [default: firefox] 07:52:53 INFO - mkdir: /builds/slave/test/build/application 07:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:53:15 INFO - Reading from file tmpfile_stdout 07:53:15 INFO - Output received: 07:53:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:53:15 INFO - Running post-action listener: _resource_record_post_action 07:53:15 INFO - [mozharness: 2017-01-02 15:53:15.330803Z] Finished install step (success) 07:53:15 INFO - [mozharness: 2017-01-02 15:53:15.330932Z] Running run-tests step. 07:53:15 INFO - Running pre-action listener: _resource_record_pre_action 07:53:15 INFO - Running main action method: run_tests 07:53:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:15 INFO - 2017-01-02 07:53:15.345 screenresolution[1628:8563] starting screenresolution argv=screenresolution get 07:53:15 INFO - 2017-01-02 07:53:15.347 screenresolution[1628:8563] Display 0: 1600x1200x32@60 07:53:15 INFO - 2017-01-02 07:53:15.353 screenresolution[1629:8565] starting screenresolution argv=screenresolution list 07:53:15 INFO - Available Modes on Display 0 07:53:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:15 INFO - Intel Iris: 07:53:15 INFO - Chipset Model: Intel Iris 07:53:15 INFO - Type: GPU 07:53:15 INFO - Bus: Built-In 07:53:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:15 INFO - Vendor: Intel (0x8086) 07:53:15 INFO - Device ID: 0x0a2e 07:53:15 INFO - Revision ID: 0x0009 07:53:15 INFO - Displays: 07:53:15 INFO - Display: 07:53:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:15 INFO - Display Serial Number: 1600x1200 60 07:53:15 INFO - Main Display: Yes 07:53:15 INFO - Mirror: Off 07:53:15 INFO - Online: Yes 07:53:15 INFO - Rotation: Supported 07:53:15 INFO - Return code: 0 07:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:53:15 INFO - Python 2.7.3 07:53:15 INFO - Return code: 0 07:53:15 INFO - grabbing minidump binary from tooltool 07:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:15 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 0x10d226d30>, '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 0x10d14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d21bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:53:15 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 07:53:15 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 07:53:15 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 07:53:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:53:15 INFO - Return code: 0 07:53:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:53:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:53:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:53:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:53:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:53:15 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/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 07:53:15 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/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0152 --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 07:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g4HSihGS60/Render', 07:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:53:15 INFO - 'HOME': '/Users/cltbld', 07:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:53:15 INFO - 'LOGNAME': 'cltbld', 07:53:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:53:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:15 INFO - 'MOZ_NO_REMOTE': '1', 07:53:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:53:15 INFO - 'NO_EM_RESTART': '1', 07:53:15 INFO - 'PAGER': '/bin/cat', 07:53:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:15 INFO - 'PWD': '/builds/slave/test', 07:53:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:53:15 INFO - 'SHELL': '/bin/bash', 07:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6PpAadyRki/Listeners', 07:53:15 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 07:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:53:15 INFO - 'USER': 'cltbld', 07:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:15 INFO - 'XPC_FLAGS': '0x0', 07:53:15 INFO - 'XPC_SERVICE_NAME': '0', 07:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:53:15 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/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 07:53:15 INFO - mozversion application_buildid: 20170102070419 07:53:15 INFO - mozversion application_changeset: 495c17aa9f9919ee8982af861101e6cf0a5a8883 07:53:15 INFO - mozversion application_display_name: Nightly 07:53:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:53:15 INFO - mozversion application_name: Firefox 07:53:15 INFO - mozversion application_remotingname: firefox 07:53:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:53:15 INFO - mozversion application_vendor: Mozilla 07:53:15 INFO - mozversion application_version: 53.0a1 07:53:15 INFO - mozversion platform_buildid: 20170102070419 07:53:15 INFO - mozversion platform_changeset: 495c17aa9f9919ee8982af861101e6cf0a5a8883 07:53:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:53:15 INFO - mozversion platform_version: 53.0a1 07:53:15 INFO - using testdate: 1483372395 07:53:15 INFO - actual date: 1483372395 07:53:15 INFO - starting webserver on 'localhost:49241' 07:53:15 INFO - SUITE-START | Running 2 tests 07:53:15 INFO - TEST-START | dromaeo_css 07:53:15 INFO - operating with platform_type : mac_ 07:53:15 INFO - Initialising browser for dromaeo_css test... 07:53:15 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpQLBtVI/profile http://localhost:49241/getInfo.html) 07:53:16 INFO - PROCESS | 1639 | 1483372396472 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:53:18 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 07:53:18 INFO - PROCESS | 1639 | colorDepth:24 07:53:18 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 07:53:18 INFO - PROCESS | 1639 | __metrics 07:53:18 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:53:18 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:19 INFO - TEST-INFO | 1639: exit 0 07:53:19 INFO - Browser initialized. 07:53:19 INFO - Running cycle 1/1 for dromaeo_css test... 07:53:19 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpQLBtVI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:31 INFO - PROCESS | 1642 | RSS: Main: 232656896 07:53:31 INFO - PROCESS | 1642 | 07:54:23 INFO - PROCESS | 1642 | 1483372463114 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:56:35 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:56:36 INFO - PROCESS | 1642 | RSS: Main: 379637760 07:56:36 INFO - PROCESS | 1642 | 07:58:19 INFO - PROCESS | 1642 | ************************* 07:58:19 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 07:58:19 INFO - PROCESS | 1642 | 07:58:19 INFO - PROCESS | 1642 | Full message: ReferenceError: fetch is not defined 07:58:19 INFO - PROCESS | 1642 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:58:19 INFO - PROCESS | 1642 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:58:19 INFO - PROCESS | 1642 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:58:19 INFO - PROCESS | 1642 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:58:19 INFO - PROCESS | 1642 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:58:19 INFO - PROCESS | 1642 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:58:19 INFO - PROCESS | 1642 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:58:19 INFO - PROCESS | 1642 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:58:19 INFO - PROCESS | 1642 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:58:19 INFO - PROCESS | 1642 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:58:19 INFO - PROCESS | 1642 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:58:19 INFO - PROCESS | 1642 | 07:58:19 INFO - PROCESS | 1642 | ************************* 07:59:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:59:40 INFO - PROCESS | 1642 | RSS: Main: 371212288 07:59:40 INFO - PROCESS | 1642 | 08:02:43 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:02:43 INFO - PROCESS | 1642 | RSS: Main: 346415104 08:02:43 INFO - PROCESS | 1642 | 08:05:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:05:46 INFO - PROCESS | 1642 | RSS: Main: 338583552 08:05:46 INFO - PROCESS | 1642 | 08:08:50 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:08:51 INFO - PROCESS | 1642 | RSS: Main: 333443072 08:08:51 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:11:54 INFO - PROCESS | 1642 | RSS: Main: 535642112 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | __start_tp_report 08:11:54 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 08:11:54 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 08:11:54 INFO - PROCESS | 1642 | |i|pagename|runs| 08:11:54 INFO - PROCESS | 1642 | |0;dojo.html;3303.3558456101055;3310.8344582770856;3323.484859846723;3332.216929021678;3370.797752134872;5512.476314750103;5597.3283206015285;5597.916031259534;5603.775848966042;5616.578756593257;5596.188552659872;5663.490285874271;5697.515711164556;5700.942990570093;5719.59962802604;5031.597472202215;5094.032133894567;5111.948880511203;5124.564412024983;5137.948620513785;4522.917343115134;5180.481951804808;5206.531412172903;5210.947890521104;5288.524032837044;5174.301469301634;5209.505097015777;5215.217717342391;5246.370435547739;5250.789968401259;5062.341895553592;5073.289739436482;5075.365579302587;5098.388193416814;5122.513361230677;5137.51193636604;5148.510891465305;5153.252778347156;5158.819441319573;5182.300389447451;5625.493705566519;5649.322081350223;5650.463205995424;5654.717264136777;5704.401037891044;6013.729382177812;6024.8493787655225;6044.818655440344;6138.478229350466;6215.409536094064;6040.818775436744;6145.784897528565;6155.045967874987;6157.63054216744;6168.259808822925;5638.661680299195;5675.772969081273;5682.460166284196;5693.4875861172695;5716.657000579895;6206.317305096436;6257;6284;6296.622202667854;6300.464460520815;5865.35481097079;6047.0627052806885;6052.394760523913;6058.394160583906;6062.9090563641585;6082;6091.0558863376245;6130.356312587114;6135.967692822268;6138.309242925233;6125.185026439465;6130.2146971548445;6136.846578835475;6138.204480310071;6143.92481315768;6118.426946161437;6150.200473938359;6162.599431036968;6185.690715464209;6186.10301506279;4815.927761083586;4826.806927722922;4831.661783675109;4866.513348665176;4905.656604037755;4355.020120472932;4368.126374725068;4371.475422949203;4390.670699697535;4429.335599660012;5687.658740475585;5691.9715401423555;5705.850965686027;5728.885422291531;5772.942270577324;6035.094735789667;6104.216637119002;6200.844978875474;6210.472109870621;6236.563440559208;5669.092945128759;5674.233978412862;5696.743646535916;5731.799387021434;5748.367679555246;5336.733163341895;5377.085895397713;5380.488853558905;5385.380681221632;5396.109641909041;6276.905846412307;6297.496200304056;6299.244090709144;6317.431431171217;6318.968405157945;6079.240094988127;6089.969550152221;6116.061877111426;6192.411720886689;6210.968945155245;6280.340564240689;6296.5277604178955;6307.96846015767;6308.495320374451;6314.400131987454;6078.513718902389;6095.634261944298;6099.786507472217;6102.792231004566;6103.206583144341;6226.283977342664;6227.532935029982;6230.345813689497;6236.875262494816;6244.937550624436;5993.940060599338;6036.33600303975;6044.032954727222;6046.30467496226;6057.7879774207695;5879.853003674943;5894.056950887837;5911.083782013795;5988.580799344179;5996.040633498619;5884.146798714248;5888.17565540816;5893.174955506318;5905.202797622266;5951.7619295227705;6050.2437195350585;6066.787662431793;6073.240844894388;6073.969630151821;6074.179985701874;5709.942900570941;5731.914021289767;5733.569982251264;5840.707964601668;5847.912281315698 08:11:54 INFO - PROCESS | 1642 | |1;ext.html;455.5808656036445;463.3166080031952;468.80310269686726;500.8197049062335;522.6210997027156;30470;39551;41423.58576414235;41518.58481415185;41525.792371038144;42809.57190428095;42903.141937161236;42988.785056074754;43087.13825723484;43264.91837704059;6132.386761323854;9266.53667316633;9457.10157535033;9465.526723663823;9468.81062378752;8603.95698021509;9135;11453.136715821045;11462.656120316362;11636.767264654702;11316.094712423028;11349.659510214708;11369.260998035143;11388.658340249805;11396.316221026724;11374.203805733601;11425;11433.542658293658;11461.36962467064;11464.254823436491;11209.271397359145;11280.379579123144;11283.887161128365;11287.040601548879;11301.378424186667;39117.02207444808;39476.01309967245;39683.80948571547;39775.80112099421;39792.00519986994;3950.940735888964;3992;4009.7594144351433;4025.19496100781;4073;29666.406671866658;30698.53952190719;30946;30970.690293097006;31140;2974.154832753423;3095.071478556424;3160.225744692565;3160.620725512953;3163.9525407118913;479453;480376.1962380401;481506;481895;482621;61594.69202653958;62071.06893396512;62146.689266554284;62182;62200.37799622036;63838;64128.679356603854;64203;64224;64425.67787161034;15512.91409601317;15719.921400392925;15748.92125539365;15754.606134846721;15762.527124186292;10830.891691083147;11103.500342484607;11124.888751112385;11688.88311116878;11755.941220294015;15990.120543369994;16108;16110.113943733208;17644.853084549464;18162.81837181638;6293.496520278367;6354.586951848115;6401.103845461639;6406.483429182415;6425.485961123099;755.5731011978214;784.2667106255669;826.5411548616696;869.7215093812086;878.3544095090103;28799;29333;29448.55827162594;29749.553756693665;30154.793808247865;1026.414943482208;1062.888396718349;1065.738893970982;1074.9140068794477;1120.9775804483866;14807.555773326818;15395.538133856002;15490.225488725519;15640.4525841595;15881.967672101277;235710;777756;842522;857601;860640;44113.558864410945;52370.73814630903;52661.63022663989;53711.73144134333;56497.717511413;1189.8988585970117;1351.4999450203488;1377.091119860907;1382.5575815739269;1389.9714211483429;28180.577291340236;28496.43007139804;28658.426831462835;29118.27204319909;29191.416171676025;46210.537894620626;46821.2976805355;46829.53170468388;46901.53098469108;46918;557514;559483.8103237994;560671;561689;562425;20251.898740506203;24142.637860431758;24306;24654.506909862062;24703.258902232963;41448.46630642081;42134.78932605317;42811;42935;43075.56924430715;25633.743662563134;33832.1541961453;34842.82578587091;36491;36504.26991460103;31622.525662114625;34190.82904585461;34214;35772;35817.64182358143;34371;34501.96494105181;34568.790092346644;34895.82552087223;35113.12217194488;24970.875145624155;30015.949441769433;31941.36117277595;32117.6788232124;32176.517352239265;18430.89414635126;23121.3063608092;23793.881030594737;23996.920138594156;24271.271861844172 08:11:54 INFO - PROCESS | 1642 | |2;jquery.html;352.18996308490466;368.1165203511572;373.10082700691333;377.6223776223776;399.6283456385562;14708.191049492274;15064.849351506482;15442.382304707813;19859.900700496477;20356.89821550894;16854.747178792328;19620.313289034886;20342.796572034276;21144.048517816696;21191.152353905865;5626.606137570382;5797.246357973458;5886.323072846634;5894.909441753268;5896.233489646362;4052.230076285511;6202.5968312059795;6447.7743278985245;6494.31809659986;6506.609603423786;5667.866426714653;6276.654783986879;6297.149884765567;6349.746010159588;6454.838629034265;6434.324395938429;6487.83780405489;6494.870102597969;6495.12315837363;6496.090547323379;5396.19057141428;5571.387147413781;5638.859028524279;5652.463016013472;5656.717164141776;24519.38701532458;25078.24765257045;25369.61945570818;25384.238472845846;25657.35856603581;2473.987630061856;2645.391559941205;2674.572068469055;2695.501332253539;2727.9590806137926;13984;15217.6195595111;15695.136767477785;15861.36554537817;15927;1346.9224620303817;1554.4947891935053;1681.7645529625863;1712.86297096232;1800.6938820400562;104224;107106;107765.92234077715;108001;109765;9162.221211197122;9610.95194524037;9630.566624501915;9739.951300243596;10937.4531273436;12072.75854482923;12154.574589889311;12172.391380430943;12179.391030448442;12196.268223906594;6394.744210231632;7347.485676002735;7398.3341499265325;7466.664000120008;7557.206493318233;6300.842478938063;7564.546127232384;7618.961905190438;7624.12322582899;7705.190954949761;3774.622262874031;3784.243151369682;3793.943090853639;3847.3267178244255;3861.150546879738;1847.87064905458;1888.5278680329918;1906.9035304699576;1907.980920190808;1913.3494611832043;912.8037471943564;966.246327864267;991.940483570988;996.2776986684596;1001.3514254796169;19616.411507654793;20572;20623.69064464034;20658.17367305321;20833.479163021046;933.453929451265;1013.4527355228095;1029.2075102171286;1032.2258306270298;1033.715728174758;10522.000409961041;10803.243772935819;10894.509747061402;10906.291091079054;10961.561537538568;519457;547463;550489;563045.2934147367;563691;88737.11262887288;113856;127518;127904.36047819887;129129.70870291365;948.3788118782209;963.8265112279717;995.5868314649484;995.8796354101636;996.3324572536418;18146.637067258845;18429.53926151857;18464.26142954267;18491.260349585868;18508;53376.466235337146;53843.73078134584;54073.72963135159;54744.45255547553;55612.72193639006;67490;73842.63078684572;74228.25771742429;74815.62592187004;75416;6595.538312318092;8053.476524025919;8117.959410203148;8131.455023545507;8198.631061602124;10633.627823026467;12715.80926286125;12718.872811271769;13005.284709341076;13049.28228947417;11112.666620001413;11176.664700059011;11356.205065645325;11415.37215453158;11512.712182195177;10996.780064398827;11069.889301106887;11175.888241117485;11211.566054503932;11377.317360958368;11246.943765281121;11320.829415549328;11455.770884582094;11518.424078795859;11547.538098475967;6945.305469453015;7790.8575141836445;7951.602419879098;8186.672533098609;8351.457155284887;9528.190103840974;11365.772684546097;11560.47977841016;11643.12676549279;11694.006009489289 08:11:54 INFO - PROCESS | 1642 | |3;mootools.html;1384.7438223928575;1384.7784354503276;1403.4035534897666;1404.54352335491;1406.5372013106364;7338.376238019761;7785.844283114335;7883.526988380694;8185.9181408185905;8195.508269503827;7374.2994415530575;7575.242475752422;7592.8861067084035;7880.645370958307;8006.319462845665;2096.8008039236247;2130.7017017617545;2222.822174226062;2265.5355652091366;2266.29744779119;2255.436140964759;2290.931272061841;2299.9195028174017;2301.838871279011;2304.4699719064624;1944.0862794486638;2005.4886004068942;2212.6791615215784;2216.6120928837468;2251.808596269311;2230.832687548432;2247.9662805057856;2248.2355998960434;2250.7861753133425;2255.548890221962;2236;2244.51742875281;2264.230161745015;2265.535565209128;2267.7618850020676;8026.919730802676;8095.716649917284;8113.513189208607;8143.511389316601;8168.918310816875;4946.87632809179;5106.897862042775;5112.233165025239;5116.513931176532;5121.718305493197;3199.936001279985;3381.4927760835826;3427.657234276577;3435.038189306998;3454.516367708523;945.8820349976355;1057.5981127171704;1176.4823477669797;1223.4494477485168;1224.4734764051454;10441.738956526147;10477.68566942993;10491;10501.36991780488;10501.9474902625;4385.51759306476;4511.571400716926;4559;4568.109218702321;4635.791389387486;9146.176844084064;9158.267338612895;9172.449653020707;9180.540972951325;9203.4938078405;4358.5205627380965;5087.135187018216;5087.211482220262;5091.567216786615;5095.923561146586;3904.902377440587;4118.5263694674895;4647.256438969748;4652.464966528893;4694.718316900997;3115.797473164237;3120.469520181575;3790.6209379061875;3825.3114439401174;3836.846526138979;1252.261165912098;1256.9308688022247;1262.3751243134523;1272.4782839035952;1272.5673271087874;802.4022103532789;805.2672068417712;890.1943740914483;903.1690844423147;903.5256490342623;5061.6962982221185;5454.699991500426;5666.546676265889;5668.433156684298;5999.880002400015;765.663108232376;852.9317654587618;875.9912400876037;877.7191298784455;888.8223104386714;3760.435934609776;3767.6232376762655;3781.1303400217553;3859.150986782903;3894.766314021168;9479.431234125974;9683.66107186245;9777.364471309342;9791.804163916824;9823.852642210373;9740.95129524362;9744.805103897883;9754.268429867789;9761.609535618638;9781;7343.63281835906;7534.284242996907;7687.0006899102755;7691.846163076707;7716.5370077795515;5515.448455154453;5615.691136987505;5675.971620141873;5696.401877802937;5719.342275638355;8662.740117796477;8715.34634902372;8932.46405215663;9033.367664263438;9045.638174472948;8869.246114080117;8982.550872456482;8996.100389960951;9114.680986165453;9216.400933939272;6592.24189218246;6736.157980252468;6746.595204287759;6834.658267086526;7052.9647351762915;7546.6604002821105;7747.806304842424;7758.146603873456;7759.961200193963;7772.92227077722;6874.759383421556;6987.580745155307;6988.37104660583;7046.612436315847;7125.964370178116;5746.281714785652;6852.143482064742;6872.69072891711;6873.450123990169;6886.8622627548175;6051.727672254671;6120.540959427972;6237;6246.750129994749;6268;3969.5831937647285;4759.500252473579;4805.08703346377;4826.227803551415;4831.541003604652;4089.89775255621;4184.727992680466;4731.929021064618;4823.445303789968;4915.5330243626795 08:11:54 INFO - PROCESS | 1642 | |4;prototype.html;323.36136131141;336.8147518864624;340.2820049695143;352.545216670495;362.7787049899562;4829.613630909527;4892.657513974019;4915.500772264458;5218.895622087561;5246.5278124968745;4375.474943006836;4661.627069834413;4756.0250148719515;5060.721660308682;5158.9622099270855;3587.6053634100226;3999.7600143991453;4014.1570270243137;4017;4017.6384125428704;4003.4595329630565;4014.8996275093064;4052.7770972596495;4078.449409329732;4092.1815636872598;4045.898852528681;4050.9392359114636;4082.2447847148223;4085.775282359469;4104.035551645361;3158.641075316072;3737.8786364090665;3763.8118094095184;4043.433919251307;4094.242565125461;3070.9385812283854;3345.2138747394342;3537.3728085080893;3538.327717733622;3548.361294966905;4466.620337271336;4796.640251981115;4985.775640096204;5024.201887197717;5058.924116138261;4664.486906440323;4666.440027196724;4679.227927391977;4709.811607535695;4718.740469274188;4815.975920120412;4860.513948605147;5267.051930652481;5398.811041613525;5489.505944465017;3795.4686343911876;3940.6847452203756;3955;3984.8406063757707;4001.8399264029404;5445.809396671098;5602.795398989156;5628.831135065954;5759;5781.624194427349;3254.902352929416;3349.9665003349655;4001.5398229203433;4054.2904991626347;4122.319817230184;3192.7650644548908;3938.8621398250925;4111.8972025698995;4149.709520333608;4206.915861682749;4216.756056963203;4496.3255511673515;4725.1967165581245;4777.522247775264;4781.306710527024;5296.470352964673;5548.4174161713245;5557.77768889248;5585.888282234332;5660.886782264414;1093.704699731068;1134.5348407153192;1238.7027113492697;1363.677233083907;1397.566754306148;847.7456762971208;971.4365667912593;974.6198982396871;987.4124895687055;988.5847943863595;4402.559744025636;4416;4504;4982.651214415028;5020.2720605511995;4687.531246875285;5223.597145313199;5364;5419.376771671229;5451.236826844245;4172.956760809798;4931.841017360917;4971.477994810565;4974.651774375759;5008.899822003539;2486.9875650621993;2779.88880444784;2817.7604903583424;2818.689944106147;2828.8302701837956;4847.878803029953;5466.753996070186;5559.555235581144;5581.274434323512;5595.104783234744;4449.421575195204;4739.620830333634;5478.8358598353425;5546.306711661043;5581.134924086692;5543.861403464945;5571.415001424792;5605.467480589337;5641.915371269434;5648.51987581052;5199.752059505767;5280.498352656491;5290.470952904679;5378.381486128988;5380.704061276668;4761.476237613929;5021.995600879912;5147.485251474823;5200.375954885438;5385.8114965976;4867.953390021163;5496.477834605706;5690.174924635988;5714.914276285942;5770.114942528736;4023.979880100581;4062.898427539335;4071.6742660586488;4073.185362927367;4088.407180958804;4690.835820746258;5653.604247702621;5665.773369065191;5717;5828.883422331614;4516.515916246117;5478.178273259043;5540.944590554042;5548.306461692288;5578.163275508706;4944.678595891256;5503.569072041379;5511.448855114457;5530.640508366943;5609.298837645294;5153.119111524342;5252.763625636932;5455.781768729206;5466.945330546644;5543.334799823886;4853.781579828846;5113.821016264413;5488.862778430571;5493.725313734218;5496.7526461308535;4531.977340113278;5248.8425347239645;5372.462753724596;5415.887803756459;5465.508104270635 08:11:54 INFO - PROCESS | 1642 | |5;yui.html;726.3100054947801;728.621052264357;731.9304666056724;744.702119152339;781.7499094737574;15157.772633410494;15690.921545392257;15730.685386292267;15731.449399271029;15795;14098.929505352484;14270.857291427083;14291;14307.642308942282;14336.498222562186;665.6904539389183;687.4946914018193;823.1315961660446;853.2491407561369;861.1689719420758;822.6298165825373;847.6016272351985;851.4295422067199;862.5816479007676;864.8918885139358;909.7498187998287;920.089111779337;922.0548937339214;922.2990527199344;923.5520772425368;695.6139342664819;724.5580196080386;913.6299798581585;914.4878867834001;914.7850255190025;650.151552224347;671.6943790575325;678.3216783216783;678.7488629207224;680.8195828105556;14643.19462429566;14652.340644671016;14663.120212787268;14663.34014969329;14681.265936703228;13166.144200626928;13229.933850330783;13247.66880827987;13255.73488530224;13311.134776239513;10712.946435267851;11989.580364687194;12060;12207;12269.815952760715;4169;4216.7680777557225;4245.12974840157;4266.765327906964;4348.91302173958;89075.55462222647;91442;91518.5424072882;94732.99999999932;97310;10486.423246721504;10497.737556561147;10506.949305069586;10626.203034772423;10682.946585267024;20396.694049589267;20521.281755138498;20564.485887852625;20564.58870822575;20584.897075514527;6183.938160618426;7282.344588987016;7323.707051717872;7503.624818759149;7582.469227154046;4104.220198162399;4182.644475219579;4189.622933935961;4194.4337514435165;4219.936700949488;3436.879709210216;3486.738494612914;3525.4359302511475;3547.8758243461357;3555.182308069107;633.4394061255822;640.9294977552465;655.7213184396612;673.6463356737654;694.8992396102536;605.3129697793037;658.9341065893468;677.3226773226774;680.2890233774747;689.7723751162105;8790.824183516293;9136.040715724886;9259.490728010023;9347.251743111912;9349.579268932914;632.6520413772407;647.8607099473636;667.732906837269;668.3383450384084;670.6367566653238;4297.914041719148;4417.2269852775635;4423.535528769497;4431.756253406029;4447.575763589792;101574.49212754036;103055;103255;105914.47042764736;108209.37685934718;72016;74122;75504;76615.61692191503;90256.548717256;81997;82378.76431853526;83975.580122099;85063.14936850552;85063.57468212744;9760.267979901393;9876.308658393844;10018.849717254101;10132.290739648153;10302.793944121226;35517.82241088778;46594.53405466038;51491.970160597324;51705.965880682925;53739.46260537345;69027.96458053033;69666.25834354182;71317;71534.64232678803;72048.63975680088;13648.181109133484;13732;13735.587932362045;13759.587212383645;14092.295385230902;12909.806352904901;14600.780988285396;14841.257937102886;14935.925320373763;15471.369867122392;12009.459574319002;13366.967206973124;13736;14058.367373468014;14474.927625361806;11636.825447618123;12143.463139459267;13507.122037067558;13890;14146;12218;13385.063045587105;13782.793258101337;13835.377408016462;14105.083169593943;10867.13062954946;11212;11309.547618095514;11369.147313951322;11385.519445712995;9429.764255893384;9524.095210955225;9693.854592181264;10725.517351719036;11063.280886742335 08:11:54 INFO - PROCESS | 1642 | __end_tp_report 08:11:54 INFO - PROCESS | 1642 | __start_cc_report 08:11:54 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1576 08:11:54 INFO - PROCESS | 1642 | __end_cc_report 08:11:54 INFO - PROCESS | 1642 | __startTimestamp1483373514405__endTimestamp 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | ------- Summary: start ------- 08:11:54 INFO - PROCESS | 1642 | Number of tests: 6 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5680.63 Median:5884.15 stddev:634.75 (10.8%) stddev-sans-first:608.69 08:11:54 INFO - PROCESS | 1642 | Values: 3303.4 3310.8 3323.5 3332.2 3370.8 5512.5 5597.3 5597.9 5603.8 5616.6 5596.2 5663.5 5697.5 5700.9 5719.6 5031.6 5094.0 5111.9 5124.6 5137.9 4522.9 5180.5 5206.5 5210.9 5288.5 5174.3 5209.5 5215.2 5246.4 5250.8 5062.3 5073.3 5075.4 5098.4 5122.5 5137.5 5148.5 5153.3 5158.8 5182.3 5625.5 5649.3 5650.5 5654.7 5704.4 6013.7 6024.8 6044.8 6138.5 6215.4 6040.8 6145.8 6155.0 6157.6 6168.3 5638.7 5675.8 5682.5 5693.5 5716.7 6206.3 6257.0 6284.0 6296.6 6300.5 5865.4 6047.1 6052.4 6058.4 6062.9 6082.0 6091.1 6130.4 6136.0 6138.3 6125.2 6130.2 6136.8 6138.2 6143.9 6118.4 6150.2 6162.6 6185.7 6186.1 4815.9 4826.8 4831.7 4866.5 4905.7 4355.0 4368.1 4371.5 4390.7 4429.3 5687.7 5692.0 5705.9 5728.9 5772.9 6035.1 6104.2 6200.8 6210.5 6236.6 5669.1 5674.2 5696.7 5731.8 5748.4 5336.7 5377.1 5380.5 5385.4 5396.1 6276.9 6297.5 6299.2 6317.4 6319.0 6079.2 6090.0 6116.1 6192.4 6211.0 6280.3 6296.5 6308.0 6308.5 6314.4 6078.5 6095.6 6099.8 6102.8 6103.2 6226.3 6227.5 6230.3 6236.9 6244.9 5993.9 6036.3 6044.0 6046.3 6057.8 5879.9 5894.1 5911.1 5988.6 5996.0 5884.1 5888.2 5893.2 5905.2 5951.8 6050.2 6066.8 6073.2 6074.0 6074.2 5709.9 5731.9 5733.6 5840.7 5847.9 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:70634.63 Median:34436.48 stddev:164140.49 (476.6%) stddev-sans-first:164516.37 08:11:54 INFO - PROCESS | 1642 | Values: 455.6 463.3 468.8 500.8 522.6 30470.0 39551.0 41423.6 41518.6 41525.8 42809.6 42903.1 42988.8 43087.1 43264.9 6132.4 9266.5 9457.1 9465.5 9468.8 8604.0 9135.0 11453.1 11462.7 11636.8 11316.1 11349.7 11369.3 11388.7 11396.3 11374.2 11425.0 11433.5 11461.4 11464.3 11209.3 11280.4 11283.9 11287.0 11301.4 39117.0 39476.0 39683.8 39775.8 39792.0 3950.9 3992.0 4009.8 4025.2 4073.0 29666.4 30698.5 30946.0 30970.7 31140.0 2974.2 3095.1 3160.2 3160.6 3164.0 479453.0 480376.2 481506.0 481895.0 482621.0 61594.7 62071.1 62146.7 62182.0 62200.4 63838.0 64128.7 64203.0 64224.0 64425.7 15512.9 15719.9 15748.9 15754.6 15762.5 10830.9 11103.5 11124.9 11688.9 11755.9 15990.1 16108.0 16110.1 17644.9 18162.8 6293.5 6354.6 6401.1 6406.5 6425.5 755.6 784.3 826.5 869.7 878.4 28799.0 29333.0 29448.6 29749.6 30154.8 1026.4 1062.9 1065.7 1074.9 1121.0 14807.6 15395.5 15490.2 15640.5 15882.0 235710.0 777756.0 842522.0 857601.0 860640.0 44113.6 52370.7 52661.6 53711.7 56497.7 1189.9 1351.5 1377.1 1382.6 1390.0 28180.6 28496.4 28658.4 29118.3 29191.4 46210.5 46821.3 46829.5 46901.5 46918.0 557514.0 559483.8 560671.0 561689.0 562425.0 20251.9 24142.6 24306.0 24654.5 24703.3 41448.5 42134.8 42811.0 42935.0 43075.6 25633.7 33832.2 34842.8 36491.0 36504.3 31622.5 34190.8 34214.0 35772.0 35817.6 34371.0 34502.0 34568.8 34895.8 35113.1 24970.9 30015.9 31941.4 32117.7 32176.5 18430.9 23121.3 23793.9 23996.9 24271.3 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:32814.27 Median:2659.98 stddev:91598.09 (3443.6%) stddev-sans-first:91822.62 08:11:54 INFO - PROCESS | 1642 | Values: 352.2 368.1 373.1 377.6 399.6 14708.2 15064.8 15442.4 19859.9 20356.9 16854.7 19620.3 20342.8 21144.0 21191.2 5626.6 5797.2 5886.3 5894.9 5896.2 4052.2 6202.6 6447.8 6494.3 6506.6 5667.9 6276.7 6297.1 6349.7 6454.8 6434.3 6487.8 6494.9 6495.1 6496.1 5396.2 5571.4 5638.9 5652.5 5656.7 24519.4 25078.2 25369.6 25384.2 25657.4 2474.0 2645.4 2674.6 2695.5 2728.0 13984.0 15217.6 15695.1 15861.4 15927.0 1346.9 1554.5 1681.8 1712.9 1800.7 104224.0 107106.0 107765.9 108001.0 109765.0 9162.2 9611.0 9630.6 9740.0 10937.5 12072.8 12154.6 12172.4 12179.4 12196.3 6394.7 7347.5 7398.3 7466.7 7557.2 6300.8 7564.5 7619.0 7624.1 7705.2 3774.6 3784.2 3793.9 3847.3 3861.2 1847.9 1888.5 1906.9 1908.0 1913.3 912.8 966.2 991.9 996.3 1001.4 19616.4 20572.0 20623.7 20658.2 20833.5 933.5 1013.5 1029.2 1032.2 1033.7 10522.0 10803.2 10894.5 10906.3 10961.6 519457.0 547463.0 550489.0 563045.3 563691.0 88737.1 113856.0 127518.0 127904.4 129129.7 948.4 963.8 995.6 995.9 996.3 18146.6 18429.5 18464.3 18491.3 18508.0 53376.5 53843.7 54073.7 54744.5 55612.7 67490.0 73842.6 74228.3 74815.6 75416.0 6595.5 8053.5 8118.0 8131.5 8198.6 10633.6 12715.8 12718.9 13005.3 13049.3 11112.7 11176.7 11356.2 11415.4 11512.7 10996.8 11069.9 11175.9 11211.6 11377.3 11246.9 11320.8 11455.8 11518.4 11547.5 6945.3 7790.9 7951.6 8186.7 8351.5 9528.2 11365.8 11560.5 11643.1 11694.0 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5259.92 Median:5485.07 stddev:2873.91 (52.4%) stddev-sans-first:2867.21 08:11:54 INFO - PROCESS | 1642 | Values: 1384.7 1384.8 1403.4 1404.5 1406.5 7338.4 7785.8 7883.5 8185.9 8195.5 7374.3 7575.2 7592.9 7880.6 8006.3 2096.8 2130.7 2222.8 2265.5 2266.3 2255.4 2290.9 2299.9 2301.8 2304.5 1944.1 2005.5 2212.7 2216.6 2251.8 2230.8 2248.0 2248.2 2250.8 2255.5 2236.0 2244.5 2264.2 2265.5 2267.8 8026.9 8095.7 8113.5 8143.5 8168.9 4946.9 5106.9 5112.2 5116.5 5121.7 3199.9 3381.5 3427.7 3435.0 3454.5 945.9 1057.6 1176.5 1223.4 1224.5 10441.7 10477.7 10491.0 10501.4 10501.9 4385.5 4511.6 4559.0 4568.1 4635.8 9146.2 9158.3 9172.4 9180.5 9203.5 4358.5 5087.1 5087.2 5091.6 5095.9 3904.9 4118.5 4647.3 4652.5 4694.7 3115.8 3120.5 3790.6 3825.3 3836.8 1252.3 1256.9 1262.4 1272.5 1272.6 802.4 805.3 890.2 903.2 903.5 5061.7 5454.7 5666.5 5668.4 5999.9 765.7 852.9 876.0 877.7 888.8 3760.4 3767.6 3781.1 3859.2 3894.8 9479.4 9683.7 9777.4 9791.8 9823.9 9741.0 9744.8 9754.3 9761.6 9781.0 7343.6 7534.3 7687.0 7691.8 7716.5 5515.4 5615.7 5676.0 5696.4 5719.3 8662.7 8715.3 8932.5 9033.4 9045.6 8869.2 8982.6 8996.1 9114.7 9216.4 6592.2 6736.2 6746.6 6834.7 7053.0 7546.7 7747.8 7758.1 7760.0 7772.9 6874.8 6987.6 6988.4 7046.6 7126.0 5746.3 6852.1 6872.7 6873.5 6886.9 6051.7 6120.5 6237.0 6246.8 6268.0 3969.6 4759.5 4805.1 4826.2 4831.5 4089.9 4184.7 4731.9 4823.4 4915.5 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4428.56 Median:4864.23 stddev:1319.11 (27.1%) stddev-sans-first:1286.32 08:11:54 INFO - PROCESS | 1642 | Values: 323.4 336.8 340.3 352.5 362.8 4829.6 4892.7 4915.5 5218.9 5246.5 4375.5 4661.6 4756.0 5060.7 5159.0 3587.6 3999.8 4014.2 4017.0 4017.6 4003.5 4014.9 4052.8 4078.4 4092.2 4045.9 4050.9 4082.2 4085.8 4104.0 3158.6 3737.9 3763.8 4043.4 4094.2 3070.9 3345.2 3537.4 3538.3 3548.4 4466.6 4796.6 4985.8 5024.2 5058.9 4664.5 4666.4 4679.2 4709.8 4718.7 4816.0 4860.5 5267.1 5398.8 5489.5 3795.5 3940.7 3955.0 3984.8 4001.8 5445.8 5602.8 5628.8 5759.0 5781.6 3254.9 3350.0 4001.5 4054.3 4122.3 3192.8 3938.9 4111.9 4149.7 4206.9 4216.8 4496.3 4725.2 4777.5 4781.3 5296.5 5548.4 5557.8 5585.9 5660.9 1093.7 1134.5 1238.7 1363.7 1397.6 847.7 971.4 974.6 987.4 988.6 4402.6 4416.0 4504.0 4982.7 5020.3 4687.5 5223.6 5364.0 5419.4 5451.2 4173.0 4931.8 4971.5 4974.7 5008.9 2487.0 2779.9 2817.8 2818.7 2828.8 4847.9 5466.8 5559.6 5581.3 5595.1 4449.4 4739.6 5478.8 5546.3 5581.1 5543.9 5571.4 5605.5 5641.9 5648.5 5199.8 5280.5 5290.5 5378.4 5380.7 4761.5 5022.0 5147.5 5200.4 5385.8 4868.0 5496.5 5690.2 5714.9 5770.1 4024.0 4062.9 4071.7 4073.2 4088.4 4690.8 5653.6 5665.8 5717.0 5828.9 4516.5 5478.2 5540.9 5548.3 5578.2 4944.7 5503.6 5511.4 5530.6 5609.3 5153.1 5252.8 5455.8 5466.9 5543.3 4853.8 5113.8 5488.9 5493.7 5496.8 4532.0 5248.8 5372.5 5415.9 5465.5 08:11:54 INFO - PROCESS | 1642 | 08:11:54 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19918.62 Median:4323.41 stddev:28289.94 (654.3%) stddev-sans-first:28332.59 08:11:54 INFO - PROCESS | 1642 | Values: 726.3 728.6 731.9 744.7 781.7 15157.8 15690.9 15730.7 15731.4 15795.0 14098.9 14270.9 14291.0 14307.6 14336.5 665.7 687.5 823.1 853.2 861.2 822.6 847.6 851.4 862.6 864.9 909.7 920.1 922.1 922.3 923.6 695.6 724.6 913.6 914.5 914.8 650.2 671.7 678.3 678.7 680.8 14643.2 14652.3 14663.1 14663.3 14681.3 13166.1 13229.9 13247.7 13255.7 13311.1 10712.9 11989.6 12060.0 12207.0 12269.8 4169.0 4216.8 4245.1 4266.8 4348.9 89075.6 91442.0 91518.5 94733.0 97310.0 10486.4 10497.7 10506.9 10626.2 10682.9 20396.7 20521.3 20564.5 20564.6 20584.9 6183.9 7282.3 7323.7 7503.6 7582.5 4104.2 4182.6 4189.6 4194.4 4219.9 3436.9 3486.7 3525.4 3547.9 3555.2 633.4 640.9 655.7 673.6 694.9 605.3 658.9 677.3 680.3 689.8 8790.8 9136.0 9259.5 9347.3 9349.6 632.7 647.9 667.7 668.3 670.6 4297.9 4417.2 4423.5 4431.8 4447.6 101574.5 103055.0 103255.0 105914.5 108209.4 72016.0 74122.0 75504.0 76615.6 90256.5 81997.0 82378.8 83975.6 85063.1 85063.6 9760.3 9876.3 10018.8 10132.3 10302.8 35517.8 46594.5 51492.0 51706.0 53739.5 69028.0 69666.3 71317.0 71534.6 72048.6 13648.2 13732.0 13735.6 13759.6 14092.3 12909.8 14600.8 14841.3 14935.9 15471.4 12009.5 13367.0 13736.0 14058.4 14474.9 11636.8 12143.5 13507.1 13890.0 14146.0 12218.0 13385.1 13782.8 13835.4 14105.1 10867.1 11212.0 11309.5 11369.1 11385.5 9429.8 9524.1 9693.9 10725.5 11063.3 08:11:54 INFO - PROCESS | 1642 | -------- Summary: end -------- 08:11:54 INFO - PROCESS | 1642 | 08:11:55 INFO - TEST-INFO | 1642: exit 0 08:11:55 INFO - TEST-OK | dromaeo_css | took 1119154ms 08:11:55 INFO - TEST-START | kraken 08:11:55 INFO - operating with platform_type : mac_ 08:11:55 INFO - Initialising browser for kraken test... 08:11:55 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpryeCP0/profile http://localhost:49241/getInfo.html) 08:11:55 INFO - PROCESS | 1649 | 1483373515576 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:11:57 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:11:57 INFO - PROCESS | 1649 | colorDepth:24 08:11:57 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:11:57 INFO - PROCESS | 1649 | __metrics 08:11:57 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:11:57 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:58 INFO - TEST-INFO | 1649: exit 0 08:11:58 INFO - Browser initialized. 08:11:58 INFO - Running cycle 1/1 for kraken test... 08:11:58 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpryeCP0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:10 INFO - PROCESS | 1650 | RSS: Main: 233132032 08:12:10 INFO - PROCESS | 1650 | 08:12:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:12:13 INFO - PROCESS | 1650 | RSS: Main: 286253056 08:12:13 INFO - PROCESS | 1650 | 08:12:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:12:16 INFO - PROCESS | 1650 | RSS: Main: 287391744 08:12:16 INFO - PROCESS | 1650 | 08:12:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:12:20 INFO - PROCESS | 1650 | RSS: Main: 379899904 08:12:20 INFO - PROCESS | 1650 | 08:12:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:12:23 INFO - PROCESS | 1650 | RSS: Main: 280928256 08:12:23 INFO - PROCESS | 1650 | 08:12:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:12:26 INFO - PROCESS | 1650 | RSS: Main: 285855744 08:12:26 INFO - PROCESS | 1650 | 08:12:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:12:29 INFO - PROCESS | 1650 | RSS: Main: 326955008 08:12:29 INFO - PROCESS | 1650 | 08:12:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:12:33 INFO - PROCESS | 1650 | RSS: Main: 328941568 08:12:33 INFO - PROCESS | 1650 | 08:12:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:12:36 INFO - PROCESS | 1650 | RSS: Main: 324952064 08:12:36 INFO - PROCESS | 1650 | 08:12:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:12:39 INFO - PROCESS | 1650 | RSS: Main: 303439872 08:12:39 INFO - PROCESS | 1650 | 08:12:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:12:41 INFO - PROCESS | 1650 | RSS: Main: 305254400 08:12:41 INFO - PROCESS | 1650 | 08:12:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:12:44 INFO - PROCESS | 1650 | RSS: Main: 308011008 08:12:44 INFO - PROCESS | 1650 | 08:12:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:12:48 INFO - PROCESS | 1650 | RSS: Main: 323936256 08:12:48 INFO - PROCESS | 1650 | 08:12:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:12:51 INFO - PROCESS | 1650 | RSS: Main: 318427136 08:12:51 INFO - PROCESS | 1650 | 08:12:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:12:54 INFO - PROCESS | 1650 | RSS: Main: 362184704 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | __start_tp_report 08:12:54 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 08:12:54 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 08:12:54 INFO - PROCESS | 1650 | |i|pagename|runs| 08:12:54 INFO - PROCESS | 1650 | |0;ai-astar;112;115;113;114;114;114;115;113;114;113 08:12:54 INFO - PROCESS | 1650 | |1;audio-beat-detection;143;107;111;168;141;115;111;109;127;110 08:12:54 INFO - PROCESS | 1650 | |2;audio-dft;183;184;186;182;183;180;180;184;184;182 08:12:54 INFO - PROCESS | 1650 | |3;audio-fft;81;60;81;77;57;76;82;82;97;60 08:12:54 INFO - PROCESS | 1650 | |4;audio-oscillator;89;71;86;70;69;88;117;99;98;84 08:12:54 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;86;88;86;87;85;98;130;88;85 08:12:54 INFO - PROCESS | 1650 | |6;imaging-darkroom;94;113;150;151;95;94;94;94;94;94 08:12:54 INFO - PROCESS | 1650 | |7;imaging-desaturate;87;87;87;85;88;85;88;87;88;88 08:12:54 INFO - PROCESS | 1650 | |8;json-parse-financial;57;56;57;56;57;56;55;56;55;54 08:12:54 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;67;72;73;78;73;70;72;71;71;72 08:12:54 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;88;87;86;88;86;109;88;87;87;87 08:12:54 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;142;140;156;151;143;141;141;138;183;155 08:12:54 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;172;174;190;152;221;231;180;170;176;230 08:12:54 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;63;67;84;86;86;85;74;65;66 08:12:54 INFO - PROCESS | 1650 | __end_tp_report 08:12:54 INFO - PROCESS | 1650 | __start_cc_report 08:12:54 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2022 08:12:54 INFO - PROCESS | 1650 | __end_cc_report 08:12:54 INFO - PROCESS | 1650 | __startTimestamp1483373574057__endTimestamp 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | ------- Summary: start ------- 08:12:54 INFO - PROCESS | 1650 | Number of tests: 14 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:0.78 08:12:54 INFO - PROCESS | 1650 | Values: 112.0 115.0 113.0 114.0 114.0 114.0 115.0 113.0 114.0 113.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:121.00 stddev:20.34 (16.8%) stddev-sans-first:20.40 08:12:54 INFO - PROCESS | 1650 | Values: 143.0 107.0 111.0 168.0 141.0 115.0 111.0 109.0 127.0 110.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:1.87 (1.0%) stddev-sans-first:1.99 08:12:54 INFO - PROCESS | 1650 | Values: 183.0 184.0 186.0 182.0 183.0 180.0 180.0 184.0 184.0 182.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:75.30 Median:81.00 stddev:12.61 (15.6%) stddev-sans-first:13.21 08:12:54 INFO - PROCESS | 1650 | Values: 81.0 60.0 81.0 77.0 57.0 76.0 82.0 82.0 97.0 60.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:87.00 stddev:15.09 (17.3%) stddev-sans-first:15.99 08:12:54 INFO - PROCESS | 1650 | Values: 89.0 71.0 86.0 70.0 69.0 88.0 117.0 99.0 98.0 84.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:87.50 stddev:13.84 (15.8%) stddev-sans-first:14.60 08:12:54 INFO - PROCESS | 1650 | Values: 88.0 86.0 88.0 86.0 87.0 85.0 98.0 130.0 88.0 85.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:94.00 stddev:23.52 (25.0%) stddev-sans-first:24.45 08:12:54 INFO - PROCESS | 1650 | Values: 94.0 113.0 150.0 151.0 95.0 94.0 94.0 94.0 94.0 94.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:87.50 stddev:1.15 (1.3%) stddev-sans-first:1.22 08:12:54 INFO - PROCESS | 1650 | Values: 87.0 87.0 87.0 85.0 88.0 85.0 88.0 87.0 88.0 88.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 08:12:54 INFO - PROCESS | 1650 | Values: 57.0 56.0 57.0 56.0 57.0 56.0 55.0 56.0 55.0 54.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:2.77 (3.8%) stddev-sans-first:2.30 08:12:54 INFO - PROCESS | 1650 | Values: 67.0 72.0 73.0 78.0 73.0 70.0 72.0 71.0 71.0 72.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:87.00 stddev:6.96 (8.0%) stddev-sans-first:7.37 08:12:54 INFO - PROCESS | 1650 | Values: 88.0 87.0 86.0 88.0 86.0 109.0 88.0 87.0 87.0 87.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:147.00 stddev:13.58 (9.2%) stddev-sans-first:14.17 08:12:54 INFO - PROCESS | 1650 | Values: 142.0 140.0 156.0 151.0 143.0 141.0 141.0 138.0 183.0 155.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.60 Median:185.00 stddev:27.81 (15.0%) stddev-sans-first:28.76 08:12:54 INFO - PROCESS | 1650 | Values: 172.0 174.0 190.0 152.0 221.0 231.0 180.0 170.0 176.0 230.0 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:79.00 stddev:9.77 (12.4%) stddev-sans-first:10.08 08:12:54 INFO - PROCESS | 1650 | Values: 68.0 63.0 67.0 84.0 86.0 86.0 85.0 74.0 65.0 66.0 08:12:54 INFO - PROCESS | 1650 | -------- Summary: end -------- 08:12:54 INFO - PROCESS | 1650 | 08:12:54 INFO - TEST-INFO | 1650: exit 0 08:12:54 INFO - TEST-OK | kraken | took 59911ms 08:12:55 INFO - SUITE-END | took 1179s 08:12:55 INFO - Completed test suite (00:19:39) 08:12:55 INFO - javascript benchmark 08:12:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3303.3558456101055, 3310.8344582770856, 3323.484859846723, 3332.216929021678, 3370.797752134872, 5512.476314750103, 5597.3283206015285, 5597.916031259534, 5603.775848966042, 5616.578756593257, 5596.188552659872, 5663.490285874271, 5697.515711164556, 5700.942990570093, 5719.59962802604, 5031.597472202215, 5094.032133894567, 5111.948880511203, 5124.564412024983, 5137.948620513785, 4522.917343115134, 5180.481951804808, 5206.531412172903, 5210.947890521104, 5288.524032837044, 5174.301469301634, 5209.505097015777, 5215.217717342391, 5246.370435547739, 5250.789968401259, 5062.341895553592, 5073.289739436482, 5075.365579302587, 5098.388193416814, 5122.513361230677, 5137.51193636604, 5148.510891465305, 5153.252778347156, 5158.819441319573, 5182.300389447451, 5625.493705566519, 5649.322081350223, 5650.463205995424, 5654.717264136777, 5704.401037891044, 6013.729382177812, 6024.8493787655225, 6044.818655440344, 6138.478229350466, 6215.409536094064, 6040.818775436744, 6145.784897528565, 6155.045967874987, 6157.63054216744, 6168.259808822925, 5638.661680299195, 5675.772969081273, 5682.460166284196, 5693.4875861172695, 5716.657000579895, 6206.317305096436, 6257.0, 6284.0, 6296.622202667854, 6300.464460520815, 5865.35481097079, 6047.0627052806885, 6052.394760523913, 6058.394160583906, 6062.9090563641585, 6082.0, 6091.0558863376245, 6130.356312587114, 6135.967692822268, 6138.309242925233, 6125.185026439465, 6130.2146971548445, 6136.846578835475, 6138.204480310071, 6143.92481315768, 6118.426946161437, 6150.200473938359, 6162.599431036968, 6185.690715464209, 6186.10301506279, 4815.927761083586, 4826.806927722922, 4831.661783675109, 4866.513348665176, 4905.656604037755, 4355.020120472932, 4368.126374725068, 4371.475422949203, 4390.670699697535, 4429.335599660012, 5687.658740475585, 5691.9715401423555, 5705.850965686027, 5728.885422291531, 5772.942270577324, 6035.094735789667, 6104.216637119002, 6200.844978875474, 6210.472109870621, 6236.563440559208, 5669.092945128759, 5674.233978412862, 5696.743646535916, 5731.799387021434, 5748.367679555246, 5336.733163341895, 5377.085895397713, 5380.488853558905, 5385.380681221632, 5396.109641909041, 6276.905846412307, 6297.496200304056, 6299.244090709144, 6317.431431171217, 6318.968405157945, 6079.240094988127, 6089.969550152221, 6116.061877111426, 6192.411720886689, 6210.968945155245, 6280.340564240689, 6296.5277604178955, 6307.96846015767, 6308.495320374451, 6314.400131987454, 6078.513718902389, 6095.634261944298, 6099.786507472217, 6102.792231004566, 6103.206583144341, 6226.283977342664, 6227.532935029982, 6230.345813689497, 6236.875262494816, 6244.937550624436, 5993.940060599338, 6036.33600303975, 6044.032954727222, 6046.30467496226, 6057.7879774207695, 5879.853003674943, 5894.056950887837, 5911.083782013795, 5988.580799344179, 5996.040633498619, 5884.146798714248, 5888.17565540816, 5893.174955506318, 5905.202797622266, 5951.7619295227705, 6050.2437195350585, 6066.787662431793, 6073.240844894388, 6073.969630151821, 6074.179985701874, 5709.942900570941, 5731.914021289767, 5733.569982251264, 5840.707964601668, 5847.912281315698], "value": 5639.064829732293, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.5808656036445, 463.3166080031952, 468.80310269686726, 500.8197049062335, 522.6210997027156, 30470.0, 39551.0, 41423.58576414235, 41518.58481415185, 41525.792371038144, 42809.57190428095, 42903.141937161236, 42988.785056074754, 43087.13825723484, 43264.91837704059, 6132.386761323854, 9266.53667316633, 9457.10157535033, 9465.526723663823, 9468.81062378752, 8603.95698021509, 9135.0, 11453.136715821045, 11462.656120316362, 11636.767264654702, 11316.094712423028, 11349.659510214708, 11369.260998035143, 11388.658340249805, 11396.316221026724, 11374.203805733601, 11425.0, 11433.542658293658, 11461.36962467064, 11464.254823436491, 11209.271397359145, 11280.379579123144, 11283.887161128365, 11287.040601548879, 11301.378424186667, 39117.02207444808, 39476.01309967245, 39683.80948571547, 39775.80112099421, 39792.00519986994, 3950.940735888964, 3992.0, 4009.7594144351433, 4025.19496100781, 4073.0, 29666.406671866658, 30698.53952190719, 30946.0, 30970.690293097006, 31140.0, 2974.154832753423, 3095.071478556424, 3160.225744692565, 3160.620725512953, 3163.9525407118913, 479453.0, 480376.1962380401, 481506.0, 481895.0, 482621.0, 61594.69202653958, 62071.06893396512, 62146.689266554284, 62182.0, 62200.37799622036, 63838.0, 64128.679356603854, 64203.0, 64224.0, 64425.67787161034, 15512.91409601317, 15719.921400392925, 15748.92125539365, 15754.606134846721, 15762.527124186292, 10830.891691083147, 11103.500342484607, 11124.888751112385, 11688.88311116878, 11755.941220294015, 15990.120543369994, 16108.0, 16110.113943733208, 17644.853084549464, 18162.81837181638, 6293.496520278367, 6354.586951848115, 6401.103845461639, 6406.483429182415, 6425.485961123099, 755.5731011978214, 784.2667106255669, 826.5411548616696, 869.7215093812086, 878.3544095090103, 28799.0, 29333.0, 29448.55827162594, 29749.553756693665, 30154.793808247865, 1026.414943482208, 1062.888396718349, 1065.738893970982, 1074.9140068794477, 1120.9775804483866, 14807.555773326818, 15395.538133856002, 15490.225488725519, 15640.4525841595, 15881.967672101277, 235710.0, 777756.0, 842522.0, 857601.0, 860640.0, 44113.558864410945, 52370.73814630903, 52661.63022663989, 53711.73144134333, 56497.717511413, 1189.8988585970117, 1351.4999450203488, 1377.091119860907, 1382.5575815739269, 1389.9714211483429, 28180.577291340236, 28496.43007139804, 28658.426831462835, 29118.27204319909, 29191.416171676025, 46210.537894620626, 46821.2976805355, 46829.53170468388, 46901.53098469108, 46918.0, 557514.0, 559483.8103237994, 560671.0, 561689.0, 562425.0, 20251.898740506203, 24142.637860431758, 24306.0, 24654.506909862062, 24703.258902232963, 41448.46630642081, 42134.78932605317, 42811.0, 42935.0, 43075.56924430715, 25633.743662563134, 33832.1541961453, 34842.82578587091, 36491.0, 36504.26991460103, 31622.525662114625, 34190.82904585461, 34214.0, 35772.0, 35817.64182358143, 34371.0, 34501.96494105181, 34568.790092346644, 34895.82552087223, 35113.12217194488, 24970.875145624155, 30015.949441769433, 31941.36117277595, 32117.6788232124, 32176.517352239265, 18430.89414635126, 23121.3063608092, 23793.881030594737, 23996.920138594156, 24271.271861844172], "value": 19438.902712927156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.18996308490466, 368.1165203511572, 373.10082700691333, 377.6223776223776, 399.6283456385562, 14708.191049492274, 15064.849351506482, 15442.382304707813, 19859.900700496477, 20356.89821550894, 16854.747178792328, 19620.313289034886, 20342.796572034276, 21144.048517816696, 21191.152353905865, 5626.606137570382, 5797.246357973458, 5886.323072846634, 5894.909441753268, 5896.233489646362, 4052.230076285511, 6202.5968312059795, 6447.7743278985245, 6494.31809659986, 6506.609603423786, 5667.866426714653, 6276.654783986879, 6297.149884765567, 6349.746010159588, 6454.838629034265, 6434.324395938429, 6487.83780405489, 6494.870102597969, 6495.12315837363, 6496.090547323379, 5396.19057141428, 5571.387147413781, 5638.859028524279, 5652.463016013472, 5656.717164141776, 24519.38701532458, 25078.24765257045, 25369.61945570818, 25384.238472845846, 25657.35856603581, 2473.987630061856, 2645.391559941205, 2674.572068469055, 2695.501332253539, 2727.9590806137926, 13984.0, 15217.6195595111, 15695.136767477785, 15861.36554537817, 15927.0, 1346.9224620303817, 1554.4947891935053, 1681.7645529625863, 1712.86297096232, 1800.6938820400562, 104224.0, 107106.0, 107765.92234077715, 108001.0, 109765.0, 9162.221211197122, 9610.95194524037, 9630.566624501915, 9739.951300243596, 10937.4531273436, 12072.75854482923, 12154.574589889311, 12172.391380430943, 12179.391030448442, 12196.268223906594, 6394.744210231632, 7347.485676002735, 7398.3341499265325, 7466.664000120008, 7557.206493318233, 6300.842478938063, 7564.546127232384, 7618.961905190438, 7624.12322582899, 7705.190954949761, 3774.622262874031, 3784.243151369682, 3793.943090853639, 3847.3267178244255, 3861.150546879738, 1847.87064905458, 1888.5278680329918, 1906.9035304699576, 1907.980920190808, 1913.3494611832043, 912.8037471943564, 966.246327864267, 991.940483570988, 996.2776986684596, 1001.3514254796169, 19616.411507654793, 20572.0, 20623.69064464034, 20658.17367305321, 20833.479163021046, 933.453929451265, 1013.4527355228095, 1029.2075102171286, 1032.2258306270298, 1033.715728174758, 10522.000409961041, 10803.243772935819, 10894.509747061402, 10906.291091079054, 10961.561537538568, 519457.0, 547463.0, 550489.0, 563045.2934147367, 563691.0, 88737.11262887288, 113856.0, 127518.0, 127904.36047819887, 129129.70870291365, 948.3788118782209, 963.8265112279717, 995.5868314649484, 995.8796354101636, 996.3324572536418, 18146.637067258845, 18429.53926151857, 18464.26142954267, 18491.260349585868, 18508.0, 53376.466235337146, 53843.73078134584, 54073.72963135159, 54744.45255547553, 55612.72193639006, 67490.0, 73842.63078684572, 74228.25771742429, 74815.62592187004, 75416.0, 6595.538312318092, 8053.476524025919, 8117.959410203148, 8131.455023545507, 8198.631061602124, 10633.627823026467, 12715.80926286125, 12718.872811271769, 13005.284709341076, 13049.28228947417, 11112.666620001413, 11176.664700059011, 11356.205065645325, 11415.37215453158, 11512.712182195177, 10996.780064398827, 11069.889301106887, 11175.888241117485, 11211.566054503932, 11377.317360958368, 11246.943765281121, 11320.829415549328, 11455.770884582094, 11518.424078795859, 11547.538098475967, 6945.305469453015, 7790.8575141836445, 7951.602419879098, 8186.672533098609, 8351.457155284887, 9528.190103840974, 11365.772684546097, 11560.47977841016, 11643.12676549279, 11694.006009489289], "value": 9379.01704649483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.7438223928575, 1384.7784354503276, 1403.4035534897666, 1404.54352335491, 1406.5372013106364, 7338.376238019761, 7785.844283114335, 7883.526988380694, 8185.9181408185905, 8195.508269503827, 7374.2994415530575, 7575.242475752422, 7592.8861067084035, 7880.645370958307, 8006.319462845665, 2096.8008039236247, 2130.7017017617545, 2222.822174226062, 2265.5355652091366, 2266.29744779119, 2255.436140964759, 2290.931272061841, 2299.9195028174017, 2301.838871279011, 2304.4699719064624, 1944.0862794486638, 2005.4886004068942, 2212.6791615215784, 2216.6120928837468, 2251.808596269311, 2230.832687548432, 2247.9662805057856, 2248.2355998960434, 2250.7861753133425, 2255.548890221962, 2236.0, 2244.51742875281, 2264.230161745015, 2265.535565209128, 2267.7618850020676, 8026.919730802676, 8095.716649917284, 8113.513189208607, 8143.511389316601, 8168.918310816875, 4946.87632809179, 5106.897862042775, 5112.233165025239, 5116.513931176532, 5121.718305493197, 3199.936001279985, 3381.4927760835826, 3427.657234276577, 3435.038189306998, 3454.516367708523, 945.8820349976355, 1057.5981127171704, 1176.4823477669797, 1223.4494477485168, 1224.4734764051454, 10441.738956526147, 10477.68566942993, 10491.0, 10501.36991780488, 10501.9474902625, 4385.51759306476, 4511.571400716926, 4559.0, 4568.109218702321, 4635.791389387486, 9146.176844084064, 9158.267338612895, 9172.449653020707, 9180.540972951325, 9203.4938078405, 4358.5205627380965, 5087.135187018216, 5087.211482220262, 5091.567216786615, 5095.923561146586, 3904.902377440587, 4118.5263694674895, 4647.256438969748, 4652.464966528893, 4694.718316900997, 3115.797473164237, 3120.469520181575, 3790.6209379061875, 3825.3114439401174, 3836.846526138979, 1252.261165912098, 1256.9308688022247, 1262.3751243134523, 1272.4782839035952, 1272.5673271087874, 802.4022103532789, 805.2672068417712, 890.1943740914483, 903.1690844423147, 903.5256490342623, 5061.6962982221185, 5454.699991500426, 5666.546676265889, 5668.433156684298, 5999.880002400015, 765.663108232376, 852.9317654587618, 875.9912400876037, 877.7191298784455, 888.8223104386714, 3760.435934609776, 3767.6232376762655, 3781.1303400217553, 3859.150986782903, 3894.766314021168, 9479.431234125974, 9683.66107186245, 9777.364471309342, 9791.804163916824, 9823.852642210373, 9740.95129524362, 9744.805103897883, 9754.268429867789, 9761.609535618638, 9781.0, 7343.63281835906, 7534.284242996907, 7687.0006899102755, 7691.846163076707, 7716.5370077795515, 5515.448455154453, 5615.691136987505, 5675.971620141873, 5696.401877802937, 5719.342275638355, 8662.740117796477, 8715.34634902372, 8932.46405215663, 9033.367664263438, 9045.638174472948, 8869.246114080117, 8982.550872456482, 8996.100389960951, 9114.680986165453, 9216.400933939272, 6592.24189218246, 6736.157980252468, 6746.595204287759, 6834.658267086526, 7052.9647351762915, 7546.6604002821105, 7747.806304842424, 7758.146603873456, 7759.961200193963, 7772.92227077722, 6874.759383421556, 6987.580745155307, 6988.37104660583, 7046.612436315847, 7125.964370178116, 5746.281714785652, 6852.143482064742, 6872.69072891711, 6873.450123990169, 6886.8622627548175, 6051.727672254671, 6120.540959427972, 6237.0, 6246.750129994749, 6268.0, 3969.5831937647285, 4759.500252473579, 4805.08703346377, 4826.227803551415, 4831.541003604652, 4089.89775255621, 4184.727992680466, 4731.929021064618, 4823.445303789968, 4915.5330243626795], "value": 4269.558079291716, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.36136131141, 336.8147518864624, 340.2820049695143, 352.545216670495, 362.7787049899562, 4829.613630909527, 4892.657513974019, 4915.500772264458, 5218.895622087561, 5246.5278124968745, 4375.474943006836, 4661.627069834413, 4756.0250148719515, 5060.721660308682, 5158.9622099270855, 3587.6053634100226, 3999.7600143991453, 4014.1570270243137, 4017.0, 4017.6384125428704, 4003.4595329630565, 4014.8996275093064, 4052.7770972596495, 4078.449409329732, 4092.1815636872598, 4045.898852528681, 4050.9392359114636, 4082.2447847148223, 4085.775282359469, 4104.035551645361, 3158.641075316072, 3737.8786364090665, 3763.8118094095184, 4043.433919251307, 4094.242565125461, 3070.9385812283854, 3345.2138747394342, 3537.3728085080893, 3538.327717733622, 3548.361294966905, 4466.620337271336, 4796.640251981115, 4985.775640096204, 5024.201887197717, 5058.924116138261, 4664.486906440323, 4666.440027196724, 4679.227927391977, 4709.811607535695, 4718.740469274188, 4815.975920120412, 4860.513948605147, 5267.051930652481, 5398.811041613525, 5489.505944465017, 3795.4686343911876, 3940.6847452203756, 3955.0, 3984.8406063757707, 4001.8399264029404, 5445.809396671098, 5602.795398989156, 5628.831135065954, 5759.0, 5781.624194427349, 3254.902352929416, 3349.9665003349655, 4001.5398229203433, 4054.2904991626347, 4122.319817230184, 3192.7650644548908, 3938.8621398250925, 4111.8972025698995, 4149.709520333608, 4206.915861682749, 4216.756056963203, 4496.3255511673515, 4725.1967165581245, 4777.522247775264, 4781.306710527024, 5296.470352964673, 5548.4174161713245, 5557.77768889248, 5585.888282234332, 5660.886782264414, 1093.704699731068, 1134.5348407153192, 1238.7027113492697, 1363.677233083907, 1397.566754306148, 847.7456762971208, 971.4365667912593, 974.6198982396871, 987.4124895687055, 988.5847943863595, 4402.559744025636, 4416.0, 4504.0, 4982.651214415028, 5020.2720605511995, 4687.531246875285, 5223.597145313199, 5364.0, 5419.376771671229, 5451.236826844245, 4172.956760809798, 4931.841017360917, 4971.477994810565, 4974.651774375759, 5008.899822003539, 2486.9875650621993, 2779.88880444784, 2817.7604903583424, 2818.689944106147, 2828.8302701837956, 4847.878803029953, 5466.753996070186, 5559.555235581144, 5581.274434323512, 5595.104783234744, 4449.421575195204, 4739.620830333634, 5478.8358598353425, 5546.306711661043, 5581.134924086692, 5543.861403464945, 5571.415001424792, 5605.467480589337, 5641.915371269434, 5648.51987581052, 5199.752059505767, 5280.498352656491, 5290.470952904679, 5378.381486128988, 5380.704061276668, 4761.476237613929, 5021.995600879912, 5147.485251474823, 5200.375954885438, 5385.8114965976, 4867.953390021163, 5496.477834605706, 5690.174924635988, 5714.914276285942, 5770.114942528736, 4023.979880100581, 4062.898427539335, 4071.6742660586488, 4073.185362927367, 4088.407180958804, 4690.835820746258, 5653.604247702621, 5665.773369065191, 5717.0, 5828.883422331614, 4516.515916246117, 5478.178273259043, 5540.944590554042, 5548.306461692288, 5578.163275508706, 4944.678595891256, 5503.569072041379, 5511.448855114457, 5530.640508366943, 5609.298837645294, 5153.119111524342, 5252.763625636932, 5455.781768729206, 5466.945330546644, 5543.334799823886, 4853.781579828846, 5113.821016264413, 5488.862778430571, 5493.725313734218, 5496.7526461308535, 4531.977340113278, 5248.8425347239645, 5372.462753724596, 5415.887803756459, 5465.508104270635], "value": 4022.5217544315014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.3100054947801, 728.621052264357, 731.9304666056724, 744.702119152339, 781.7499094737574, 15157.772633410494, 15690.921545392257, 15730.685386292267, 15731.449399271029, 15795.0, 14098.929505352484, 14270.857291427083, 14291.0, 14307.642308942282, 14336.498222562186, 665.6904539389183, 687.4946914018193, 823.1315961660446, 853.2491407561369, 861.1689719420758, 822.6298165825373, 847.6016272351985, 851.4295422067199, 862.5816479007676, 864.8918885139358, 909.7498187998287, 920.089111779337, 922.0548937339214, 922.2990527199344, 923.5520772425368, 695.6139342664819, 724.5580196080386, 913.6299798581585, 914.4878867834001, 914.7850255190025, 650.151552224347, 671.6943790575325, 678.3216783216783, 678.7488629207224, 680.8195828105556, 14643.19462429566, 14652.340644671016, 14663.120212787268, 14663.34014969329, 14681.265936703228, 13166.144200626928, 13229.933850330783, 13247.66880827987, 13255.73488530224, 13311.134776239513, 10712.946435267851, 11989.580364687194, 12060.0, 12207.0, 12269.815952760715, 4169.0, 4216.7680777557225, 4245.12974840157, 4266.765327906964, 4348.91302173958, 89075.55462222647, 91442.0, 91518.5424072882, 94732.99999999932, 97310.0, 10486.423246721504, 10497.737556561147, 10506.949305069586, 10626.203034772423, 10682.946585267024, 20396.694049589267, 20521.281755138498, 20564.485887852625, 20564.58870822575, 20584.897075514527, 6183.938160618426, 7282.344588987016, 7323.707051717872, 7503.624818759149, 7582.469227154046, 4104.220198162399, 4182.644475219579, 4189.622933935961, 4194.4337514435165, 4219.936700949488, 3436.879709210216, 3486.738494612914, 3525.4359302511475, 3547.8758243461357, 3555.182308069107, 633.4394061255822, 640.9294977552465, 655.7213184396612, 673.6463356737654, 694.8992396102536, 605.3129697793037, 658.9341065893468, 677.3226773226774, 680.2890233774747, 689.7723751162105, 8790.824183516293, 9136.040715724886, 9259.490728010023, 9347.251743111912, 9349.579268932914, 632.6520413772407, 647.8607099473636, 667.732906837269, 668.3383450384084, 670.6367566653238, 4297.914041719148, 4417.2269852775635, 4423.535528769497, 4431.756253406029, 4447.575763589792, 101574.49212754036, 103055.0, 103255.0, 105914.47042764736, 108209.37685934718, 72016.0, 74122.0, 75504.0, 76615.61692191503, 90256.548717256, 81997.0, 82378.76431853526, 83975.580122099, 85063.14936850552, 85063.57468212744, 9760.267979901393, 9876.308658393844, 10018.849717254101, 10132.290739648153, 10302.793944121226, 35517.82241088778, 46594.53405466038, 51491.970160597324, 51705.965880682925, 53739.46260537345, 69027.96458053033, 69666.25834354182, 71317.0, 71534.64232678803, 72048.63975680088, 13648.181109133484, 13732.0, 13735.587932362045, 13759.587212383645, 14092.295385230902, 12909.806352904901, 14600.780988285396, 14841.257937102886, 14935.925320373763, 15471.369867122392, 12009.459574319002, 13366.967206973124, 13736.0, 14058.367373468014, 14474.927625361806, 11636.825447618123, 12143.463139459267, 13507.122037067558, 13890.0, 14146.0, 12218.0, 13385.063045587105, 13782.793258101337, 13835.377408016462, 14105.083169593943, 10867.13062954946, 11212.0, 11309.547618095514, 11369.147313951322, 11385.519445712995, 9429.764255893384, 9524.095210955225, 9693.854592181264, 10725.517351719036, 11063.280886742335], "value": 7370.65760740153, "unit": "score"}], "name": "dromaeo_css", "value": 7118.885214652199, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 113.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 111.0, 168.0, 141.0, 115.0, 111.0, 109.0, 127.0, 110.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 182.0, 183.0, 180.0, 180.0, 184.0, 184.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 81.0, 77.0, 57.0, 76.0, 82.0, 82.0, 97.0, 60.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 70.0, 69.0, 88.0, 117.0, 99.0, 98.0, 84.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 87.0, 85.0, 98.0, 130.0, 88.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 113.0, 150.0, 151.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 85.0, 88.0, 85.0, 88.0, 87.0, 88.0, 88.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 56.0, 57.0, 56.0, 55.0, 56.0, 55.0, 54.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 73.0, 78.0, 73.0, 70.0, 72.0, 71.0, 71.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 86.0, 88.0, 86.0, 109.0, 88.0, 87.0, 87.0, 87.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 156.0, 151.0, 143.0, 141.0, 141.0, 138.0, 183.0, 155.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 190.0, 152.0, 221.0, 231.0, 180.0, 170.0, 176.0, 230.0], "value": 189.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 67.0, 84.0, 86.0, 86.0, 85.0, 74.0, 65.0, 66.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1499.6, "alertThreshold": 2.0}]} 08:12:55 INFO - Return code: 0 08:12:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:12:55 INFO - # TBPL SUCCESS # 08:12:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:12:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:12:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:12:55 INFO - 2017-01-02 08:12:55.357 screenresolution[1654:10896] starting screenresolution argv=screenresolution get 08:12:55 INFO - 2017-01-02 08:12:55.360 screenresolution[1654:10896] Display 0: 1600x1200x32@60 08:12:55 INFO - 2017-01-02 08:12:55.366 screenresolution[1655:10899] starting screenresolution argv=screenresolution list 08:12:55 INFO - Available Modes on Display 0 08:12:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:12:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:12:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:12:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:12:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:12:55 INFO - Intel Iris: 08:12:55 INFO - Chipset Model: Intel Iris 08:12:55 INFO - Type: GPU 08:12:55 INFO - Bus: Built-In 08:12:55 INFO - VRAM (Dynamic, Max): 1536 MB 08:12:55 INFO - Vendor: Intel (0x8086) 08:12:55 INFO - Device ID: 0x0a2e 08:12:55 INFO - Revision ID: 0x0009 08:12:55 INFO - Displays: 08:12:55 INFO - Display: 08:12:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:12:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:12:55 INFO - Display Serial Number: 1600x1200 60 08:12:55 INFO - Main Display: Yes 08:12:55 INFO - Mirror: Off 08:12:55 INFO - Online: Yes 08:12:55 INFO - Rotation: Supported 08:12:55 INFO - Return code: 0 08:12:55 INFO - Running post-action listener: _resource_record_post_action 08:12:55 INFO - [mozharness: 2017-01-02 16:12:55.605963Z] Finished run-tests step (success) 08:12:55 INFO - Running post-run listener: _resource_record_post_run 08:12:55 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33340416; Write bytes: 551057408; Read time: 306; Write time: 1742 08:12:55 INFO - TinderboxPrint: CPU usage
53.6% 08:12:55 INFO - TinderboxPrint: I/O read bytes / time
33,340,416 / 306 08:12:55 INFO - TinderboxPrint: I/O write bytes / time
551,057,408 / 1,742 08:12:55 INFO - TinderboxPrint: CPU idle
2,227.5 (46.4%) 08:12:55 INFO - TinderboxPrint: CPU system
960.8 (20.0%) 08:12:55 INFO - TinderboxPrint: CPU user
1,617.4 (33.7%) 08:12:55 INFO - TinderboxPrint: Swap in / out
279,146,496 / 0 08:12:55 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247853568; Write bytes: 243419136; Read time: 18103; Write time: 504 08:12:55 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29129728; Write bytes: 296165376; Read time: 276; Write time: 1201 08:12:56 INFO - Running post-run listener: _upload_blobber_files 08:12:56 INFO - Blob upload gear active. 08:12:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:12:56 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 08:12:56 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'] 08:12:56 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 08:12:56 INFO - (blobuploader) - INFO - Open directory for files ... 08:12:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:12:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:12:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:57 INFO - (blobuploader) - INFO - Done attempting. 08:12:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:12:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:58 INFO - (blobuploader) - INFO - Done attempting. 08:12:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:12:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:12:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:59 INFO - (blobuploader) - INFO - Done attempting. 08:12:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:12:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:13:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:02 INFO - (blobuploader) - INFO - Done attempting. 08:13:02 INFO - (blobuploader) - INFO - Iteration through files over. 08:13:02 INFO - Return code: 0 08:13:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:13:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:13:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67c1ca7ff13edfef2ecbf4ae95578ec356d1a7b6e27309a7dad93e0f82a5af02943112f46ba96f61af54d5653e0e7c93381e8c211a5f45eebb011d26d835051", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72d26429ddb3da2b95d810e673a76d1338b949902269c1d5860f3c56f1cdfefe76adb5f39c9002ac5ee6058e60421d63ea850a37314bed94753db5985c65b875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58f9958c432a8109303dff652f904b0dfdd8803abf09b1b62cfd3f76428f5f679502382c051f855b67f38b4574806ee86ece70beb5dbee934f40bb821cd506a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42064f6ec40383b90a9907c3d9fa17570896fbba351444a4b7c06adc93fbfb24bfb490eab03c6ac7052d2984cde8e830a2146de664e304fbf68bcf523e440fda"} 08:13:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:13:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:13:02 INFO - Contents: 08:13:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67c1ca7ff13edfef2ecbf4ae95578ec356d1a7b6e27309a7dad93e0f82a5af02943112f46ba96f61af54d5653e0e7c93381e8c211a5f45eebb011d26d835051", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72d26429ddb3da2b95d810e673a76d1338b949902269c1d5860f3c56f1cdfefe76adb5f39c9002ac5ee6058e60421d63ea850a37314bed94753db5985c65b875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58f9958c432a8109303dff652f904b0dfdd8803abf09b1b62cfd3f76428f5f679502382c051f855b67f38b4574806ee86ece70beb5dbee934f40bb821cd506a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42064f6ec40383b90a9907c3d9fa17570896fbba351444a4b7c06adc93fbfb24bfb490eab03c6ac7052d2984cde8e830a2146de664e304fbf68bcf523e440fda"} 08:13:02 INFO - Running post-run listener: copy_logs_to_upload_dir 08:13:02 INFO - Copying logs to upload dir... 08:13:02 INFO - mkdir: /builds/slave/test/build/upload/logs 08:13:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.470903 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-02 08:13:02.196771) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 08:13:02.200235) ========= 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.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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/d67c1ca7ff13edfef2ecbf4ae95578ec356d1a7b6e27309a7dad93e0f82a5af02943112f46ba96f61af54d5653e0e7c93381e8c211a5f45eebb011d26d835051", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72d26429ddb3da2b95d810e673a76d1338b949902269c1d5860f3c56f1cdfefe76adb5f39c9002ac5ee6058e60421d63ea850a37314bed94753db5985c65b875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58f9958c432a8109303dff652f904b0dfdd8803abf09b1b62cfd3f76428f5f679502382c051f855b67f38b4574806ee86ece70beb5dbee934f40bb821cd506a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42064f6ec40383b90a9907c3d9fa17570896fbba351444a4b7c06adc93fbfb24bfb490eab03c6ac7052d2984cde8e830a2146de664e304fbf68bcf523e440fda"} build_url:https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/RUCASVDVQzG8UefnYFxbSA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d67c1ca7ff13edfef2ecbf4ae95578ec356d1a7b6e27309a7dad93e0f82a5af02943112f46ba96f61af54d5653e0e7c93381e8c211a5f45eebb011d26d835051", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72d26429ddb3da2b95d810e673a76d1338b949902269c1d5860f3c56f1cdfefe76adb5f39c9002ac5ee6058e60421d63ea850a37314bed94753db5985c65b875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d58f9958c432a8109303dff652f904b0dfdd8803abf09b1b62cfd3f76428f5f679502382c051f855b67f38b4574806ee86ece70beb5dbee934f40bb821cd506a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42064f6ec40383b90a9907c3d9fa17570896fbba351444a4b7c06adc93fbfb24bfb490eab03c6ac7052d2984cde8e830a2146de664e304fbf68bcf523e440fda"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 08:13:02.235040) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 08:13:02.235418) ========= 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.g4HSihGS60/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.6PpAadyRki/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005189 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 08:13:02.283912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 08:13:02.284262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 08:13:02.284626) ========= ========= Total master_lag: 0.18 =========