builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0043 starttime: 1470130802.55 results: success (0) buildid: 20160802002149 builduid: 6b9d98a8253a4cf8a43036f1b89fe8b0 revision: 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:02.551604) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:02.552157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:02.552462) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:02.686102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:02.686385) ========= 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.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-02 02:40:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2016-08-02 02:40:02 (13.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119063 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:03.150850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:03.151170) ========= 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.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.028238 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:03.238558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 02:40:03.238987) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-02 02:40:03,314 truncating revision to first 12 chars 2016-08-02 02:40:03,314 Setting DEBUG logging. 2016-08-02 02:40:03,314 attempt 1/10 2016-08-02 02:40:03,315 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/375ae3ccf50b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 02:40:04,426 unpacking tar archive at: larch-375ae3ccf50b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 02:40:04.646151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:04.646461) ========= script_repo_revision: 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:04.646871) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:04.647216) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 02:40:04.666221) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-08-02 02:40:04.666595) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:40:04 INFO - MultiFileLogger online at 20160802 02:40:04 in /builds/slave/test 02:40:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 02:40:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:40:04 INFO - {'append_to_log': False, 02:40:04 INFO - 'base_work_dir': '/builds/slave/test', 02:40:04 INFO - 'blob_upload_branch': 'Larch', 02:40:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:40:04 INFO - 'branch': 'Larch', 02:40:04 INFO - 'buildbot_json_path': 'buildprops.json', 02:40:04 INFO - 'config_files': ('talos/mac_config.py',), 02:40:04 INFO - 'default_actions': ('clobber', 02:40:04 INFO - 'read-buildbot-config', 02:40:04 INFO - 'download-and-extract', 02:40:04 INFO - 'populate-webroot', 02:40:04 INFO - 'create-virtualenv', 02:40:04 INFO - 'install', 02:40:04 INFO - 'run-tests'), 02:40:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:40:04 INFO - 'download_minidump_stackwalk': True, 02:40:04 INFO - 'download_symbols': 'ondemand', 02:40:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:40:04 INFO - 'tooltool.py': '/tools/tooltool.py', 02:40:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:40:04 INFO - '/tools/misc-python/virtualenv.py')}, 02:40:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:40:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:40:04 INFO - 'installer_path': 'installer.exe', 02:40:04 INFO - 'log_level': 'info', 02:40:04 INFO - 'log_name': 'talos', 02:40:04 INFO - 'log_to_console': True, 02:40:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:40:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:40:04 INFO - 'opt_config_files': (), 02:40:04 INFO - 'pip_index': False, 02:40:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:04 INFO - 'cmd': ('bash', 02:40:04 INFO - '-c', 02:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:04 INFO - 'enabled': True, 02:40:04 INFO - 'halt_on_failure': False, 02:40:04 INFO - 'name': 'check_screen_resolution'},), 02:40:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:04 INFO - 'cmd': ('bash', 02:40:04 INFO - '-c', 02:40:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:04 INFO - 'enabled': True, 02:40:04 INFO - 'halt_on_failure': False, 02:40:04 INFO - 'name': 'check_screen_resolution'},), 02:40:04 INFO - 'run_cmd_checks_enabled': True, 02:40:04 INFO - 'sps_profile': False, 02:40:04 INFO - 'sps_profile_interval': 0, 02:40:04 INFO - 'suite': 'dromaeojs', 02:40:04 INFO - 'system_bits': '32', 02:40:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:40:04 INFO - 'title': 't-yosemite-r7-0043', 02:40:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:40:04 INFO - 'use_talos_json': True, 02:40:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:40:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:40:04 INFO - 'work_dir': 'build'} 02:40:04 INFO - ##### 02:40:04 INFO - ##### Running clobber step. 02:40:04 INFO - ##### 02:40:04 INFO - Running pre-action listener: _resource_record_pre_action 02:40:04 INFO - Running main action method: clobber 02:40:04 INFO - rmtree: /builds/slave/test/build 02:40:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:40:06 INFO - Running post-action listener: _resource_record_post_action 02:40:06 INFO - ##### 02:40:06 INFO - ##### Finished clobber step (success) 02:40:06 INFO - ##### 02:40:06 INFO - ##### 02:40:06 INFO - ##### Running read-buildbot-config step. 02:40:06 INFO - ##### 02:40:06 INFO - Running pre-action listener: _resource_record_pre_action 02:40:06 INFO - Running main action method: read_buildbot_config 02:40:06 INFO - Using buildbot properties: 02:40:06 INFO - { 02:40:06 INFO - "project": "", 02:40:06 INFO - "product": "firefox", 02:40:06 INFO - "script_repo_revision": "production", 02:40:06 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 02:40:06 INFO - "repository": "", 02:40:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 02:40:06 INFO - "buildid": "20160802002149", 02:40:06 INFO - "pgo_build": "False", 02:40:06 INFO - "basedir": "/builds/slave/test", 02:40:06 INFO - "buildnumber": 0, 02:40:06 INFO - "slavename": "t-yosemite-r7-0043", 02:40:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:40:06 INFO - "platform": "yosemite_r7", 02:40:06 INFO - "scheduler": "tests-larch-macosx64-talos", 02:40:06 INFO - "branch": "larch", 02:40:06 INFO - "repo_path": "projects/larch", 02:40:06 INFO - "revision": "375ae3ccf50bc90621263cb202e6cc2e7cd7c028", 02:40:06 INFO - "stage_platform": "macosx64", 02:40:06 INFO - "builduid": "6b9d98a8253a4cf8a43036f1b89fe8b0", 02:40:06 INFO - "slavebuilddir": "test" 02:40:06 INFO - } 02:40:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:40:06 INFO - Running post-action listener: _resource_record_post_action 02:40:06 INFO - ##### 02:40:06 INFO - ##### Finished read-buildbot-config step (success) 02:40:06 INFO - ##### 02:40:06 INFO - ##### 02:40:06 INFO - ##### Running download-and-extract step. 02:40:06 INFO - ##### 02:40:06 INFO - Running pre-action listener: _resource_record_pre_action 02:40:06 INFO - Running main action method: download_and_extract 02:40:06 INFO - mkdir: /builds/slave/test/build/tests 02:40:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:40:06 INFO - https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:40:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:40:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:40:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 02:40:36 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 02:40:36 INFO - retry: attempt #1 caught exception: timed out 02:40:36 INFO - retry: Failed, sleeping 30 seconds before retrying 02:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #2 02:41:12 INFO - Downloaded 1199 bytes. 02:41:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:41:12 INFO - Using the following test package requirements: 02:41:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:41:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:41:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:41:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'jsshell-mac.zip'], 02:41:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:41:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:41:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:41:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:41:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:41:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:41:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:41:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:41:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:12 INFO - https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:41:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:41:14 INFO - Downloaded 18789524 bytes. 02:41:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:41:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:41:18 INFO - Return code: 0 02:41:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:18 INFO - https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:41:19 INFO - Downloaded 18789524 bytes. 02:41:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:41:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:41:22 INFO - Return code: 0 02:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:22 INFO - https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:41:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:41:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:41:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:41:23 INFO - Downloaded 14405242 bytes. 02:41:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:41:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:41:24 INFO - Return code: 0 02:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:24 INFO - https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:41:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:41:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:41:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:41:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 02:41:27 INFO - Downloaded 112471432 bytes. 02:41:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:41:27 INFO - mkdir: /builds/slave/test/properties 02:41:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:41:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:41:27 INFO - Contents: 02:41:27 INFO - build_url:https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:41:28 INFO - Running post-action listener: _resource_record_post_action 02:41:28 INFO - Running post-action listener: set_extra_try_arguments 02:41:28 INFO - ##### 02:41:28 INFO - ##### Finished download-and-extract step (success) 02:41:28 INFO - ##### 02:41:28 INFO - ##### 02:41:28 INFO - ##### Running populate-webroot step. 02:41:28 INFO - ##### 02:41:28 INFO - Running pre-action listener: _resource_record_pre_action 02:41:28 INFO - Running main action method: populate_webroot 02:41:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:41:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:41:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:41:28 INFO - '--tppagecycles', 02:41:28 INFO - '7'], 02:41:28 INFO - 'tests': ['tsvgm']}}, 02:41:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:41:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:41:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:41:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:41:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['damp', 'tps']}, 02:41:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'tests': ['damp', 'tps']}, 02:41:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['dromaeo_dom']}, 02:41:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:41:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['basic_compositor_video']}, 02:41:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:41:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'other-e10s': {'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['a11yr', 02:41:28 INFO - 'ts_paint', 02:41:28 INFO - 'tpaint', 02:41:28 INFO - 'sessionrestore', 02:41:28 INFO - 'sessionrestore_no_auto_restore', 02:41:28 INFO - 'tabpaint']}, 02:41:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['tsvgx', 02:41:28 INFO - 'tsvgr_opacity', 02:41:28 INFO - 'tart', 02:41:28 INFO - 'tscrollx', 02:41:28 INFO - 'cart']}, 02:41:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:41:28 INFO - 'tsvgr_opacity', 02:41:28 INFO - 'tart', 02:41:28 INFO - 'tscrollx', 02:41:28 INFO - 'cart']}, 02:41:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'talos_options': ['--disable-e10s'], 02:41:28 INFO - 'tests': ['tp5o']}, 02:41:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'tests': ['tp5o']}, 02:41:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'talos_options': ['--disable-e10s', 02:41:28 INFO - '--xperf_path', 02:41:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:28 INFO - 'tests': ['tp5n']}, 02:41:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:28 INFO - 'talos_options': ['--xperf_path', 02:41:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:28 INFO - 'tests': ['tp5n']}}, 02:41:28 INFO - 'talos.zip': {'path': '', 02:41:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:41:28 INFO - Running post-action listener: _resource_record_post_action 02:41:28 INFO - ##### 02:41:28 INFO - ##### Finished populate-webroot step (success) 02:41:28 INFO - ##### 02:41:28 INFO - ##### 02:41:28 INFO - ##### Running create-virtualenv step. 02:41:28 INFO - ##### 02:41:28 INFO - Running pre-action listener: _resource_record_pre_action 02:41:28 INFO - Running main action method: create_virtualenv 02:41:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:41:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:41:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:41:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:41:28 INFO - Using real prefix '/tools/python27' 02:41:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:41:29 INFO - Installing distribute.............................................................................................................................................................................................done. 02:41:32 INFO - Installing pip.................done. 02:41:32 INFO - Return code: 0 02:41:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:32 INFO - 'HOME': '/Users/cltbld', 02:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:32 INFO - 'LOGNAME': 'cltbld', 02:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:32 INFO - 'MOZ_NO_REMOTE': '1', 02:41:32 INFO - 'NO_EM_RESTART': '1', 02:41:32 INFO - 'PAGER': '/bin/cat', 02:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:32 INFO - 'PWD': '/builds/slave/test', 02:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:32 INFO - 'SHELL': '/bin/bash', 02:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:32 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:32 INFO - 'USER': 'cltbld', 02:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:32 INFO - 'XPC_FLAGS': '0x0', 02:41:32 INFO - 'XPC_SERVICE_NAME': '0', 02:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:41:32 INFO - Cleaning up... 02:41:32 INFO - Return code: 0 02:41:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:32 INFO - 'HOME': '/Users/cltbld', 02:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:32 INFO - 'LOGNAME': 'cltbld', 02:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:32 INFO - 'MOZ_NO_REMOTE': '1', 02:41:32 INFO - 'NO_EM_RESTART': '1', 02:41:32 INFO - 'PAGER': '/bin/cat', 02:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:32 INFO - 'PWD': '/builds/slave/test', 02:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:32 INFO - 'SHELL': '/bin/bash', 02:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:32 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:32 INFO - 'USER': 'cltbld', 02:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:32 INFO - 'XPC_FLAGS': '0x0', 02:41:32 INFO - 'XPC_SERVICE_NAME': '0', 02:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:32 INFO - Downloading/unpacking psutil>=3.1.1 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:41:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:35 INFO - Installing collected packages: psutil 02:41:35 INFO - Running setup.py install for psutil 02:41:35 INFO - building 'psutil._psutil_osx' extension 02:41:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:41:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:41:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:41:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:41:36 INFO - building 'psutil._psutil_posix' extension 02:41:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:41:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:41:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:41:36 INFO - ^ 02:41:36 INFO - 1 warning generated. 02:41:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:41:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:36 INFO - Successfully installed psutil 02:41:36 INFO - Cleaning up... 02:41:36 INFO - Return code: 0 02:41:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:36 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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:41:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:41:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:36 INFO - 'HOME': '/Users/cltbld', 02:41:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:36 INFO - 'LOGNAME': 'cltbld', 02:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:36 INFO - 'MOZ_NO_REMOTE': '1', 02:41:36 INFO - 'NO_EM_RESTART': '1', 02:41:36 INFO - 'PAGER': '/bin/cat', 02:41:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:36 INFO - 'PWD': '/builds/slave/test', 02:41:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:36 INFO - 'SHELL': '/bin/bash', 02:41:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:36 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:36 INFO - 'USER': 'cltbld', 02:41:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:36 INFO - 'XPC_FLAGS': '0x0', 02:41:36 INFO - 'XPC_SERVICE_NAME': '0', 02:41:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41: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 02:41:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:41:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:41:39 INFO - Installing collected packages: mozsystemmonitor 02:41:39 INFO - Running setup.py install for mozsystemmonitor 02:41:39 INFO - Successfully installed mozsystemmonitor 02:41:39 INFO - Cleaning up... 02:41:39 INFO - Return code: 0 02:41:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:39 INFO - 'HOME': '/Users/cltbld', 02:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:39 INFO - 'LOGNAME': 'cltbld', 02:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:39 INFO - 'MOZ_NO_REMOTE': '1', 02:41:39 INFO - 'NO_EM_RESTART': '1', 02:41:39 INFO - 'PAGER': '/bin/cat', 02:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:39 INFO - 'PWD': '/builds/slave/test', 02:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:39 INFO - 'SHELL': '/bin/bash', 02:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:39 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:39 INFO - 'USER': 'cltbld', 02:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:39 INFO - 'XPC_FLAGS': '0x0', 02:41:39 INFO - 'XPC_SERVICE_NAME': '0', 02:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:39 INFO - Downloading/unpacking jsonschema==2.5.1 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:41:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - Downloading functools32-3.2.3-2.tar.gz 02:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:41:42 INFO - warning: no files found matching '*.txt' 02:41:42 INFO - no previously-included directories found matching 'build' 02:41:42 INFO - no previously-included directories found matching 'dist' 02:41:42 INFO - no previously-included directories found matching '.git*' 02:41:42 INFO - Installing collected packages: jsonschema, functools32 02:41:42 INFO - Running setup.py install for functools32 02:41:42 INFO - warning: no files found matching '*.txt' 02:41:42 INFO - no previously-included directories found matching 'build' 02:41:42 INFO - no previously-included directories found matching 'dist' 02:41:42 INFO - no previously-included directories found matching '.git*' 02:41:42 INFO - Successfully installed jsonschema functools32 02:41:42 INFO - Cleaning up... 02:41:42 INFO - Return code: 0 02:41:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:42 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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:42 INFO - 'HOME': '/Users/cltbld', 02:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:42 INFO - 'LOGNAME': 'cltbld', 02:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:42 INFO - 'MOZ_NO_REMOTE': '1', 02:41:42 INFO - 'NO_EM_RESTART': '1', 02:41:42 INFO - 'PAGER': '/bin/cat', 02:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:42 INFO - 'PWD': '/builds/slave/test', 02:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:42 INFO - 'SHELL': '/bin/bash', 02:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:42 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:42 INFO - 'USER': 'cltbld', 02:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:42 INFO - 'XPC_FLAGS': '0x0', 02:41:42 INFO - 'XPC_SERVICE_NAME': '0', 02:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:43 INFO - Downloading/unpacking blobuploader==1.2.4 02:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:45 INFO - Downloading blobuploader-1.2.4.tar.gz 02:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:41:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:41:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:46 INFO - Downloading docopt-0.6.1.tar.gz 02:41:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:41:46 INFO - Installing collected packages: blobuploader, requests, docopt 02:41:46 INFO - Running setup.py install for blobuploader 02:41:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:41:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:41:47 INFO - Running setup.py install for requests 02:41:47 INFO - Running setup.py install for docopt 02:41:47 INFO - Successfully installed blobuploader requests docopt 02:41:47 INFO - Cleaning up... 02:41:47 INFO - Return code: 0 02:41:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:47 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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:47 INFO - 'HOME': '/Users/cltbld', 02:41:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:47 INFO - 'LOGNAME': 'cltbld', 02:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:47 INFO - 'MOZ_NO_REMOTE': '1', 02:41:47 INFO - 'NO_EM_RESTART': '1', 02:41:47 INFO - 'PAGER': '/bin/cat', 02:41:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:47 INFO - 'PWD': '/builds/slave/test', 02:41:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:47 INFO - 'SHELL': '/bin/bash', 02:41:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:47 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:47 INFO - 'USER': 'cltbld', 02:41:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:47 INFO - 'XPC_FLAGS': '0x0', 02:41:47 INFO - 'XPC_SERVICE_NAME': '0', 02:41:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-y6jkjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-y2pIyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-s3rePW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-8W0QxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-NujyGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-JQYOKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-psaiox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-c3GdKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-1sEylA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:48 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-e9DVkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-iac18I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-za2MlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-RsF2l4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-nkNVoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ZJCGS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-w_wN6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:49 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-rynJ3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:41:49 INFO - Running setup.py install for manifestparser 02:41:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:41:49 INFO - Running setup.py install for mozcrash 02:41:50 INFO - Running setup.py install for mozdebug 02:41:50 INFO - Running setup.py install for mozdevice 02:41:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Running setup.py install for mozfile 02:41:50 INFO - Running setup.py install for mozhttpd 02:41:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Running setup.py install for mozinfo 02:41:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Running setup.py install for mozInstall 02:41:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:41:50 INFO - Running setup.py install for mozleak 02:41:51 INFO - Running setup.py install for mozlog 02:41:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Running setup.py install for moznetwork 02:41:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Running setup.py install for mozprocess 02:41:51 INFO - Running setup.py install for mozprofile 02:41:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Running setup.py install for mozrunner 02:41:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:41:51 INFO - Running setup.py install for mozscreenshot 02:41:52 INFO - Running setup.py install for moztest 02:41:52 INFO - Running setup.py install for mozversion 02:41:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:41:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:41:52 INFO - Cleaning up... 02:41:52 INFO - Return code: 0 02:41:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:52 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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:52 INFO - 'HOME': '/Users/cltbld', 02:41:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:52 INFO - 'LOGNAME': 'cltbld', 02:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:52 INFO - 'MOZ_NO_REMOTE': '1', 02:41:52 INFO - 'NO_EM_RESTART': '1', 02:41:52 INFO - 'PAGER': '/bin/cat', 02:41:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:52 INFO - 'PWD': '/builds/slave/test', 02:41:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:52 INFO - 'SHELL': '/bin/bash', 02:41:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:52 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:52 INFO - 'USER': 'cltbld', 02:41:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:52 INFO - 'XPC_FLAGS': '0x0', 02:41:52 INFO - 'XPC_SERVICE_NAME': '0', 02:41:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:52 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-4wg1SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:52 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-RywZKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:52 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-rD4t0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-higNfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-S2khJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-7deAqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-fAeVh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-D0csyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Ous5xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-6MgpIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ZfSS47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Nko7pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:53 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-ZSk9tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-1PWkep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-SvgKW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-OoPg1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-OeKFbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:57 INFO - Downloading blessings-1.6.tar.gz 02:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:41:57 INFO - Installing collected packages: blessings 02:41:57 INFO - Running setup.py install for blessings 02:41:57 INFO - Successfully installed blessings 02:41:57 INFO - Cleaning up... 02:41:57 INFO - Return code: 0 02:41:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:57 INFO - Reading from file tmpfile_stdout 02:41:57 INFO - Current package versions: 02:41:57 INFO - blessings == 1.6 02:41:57 INFO - blobuploader == 1.2.4 02:41:57 INFO - docopt == 0.6.1 02:41:57 INFO - functools32 == 3.2.3-2 02:41:57 INFO - jsonschema == 2.5.1 02:41:57 INFO - manifestparser == 1.1 02:41:57 INFO - mozInstall == 1.12 02:41:57 INFO - mozcrash == 0.17 02:41:57 INFO - mozdebug == 0.1 02:41:57 INFO - mozdevice == 0.48 02:41:57 INFO - mozfile == 1.2 02:41:57 INFO - mozhttpd == 0.7 02:41:57 INFO - mozinfo == 0.9 02:41:57 INFO - mozleak == 0.1 02:41:57 INFO - mozlog == 3.2 02:41:57 INFO - moznetwork == 0.27 02:41:57 INFO - mozprocess == 0.22 02:41:57 INFO - mozprofile == 0.28 02:41:57 INFO - mozrunner == 6.11 02:41:57 INFO - mozscreenshot == 0.1 02:41:57 INFO - mozsystemmonitor == 0.3 02:41:57 INFO - moztest == 0.7 02:41:57 INFO - mozversion == 1.4 02:41:57 INFO - psutil == 3.1.1 02:41:57 INFO - requests == 1.2.3 02:41:57 INFO - wsgiref == 0.1.2 02:41:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:57 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 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:57 INFO - 'HOME': '/Users/cltbld', 02:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:57 INFO - 'LOGNAME': 'cltbld', 02:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:57 INFO - 'MOZ_NO_REMOTE': '1', 02:41:57 INFO - 'NO_EM_RESTART': '1', 02:41:57 INFO - 'PAGER': '/bin/cat', 02:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:57 INFO - 'PWD': '/builds/slave/test', 02:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:57 INFO - 'SHELL': '/bin/bash', 02:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:57 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:57 INFO - 'USER': 'cltbld', 02:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:57 INFO - 'XPC_FLAGS': '0x0', 02:41:57 INFO - 'XPC_SERVICE_NAME': '0', 02:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:41:57 INFO - Cleaning up... 02:41:57 INFO - Return code: 0 02:41:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c20ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdcb4ae2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, '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.9At8n75xUN/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.FU3QmFEIDR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:57 INFO - 'HOME': '/Users/cltbld', 02:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:57 INFO - 'LOGNAME': 'cltbld', 02:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:57 INFO - 'MOZ_NO_REMOTE': '1', 02:41:57 INFO - 'NO_EM_RESTART': '1', 02:41:57 INFO - 'PAGER': '/bin/cat', 02:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:57 INFO - 'PWD': '/builds/slave/test', 02:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:57 INFO - 'SHELL': '/bin/bash', 02:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:41:57 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:57 INFO - 'USER': 'cltbld', 02:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:57 INFO - 'XPC_FLAGS': '0x0', 02:41:57 INFO - 'XPC_SERVICE_NAME': '0', 02:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:41:58 INFO - Cleaning up... 02:41:58 INFO - Return code: 0 02:41:58 INFO - Running post-action listener: _resource_record_post_action 02:41:58 INFO - Running post-action listener: _start_resource_monitoring 02:41:58 INFO - Starting resource monitoring. 02:41:58 INFO - ##### 02:41:58 INFO - ##### Finished create-virtualenv step (success) 02:41:58 INFO - ##### 02:41:58 INFO - ##### 02:41:58 INFO - ##### Running install step. 02:41:58 INFO - ##### 02:41:58 INFO - Running pre-action listener: _resource_record_pre_action 02:41:58 INFO - Running main action method: install 02:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:58 INFO - Reading from file tmpfile_stdout 02:41:58 INFO - Detecting whether we're running mozinstall >=1.0... 02:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:41:58 INFO - Reading from file tmpfile_stdout 02:41:58 INFO - Output received: 02:41:58 INFO - Usage: mozinstall [options] installer 02:41:58 INFO - Options: 02:41:58 INFO - -h, --help show this help message and exit 02:41:58 INFO - -d DEST, --destination=DEST 02:41:58 INFO - Directory to install application into. [default: 02:41:58 INFO - "/builds/slave/test"] 02:41:58 INFO - --app=APP Application being installed. [default: firefox] 02:41:58 INFO - mkdir: /builds/slave/test/build/application 02:41:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:42:33 INFO - Reading from file tmpfile_stdout 02:42:33 INFO - Output received: 02:42:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:42:33 INFO - Running post-action listener: _resource_record_post_action 02:42:33 INFO - ##### 02:42:33 INFO - ##### Finished install step (success) 02:42:33 INFO - ##### 02:42:33 INFO - ##### 02:42:33 INFO - ##### Running run-tests step. 02:42:33 INFO - ##### 02:42:33 INFO - Running pre-action listener: _resource_record_pre_action 02:42:33 INFO - Running main action method: run_tests 02:42:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:33 INFO - 2016-08-02 02:42:33.165 screenresolution[1648:8361] starting screenresolution argv=screenresolution get 02:42:33 INFO - 2016-08-02 02:42:33.167 screenresolution[1648:8361] Display 0: 1600x1200x32@60 02:42:33 INFO - 2016-08-02 02:42:33.173 screenresolution[1649:8363] starting screenresolution argv=screenresolution list 02:42:33 INFO - Available Modes on Display 0 02:42:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:33 INFO - Intel Iris: 02:42:33 INFO - Chipset Model: Intel Iris 02:42:33 INFO - Type: GPU 02:42:33 INFO - Bus: Built-In 02:42:33 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:33 INFO - Vendor: Intel (0x8086) 02:42:33 INFO - Device ID: 0x0a2e 02:42:33 INFO - Revision ID: 0x0009 02:42:33 INFO - Displays: 02:42:33 INFO - Display: 02:42:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:33 INFO - Display Serial Number: 1600x1200 60 02:42:33 INFO - Main Display: Yes 02:42:33 INFO - Mirror: Off 02:42:33 INFO - Online: Yes 02:42:33 INFO - Rotation: Supported 02:42:33 INFO - Return code: 0 02:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:42:33 INFO - Python 2.7.3 02:42:33 INFO - Return code: 0 02:42:33 INFO - grabbing minidump binary from tooltool 02:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:33 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 0x10c172d30>, '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 0x10bed7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c16ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:42:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:42:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:42:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:42:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:42:33 INFO - Return code: 0 02:42:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:42:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:42:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:42:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:42:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9At8n75xUN/Render', 02:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:33 INFO - 'HOME': '/Users/cltbld', 02:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:33 INFO - 'LOGNAME': 'cltbld', 02:42:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:42:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:33 INFO - 'MOZ_NO_REMOTE': '1', 02:42:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:42:33 INFO - 'NO_EM_RESTART': '1', 02:42:33 INFO - 'PAGER': '/bin/cat', 02:42:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:33 INFO - 'PWD': '/builds/slave/test', 02:42:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:33 INFO - 'SHELL': '/bin/bash', 02:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU3QmFEIDR/Listeners', 02:42:33 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 02:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:33 INFO - 'USER': 'cltbld', 02:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:33 INFO - 'XPC_FLAGS': '0x0', 02:42:33 INFO - 'XPC_SERVICE_NAME': '0', 02:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:42:33 INFO - mozversion application_buildid: 20160802002149 02:42:33 INFO - mozversion application_changeset: 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 02:42:33 INFO - mozversion application_display_name: Nightly 02:42:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:42:33 INFO - mozversion application_name: Firefox 02:42:33 INFO - mozversion application_remotingname: firefox 02:42:33 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 02:42:33 INFO - mozversion application_vendor: Mozilla 02:42:33 INFO - mozversion application_version: 50.0a1 02:42:33 INFO - mozversion platform_buildid: 20160802002149 02:42:33 INFO - mozversion platform_changeset: 375ae3ccf50bc90621263cb202e6cc2e7cd7c028 02:42:33 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 02:42:33 INFO - mozversion platform_version: 50.0a1 02:42:33 INFO - using testdate: 1470130953 02:42:33 INFO - actual date: 1470130953 02:42:33 INFO - starting webserver on 'localhost:49273' 02:42:33 INFO - SUITE-START | Running 2 tests 02:42:33 INFO - TEST-START | dromaeo_css 02:42:33 INFO - operating with platform_type : mac_ 02:42:33 INFO - Initialising browser for dromaeo_css test... 02:42:33 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpB4Xwde/profile http://localhost:49273/getInfo.html) 02:42:36 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 02:42:36 INFO - PROCESS | 1659 | colorDepth:24 02:42:36 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 02:42:36 INFO - PROCESS | 1659 | __metrics 02:42:36 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:42:36 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:42:37 INFO - TEST-INFO | 1659: exit 0 02:42:37 INFO - Browser initialized. 02:42:37 INFO - Running cycle 1/1 for dromaeo_css test... 02:42:37 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpB4Xwde/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:49 INFO - PROCESS | 1663 | RSS: Main: 243404800 02:42:49 INFO - PROCESS | 1663 | 02:43:40 INFO - PROCESS | 1663 | 1470131020407 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] 02:45:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 02:45:52 INFO - PROCESS | 1663 | RSS: Main: 333852672 02:45:52 INFO - PROCESS | 1663 | 02:48:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 02:48:56 INFO - PROCESS | 1663 | RSS: Main: 380231680 02:48:56 INFO - PROCESS | 1663 | 02:51:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 02:51:59 INFO - PROCESS | 1663 | RSS: Main: 369688576 02:51:59 INFO - PROCESS | 1663 | 02:55:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 02:55:02 INFO - PROCESS | 1663 | RSS: Main: 371204096 02:55:02 INFO - PROCESS | 1663 | 02:58:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 02:58:06 INFO - PROCESS | 1663 | RSS: Main: 337035264 02:58:06 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 03:01:09 INFO - PROCESS | 1663 | RSS: Main: 431742976 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | __start_tp_report 03:01:09 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:01:09 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:01:09 INFO - PROCESS | 1663 | |i|pagename|runs| 03:01:09 INFO - PROCESS | 1663 | |0;dojo.html;3343.6656334366558;3364.1925937774927;3386.4581666933295;3390.932181356373;3425.229323402235;5602.85992850179;5626.099824028156;5654.434556544344;5706.800261990826;5707.286589176353;4664.906701865969;5634.521065709419;5734.971325143369;5750.7412166452505;5753.223314852494;4975.776090075937;5094.668846524983;5128.589712822965;5135.332406787113;5252.789888404459;5249.842504724846;5258.263843061955;5270.446603106676;5303.443138470463;5330.413654498021;5102.897942041139;5144.202648589475;5145.6398052136365;5153.819616313449;5217.58259339252;5157.200633901752;5167.741612919339;5170.844874653766;5173.224016397533;5190.92213616796;5064.366954130734;5067.189249720064;5087.669301495428;5121.28302037715;5146.279520867081;5621.747021384048;5702.429757024306;5770.278715160605;5776.4801167894675;5787.826365209051;6067.1202675611785;6124.938750612481;6187.87624247517;6190.226221722285;6214.65819379934;6202.3797620238065;6206.875862482771;6216.471599914013;6237.875242495124;6250.124982502453;5834.27071616048;5886.323072846678;5887;5899.439553242435;5905.881882362329;6090.49389802587;6162.322144564095;6230.376962303824;6253.53098517613;6263.467605253604;6120.418560236771;6141.447269745744;6148.477379422709;6149.446549810538;6157.014877619559;6225.657588832658;6229;6230.376962303824;6234.2830574484515;6251.937480625135;6083.513318934474;6108.6759897029115;6113.172264937315;6172.074188871616;6172.444479996732;6161.383861613893;6194.690265486708;6202.1937148171355;6207.96896015517;6228.688565571703;4941.925871111936;4946.480619534969;4953.207486802166;4962.206047032458;4979.0788704089855;4425.579569940915;4429.468463784367;4438.112377524443;4448.7108337957925;4475.753833539122;5759.308882934075;5761.567882408753;5782.2483077199695;5784.074548072288;5818.5054270386645;5221.242919776611;6286.811395658137;6289.402506761941;6302.369763023753;6304.464120549713;5663.093904975184;5709.25779648643;5724.570657200727;5748;5753.8561535961935;5374.70439125844;5389.218563308298;5436.728163591804;5441.6190866639745;5456.236126942231;6202.162708034448;6224.564280500322;6248.687565621701;6264.436200741955;6266.342034086447;6297.748090076346;6307.463865571294;6312.43188113072;6312.84217894538;6318.304986451395;6173.290071641639;6233.937660623335;6236.563440559117;6244.375562443719;6246.968765156145;6199.473044791243;6205.689715514297;6207.131001659681;6219.40915613016;6219.844503887439;5550.639208451437;6151.954167791576;6295.055741638607;6296.653684047421;6300.149479820166;5954.612950158225;6031.396860313934;6070.726817293144;6086.482648974709;6110.480609148272;5916.26046744157;6042.425969532887;6065.8180254592435;6085.391460853879;6115.8776824462375;5985.072313791369;5992.13114098462;6006.489448396936;6011.909821352596;6070.998285283055;5979.671118088547;6084.056971169476;6102.572819902564;6116.35977301945;6131.448169664752;5974.671393073423;6027.487663548647;6035.245594300713;6048;6062.90905636407 03:01:09 INFO - PROCESS | 1663 | |1;ext.html;507.21888292030263;516.2127755173361;539.3366159623663;550.5375484592942;554.6921458590481;29169.56245656317;30865.537016944734;31041.534376984335;39239.60760392402;40475.79762101193;41746.373804392955;41997.37003944936;42105.15789684204;42125.946851328736;42300.94247643803;9494.810103797938;9674.177694895907;9677.419354838697;9678.661246856362;9686.176674982635;8589.656413743474;8781.34139939504;9532.618695252182;11559.133065020114;11817.822732659017;11648.475818588142;11717.472713727902;11723.7069073273;11741.471633776458;11762.823557646641;11661.70845728855;11662.241954272988;11673.766524669543;11694.181407301492;11709.765804683944;8543.786405339853;8778.868316975251;8779.253763430115;8806.86789698155;11275.436228188557;38328;40386.19227615431;40407.39388909169;40426.39360409596;40623.39064914029;3401.4727717203873;3408;3485.076454739496;3493.7729047611824;3584.946225806615;30845.69154308473;30998.535021974687;31046.534301985488;31444.842775786205;32243.19392015195;3233.1593785615673;3234.7897386669792;3243.594550681165;3256.478963365874;3399.0312760863035;519662;519822;522424;524103;525616;49303.06969303041;56301;57274.56813579694;57355.71322143446;60246;59069.704651476466;59435.702821486484;59497;59557;59814.401855981756;15822.683546329008;15993.520194394187;16020.359185632677;16020.599485012968;16041.037537747772;11414.94292528532;11609.129315301387;12431.502739890304;12509.311987840756;12543.686407839694;15063.698726025637;15323.31045102973;15373.846261537241;15596.454124105601;15751.68496630061;7138.750143745048;7195.928040719526;7217.314355136254;7238.819029524304;7241.312075352833;927.6521304510809;1043.838205078214;1122.680036189682;1126.2679258482053;1173.906087512997;25581.616275755878;27673;29564.704352956196;30144.24639383989;30546.84726576353;1089.145021158387;1101.4602844606086;1114.3258328711174;1122.9494672739747;1166.807476766324;14245.928770356082;14889.851101488846;15038.097714137186;15387.922845400715;15470.303836327392;683020;690437;691254;691496.5425172842;691976;49302;54929.62675933134;55152.72423637856;55415.72292138513;55465.44534554603;974.668612671695;1119.9832002519806;1201.0571701214506;1307.4443361571293;1410.4569740649924;28848.42303153883;29672.851635741685;30099.247518811328;30369.088927332214;30491.847540763043;48581.75709121432;54652.72673636765;56205.875882481305;56325.71837140788;58863.70568147132;513355;639041;640223;640435;641442;23225;23671.64492532579;23717.695526745505;26058;26506.867465662548;39666;40575.59424405836;47170;48109;48136;38404.039899002746;39282.803585981885;39339.01652458597;39510.604893950695;40084;38662.22675546417;38950.49445549914;39001.414978774774;39181.21637567176;39480.40779388254;37894.431583525715;38572.2285554293;38711.22577548377;38757.224855502165;38784.80607596944;28588.14235572936;30270.54594181133;34463;34469.31061378808;34589.962301131;25143.49713005766;25360.365990850376;25462.745372546036;25524.59614721208;26094.86952565225 03:01:09 INFO - PROCESS | 1663 | |2;jquery.html;301.3415686724507;371.4780733070037;379;391.1375417205063;401.18759511988236;19599.902000489994;20761.584768304645;20964.475888102806;21000.26499072533;21305.68041479375;17403;20000.999950002515;20093.59812803743;21034.948252587386;21069;5716.857078573038;5816.214811000503;5894.8231553053265;5926.88146237075;6569.835754106161;6484.221893372802;6514.609123452585;6529.934700652981;6535.411812936834;6548.5088618353575;6366.236051673805;6389.840253993664;6523.934760652404;6532.183477065367;6543.149390579218;6397.8400539986405;6523.478121750272;6536.084948107269;6542.34576542342;6553.770618028394;5486.286782718261;5593.412691667398;5630.380658127603;5634.098544232903;5645.125005624135;26297.868510657514;26642.20073397801;26971.190864274104;27048.91804327844;27088.593671094754;2282.6005449046515;2471.0856982916434;2474.3566672665056;2554.948901021969;3098.643655979546;9826.65606703762;10318.845217321747;10357.482125893675;10527.631532896312;16017.439389621424;1884.415831092352;1899.2782742557877;1899.4111825334053;1922.423273018103;1929.3440230321758;70574;97959.50703353615;116533;117883.82116178815;119338;11287.153463490107;11573.76852462946;11648.94175529117;11684.649460516199;11825.467853946593;14479.420823167166;14595.78106328406;14606.12363258208;14624.85375146235;14624.853751462562;8342.791430214173;8511.57442127891;8517.531535765593;8614.18165274298;8647.437916535406;7375.44684148691;8116.391270654724;8413.284870786052;8436.578171091422;8499.490030598185;4465.173942820588;4556.225441674923;4566.794494247767;4606.976965115153;4621.976890115528;1881.7365568820376;1934.6711059119702;1967.390109066194;1972.2012584903136;1985.9304924327869;866.7269810009826;905.9264771246094;949.7483166960761;967.2165545907837;969.2778879734643;20270.89864550697;21097.78902210989;21448.816230567227;21520.677189842165;21552.814595197415;897.3359713811732;931.0503286647581;941.5010044676195;1022.3916769522123;1040.7606250562412;10545.945405459546;10958.958898904591;11055.613053543088;11060.834087488694;11071.833922491169;536333.1383460063;588485;593283;595928.0203598954;603559;95064.52467737618;113281.30078048835;119122.40438797924;126056;130308;958.9760255993656;965.6330594374233;1008.8587597736323;1038.1227862456062;1039.9428031458192;15589.29848156813;18431.80193287432;18447.815521845147;19561.315353962542;19660;55877.44122558722;55904.72047639899;56744.71627641835;57498;58139.127913082186;81208;85291.5735421319;86104.1389586096;86626.56686716527;86714;7858;8106.716264930935;8128.63421146038;8165.428420010542;8401.07588165289;9210.032946540785;12289.87710122923;12927.224366538037;13261.66845828862;13290.933545332598;10755.569777208824;10879;11329.923657252548;11540.192186546861;11724.472398742248;9961.651342202988;10497.16022718196;11304.773904522028;11388.487518061542;11546.59586914454;11502.48238829238;11667.883321167019;11791.410429478321;11877.22798018126;11917.642470725892;8212.219839115274;8285.130061343472;8342;8393.790155246168;8519.233269005572;8331.916680833356;9476.526173691425;11404.48679809427;11458.770824583295;11568.595099171489 03:01:09 INFO - PROCESS | 1663 | |3;mootools.html;1428.5285855667628;1530.0666593378037;1736.9330496708915;1899.8955057471833;1923.701826216936;8598.785030374236;8925.509096999678;9069.274458043348;9070.863937040933;9078.63685452582;7664.92335076649;7918.0894197167345;8160.428769986103;8393.160683931605;8747.256483198937;2313.410080429492;2387.892544835478;2389.9283021509295;2390.3307074019303;2390.40239940015;2121.6499277619173;2354.105439932832;2374.1453076892303;2399.916002939897;2400.747921468238;2176.6952626632205;2300.64340027296;2359.587072262347;2365.2431222008963;2375.798057165143;2347.730011048734;2364.361622361961;2376.5365753678125;2382.630692242705;2382.964255536151;2344.4373350395954;2350.7061617297836;2380.19073515004;2380.750021247761;2382.4401265702545;8676.8698469523;8756.605952732143;8757.386982911199;8760.737177884674;8778.209961103468;5285.339332583427;5433.755481003364;5438.592105592096;5439.184122381635;5444.945550544483;3594.370985077601;3665.2852693724467;3722.1066943933533;3744.9625503744887;3767.340715374826;1068.3483075324045;1100.3947828694186;1125.0380924309761;1126.0934947367366;1130.604288499027;11481.196316257865;11657.650270491898;11676.76646467066;11731.530738770523;11791.997680197193;4103.958960410418;4574.176648203289;4652.651051171176;4836.056968891103;4839.95160048395;10049.397036177852;10095.44475053879;10102.595896164072;10104.545295461721;10105.747356316151;5376.596755243294;5387.191921211849;5387.488188622075;5388.7305634718105;5390.056740070472;4746.5253474652945;4908.558229759339;4912.975435122873;4915.139850526215;4939.135651261015;3825.617438256152;3834.9616503835164;3859.864904728377;3889.611038896088;3933.449317095609;1009.9899001010043;1110.6667999600088;1128.136975213962;1166.6558365282262;1175.3770501634147;788.4717239449583;883.8598208311334;931.5202670624634;934.2292608597934;992.528548939248;5981.102834574848;6001.729922153426;6185.536084793568;6255.593386429868;6347.587406818634;899.6626265150569;947.8483442649143;974.8050389922043;986.921046316293;1008.742770593494;3965.5241371035086;4001.9799901000893;4088.875559221238;4094.488188976378;4175.102352994121;10423.42671153094;10923.617673381394;10936.890631093745;10958.726031849108;11024.889751102546;9929.751756206153;10296.07335339823;10799.730006749736;10884.183686223565;10920.071793897609;7593.202713715091;7779.922200778033;7937.3650107991225;8206;8253.09215986253;4486.349479325545;5995.850103747442;6108.236470441195;6230.50155987529;6232.843210652881;9958.651447199314;10129.341592796445;10133.39199647994;10137.239707021856;10154.086132248132;9447.763805904908;9972.052654997764;10340.793184136426;10361.326513776581;10375.429351385746;7659.234076592296;7683.116441609063;7764.262395072459;7764.805879853049;7773;6168.475679567107;8237.352529494005;8260.421770476249;8343.791405214919;8381.58092095405;6217.658028808278;6568.408843203943;7474.962625186839;7622.618869056636;7627.694892204249;7409.888851667337;7437.590932498548;7509.249075092447;7526.887096693445;7588.354989825927;7140.857182856417;7151.570905745672;7206.243344448757;7240.275972402717;7250.746223882139;5069.188929771219;5090.26191202281;5091.770870310771;5118.257852611276;5196.636235463481;5059.038782631288;5062.873428164326;5125.136017410258;5142.6400151989;5298.470152984671 03:01:09 INFO - PROCESS | 1663 | |4;prototype.html;297.9970200297996;302.8243618701153;309.44300259532844;314.35243398598885;315.3377906396566;4387.670924680646;5251.684898906068;5309.964556911404;5325.760340784674;5334.89330213396;4943.876403089916;4983.975080124604;5188.195829646402;5257.631965762398;5276.393214780302;3890.7744060620957;3994.900127496807;4037.9798101009455;4059.6955228357842;4060.167665628539;3529.6823285904266;3633.981830090846;4068.1863627274515;4070.694697897655;4071.9389209161886;3162.1778337632268;3816.0650640593094;3983.880483585497;3988.2023595280916;4018.1361007383407;3966.6231707987695;3978.5026871641044;3984.900377490557;3986.7641031280396;4019.7588144711262;2934.4864648686607;3144.4497212987862;3273.9017829465156;3499.687617143571;3518.6129525752403;4929.003753479847;5129.333186685744;5149.639525233235;5168.405633352175;5242.606804489678;4151.605597468236;4564.634829213688;4586.885327866797;4619.145458090264;4651.44182698075;5025.84922452327;5084.084864724348;5456.7544460499375;5492.670439773627;5540.390557038722;3579.266250418658;3661.6521430464063;3898.538048231913;3909.9413508797393;3912.647861692433;5714.399988001242;5729.541636669098;5729.828105156852;5754.597178197528;5762.4525670061275;3013.322002549409;3566.9477504135825;3930.7248492605813;3963.3856752203455;3963.6829053675638;3380.7464440167087;4012.27778999777;4023.2556976959354;4025.7383270087353;4045.37296719009;3289.9506507402407;4277.529471758104;4456.019675671347;4502.279635258343;4536.049498715518;5519.751611177507;5521;5529.806456773994;5532.7786888524815;5558.638688485235;1223.5411720604773;1263.2041813657338;1268.5750273658243;1286.7490839286436;1330.647378444713;740.6555951482586;866.3329236487995;873.4759144513368;892.5671049540967;898.8292224477328;4648.163330600457;5037.672551284155;5225.6603320784025;5246.554042906396;5265.315508983807;4770.379850619467;5188.299579556787;5396.055690254189;5671.404502527258;5674.319081710139;4634.907301853944;4799.064182484382;4931.802727890916;5377.327834020747;5452.427495113036;2256.5825322315422;2645.0742240215773;2879.568064790298;2956.423497418026;2998.5652080448212;5583.944160558342;5868.765249390062;5875.9118613220835;5917.822465326047;5928.318243402064;4971.701697898137;5003.974980125076;5156.303898973665;5474.863128421821;5799.88400231993;4910.165271903714;5849.444302791228;5876.764929402776;5881.441263080086;5906.734196961147;4468.329750537446;5000.5999520037585;5360.6681093724355;5620.353659329229;5679.466544033171;4549.293170563509;4687.992081702314;5489.505944465017;5524.530414914777;5544.3346798384455;5137.434882163034;5817.331006934088;5848.912266316093;5874.559408044328;5924.837434336522;3549.2369140634887;3810.8567429770405;3820.3696390095324;3852.171783066654;3867.207222519433;4765.404324459442;5364.061289274439;5641.12562552805;5674.886502269849;5682.636167319896;5018.5232402921665;5443.8773738846085;5517.924004819021;5553.750081246273;5578.944210558005;5325.988062268157;5340.599455040965;5453.563714902719;5485.424030476743;5518.310211223597;4353.49934757507;4837.975810120927;5135.640505164752;5219.19102539107;5346.34263378354;5280.419153892992;5478.287822583039;5524.364698059614;5553.8333849984565;5576.159867243881;4763.404574428197;5204.687718736736;5377.8117765878005;5422.783088676409;5461.808836690697 03:01:09 INFO - PROCESS | 1663 | |5;yui.html;661.8742438936602;751.7594369801666;790.885321628364;823.2467292427425;843.8270154618303;14901.180435076067;16159.515214543553;16193.352265909349;16202.513924582267;16213.270402831871;14282.357293921772;14609.707805843876;14799.704005919875;14815.92592037041;15007.624809379771;836.0886633569423;862.5169904853302;864.3301441382915;871.3900269811126;873.3668090634303;670.5674839728372;710.147822612864;816.6161903905155;869.1048220333067;871.0288028847853;919.2875521470846;919.3059240273584;919.3472634429563;919.3794188922484;921.0329154387899;701.6702149989496;912.5802131019739;914.8719179314901;917.6375331743991;918.8943271523793;630.6657471540091;669.8024082895557;673.9923813898254;678.281022116558;678.5860625018735;14725.926370368186;14764;14771.33528991198;14777.778333325008;14802.925985370004;13631.863681363258;13959.651008724863;13979.37092830825;14153.504627338096;14158;12766.872331276754;12801.167924084904;12923.09538332317;13040.739185216338;13276.203427794364;4518.841840535599;4662.860114196579;4753.263244197155;4761.523847615245;4804.20730579454;111634;113179;113490;113638.59084227569;113985;10779;10845.566177352817;10989;11045;11147.38689372076;20758.377248682562;20953.16187352489;20974.951252437317;20977.475563111046;21018.159273628884;7208.56748595086;7269.091363579552;7281.927180728125;7350.889736653954;7370.005049318381;4401.207782599099;4403.669724770655;4458.398116254264;4459.910801783946;4609.193391156601;3455.6889879910927;3518.9824050879583;3575.6424357564556;3580.427131658922;3585.15748799032;586.9405722670535;665.9001149827468;671.4594751225213;674.6053558668234;675.3850337445245;593.5133190783603;622.5362105231632;639.3574457670012;649.4155260265799;652.6116960408549;6740.4270636995425;8025.796130580343;8525.65897364111;9122.635094596142;9236.538173091452;604.2054698072021;633.2107068363773;635.9077933699588;641.4739913271073;643.7585905285518;4273.722208056466;4320.243957307522;4325.370885084277;4379.715318504287;4521.186186486464;79691;158037.2098139502;159775;161321;161871;88487.55756221178;125630;128679.35660321638;133181;138402.61597383898;72071.27928720646;74611;76824;110646.99999999838;111226;8246.299064579578;8458.154184581492;8500.91499085026;8542.06037335905;8634.309255259439;64135;64727.67636161789;64893.67553162204;65045;65982;67168.6641566789;87120.1287987112;90888.09111908796;91593.5420322894;92620;11318.151138664469;11368.943155284502;11748.236364636272;11941;12043.578474753342;11959.641210763692;12078.403143324227;12198;12571.49714011466;12641.178323408949;11768.337922689569;11973.461194246105;12110;12226.266424014588;12294.486103227448;11811.704707382385;11830.46762895689;12004.579839705568;12221.511139554674;12244.755104897675;12421.31682757457;13440.822781662535;13613.52352667652;13615.251161185834;14212.289385530888;10540.57837686484;11209.271397359145;11234.606788762354;11637.592684256011;12010.159288849696;10469.633562825264;10748.301360411548;10837.566497340018;11069.335839849635;11610.88389116098 03:01:09 INFO - PROCESS | 1663 | __end_tp_report 03:01:09 INFO - PROCESS | 1663 | __start_cc_report 03:01:09 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1267 03:01:09 INFO - PROCESS | 1663 | __end_cc_report 03:01:09 INFO - PROCESS | 1663 | __startTimestamp1470132069882__endTimestamp 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:01:09 INFO - PROCESS | 1663 | Number of tests: 6 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5727.35 Median:5992.13 stddev:638.04 (10.6%) stddev-sans-first:611.99 03:01:09 INFO - PROCESS | 1663 | Values: 3343.7 3364.2 3386.5 3390.9 3425.2 5602.9 5626.1 5654.4 5706.8 5707.3 4664.9 5634.5 5735.0 5750.7 5753.2 4975.8 5094.7 5128.6 5135.3 5252.8 5249.8 5258.3 5270.4 5303.4 5330.4 5102.9 5144.2 5145.6 5153.8 5217.6 5157.2 5167.7 5170.8 5173.2 5190.9 5064.4 5067.2 5087.7 5121.3 5146.3 5621.7 5702.4 5770.3 5776.5 5787.8 6067.1 6124.9 6187.9 6190.2 6214.7 6202.4 6206.9 6216.5 6237.9 6250.1 5834.3 5886.3 5887.0 5899.4 5905.9 6090.5 6162.3 6230.4 6253.5 6263.5 6120.4 6141.4 6148.5 6149.4 6157.0 6225.7 6229.0 6230.4 6234.3 6251.9 6083.5 6108.7 6113.2 6172.1 6172.4 6161.4 6194.7 6202.2 6208.0 6228.7 4941.9 4946.5 4953.2 4962.2 4979.1 4425.6 4429.5 4438.1 4448.7 4475.8 5759.3 5761.6 5782.2 5784.1 5818.5 5221.2 6286.8 6289.4 6302.4 6304.5 5663.1 5709.3 5724.6 5748.0 5753.9 5374.7 5389.2 5436.7 5441.6 5456.2 6202.2 6224.6 6248.7 6264.4 6266.3 6297.7 6307.5 6312.4 6312.8 6318.3 6173.3 6233.9 6236.6 6244.4 6247.0 6199.5 6205.7 6207.1 6219.4 6219.8 5550.6 6152.0 6295.1 6296.7 6300.1 5954.6 6031.4 6070.7 6086.5 6110.5 5916.3 6042.4 6065.8 6085.4 6115.9 5985.1 5992.1 6006.5 6011.9 6071.0 5979.7 6084.1 6102.6 6116.4 6131.4 5974.7 6027.5 6035.2 6048.0 6062.9 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:73071.93 Median:38366.02 stddev:164368.44 (428.4%) stddev-sans-first:164739.24 03:01:09 INFO - PROCESS | 1663 | Values: 507.2 516.2 539.3 550.5 554.7 29169.6 30865.5 31041.5 39239.6 40475.8 41746.4 41997.4 42105.2 42125.9 42300.9 9494.8 9674.2 9677.4 9678.7 9686.2 8589.7 8781.3 9532.6 11559.1 11817.8 11648.5 11717.5 11723.7 11741.5 11762.8 11661.7 11662.2 11673.8 11694.2 11709.8 8543.8 8778.9 8779.3 8806.9 11275.4 38328.0 40386.2 40407.4 40426.4 40623.4 3401.5 3408.0 3485.1 3493.8 3584.9 30845.7 30998.5 31046.5 31444.8 32243.2 3233.2 3234.8 3243.6 3256.5 3399.0 519662.0 519822.0 522424.0 524103.0 525616.0 49303.1 56301.0 57274.6 57355.7 60246.0 59069.7 59435.7 59497.0 59557.0 59814.4 15822.7 15993.5 16020.4 16020.6 16041.0 11414.9 11609.1 12431.5 12509.3 12543.7 15063.7 15323.3 15373.8 15596.5 15751.7 7138.8 7195.9 7217.3 7238.8 7241.3 927.7 1043.8 1122.7 1126.3 1173.9 25581.6 27673.0 29564.7 30144.2 30546.8 1089.1 1101.5 1114.3 1122.9 1166.8 14245.9 14889.9 15038.1 15387.9 15470.3 683020.0 690437.0 691254.0 691496.5 691976.0 49302.0 54929.6 55152.7 55415.7 55465.4 974.7 1120.0 1201.1 1307.4 1410.5 28848.4 29672.9 30099.2 30369.1 30491.8 48581.8 54652.7 56205.9 56325.7 58863.7 513355.0 639041.0 640223.0 640435.0 641442.0 23225.0 23671.6 23717.7 26058.0 26506.9 39666.0 40575.6 47170.0 48109.0 48136.0 38404.0 39282.8 39339.0 39510.6 40084.0 38662.2 38950.5 39001.4 39181.2 39480.4 37894.4 38572.2 38711.2 38757.2 38784.8 28588.1 30270.5 34463.0 34469.3 34590.0 25143.5 25360.4 25462.7 25524.6 26094.9 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34371.79 Median:13694.97 stddev:97191.11 (709.7%) stddev-sans-first:97430.09 03:01:09 INFO - PROCESS | 1663 | Values: 301.3 371.5 379.0 391.1 401.2 19599.9 20761.6 20964.5 21000.3 21305.7 17403.0 20001.0 20093.6 21034.9 21069.0 5716.9 5816.2 5894.8 5926.9 6569.8 6484.2 6514.6 6529.9 6535.4 6548.5 6366.2 6389.8 6523.9 6532.2 6543.1 6397.8 6523.5 6536.1 6542.3 6553.8 5486.3 5593.4 5630.4 5634.1 5645.1 26297.9 26642.2 26971.2 27048.9 27088.6 2282.6 2471.1 2474.4 2554.9 3098.6 9826.7 10318.8 10357.5 10527.6 16017.4 1884.4 1899.3 1899.4 1922.4 1929.3 70574.0 97959.5 116533.0 117883.8 119338.0 11287.2 11573.8 11648.9 11684.6 11825.5 14479.4 14595.8 14606.1 14624.9 14624.9 8342.8 8511.6 8517.5 8614.2 8647.4 7375.4 8116.4 8413.3 8436.6 8499.5 4465.2 4556.2 4566.8 4607.0 4622.0 1881.7 1934.7 1967.4 1972.2 1985.9 866.7 905.9 949.7 967.2 969.3 20270.9 21097.8 21448.8 21520.7 21552.8 897.3 931.1 941.5 1022.4 1040.8 10545.9 10959.0 11055.6 11060.8 11071.8 536333.1 588485.0 593283.0 595928.0 603559.0 95064.5 113281.3 119122.4 126056.0 130308.0 959.0 965.6 1008.9 1038.1 1039.9 15589.3 18431.8 18447.8 19561.3 19660.0 55877.4 55904.7 56744.7 57498.0 58139.1 81208.0 85291.6 86104.1 86626.6 86714.0 7858.0 8106.7 8128.6 8165.4 8401.1 9210.0 12289.9 12927.2 13261.7 13290.9 10755.6 10879.0 11329.9 11540.2 11724.5 9961.7 10497.2 11304.8 11388.5 11546.6 11502.5 11667.9 11791.4 11877.2 11917.6 8212.2 8285.1 8342.0 8393.8 8519.2 8331.9 9476.5 11404.5 11458.8 11568.6 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5730.48 Median:4914.06 stddev:3197.23 (65.1%) stddev-sans-first:3189.85 03:01:09 INFO - PROCESS | 1663 | Values: 1428.5 1530.1 1736.9 1899.9 1923.7 8598.8 8925.5 9069.3 9070.9 9078.6 7664.9 7918.1 8160.4 8393.2 8747.3 2313.4 2387.9 2389.9 2390.3 2390.4 2121.6 2354.1 2374.1 2399.9 2400.7 2176.7 2300.6 2359.6 2365.2 2375.8 2347.7 2364.4 2376.5 2382.6 2383.0 2344.4 2350.7 2380.2 2380.8 2382.4 8676.9 8756.6 8757.4 8760.7 8778.2 5285.3 5433.8 5438.6 5439.2 5444.9 3594.4 3665.3 3722.1 3745.0 3767.3 1068.3 1100.4 1125.0 1126.1 1130.6 11481.2 11657.7 11676.8 11731.5 11792.0 4104.0 4574.2 4652.7 4836.1 4840.0 10049.4 10095.4 10102.6 10104.5 10105.7 5376.6 5387.2 5387.5 5388.7 5390.1 4746.5 4908.6 4913.0 4915.1 4939.1 3825.6 3835.0 3859.9 3889.6 3933.4 1010.0 1110.7 1128.1 1166.7 1175.4 788.5 883.9 931.5 934.2 992.5 5981.1 6001.7 6185.5 6255.6 6347.6 899.7 947.8 974.8 986.9 1008.7 3965.5 4002.0 4088.9 4094.5 4175.1 10423.4 10923.6 10936.9 10958.7 11024.9 9929.8 10296.1 10799.7 10884.2 10920.1 7593.2 7779.9 7937.4 8206.0 8253.1 4486.3 5995.9 6108.2 6230.5 6232.8 9958.7 10129.3 10133.4 10137.2 10154.1 9447.8 9972.1 10340.8 10361.3 10375.4 7659.2 7683.1 7764.3 7764.8 7773.0 6168.5 8237.4 8260.4 8343.8 8381.6 6217.7 6568.4 7475.0 7622.6 7627.7 7409.9 7437.6 7509.2 7526.9 7588.4 7140.9 7151.6 7206.2 7240.3 7250.7 5069.2 5090.3 5091.8 5118.3 5196.6 5059.0 5062.9 5125.1 5142.6 5298.5 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4453.47 Median:5022.19 stddev:1378.46 (27.4%) stddev-sans-first:1346.58 03:01:09 INFO - PROCESS | 1663 | Values: 298.0 302.8 309.4 314.4 315.3 4387.7 5251.7 5310.0 5325.8 5334.9 4943.9 4984.0 5188.2 5257.6 5276.4 3890.8 3994.9 4038.0 4059.7 4060.2 3529.7 3634.0 4068.2 4070.7 4071.9 3162.2 3816.1 3983.9 3988.2 4018.1 3966.6 3978.5 3984.9 3986.8 4019.8 2934.5 3144.4 3273.9 3499.7 3518.6 4929.0 5129.3 5149.6 5168.4 5242.6 4151.6 4564.6 4586.9 4619.1 4651.4 5025.8 5084.1 5456.8 5492.7 5540.4 3579.3 3661.7 3898.5 3909.9 3912.6 5714.4 5729.5 5729.8 5754.6 5762.5 3013.3 3566.9 3930.7 3963.4 3963.7 3380.7 4012.3 4023.3 4025.7 4045.4 3290.0 4277.5 4456.0 4502.3 4536.0 5519.8 5521.0 5529.8 5532.8 5558.6 1223.5 1263.2 1268.6 1286.7 1330.6 740.7 866.3 873.5 892.6 898.8 4648.2 5037.7 5225.7 5246.6 5265.3 4770.4 5188.3 5396.1 5671.4 5674.3 4634.9 4799.1 4931.8 5377.3 5452.4 2256.6 2645.1 2879.6 2956.4 2998.6 5583.9 5868.8 5875.9 5917.8 5928.3 4971.7 5004.0 5156.3 5474.9 5799.9 4910.2 5849.4 5876.8 5881.4 5906.7 4468.3 5000.6 5360.7 5620.4 5679.5 4549.3 4688.0 5489.5 5524.5 5544.3 5137.4 5817.3 5848.9 5874.6 5924.8 3549.2 3810.9 3820.4 3852.2 3867.2 4765.4 5364.1 5641.1 5674.9 5682.6 5018.5 5443.9 5517.9 5553.8 5578.9 5326.0 5340.6 5453.6 5485.4 5518.3 4353.5 4838.0 5135.6 5219.2 5346.3 5280.4 5478.3 5524.4 5553.8 5576.2 4763.4 5204.7 5377.8 5422.8 5461.8 03:01:09 INFO - PROCESS | 1663 | 03:01:09 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:23781.51 Median:4296.98 stddev:38299.89 (891.3%) stddev-sans-first:38368.00 03:01:09 INFO - PROCESS | 1663 | Values: 661.9 751.8 790.9 823.2 843.8 14901.2 16159.5 16193.4 16202.5 16213.3 14282.4 14609.7 14799.7 14815.9 15007.6 836.1 862.5 864.3 871.4 873.4 670.6 710.1 816.6 869.1 871.0 919.3 919.3 919.3 919.4 921.0 701.7 912.6 914.9 917.6 918.9 630.7 669.8 674.0 678.3 678.6 14725.9 14764.0 14771.3 14777.8 14802.9 13631.9 13959.7 13979.4 14153.5 14158.0 12766.9 12801.2 12923.1 13040.7 13276.2 4518.8 4662.9 4753.3 4761.5 4804.2 111634.0 113179.0 113490.0 113638.6 113985.0 10779.0 10845.6 10989.0 11045.0 11147.4 20758.4 20953.2 20975.0 20977.5 21018.2 7208.6 7269.1 7281.9 7350.9 7370.0 4401.2 4403.7 4458.4 4459.9 4609.2 3455.7 3519.0 3575.6 3580.4 3585.2 586.9 665.9 671.5 674.6 675.4 593.5 622.5 639.4 649.4 652.6 6740.4 8025.8 8525.7 9122.6 9236.5 604.2 633.2 635.9 641.5 643.8 4273.7 4320.2 4325.4 4379.7 4521.2 79691.0 158037.2 159775.0 161321.0 161871.0 88487.6 125630.0 128679.4 133181.0 138402.6 72071.3 74611.0 76824.0 110647.0 111226.0 8246.3 8458.2 8500.9 8542.1 8634.3 64135.0 64727.7 64893.7 65045.0 65982.0 67168.7 87120.1 90888.1 91593.5 92620.0 11318.2 11368.9 11748.2 11941.0 12043.6 11959.6 12078.4 12198.0 12571.5 12641.2 11768.3 11973.5 12110.0 12226.3 12294.5 11811.7 11830.5 12004.6 12221.5 12244.8 12421.3 13440.8 13613.5 13615.3 14212.3 10540.6 11209.3 11234.6 11637.6 12010.2 10469.6 10748.3 10837.6 11069.3 11610.9 03:01:09 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:01:09 INFO - PROCESS | 1663 | 03:01:10 INFO - TEST-INFO | 1663: exit 0 03:01:10 INFO - TEST-OK | dromaeo_css | took 1116711ms 03:01:10 INFO - TEST-START | kraken 03:01:10 INFO - operating with platform_type : mac_ 03:01:10 INFO - Initialising browser for kraken test... 03:01:10 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmp4ajCL5/profile http://localhost:49273/getInfo.html) 03:01:12 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 03:01:12 INFO - PROCESS | 1669 | colorDepth:24 03:01:12 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 03:01:12 INFO - PROCESS | 1669 | __metrics 03:01:12 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:01:12 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:13 INFO - TEST-INFO | 1669: exit 0 03:01:13 INFO - Browser initialized. 03:01:13 INFO - Running cycle 1/1 for kraken test... 03:01:13 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmp4ajCL5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:25 INFO - PROCESS | 1670 | RSS: Main: 244776960 03:01:25 INFO - PROCESS | 1670 | 03:01:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:29 INFO - PROCESS | 1670 | RSS: Main: 294408192 03:01:29 INFO - PROCESS | 1670 | 03:01:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 03:01:32 INFO - PROCESS | 1670 | RSS: Main: 304439296 03:01:32 INFO - PROCESS | 1670 | 03:01:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 03:01:36 INFO - PROCESS | 1670 | RSS: Main: 392265728 03:01:36 INFO - PROCESS | 1670 | 03:01:38 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 03:01:39 INFO - PROCESS | 1670 | RSS: Main: 290164736 03:01:39 INFO - PROCESS | 1670 | 03:01:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:01:41 INFO - PROCESS | 1670 | RSS: Main: 294096896 03:01:41 INFO - PROCESS | 1670 | 03:01:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 03:01:45 INFO - PROCESS | 1670 | RSS: Main: 329383936 03:01:45 INFO - PROCESS | 1670 | 03:01:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 03:01:48 INFO - PROCESS | 1670 | RSS: Main: 333295616 03:01:48 INFO - PROCESS | 1670 | 03:01:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 03:01:52 INFO - PROCESS | 1670 | RSS: Main: 332070912 03:01:52 INFO - PROCESS | 1670 | 03:01:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:01:54 INFO - PROCESS | 1670 | RSS: Main: 311885824 03:01:54 INFO - PROCESS | 1670 | 03:01:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:01:56 INFO - PROCESS | 1670 | RSS: Main: 318529536 03:01:56 INFO - PROCESS | 1670 | 03:01:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:01:59 INFO - PROCESS | 1670 | RSS: Main: 320286720 03:01:59 INFO - PROCESS | 1670 | 03:02:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:02:03 INFO - PROCESS | 1670 | RSS: Main: 324890624 03:02:03 INFO - PROCESS | 1670 | 03:02:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:02:06 INFO - PROCESS | 1670 | RSS: Main: 327495680 03:02:06 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 03:02:08 INFO - PROCESS | 1670 | RSS: Main: 371060736 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | __start_tp_report 03:02:08 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 03:02:08 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 03:02:08 INFO - PROCESS | 1670 | |i|pagename|runs| 03:02:08 INFO - PROCESS | 1670 | |0;ai-astar;102;113;112;112;114;112;113;113;112;113 03:02:08 INFO - PROCESS | 1670 | |1;audio-beat-detection;139;109;107;156;106;107;126;106;141;105 03:02:08 INFO - PROCESS | 1670 | |2;audio-dft;180;183;184;192;184;184;179;181;185;184 03:02:08 INFO - PROCESS | 1670 | |3;audio-fft;80;77;58;108;58;58;60;68;82;59 03:02:08 INFO - PROCESS | 1670 | |4;audio-oscillator;84;94;91;72;70;65;89;64;64;100 03:02:08 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;88;85;89;86;88;85;88;86;88;84 03:02:08 INFO - PROCESS | 1670 | |6;imaging-darkroom;134;125;93;94;93;93;95;94;94;99 03:02:08 INFO - PROCESS | 1670 | |7;imaging-desaturate;90;97;92;92;96;91;93;88;90;85 03:02:08 INFO - PROCESS | 1670 | |8;json-parse-financial;55;53;55;55;55;55;55;56;54;56 03:02:08 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;66;70;77;76;72;69;69;73;68;71 03:02:08 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;85;84;81;82;82;83;82;82;83;86 03:02:08 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;137;139;138;135;134;133;147;136;233;146 03:02:08 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;166;165;165;163;164;167;165;166;166;166 03:02:08 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;67;67;67;66;66;66;67;67;68 03:02:08 INFO - PROCESS | 1670 | __end_tp_report 03:02:08 INFO - PROCESS | 1670 | __start_cc_report 03:02:08 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2056 03:02:08 INFO - PROCESS | 1670 | __end_cc_report 03:02:08 INFO - PROCESS | 1670 | __startTimestamp1470132128658__endTimestamp 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | ------- Summary: start ------- 03:02:08 INFO - PROCESS | 1670 | Number of tests: 14 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.44 (3.0%) stddev-sans-first:0.71 03:02:08 INFO - PROCESS | 1670 | Values: 102.0 113.0 112.0 112.0 114.0 112.0 113.0 113.0 112.0 113.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:117.50 stddev:18.88 (16.1%) stddev-sans-first:18.76 03:02:08 INFO - PROCESS | 1670 | Values: 139.0 109.0 107.0 156.0 106.0 107.0 126.0 106.0 141.0 105.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:3.57 (1.9%) stddev-sans-first:3.54 03:02:08 INFO - PROCESS | 1670 | Values: 180.0 183.0 184.0 192.0 184.0 184.0 179.0 181.0 185.0 184.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:70.80 Median:64.00 stddev:16.29 (25.4%) stddev-sans-first:16.93 03:02:08 INFO - PROCESS | 1670 | Values: 80.0 77.0 58.0 108.0 58.0 58.0 60.0 68.0 82.0 59.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:78.00 stddev:13.78 (17.7%) stddev-sans-first:14.52 03:02:08 INFO - PROCESS | 1670 | Values: 84.0 94.0 91.0 72.0 70.0 65.0 89.0 64.0 64.0 100.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:1.70 (1.9%) stddev-sans-first:1.74 03:02:08 INFO - PROCESS | 1670 | Values: 88.0 85.0 89.0 86.0 88.0 85.0 88.0 86.0 88.0 84.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:94.00 stddev:15.06 (16.0%) stddev-sans-first:10.38 03:02:08 INFO - PROCESS | 1670 | Values: 134.0 125.0 93.0 94.0 93.0 93.0 95.0 94.0 94.0 99.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:92.00 stddev:3.53 (3.8%) stddev-sans-first:3.71 03:02:08 INFO - PROCESS | 1670 | Values: 90.0 97.0 92.0 92.0 96.0 91.0 93.0 88.0 90.0 85.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 03:02:08 INFO - PROCESS | 1670 | Values: 55.0 53.0 55.0 55.0 55.0 55.0 55.0 56.0 54.0 56.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.48 (4.9%) stddev-sans-first:3.16 03:02:08 INFO - PROCESS | 1670 | Values: 66.0 70.0 77.0 76.0 72.0 69.0 69.0 73.0 68.0 71.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:1.56 (1.9%) stddev-sans-first:1.48 03:02:08 INFO - PROCESS | 1670 | Values: 85.0 84.0 81.0 82.0 82.0 83.0 82.0 82.0 83.0 86.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:138.50 stddev:30.31 (21.9%) stddev-sans-first:31.89 03:02:08 INFO - PROCESS | 1670 | Values: 137.0 139.0 138.0 135.0 134.0 133.0 147.0 136.0 233.0 146.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 03:02:08 INFO - PROCESS | 1670 | Values: 166.0 165.0 165.0 163.0 164.0 167.0 165.0 166.0 166.0 166.0 03:02:08 INFO - PROCESS | 1670 | 03:02:08 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 03:02:08 INFO - PROCESS | 1670 | Values: 67.0 67.0 67.0 67.0 66.0 66.0 66.0 67.0 67.0 68.0 03:02:08 INFO - PROCESS | 1670 | -------- Summary: end -------- 03:02:08 INFO - PROCESS | 1670 | 03:02:09 INFO - TEST-INFO | 1670: exit 0 03:02:09 INFO - TEST-OK | kraken | took 59008ms 03:02:09 INFO - SUITE-END | took 1175s 03:02:09 INFO - Completed test suite (00:19:36) 03:02:09 INFO - javascript benchmark 03:02:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.6656334366558, 3364.1925937774927, 3386.4581666933295, 3390.932181356373, 3425.229323402235, 5602.85992850179, 5626.099824028156, 5654.434556544344, 5706.800261990826, 5707.286589176353, 4664.906701865969, 5634.521065709419, 5734.971325143369, 5750.7412166452505, 5753.223314852494, 4975.776090075937, 5094.668846524983, 5128.589712822965, 5135.332406787113, 5252.789888404459, 5249.842504724846, 5258.263843061955, 5270.446603106676, 5303.443138470463, 5330.413654498021, 5102.897942041139, 5144.202648589475, 5145.6398052136365, 5153.819616313449, 5217.58259339252, 5157.200633901752, 5167.741612919339, 5170.844874653766, 5173.224016397533, 5190.92213616796, 5064.366954130734, 5067.189249720064, 5087.669301495428, 5121.28302037715, 5146.279520867081, 5621.747021384048, 5702.429757024306, 5770.278715160605, 5776.4801167894675, 5787.826365209051, 6067.1202675611785, 6124.938750612481, 6187.87624247517, 6190.226221722285, 6214.65819379934, 6202.3797620238065, 6206.875862482771, 6216.471599914013, 6237.875242495124, 6250.124982502453, 5834.27071616048, 5886.323072846678, 5887.0, 5899.439553242435, 5905.881882362329, 6090.49389802587, 6162.322144564095, 6230.376962303824, 6253.53098517613, 6263.467605253604, 6120.418560236771, 6141.447269745744, 6148.477379422709, 6149.446549810538, 6157.014877619559, 6225.657588832658, 6229.0, 6230.376962303824, 6234.2830574484515, 6251.937480625135, 6083.513318934474, 6108.6759897029115, 6113.172264937315, 6172.074188871616, 6172.444479996732, 6161.383861613893, 6194.690265486708, 6202.1937148171355, 6207.96896015517, 6228.688565571703, 4941.925871111936, 4946.480619534969, 4953.207486802166, 4962.206047032458, 4979.0788704089855, 4425.579569940915, 4429.468463784367, 4438.112377524443, 4448.7108337957925, 4475.753833539122, 5759.308882934075, 5761.567882408753, 5782.2483077199695, 5784.074548072288, 5818.5054270386645, 5221.242919776611, 6286.811395658137, 6289.402506761941, 6302.369763023753, 6304.464120549713, 5663.093904975184, 5709.25779648643, 5724.570657200727, 5748.0, 5753.8561535961935, 5374.70439125844, 5389.218563308298, 5436.728163591804, 5441.6190866639745, 5456.236126942231, 6202.162708034448, 6224.564280500322, 6248.687565621701, 6264.436200741955, 6266.342034086447, 6297.748090076346, 6307.463865571294, 6312.43188113072, 6312.84217894538, 6318.304986451395, 6173.290071641639, 6233.937660623335, 6236.563440559117, 6244.375562443719, 6246.968765156145, 6199.473044791243, 6205.689715514297, 6207.131001659681, 6219.40915613016, 6219.844503887439, 5550.639208451437, 6151.954167791576, 6295.055741638607, 6296.653684047421, 6300.149479820166, 5954.612950158225, 6031.396860313934, 6070.726817293144, 6086.482648974709, 6110.480609148272, 5916.26046744157, 6042.425969532887, 6065.8180254592435, 6085.391460853879, 6115.8776824462375, 5985.072313791369, 5992.13114098462, 6006.489448396936, 6011.909821352596, 6070.998285283055, 5979.671118088547, 6084.056971169476, 6102.572819902564, 6116.35977301945, 6131.448169664752, 5974.671393073423, 6027.487663548647, 6035.245594300713, 6048.0, 6062.90905636407], "value": 5686.854411934456, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.21888292030263, 516.2127755173361, 539.3366159623663, 550.5375484592942, 554.6921458590481, 29169.56245656317, 30865.537016944734, 31041.534376984335, 39239.60760392402, 40475.79762101193, 41746.373804392955, 41997.37003944936, 42105.15789684204, 42125.946851328736, 42300.94247643803, 9494.810103797938, 9674.177694895907, 9677.419354838697, 9678.661246856362, 9686.176674982635, 8589.656413743474, 8781.34139939504, 9532.618695252182, 11559.133065020114, 11817.822732659017, 11648.475818588142, 11717.472713727902, 11723.7069073273, 11741.471633776458, 11762.823557646641, 11661.70845728855, 11662.241954272988, 11673.766524669543, 11694.181407301492, 11709.765804683944, 8543.786405339853, 8778.868316975251, 8779.253763430115, 8806.86789698155, 11275.436228188557, 38328.0, 40386.19227615431, 40407.39388909169, 40426.39360409596, 40623.39064914029, 3401.4727717203873, 3408.0, 3485.076454739496, 3493.7729047611824, 3584.946225806615, 30845.69154308473, 30998.535021974687, 31046.534301985488, 31444.842775786205, 32243.19392015195, 3233.1593785615673, 3234.7897386669792, 3243.594550681165, 3256.478963365874, 3399.0312760863035, 519662.0, 519822.0, 522424.0, 524103.0, 525616.0, 49303.06969303041, 56301.0, 57274.56813579694, 57355.71322143446, 60246.0, 59069.704651476466, 59435.702821486484, 59497.0, 59557.0, 59814.401855981756, 15822.683546329008, 15993.520194394187, 16020.359185632677, 16020.599485012968, 16041.037537747772, 11414.94292528532, 11609.129315301387, 12431.502739890304, 12509.311987840756, 12543.686407839694, 15063.698726025637, 15323.31045102973, 15373.846261537241, 15596.454124105601, 15751.68496630061, 7138.750143745048, 7195.928040719526, 7217.314355136254, 7238.819029524304, 7241.312075352833, 927.6521304510809, 1043.838205078214, 1122.680036189682, 1126.2679258482053, 1173.906087512997, 25581.616275755878, 27673.0, 29564.704352956196, 30144.24639383989, 30546.84726576353, 1089.145021158387, 1101.4602844606086, 1114.3258328711174, 1122.9494672739747, 1166.807476766324, 14245.928770356082, 14889.851101488846, 15038.097714137186, 15387.922845400715, 15470.303836327392, 683020.0, 690437.0, 691254.0, 691496.5425172842, 691976.0, 49302.0, 54929.62675933134, 55152.72423637856, 55415.72292138513, 55465.44534554603, 974.668612671695, 1119.9832002519806, 1201.0571701214506, 1307.4443361571293, 1410.4569740649924, 28848.42303153883, 29672.851635741685, 30099.247518811328, 30369.088927332214, 30491.847540763043, 48581.75709121432, 54652.72673636765, 56205.875882481305, 56325.71837140788, 58863.70568147132, 513355.0, 639041.0, 640223.0, 640435.0, 641442.0, 23225.0, 23671.64492532579, 23717.695526745505, 26058.0, 26506.867465662548, 39666.0, 40575.59424405836, 47170.0, 48109.0, 48136.0, 38404.039899002746, 39282.803585981885, 39339.01652458597, 39510.604893950695, 40084.0, 38662.22675546417, 38950.49445549914, 39001.414978774774, 39181.21637567176, 39480.40779388254, 37894.431583525715, 38572.2285554293, 38711.22577548377, 38757.224855502165, 38784.80607596944, 28588.14235572936, 30270.54594181133, 34463.0, 34469.31061378808, 34589.962301131, 25143.49713005766, 25360.365990850376, 25462.745372546036, 25524.59614721208, 26094.86952565225], "value": 19961.88566551117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.3415686724507, 371.4780733070037, 379.0, 391.1375417205063, 401.18759511988236, 19599.902000489994, 20761.584768304645, 20964.475888102806, 21000.26499072533, 21305.68041479375, 17403.0, 20000.999950002515, 20093.59812803743, 21034.948252587386, 21069.0, 5716.857078573038, 5816.214811000503, 5894.8231553053265, 5926.88146237075, 6569.835754106161, 6484.221893372802, 6514.609123452585, 6529.934700652981, 6535.411812936834, 6548.5088618353575, 6366.236051673805, 6389.840253993664, 6523.934760652404, 6532.183477065367, 6543.149390579218, 6397.8400539986405, 6523.478121750272, 6536.084948107269, 6542.34576542342, 6553.770618028394, 5486.286782718261, 5593.412691667398, 5630.380658127603, 5634.098544232903, 5645.125005624135, 26297.868510657514, 26642.20073397801, 26971.190864274104, 27048.91804327844, 27088.593671094754, 2282.6005449046515, 2471.0856982916434, 2474.3566672665056, 2554.948901021969, 3098.643655979546, 9826.65606703762, 10318.845217321747, 10357.482125893675, 10527.631532896312, 16017.439389621424, 1884.415831092352, 1899.2782742557877, 1899.4111825334053, 1922.423273018103, 1929.3440230321758, 70574.0, 97959.50703353615, 116533.0, 117883.82116178815, 119338.0, 11287.153463490107, 11573.76852462946, 11648.94175529117, 11684.649460516199, 11825.467853946593, 14479.420823167166, 14595.78106328406, 14606.12363258208, 14624.85375146235, 14624.853751462562, 8342.791430214173, 8511.57442127891, 8517.531535765593, 8614.18165274298, 8647.437916535406, 7375.44684148691, 8116.391270654724, 8413.284870786052, 8436.578171091422, 8499.490030598185, 4465.173942820588, 4556.225441674923, 4566.794494247767, 4606.976965115153, 4621.976890115528, 1881.7365568820376, 1934.6711059119702, 1967.390109066194, 1972.2012584903136, 1985.9304924327869, 866.7269810009826, 905.9264771246094, 949.7483166960761, 967.2165545907837, 969.2778879734643, 20270.89864550697, 21097.78902210989, 21448.816230567227, 21520.677189842165, 21552.814595197415, 897.3359713811732, 931.0503286647581, 941.5010044676195, 1022.3916769522123, 1040.7606250562412, 10545.945405459546, 10958.958898904591, 11055.613053543088, 11060.834087488694, 11071.833922491169, 536333.1383460063, 588485.0, 593283.0, 595928.0203598954, 603559.0, 95064.52467737618, 113281.30078048835, 119122.40438797924, 126056.0, 130308.0, 958.9760255993656, 965.6330594374233, 1008.8587597736323, 1038.1227862456062, 1039.9428031458192, 15589.29848156813, 18431.80193287432, 18447.815521845147, 19561.315353962542, 19660.0, 55877.44122558722, 55904.72047639899, 56744.71627641835, 57498.0, 58139.127913082186, 81208.0, 85291.5735421319, 86104.1389586096, 86626.56686716527, 86714.0, 7858.0, 8106.716264930935, 8128.63421146038, 8165.428420010542, 8401.07588165289, 9210.032946540785, 12289.87710122923, 12927.224366538037, 13261.66845828862, 13290.933545332598, 10755.569777208824, 10879.0, 11329.923657252548, 11540.192186546861, 11724.472398742248, 9961.651342202988, 10497.16022718196, 11304.773904522028, 11388.487518061542, 11546.59586914454, 11502.48238829238, 11667.883321167019, 11791.410429478321, 11877.22798018126, 11917.642470725892, 8212.219839115274, 8285.130061343472, 8342.0, 8393.790155246168, 8519.233269005572, 8331.916680833356, 9476.526173691425, 11404.48679809427, 11458.770824583295, 11568.595099171489], "value": 9725.631173000518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.5285855667628, 1530.0666593378037, 1736.9330496708915, 1899.8955057471833, 1923.701826216936, 8598.785030374236, 8925.509096999678, 9069.274458043348, 9070.863937040933, 9078.63685452582, 7664.92335076649, 7918.0894197167345, 8160.428769986103, 8393.160683931605, 8747.256483198937, 2313.410080429492, 2387.892544835478, 2389.9283021509295, 2390.3307074019303, 2390.40239940015, 2121.6499277619173, 2354.105439932832, 2374.1453076892303, 2399.916002939897, 2400.747921468238, 2176.6952626632205, 2300.64340027296, 2359.587072262347, 2365.2431222008963, 2375.798057165143, 2347.730011048734, 2364.361622361961, 2376.5365753678125, 2382.630692242705, 2382.964255536151, 2344.4373350395954, 2350.7061617297836, 2380.19073515004, 2380.750021247761, 2382.4401265702545, 8676.8698469523, 8756.605952732143, 8757.386982911199, 8760.737177884674, 8778.209961103468, 5285.339332583427, 5433.755481003364, 5438.592105592096, 5439.184122381635, 5444.945550544483, 3594.370985077601, 3665.2852693724467, 3722.1066943933533, 3744.9625503744887, 3767.340715374826, 1068.3483075324045, 1100.3947828694186, 1125.0380924309761, 1126.0934947367366, 1130.604288499027, 11481.196316257865, 11657.650270491898, 11676.76646467066, 11731.530738770523, 11791.997680197193, 4103.958960410418, 4574.176648203289, 4652.651051171176, 4836.056968891103, 4839.95160048395, 10049.397036177852, 10095.44475053879, 10102.595896164072, 10104.545295461721, 10105.747356316151, 5376.596755243294, 5387.191921211849, 5387.488188622075, 5388.7305634718105, 5390.056740070472, 4746.5253474652945, 4908.558229759339, 4912.975435122873, 4915.139850526215, 4939.135651261015, 3825.617438256152, 3834.9616503835164, 3859.864904728377, 3889.611038896088, 3933.449317095609, 1009.9899001010043, 1110.6667999600088, 1128.136975213962, 1166.6558365282262, 1175.3770501634147, 788.4717239449583, 883.8598208311334, 931.5202670624634, 934.2292608597934, 992.528548939248, 5981.102834574848, 6001.729922153426, 6185.536084793568, 6255.593386429868, 6347.587406818634, 899.6626265150569, 947.8483442649143, 974.8050389922043, 986.921046316293, 1008.742770593494, 3965.5241371035086, 4001.9799901000893, 4088.875559221238, 4094.488188976378, 4175.102352994121, 10423.42671153094, 10923.617673381394, 10936.890631093745, 10958.726031849108, 11024.889751102546, 9929.751756206153, 10296.07335339823, 10799.730006749736, 10884.183686223565, 10920.071793897609, 7593.202713715091, 7779.922200778033, 7937.3650107991225, 8206.0, 8253.09215986253, 4486.349479325545, 5995.850103747442, 6108.236470441195, 6230.50155987529, 6232.843210652881, 9958.651447199314, 10129.341592796445, 10133.39199647994, 10137.239707021856, 10154.086132248132, 9447.763805904908, 9972.052654997764, 10340.793184136426, 10361.326513776581, 10375.429351385746, 7659.234076592296, 7683.116441609063, 7764.262395072459, 7764.805879853049, 7773.0, 6168.475679567107, 8237.352529494005, 8260.421770476249, 8343.791405214919, 8381.58092095405, 6217.658028808278, 6568.408843203943, 7474.962625186839, 7622.618869056636, 7627.694892204249, 7409.888851667337, 7437.590932498548, 7509.249075092447, 7526.887096693445, 7588.354989825927, 7140.857182856417, 7151.570905745672, 7206.243344448757, 7240.275972402717, 7250.746223882139, 5069.188929771219, 5090.26191202281, 5091.770870310771, 5118.257852611276, 5196.636235463481, 5059.038782631288, 5062.873428164326, 5125.136017410258, 5142.6400151989, 5298.470152984671], "value": 4609.583462109269, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.9970200297996, 302.8243618701153, 309.44300259532844, 314.35243398598885, 315.3377906396566, 4387.670924680646, 5251.684898906068, 5309.964556911404, 5325.760340784674, 5334.89330213396, 4943.876403089916, 4983.975080124604, 5188.195829646402, 5257.631965762398, 5276.393214780302, 3890.7744060620957, 3994.900127496807, 4037.9798101009455, 4059.6955228357842, 4060.167665628539, 3529.6823285904266, 3633.981830090846, 4068.1863627274515, 4070.694697897655, 4071.9389209161886, 3162.1778337632268, 3816.0650640593094, 3983.880483585497, 3988.2023595280916, 4018.1361007383407, 3966.6231707987695, 3978.5026871641044, 3984.900377490557, 3986.7641031280396, 4019.7588144711262, 2934.4864648686607, 3144.4497212987862, 3273.9017829465156, 3499.687617143571, 3518.6129525752403, 4929.003753479847, 5129.333186685744, 5149.639525233235, 5168.405633352175, 5242.606804489678, 4151.605597468236, 4564.634829213688, 4586.885327866797, 4619.145458090264, 4651.44182698075, 5025.84922452327, 5084.084864724348, 5456.7544460499375, 5492.670439773627, 5540.390557038722, 3579.266250418658, 3661.6521430464063, 3898.538048231913, 3909.9413508797393, 3912.647861692433, 5714.399988001242, 5729.541636669098, 5729.828105156852, 5754.597178197528, 5762.4525670061275, 3013.322002549409, 3566.9477504135825, 3930.7248492605813, 3963.3856752203455, 3963.6829053675638, 3380.7464440167087, 4012.27778999777, 4023.2556976959354, 4025.7383270087353, 4045.37296719009, 3289.9506507402407, 4277.529471758104, 4456.019675671347, 4502.279635258343, 4536.049498715518, 5519.751611177507, 5521.0, 5529.806456773994, 5532.7786888524815, 5558.638688485235, 1223.5411720604773, 1263.2041813657338, 1268.5750273658243, 1286.7490839286436, 1330.647378444713, 740.6555951482586, 866.3329236487995, 873.4759144513368, 892.5671049540967, 898.8292224477328, 4648.163330600457, 5037.672551284155, 5225.6603320784025, 5246.554042906396, 5265.315508983807, 4770.379850619467, 5188.299579556787, 5396.055690254189, 5671.404502527258, 5674.319081710139, 4634.907301853944, 4799.064182484382, 4931.802727890916, 5377.327834020747, 5452.427495113036, 2256.5825322315422, 2645.0742240215773, 2879.568064790298, 2956.423497418026, 2998.5652080448212, 5583.944160558342, 5868.765249390062, 5875.9118613220835, 5917.822465326047, 5928.318243402064, 4971.701697898137, 5003.974980125076, 5156.303898973665, 5474.863128421821, 5799.88400231993, 4910.165271903714, 5849.444302791228, 5876.764929402776, 5881.441263080086, 5906.734196961147, 4468.329750537446, 5000.5999520037585, 5360.6681093724355, 5620.353659329229, 5679.466544033171, 4549.293170563509, 4687.992081702314, 5489.505944465017, 5524.530414914777, 5544.3346798384455, 5137.434882163034, 5817.331006934088, 5848.912266316093, 5874.559408044328, 5924.837434336522, 3549.2369140634887, 3810.8567429770405, 3820.3696390095324, 3852.171783066654, 3867.207222519433, 4765.404324459442, 5364.061289274439, 5641.12562552805, 5674.886502269849, 5682.636167319896, 5018.5232402921665, 5443.8773738846085, 5517.924004819021, 5553.750081246273, 5578.944210558005, 5325.988062268157, 5340.599455040965, 5453.563714902719, 5485.424030476743, 5518.310211223597, 4353.49934757507, 4837.975810120927, 5135.640505164752, 5219.19102539107, 5346.34263378354, 5280.419153892992, 5478.287822583039, 5524.364698059614, 5553.8333849984565, 5576.159867243881, 4763.404574428197, 5204.687718736736, 5377.8117765878005, 5422.783088676409, 5461.808836690697], "value": 4013.9687302297953, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.8742438936602, 751.7594369801666, 790.885321628364, 823.2467292427425, 843.8270154618303, 14901.180435076067, 16159.515214543553, 16193.352265909349, 16202.513924582267, 16213.270402831871, 14282.357293921772, 14609.707805843876, 14799.704005919875, 14815.92592037041, 15007.624809379771, 836.0886633569423, 862.5169904853302, 864.3301441382915, 871.3900269811126, 873.3668090634303, 670.5674839728372, 710.147822612864, 816.6161903905155, 869.1048220333067, 871.0288028847853, 919.2875521470846, 919.3059240273584, 919.3472634429563, 919.3794188922484, 921.0329154387899, 701.6702149989496, 912.5802131019739, 914.8719179314901, 917.6375331743991, 918.8943271523793, 630.6657471540091, 669.8024082895557, 673.9923813898254, 678.281022116558, 678.5860625018735, 14725.926370368186, 14764.0, 14771.33528991198, 14777.778333325008, 14802.925985370004, 13631.863681363258, 13959.651008724863, 13979.37092830825, 14153.504627338096, 14158.0, 12766.872331276754, 12801.167924084904, 12923.09538332317, 13040.739185216338, 13276.203427794364, 4518.841840535599, 4662.860114196579, 4753.263244197155, 4761.523847615245, 4804.20730579454, 111634.0, 113179.0, 113490.0, 113638.59084227569, 113985.0, 10779.0, 10845.566177352817, 10989.0, 11045.0, 11147.38689372076, 20758.377248682562, 20953.16187352489, 20974.951252437317, 20977.475563111046, 21018.159273628884, 7208.56748595086, 7269.091363579552, 7281.927180728125, 7350.889736653954, 7370.005049318381, 4401.207782599099, 4403.669724770655, 4458.398116254264, 4459.910801783946, 4609.193391156601, 3455.6889879910927, 3518.9824050879583, 3575.6424357564556, 3580.427131658922, 3585.15748799032, 586.9405722670535, 665.9001149827468, 671.4594751225213, 674.6053558668234, 675.3850337445245, 593.5133190783603, 622.5362105231632, 639.3574457670012, 649.4155260265799, 652.6116960408549, 6740.4270636995425, 8025.796130580343, 8525.65897364111, 9122.635094596142, 9236.538173091452, 604.2054698072021, 633.2107068363773, 635.9077933699588, 641.4739913271073, 643.7585905285518, 4273.722208056466, 4320.243957307522, 4325.370885084277, 4379.715318504287, 4521.186186486464, 79691.0, 158037.2098139502, 159775.0, 161321.0, 161871.0, 88487.55756221178, 125630.0, 128679.35660321638, 133181.0, 138402.61597383898, 72071.27928720646, 74611.0, 76824.0, 110646.99999999838, 111226.0, 8246.299064579578, 8458.154184581492, 8500.91499085026, 8542.06037335905, 8634.309255259439, 64135.0, 64727.67636161789, 64893.67553162204, 65045.0, 65982.0, 67168.6641566789, 87120.1287987112, 90888.09111908796, 91593.5420322894, 92620.0, 11318.151138664469, 11368.943155284502, 11748.236364636272, 11941.0, 12043.578474753342, 11959.641210763692, 12078.403143324227, 12198.0, 12571.49714011466, 12641.178323408949, 11768.337922689569, 11973.461194246105, 12110.0, 12226.266424014588, 12294.486103227448, 11811.704707382385, 11830.46762895689, 12004.579839705568, 12221.511139554674, 12244.755104897675, 12421.31682757457, 13440.822781662535, 13613.52352667652, 13615.251161185834, 14212.289385530888, 10540.57837686484, 11209.271397359145, 11234.606788762354, 11637.592684256011, 12010.159288849696, 10469.633562825264, 10748.301360411548, 10837.566497340018, 11069.335839849635, 11610.88389116098], "value": 7639.866219172241, "unit": "score"}], "name": "dromaeo_css", "value": 7337.700235021595, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 112.0, 114.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 107.0, 156.0, 106.0, 107.0, 126.0, 106.0, 141.0, 105.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 184.0, 192.0, 184.0, 184.0, 179.0, 181.0, 185.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 58.0, 108.0, 58.0, 58.0, 60.0, 68.0, 82.0, 59.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 94.0, 91.0, 72.0, 70.0, 65.0, 89.0, 64.0, 64.0, 100.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 93.0, 94.0, 93.0, 93.0, 95.0, 94.0, 94.0, 99.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 92.0, 92.0, 96.0, 91.0, 93.0, 88.0, 90.0, 85.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 56.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 77.0, 76.0, 72.0, 69.0, 69.0, 73.0, 68.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 81.0, 82.0, 82.0, 83.0, 82.0, 82.0, 83.0, 86.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 138.0, 135.0, 134.0, 133.0, 147.0, 136.0, 233.0, 146.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 163.0, 164.0, 167.0, 165.0, 166.0, 166.0, 166.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 67.0, 68.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1433.8999999999999, "alertThreshold": 2.0}]} 03:02:09 INFO - Return code: 0 03:02:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:02:09 INFO - # TBPL SUCCESS # 03:02:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:09 INFO - 2016-08-02 03:02:09.961 screenresolution[1674:10523] starting screenresolution argv=screenresolution get 03:02:09 INFO - 2016-08-02 03:02:09.964 screenresolution[1674:10523] Display 0: 1600x1200x32@60 03:02:09 INFO - 2016-08-02 03:02:09.970 screenresolution[1675:10526] starting screenresolution argv=screenresolution list 03:02:09 INFO - Available Modes on Display 0 03:02:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:10 INFO - Intel Iris: 03:02:10 INFO - Chipset Model: Intel Iris 03:02:10 INFO - Type: GPU 03:02:10 INFO - Bus: Built-In 03:02:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:10 INFO - Vendor: Intel (0x8086) 03:02:10 INFO - Device ID: 0x0a2e 03:02:10 INFO - Revision ID: 0x0009 03:02:10 INFO - Displays: 03:02:10 INFO - Display: 03:02:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:10 INFO - Display Serial Number: 1600x1200 60 03:02:10 INFO - Main Display: Yes 03:02:10 INFO - Mirror: Off 03:02:10 INFO - Online: Yes 03:02:10 INFO - Rotation: Supported 03:02:10 INFO - Return code: 0 03:02:10 INFO - Running post-action listener: _resource_record_post_action 03:02:10 INFO - ##### 03:02:10 INFO - ##### Finished run-tests step (success) 03:02:10 INFO - ##### 03:02:10 INFO - Running post-run listener: _resource_record_post_run 03:02:10 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 39280640; Write bytes: 706585600; Read time: 365; Write time: 2018 03:02:10 INFO - TinderboxPrint: CPU usage
53.4% 03:02:10 INFO - TinderboxPrint: I/O read bytes / time
39,280,640 / 365 03:02:10 INFO - TinderboxPrint: I/O write bytes / time
706,585,600 / 2,018 03:02:10 INFO - TinderboxPrint: CPU idle
2,258.8 (46.6%) 03:02:10 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 03:02:10 INFO - TinderboxPrint: CPU user
1,621.0 (33.5%) 03:02:10 INFO - TinderboxPrint: Swap in / out
450,301,952 / 0 03:02:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387836416; Write bytes: 381556224; Read time: 29079; Write time: 766 03:02:10 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 38359040; Write bytes: 297524736; Read time: 352; Write time: 1202 03:02:10 INFO - Running post-run listener: _upload_blobber_files 03:02:10 INFO - Blob upload gear active. 03:02:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:02:10 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:02:10 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:02:11 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:02:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:02:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:12 INFO - (blobuploader) - INFO - Done attempting. 03:02:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:02:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:02:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:12 INFO - (blobuploader) - INFO - Done attempting. 03:02:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:16 INFO - (blobuploader) - INFO - Done attempting. 03:02:16 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:16 INFO - Return code: 0 03:02:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f44245f48e73c7b43afc753baad67adba44d69fe2a841fb96a576fc5a4547cd6ca1bb6681e24e88d66e2b5bc0783a7167d5d3681e517e76dda8bf72bf95ce4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f51819078beddf81120fe5ae37bc88943331f1592deeb86d6101ce4fc243c0032d5530f0f7c45e5531943197bbbbab77d165f4873d4ac71a31531befa40a9a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d7b338bf67ac24d4f1b118bca26ecad94565f612ac020c84ae504406c0fe7379e9c37433b403352534f8e25b3eb71b7ef6f482d3a6c92a1830018c3ffddcdde0"} 03:02:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:16 INFO - Contents: 03:02:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f44245f48e73c7b43afc753baad67adba44d69fe2a841fb96a576fc5a4547cd6ca1bb6681e24e88d66e2b5bc0783a7167d5d3681e517e76dda8bf72bf95ce4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f51819078beddf81120fe5ae37bc88943331f1592deeb86d6101ce4fc243c0032d5530f0f7c45e5531943197bbbbab77d165f4873d4ac71a31531befa40a9a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d7b338bf67ac24d4f1b118bca26ecad94565f612ac020c84ae504406c0fe7379e9c37433b403352534f8e25b3eb71b7ef6f482d3a6c92a1830018c3ffddcdde0"} 03:02:16 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:16 INFO - Copying logs to upload dir... 03:02:16 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1331.377777 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-08-02 03:02:16.085965) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 03:02:16.089377) ========= 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.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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/Larch/sha512/1f44245f48e73c7b43afc753baad67adba44d69fe2a841fb96a576fc5a4547cd6ca1bb6681e24e88d66e2b5bc0783a7167d5d3681e517e76dda8bf72bf95ce4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f51819078beddf81120fe5ae37bc88943331f1592deeb86d6101ce4fc243c0032d5530f0f7c45e5531943197bbbbab77d165f4873d4ac71a31531befa40a9a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d7b338bf67ac24d4f1b118bca26ecad94565f612ac020c84ae504406c0fe7379e9c37433b403352534f8e25b3eb71b7ef6f482d3a6c92a1830018c3ffddcdde0"} build_url:https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/OXx8XOayRV-rM7YTjMNiGg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1f44245f48e73c7b43afc753baad67adba44d69fe2a841fb96a576fc5a4547cd6ca1bb6681e24e88d66e2b5bc0783a7167d5d3681e517e76dda8bf72bf95ce4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f51819078beddf81120fe5ae37bc88943331f1592deeb86d6101ce4fc243c0032d5530f0f7c45e5531943197bbbbab77d165f4873d4ac71a31531befa40a9a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d7b338bf67ac24d4f1b118bca26ecad94565f612ac020c84ae504406c0fe7379e9c37433b403352534f8e25b3eb71b7ef6f482d3a6c92a1830018c3ffddcdde0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 03:02:16.122772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-08-02 03:02:16.123185) ========= 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.9At8n75xUN/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.FU3QmFEIDR/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.005353 ========= master_lag: 18.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2016-08-02 03:02:34.576243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 03:02:34.576559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 03:02:34.610115) ========= ========= Total master_lag: 18.94 =========