builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0031 starttime: 1461584325.56 results: success (0) buildid: 20160425025858 builduid: 5c06dcff46af4729b0c3b3b518ab3006 revision: 1c6385ae1fe7e37d8f23f958ce14582f07af729e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.560463) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.560866) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.561138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.579821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.580100) ========= 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.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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-04-25 04:38:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.68M=0.001s 2016-04-25 04:38:45 (9.68 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.693192) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.693515) ========= 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.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.027756 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:45.742492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:38:45.742862) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1c6385ae1fe7e37d8f23f958ce14582f07af729e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1c6385ae1fe7e37d8f23f958ce14582f07af729e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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-04-25 04:38:45,817 truncating revision to first 12 chars 2016-04-25 04:38:45,817 Setting DEBUG logging. 2016-04-25 04:38:45,817 attempt 1/10 2016-04-25 04:38:45,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1c6385ae1fe7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 04:38:46,441 unpacking tar archive at: fx-team-1c6385ae1fe7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.867194 ========= master_lag: 0.90 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 04:38:47.507616) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:47.508003) ========= script_repo_revision: 1c6385ae1fe7e37d8f23f958ce14582f07af729e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:47.508393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:47.508653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:38:47.544351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-25 04:38:47.544865) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:38:47 INFO - MultiFileLogger online at 20160425 04:38:47 in /builds/slave/test 04:38:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:38:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:47 INFO - {'append_to_log': False, 04:38:47 INFO - 'base_work_dir': '/builds/slave/test', 04:38:47 INFO - 'blob_upload_branch': 'Fx-Team', 04:38:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:47 INFO - 'branch': 'Fx-Team', 04:38:47 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:47 INFO - 'config_files': ('talos/mac_config.py',), 04:38:47 INFO - 'default_actions': ('clobber', 04:38:47 INFO - 'read-buildbot-config', 04:38:47 INFO - 'download-and-extract', 04:38:47 INFO - 'populate-webroot', 04:38:47 INFO - 'create-virtualenv', 04:38:47 INFO - 'install', 04:38:47 INFO - 'run-tests'), 04:38:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:47 INFO - 'download_minidump_stackwalk': True, 04:38:47 INFO - 'download_symbols': 'ondemand', 04:38:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:47 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:47 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:47 INFO - 'installer_path': 'installer.exe', 04:38:47 INFO - 'log_level': 'info', 04:38:47 INFO - 'log_name': 'talos', 04:38:47 INFO - 'log_to_console': True, 04:38:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:38:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:38:47 INFO - 'opt_config_files': (), 04:38:47 INFO - 'pip_index': False, 04:38:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:47 INFO - 'cmd': ('bash', 04:38:47 INFO - '-c', 04:38:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:47 INFO - 'enabled': True, 04:38:47 INFO - 'halt_on_failure': False, 04:38:47 INFO - 'name': 'check_screen_resolution'},), 04:38:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:47 INFO - 'cmd': ('bash', 04:38:47 INFO - '-c', 04:38:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:47 INFO - 'enabled': True, 04:38:47 INFO - 'halt_on_failure': False, 04:38:47 INFO - 'name': 'check_screen_resolution'},), 04:38:47 INFO - 'run_cmd_checks_enabled': True, 04:38:47 INFO - 'sps_profile': False, 04:38:47 INFO - 'sps_profile_interval': 0, 04:38:47 INFO - 'suite': 'dromaeojs', 04:38:47 INFO - 'system_bits': '32', 04:38:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:47 INFO - 'title': 't-yosemite-r7-0031', 04:38:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:47 INFO - 'use_talos_json': True, 04:38:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:47 INFO - 'work_dir': 'build'} 04:38:47 INFO - ##### 04:38:47 INFO - ##### Running clobber step. 04:38:47 INFO - ##### 04:38:47 INFO - Running pre-action listener: _resource_record_pre_action 04:38:47 INFO - Running main action method: clobber 04:38:47 INFO - rmtree: /builds/slave/test/build 04:38:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:49 INFO - Running post-action listener: _resource_record_post_action 04:38:49 INFO - ##### 04:38:49 INFO - ##### Running read-buildbot-config step. 04:38:49 INFO - ##### 04:38:49 INFO - Running pre-action listener: _resource_record_pre_action 04:38:49 INFO - Running main action method: read_buildbot_config 04:38:49 INFO - Using buildbot properties: 04:38:49 INFO - { 04:38:49 INFO - "project": "", 04:38:49 INFO - "product": "firefox", 04:38:49 INFO - "script_repo_revision": "production", 04:38:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:38:49 INFO - "repository": "", 04:38:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:38:49 INFO - "buildid": "20160425025858", 04:38:49 INFO - "pgo_build": "False", 04:38:49 INFO - "basedir": "/builds/slave/test", 04:38:49 INFO - "buildnumber": 663, 04:38:49 INFO - "slavename": "t-yosemite-r7-0031", 04:38:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:38:49 INFO - "platform": "yosemite_r7", 04:38:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:38:49 INFO - "branch": "fx-team", 04:38:49 INFO - "repo_path": "integration/fx-team", 04:38:49 INFO - "revision": "1c6385ae1fe7e37d8f23f958ce14582f07af729e", 04:38:49 INFO - "stage_platform": "macosx64", 04:38:49 INFO - "builduid": "5c06dcff46af4729b0c3b3b518ab3006", 04:38:49 INFO - "slavebuilddir": "test" 04:38:49 INFO - } 04:38:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:38:49 INFO - Running post-action listener: _resource_record_post_action 04:38:49 INFO - ##### 04:38:49 INFO - ##### Running download-and-extract step. 04:38:49 INFO - ##### 04:38:49 INFO - Running pre-action listener: _resource_record_pre_action 04:38:49 INFO - Running main action method: download_and_extract 04:38:49 INFO - mkdir: /builds/slave/test/build/tests 04:38:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:38:49 INFO - https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:38:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:38:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:38:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:38:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:38:51 INFO - Downloaded 1199 bytes. 04:38:51 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:38:51 INFO - Using the following test package requirements: 04:38:51 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:38:51 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:38:51 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:38:51 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'jsshell-mac.zip'], 04:38:51 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:38:51 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:38:51 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:38:51 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:38:51 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:38:51 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:38:51 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:38:51 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:38:51 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')]} 04:38:51 INFO - https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:38:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:38:53 INFO - Downloaded 18111288 bytes. 04:38:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:38:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:38:56 INFO - Return code: 0 04:38:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:38:56 INFO - https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:38:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:38:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:38:57 INFO - Downloaded 18111288 bytes. 04:38:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:38:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:39:01 INFO - Return code: 0 04:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:01 INFO - https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:39:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:39:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:39:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:39:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:39:02 INFO - Downloaded 10840145 bytes. 04:39:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:39:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:39:02 INFO - Return code: 0 04:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:02 INFO - https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:39:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:39:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:39:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:39:06 INFO - Downloaded 110145679 bytes. 04:39:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:39:06 INFO - mkdir: /builds/slave/test/properties 04:39:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:06 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:06 INFO - Contents: 04:39:06 INFO - build_url:https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:39:07 INFO - Running post-action listener: _resource_record_post_action 04:39:07 INFO - Running post-action listener: set_extra_try_arguments 04:39:07 INFO - ##### 04:39:07 INFO - ##### Running populate-webroot step. 04:39:07 INFO - ##### 04:39:07 INFO - Running pre-action listener: _resource_record_pre_action 04:39:07 INFO - Running main action method: populate_webroot 04:39:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:07 INFO - '--tppagecycles', 04:39:07 INFO - '7'], 04:39:07 INFO - 'tests': ['tsvgm']}}, 04:39:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['damp', 'tps']}, 04:39:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'tests': ['damp', 'tps']}, 04:39:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['dromaeo_dom']}, 04:39:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['a11yr', 04:39:07 INFO - 'ts_paint', 04:39:07 INFO - 'tpaint', 04:39:07 INFO - 'sessionrestore', 04:39:07 INFO - 'sessionrestore_no_auto_restore', 04:39:07 INFO - 'tabpaint']}, 04:39:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['tsvgx', 04:39:07 INFO - 'tsvgr_opacity', 04:39:07 INFO - 'tart', 04:39:07 INFO - 'tscrollx', 04:39:07 INFO - 'cart']}, 04:39:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:07 INFO - 'tsvgr_opacity', 04:39:07 INFO - 'tart', 04:39:07 INFO - 'tscrollx', 04:39:07 INFO - 'cart']}, 04:39:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'talos_options': ['--disable-e10s'], 04:39:07 INFO - 'tests': ['tp5o']}, 04:39:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'tests': ['tp5o']}, 04:39:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'talos_options': ['--disable-e10s', 04:39:07 INFO - '--xperf_path', 04:39:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:07 INFO - 'tests': ['tp5n']}, 04:39:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:07 INFO - 'talos_options': ['--xperf_path', 04:39:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:07 INFO - 'tests': ['tp5n']}}, 04:39:07 INFO - 'talos.zip': {'path': '', 04:39:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:07 INFO - Running post-action listener: _resource_record_post_action 04:39:07 INFO - ##### 04:39:07 INFO - ##### Running create-virtualenv step. 04:39:07 INFO - ##### 04:39:07 INFO - Running pre-action listener: _resource_record_pre_action 04:39:07 INFO - Running main action method: create_virtualenv 04:39:07 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39:07 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 04:39:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:07 INFO - Using real prefix '/tools/python27' 04:39:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:08 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:11 INFO - Installing pip.................done. 04:39:11 INFO - Return code: 0 04:39:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:39:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:11 INFO - 'HOME': '/Users/cltbld', 04:39:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:11 INFO - 'LOGNAME': 'cltbld', 04:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:11 INFO - 'MOZ_NO_REMOTE': '1', 04:39:11 INFO - 'NO_EM_RESTART': '1', 04:39:11 INFO - 'PAGER': '/bin/cat', 04:39:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:11 INFO - 'PWD': '/builds/slave/test', 04:39:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:11 INFO - 'SHELL': '/bin/bash', 04:39:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:11 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:11 INFO - 'USER': 'cltbld', 04:39:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:11 INFO - 'XPC_FLAGS': '0x0', 04:39:11 INFO - 'XPC_SERVICE_NAME': '0', 04:39:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:11 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 04:39:11 INFO - Cleaning up... 04:39:11 INFO - Return code: 0 04:39:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:39:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:11 INFO - 'HOME': '/Users/cltbld', 04:39:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:11 INFO - 'LOGNAME': 'cltbld', 04:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:11 INFO - 'MOZ_NO_REMOTE': '1', 04:39:11 INFO - 'NO_EM_RESTART': '1', 04:39:11 INFO - 'PAGER': '/bin/cat', 04:39:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:11 INFO - 'PWD': '/builds/slave/test', 04:39:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:11 INFO - 'SHELL': '/bin/bash', 04:39:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:11 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:11 INFO - 'USER': 'cltbld', 04:39:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:11 INFO - 'XPC_FLAGS': '0x0', 04:39:11 INFO - 'XPC_SERVICE_NAME': '0', 04:39:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:11 INFO - Downloading/unpacking psutil>=0.7.1 04:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:14 INFO - Installing collected packages: psutil 04:39:14 INFO - Running setup.py install for psutil 04:39:14 INFO - building 'psutil._psutil_osx' extension 04:39:14 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 04:39:15 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 04:39:15 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 04:39:15 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 04:39:15 INFO - building 'psutil._psutil_posix' extension 04:39:15 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 04:39:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:39:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:39:15 INFO - ^ 04:39:15 INFO - 1 warning generated. 04:39:15 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 04:39:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:15 INFO - Successfully installed psutil 04:39:15 INFO - Cleaning up... 04:39:15 INFO - Return code: 0 04:39:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:15 INFO - 'HOME': '/Users/cltbld', 04:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:15 INFO - 'LOGNAME': 'cltbld', 04:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:15 INFO - 'MOZ_NO_REMOTE': '1', 04:39:15 INFO - 'NO_EM_RESTART': '1', 04:39:15 INFO - 'PAGER': '/bin/cat', 04:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:15 INFO - 'PWD': '/builds/slave/test', 04:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:15 INFO - 'SHELL': '/bin/bash', 04:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:15 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:15 INFO - 'USER': 'cltbld', 04:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:15 INFO - 'XPC_FLAGS': '0x0', 04:39:15 INFO - 'XPC_SERVICE_NAME': '0', 04:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:39:16 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 04:39:16 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 04:39:16 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 04:39:16 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 04:39:16 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 04:39:16 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 04:39:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:39:18 INFO - Installing collected packages: mozsystemmonitor 04:39:18 INFO - Running setup.py install for mozsystemmonitor 04:39:18 INFO - Successfully installed mozsystemmonitor 04:39:18 INFO - Cleaning up... 04:39:18 INFO - Return code: 0 04:39:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:18 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 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:18 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 04:39:18 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 04:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:18 INFO - 'HOME': '/Users/cltbld', 04:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:18 INFO - 'LOGNAME': 'cltbld', 04:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:18 INFO - 'MOZ_NO_REMOTE': '1', 04:39:18 INFO - 'NO_EM_RESTART': '1', 04:39:18 INFO - 'PAGER': '/bin/cat', 04:39:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:18 INFO - 'PWD': '/builds/slave/test', 04:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:18 INFO - 'SHELL': '/bin/bash', 04:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:18 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:18 INFO - 'USER': 'cltbld', 04:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:18 INFO - 'XPC_FLAGS': '0x0', 04:39:18 INFO - 'XPC_SERVICE_NAME': '0', 04:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:19 INFO - Downloading/unpacking blobuploader==1.2.4 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:21 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 04:39:21 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 04:39:21 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 04:39:21 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 04:39:21 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 04:39:21 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 04:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 INFO - Downloading docopt-0.6.1.tar.gz 04:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:22 INFO - Running setup.py install for blobuploader 04:39:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:22 INFO - Running setup.py install for requests 04:39:23 INFO - Running setup.py install for docopt 04:39:23 INFO - Successfully installed blobuploader requests docopt 04:39:23 INFO - Cleaning up... 04:39:23 INFO - Return code: 0 04:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:23 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 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:23 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 04:39:23 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 04:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:23 INFO - 'HOME': '/Users/cltbld', 04:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:23 INFO - 'LOGNAME': 'cltbld', 04:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:23 INFO - 'MOZ_NO_REMOTE': '1', 04:39:23 INFO - 'NO_EM_RESTART': '1', 04:39:23 INFO - 'PAGER': '/bin/cat', 04:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:23 INFO - 'PWD': '/builds/slave/test', 04:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:23 INFO - 'SHELL': '/bin/bash', 04:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:23 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:23 INFO - 'USER': 'cltbld', 04:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:23 INFO - 'XPC_FLAGS': '0x0', 04:39:23 INFO - 'XPC_SERVICE_NAME': '0', 04:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:23 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Ee8ADU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-TeGlD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-jhz5Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-R1ci_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-wMxtPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-milOv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-aY4elX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-KCL3Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-bw1ErY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ZDTNiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:24 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-aQI9m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-fAv6_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-bgcFpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-aYaM2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-FCRQ3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Cn1j1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:25 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-fpd_dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:25 INFO - Running setup.py install for manifestparser 04:39:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozcrash 04:39:25 INFO - Running setup.py install for mozdebug 04:39:26 INFO - Running setup.py install for mozdevice 04:39:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozfile 04:39:26 INFO - Running setup.py install for mozhttpd 04:39:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozinfo 04:39:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozInstall 04:39:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozleak 04:39:27 INFO - Running setup.py install for mozlog 04:39:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Running setup.py install for moznetwork 04:39:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Running setup.py install for mozprocess 04:39:27 INFO - Running setup.py install for mozprofile 04:39:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Running setup.py install for mozrunner 04:39:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:27 INFO - Running setup.py install for mozscreenshot 04:39:27 INFO - Running setup.py install for moztest 04:39:28 INFO - Running setup.py install for mozversion 04:39:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:28 INFO - Cleaning up... 04:39:28 INFO - Return code: 0 04:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:28 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')]} 04:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:28 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')]} 04:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:28 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 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:28 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 04:39:28 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 04:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:28 INFO - 'HOME': '/Users/cltbld', 04:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:28 INFO - 'LOGNAME': 'cltbld', 04:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:28 INFO - 'MOZ_NO_REMOTE': '1', 04:39:28 INFO - 'NO_EM_RESTART': '1', 04:39:28 INFO - 'PAGER': '/bin/cat', 04:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:28 INFO - 'PWD': '/builds/slave/test', 04:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:28 INFO - 'SHELL': '/bin/bash', 04:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:28 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:28 INFO - 'USER': 'cltbld', 04:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:28 INFO - 'XPC_FLAGS': '0x0', 04:39:28 INFO - 'XPC_SERVICE_NAME': '0', 04:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:28 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-zemRlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:28 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Hrqv7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:28 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-1AinBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:28 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-kY_0VU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:28 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Wryfw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-F9DJsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-5Yxqyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ULJ_HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-QZwHcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-E5_pmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-duAnlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Q4uIbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-rUnTBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:29 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)) 04:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:29 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-zLgxBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:30 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)) 04:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:30 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-NMkqFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:30 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)) 04:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:30 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-zSErk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:30 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)) 04:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:30 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-tFIOX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:32 INFO - Downloading blessings-1.6.tar.gz 04:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:39:32 INFO - Installing collected packages: blessings 04:39:32 INFO - Running setup.py install for blessings 04:39:33 INFO - Successfully installed blessings 04:39:33 INFO - Cleaning up... 04:39:33 INFO - Return code: 0 04:39:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:39:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:33 INFO - Reading from file tmpfile_stdout 04:39:33 INFO - Current package versions: 04:39:33 INFO - blessings == 1.6 04:39:33 INFO - blobuploader == 1.2.4 04:39:33 INFO - docopt == 0.6.1 04:39:33 INFO - manifestparser == 1.1 04:39:33 INFO - mozInstall == 1.12 04:39:33 INFO - mozcrash == 0.17 04:39:33 INFO - mozdebug == 0.1 04:39:33 INFO - mozdevice == 0.48 04:39:33 INFO - mozfile == 1.2 04:39:33 INFO - mozhttpd == 0.7 04:39:33 INFO - mozinfo == 0.9 04:39:33 INFO - mozleak == 0.1 04:39:33 INFO - mozlog == 3.1 04:39:33 INFO - moznetwork == 0.27 04:39:33 INFO - mozprocess == 0.22 04:39:33 INFO - mozprofile == 0.28 04:39:33 INFO - mozrunner == 6.11 04:39:33 INFO - mozscreenshot == 0.1 04:39:33 INFO - mozsystemmonitor == 0.0 04:39:33 INFO - moztest == 0.7 04:39:33 INFO - mozversion == 1.4 04:39:33 INFO - psutil == 3.1.1 04:39:33 INFO - requests == 1.2.3 04:39:33 INFO - wsgiref == 0.1.2 04:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:33 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 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:33 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 04:39:33 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 04:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:33 INFO - 'HOME': '/Users/cltbld', 04:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:33 INFO - 'LOGNAME': 'cltbld', 04:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:33 INFO - 'MOZ_NO_REMOTE': '1', 04:39:33 INFO - 'NO_EM_RESTART': '1', 04:39:33 INFO - 'PAGER': '/bin/cat', 04:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:33 INFO - 'PWD': '/builds/slave/test', 04:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:33 INFO - 'SHELL': '/bin/bash', 04:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:33 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:33 INFO - 'USER': 'cltbld', 04:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:33 INFO - 'XPC_FLAGS': '0x0', 04:39:33 INFO - 'XPC_SERVICE_NAME': '0', 04:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 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)) 04:39:33 INFO - Cleaning up... 04:39:33 INFO - Return code: 0 04:39:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:33 INFO - 'HOME': '/Users/cltbld', 04:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:33 INFO - 'LOGNAME': 'cltbld', 04:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:33 INFO - 'MOZ_NO_REMOTE': '1', 04:39:33 INFO - 'NO_EM_RESTART': '1', 04:39:33 INFO - 'PAGER': '/bin/cat', 04:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:33 INFO - 'PWD': '/builds/slave/test', 04:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:33 INFO - 'SHELL': '/bin/bash', 04:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:33 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:33 INFO - 'USER': 'cltbld', 04:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:33 INFO - 'XPC_FLAGS': '0x0', 04:39:33 INFO - 'XPC_SERVICE_NAME': '0', 04:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:33 INFO - Downloading/unpacking jsonschema 04:39:33 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 04:39:33 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 04:39:33 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 04:39:33 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 04:39:33 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 04:39:33 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 04:39:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:36 INFO - Downloading/unpacking functools32 (from jsonschema) 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:36 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:36 INFO - warning: no files found matching '*.txt' 04:39:36 INFO - no previously-included directories found matching 'build' 04:39:36 INFO - no previously-included directories found matching 'dist' 04:39:36 INFO - no previously-included directories found matching '.git*' 04:39:36 INFO - Installing collected packages: jsonschema, functools32 04:39:36 INFO - Running setup.py install for functools32 04:39:36 INFO - warning: no files found matching '*.txt' 04:39:36 INFO - no previously-included directories found matching 'build' 04:39:36 INFO - no previously-included directories found matching 'dist' 04:39:36 INFO - no previously-included directories found matching '.git*' 04:39:37 INFO - Successfully installed jsonschema functools32 04:39:37 INFO - Cleaning up... 04:39:37 INFO - Return code: 0 04:39:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a50ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a51b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f42d2c660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, '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.04bu7Hw1BZ/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.MRHF175GNN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 04:39:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:39:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:39:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:37 INFO - 'HOME': '/Users/cltbld', 04:39:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:37 INFO - 'LOGNAME': 'cltbld', 04:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:37 INFO - 'MOZ_NO_REMOTE': '1', 04:39:37 INFO - 'NO_EM_RESTART': '1', 04:39:37 INFO - 'PAGER': '/bin/cat', 04:39:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:37 INFO - 'PWD': '/builds/slave/test', 04:39:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:37 INFO - 'SHELL': '/bin/bash', 04:39:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:39:37 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:39:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:37 INFO - 'USER': 'cltbld', 04:39:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:37 INFO - 'XPC_FLAGS': '0x0', 04:39:37 INFO - 'XPC_SERVICE_NAME': '0', 04:39:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:37 INFO - Downloading/unpacking flake8 04:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:39:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:39:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:39:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:39:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:39:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:39:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:39:41 INFO - no previously-included directories found matching 'docs/_build' 04:39:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:39:41 INFO - Running setup.py install for pep8 04:39:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:39:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:39:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:39:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:39:41 INFO - no previously-included directories found matching 'docs/_build' 04:39:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:39:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:39:41 INFO - Cleaning up... 04:39:41 INFO - Return code: 0 04:39:41 INFO - Running post-action listener: _resource_record_post_action 04:39:41 INFO - Running post-action listener: _start_resource_monitoring 04:39:41 INFO - Starting resource monitoring. 04:39:41 INFO - ##### 04:39:41 INFO - ##### Running install step. 04:39:41 INFO - ##### 04:39:41 INFO - Running pre-action listener: _resource_record_pre_action 04:39:41 INFO - Running main action method: install 04:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:41 INFO - Reading from file tmpfile_stdout 04:39:41 INFO - Detecting whether we're running mozinstall >=1.0... 04:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:39:41 INFO - Reading from file tmpfile_stdout 04:39:41 INFO - Output received: 04:39:41 INFO - Usage: mozinstall [options] installer 04:39:41 INFO - Options: 04:39:41 INFO - -h, --help show this help message and exit 04:39:41 INFO - -d DEST, --destination=DEST 04:39:41 INFO - Directory to install application into. [default: 04:39:41 INFO - "/builds/slave/test"] 04:39:41 INFO - --app=APP Application being installed. [default: firefox] 04:39:41 INFO - mkdir: /builds/slave/test/build/application 04:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:40:15 INFO - Reading from file tmpfile_stdout 04:40:15 INFO - Output received: 04:40:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:40:15 INFO - Running post-action listener: _resource_record_post_action 04:40:15 INFO - ##### 04:40:15 INFO - ##### Running run-tests step. 04:40:15 INFO - ##### 04:40:15 INFO - Running pre-action listener: _resource_record_pre_action 04:40:15 INFO - Running main action method: run_tests 04:40:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:15 INFO - 2016-04-25 04:40:15.141 screenresolution[1639:7602] starting screenresolution argv=screenresolution get 04:40:15 INFO - 2016-04-25 04:40:15.144 screenresolution[1639:7602] Display 0: 1600x1200x32@60 04:40:15 INFO - 2016-04-25 04:40:15.150 screenresolution[1640:7604] starting screenresolution argv=screenresolution list 04:40:15 INFO - Available Modes on Display 0 04:40:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:15 INFO - Intel Iris: 04:40:15 INFO - Chipset Model: Intel Iris 04:40:15 INFO - Type: GPU 04:40:15 INFO - Bus: Built-In 04:40:15 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:15 INFO - Vendor: Intel (0x8086) 04:40:15 INFO - Device ID: 0x0a2e 04:40:15 INFO - Revision ID: 0x0009 04:40:15 INFO - Displays: 04:40:15 INFO - Display: 04:40:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:15 INFO - Display Serial Number: 1600x1200 60 04:40:15 INFO - Main Display: Yes 04:40:15 INFO - Mirror: Off 04:40:15 INFO - Online: Yes 04:40:15 INFO - Rotation: Supported 04:40:15 INFO - Return code: 0 04:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:40:15 INFO - Python 2.7.3 04:40:15 INFO - Return code: 0 04:40:15 INFO - grabbing minidump binary from tooltool 04:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:40:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3aae00>, '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 0x10a1d3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:40:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:40:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:40:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:40:15 INFO - Return code: 0 04:40:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:40:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:40:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:40:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:40:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:40:16 INFO - Return code: 0 04:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--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 04:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0031 --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 04:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.04bu7Hw1BZ/Render', 04:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:16 INFO - 'HOME': '/Users/cltbld', 04:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:16 INFO - 'LOGNAME': 'cltbld', 04:40:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:40:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:16 INFO - 'MOZ_NO_REMOTE': '1', 04:40:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:40:16 INFO - 'NO_EM_RESTART': '1', 04:40:16 INFO - 'PAGER': '/bin/cat', 04:40:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:16 INFO - 'PWD': '/builds/slave/test', 04:40:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:16 INFO - 'SHELL': '/bin/bash', 04:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MRHF175GNN/Listeners', 04:40:16 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 04:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:16 INFO - 'USER': 'cltbld', 04:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:16 INFO - 'XPC_FLAGS': '0x0', 04:40:16 INFO - 'XPC_SERVICE_NAME': '0', 04:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--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 04:40:16 INFO - mozversion application_buildid: 20160425025858 04:40:16 INFO - mozversion application_changeset: 1c6385ae1fe7e37d8f23f958ce14582f07af729e 04:40:16 INFO - mozversion application_display_name: Nightly 04:40:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:40:16 INFO - mozversion application_name: Firefox 04:40:16 INFO - mozversion application_remotingname: firefox 04:40:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:40:16 INFO - mozversion application_vendor: Mozilla 04:40:16 INFO - mozversion application_version: 48.0a1 04:40:16 INFO - mozversion platform_buildid: 20160425025858 04:40:16 INFO - mozversion platform_changeset: 1c6385ae1fe7e37d8f23f958ce14582f07af729e 04:40:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:40:16 INFO - mozversion platform_version: 48.0a1 04:40:16 INFO - using testdate: 1461584416 04:40:16 INFO - actual date: 1461584416 04:40:16 INFO - starting webserver on 'localhost:49275' 04:40:16 INFO - SUITE-START | Running 2 tests 04:40:16 INFO - TEST-START | dromaeo_css 04:40:16 INFO - operating with platform_type : mac_ 04:40:16 INFO - Initialising browser for dromaeo_css test... 04:40:16 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpg1mQSm/profile http://localhost:49275/getInfo.html) 04:40:19 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 04:40:19 INFO - PROCESS | 1658 | colorDepth:24 04:40:19 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 04:40:19 INFO - PROCESS | 1658 | __metrics 04:40:19 INFO - PROCESS | 1658 | 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" 04:40:19 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:40:19 INFO - TEST-INFO | 1658: exit 0 04:40:19 INFO - Browser initialized. 04:40:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:40:19 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpg1mQSm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:31 INFO - PROCESS | 1660 | RSS: Main: 239529984 04:40:31 INFO - PROCESS | 1660 | 04:41:22 INFO - PROCESS | 1660 | 1461584482728 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] 04:43:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 04:43:35 INFO - PROCESS | 1660 | RSS: Main: 369012736 04:43:35 INFO - PROCESS | 1660 | 04:46:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 04:46:38 INFO - PROCESS | 1660 | RSS: Main: 367194112 04:46:38 INFO - PROCESS | 1660 | 04:49:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 04:49:41 INFO - PROCESS | 1660 | RSS: Main: 376492032 04:49:41 INFO - PROCESS | 1660 | 04:52:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 04:52:44 INFO - PROCESS | 1660 | RSS: Main: 374423552 04:52:44 INFO - PROCESS | 1660 | 04:55:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 04:55:48 INFO - PROCESS | 1660 | RSS: Main: 342351872 04:55:48 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 04:58:51 INFO - PROCESS | 1660 | RSS: Main: 382021632 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | __start_tp_report 04:58:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:58:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:58:51 INFO - PROCESS | 1660 | |i|pagename|runs| 04:58:51 INFO - PROCESS | 1660 | |0;dojo.html;3367.7979321240728;3390.2541440883015;3417.2652879630873;3426.845791939363;3433.313337332534;5533.723313834313;5578.63738856974;5583.637063590869;5604.467575580318;5619.409961953992;5579.995600791855;5584.972075139619;5700.116481945295;5727.8281651550415;5740.138979153129;4845.1790468483905;4951.133551628451;5060.924086138702;5070.569001634847;5074.086664400406;4322.654187664979;5135.9743201283945;5138.383393992726;5178.48215178481;5202.4277329493725;5025.7235852028125;5090.05833920724;5093.363329583802;5113.386393632751;5121.615878809085;5078.822241221539;5089.160288552384;5089.389273287229;5103.591712662978;5109.897802043975;5029.119904016819;5036.169032109698;5049.520295571915;5056.241563765428;5132.640715149941;5459.181122831567;5506;5514.61397702157;5582.078956972095;5590.133529302964;6040.305364883051;6124.418180272867;6129.69351532426;6150.415710507495;6162.106494558308;6060.636361818261;6085.056816193497;6137.478314343286;6231.501479881644;6246.437820596168;5608.46719561641;5634.183043458716;5654.74553645087;5711.428857114297;5725.083986562214;6285.088662143963;6295.464885484694;6331.0819931110655;6338.651374174372;6345.904811427832;5948.078047902582;6016.188256805954;6017.608855424383;6029.668368239789;6030.939690603038;6098.91636565712;6191.566590338633;6197.5351848611535;6210.813675589739;6219.222597175353;6039.5470339723815;6058.220696595731;6061.090836374579;6079.878402432015;6139.017757158833;5850.239468869105;6035.698215089228;6061.272647282354;6109.419605137505;6120.693965301717;4810.63920205979;4812.06164797868;4872.4153101628035;4891.241857512091;4891.315215869781;4352.912941741148;4362.694611377173;4371.322445021026;4398.978005109954;4424.734515929054;5500.367457742411;5610.915836262459;5615.185798059259;5625.212470254168;5635.605507614428;6121.971262793518;6128.686460748404;6245.156903817927;6259.935810912156;6262.6868656566985;5590.944090559124;5593.356763972114;5594.748236329375;5601.439856014366;5657.12314591239;5230.686158830483;5246.580273578104;5319.28189694394;5328.733563321818;5329.706866122323;6144.126041557279;6285.277193122849;6311.873762524724;6312.652804095819;6316.494680425463;6206.193194884636;6245.781397651061;6249.593776404519;6254.906176407267;6260.373962603703;6158.722857471335;6250.968745156245;6251.906221406592;6260.530460215593;6261.499080073674;6063.302720187235;6111.434441111609;6118.162361256178;6130.031268545553;6148.354422785543;6183.96908015457;6195.752169913153;6217.844553886189;6227.1282020516255;6235.46998505114;5799.739011744566;5950.315713692808;5958.418735007836;5985.610935289193;6052;5912.852178695567;5982.521398288213;5994.610350327215;6020.397960203944;6039.154518367344;5619.8595035124445;5809.477147056785;5813.82558523245;5824.097264923944;5829.8251052468495;5844.064949608042;5943.019401798655;5993.101034844633;6016.398360163948;6076.118962750465;4964.429090654621;5807.82576522705;5826.067829147316;5867.911981320198;5917.881642367042 04:58:51 INFO - PROCESS | 1660 | |1;ext.html;529.3832684922066;532.7549327309438;565.6097292867921;583.6206465797231;585.5286494372028;26784.598231026524;30565.84717076417;30580.69419305806;30585.235369115726;30592.38815223697;33398.833005835;39214.60785392145;40138.7784893342;40162.39756403649;40285;9305.906940930605;9608.903910960871;9628.13346798788;9634.903650963506;9636.036396360378;9451.810963780754;9621.75945601362;9632.711018669452;9643.614255429775;9645.37305075168;9572.617095316178;9621.134097931184;9650.855237171412;9729.854052189223;9730.318877678565;9652.083052110038;9758.170555502791;9760.414375137443;9771.951140244253;9813.067758562926;9549.952250238704;9678.128968392879;9679.177269932065;9681.806363872754;9683.03169683033;40311.7984410079;40778;40797.79601102005;40823.387649185286;40890;3182.80903145812;3239.433099207653;3284.3267130238246;3396.0491062502538;3497.2480916603054;23114;27969.86015069932;28509.71490285112;28706.282342941384;28744.568831467546;2708.9187324380105;2783.1233161554046;2797.678266999301;2798.8180768250204;2799.0623141247706;387988.9999999972;461061;465922;466074;466586.3413658607;33095.50356744651;55899.161512577346;57566;57571.71214143903;57611.13583296254;34618.30763384768;34940;34967.65032349695;35009.774657886854;35041.824790875886;14304.642383940276;14488;14517.927410363092;14528.346224419927;14534.05528640635;12303.630891073282;12361.876381236252;12367.6908077297;12373.75252494945;12381.814272785916;7959.243871832166;8049.879251811228;8148.837023259502;8167.918320816834;8218.63016164274;2955.497565413885;3419.5725534308212;3437.8796742114405;3443;3443.741719371057;993.1558175550869;1011.7420057885192;1034.2713627464439;1085.5766251162054;1116.1907616977626;27097.322566935985;29189.124326270245;29354.412911741645;29809.40381192364;29913.700862991092;1077.978440431187;1095.4686976816238;1103.7130346110068;1114.6210288501948;1124.4995976790412;15089;15183.240837958057;15227.543173704807;15297.849142630675;15381.692366152614;637877;646077.769611149;646326;646719;646803;48724;57206.14190787142;59506.70246648739;59544.70227648834;59644.40355596475;1332.9800052999212;1337.338017681254;1337.7859542473352;1478.8816894648403;1488.2633096617244;27802.582961256;28218;28978.85510572518;28997.855010724812;29599.408011840074;48202.517974819806;48504;48569.02861942812;48579.757101215684;48738;420499.79500204604;421126;421326;421955;422127;22703.091876325423;23981.280561583182;25097.87451062733;26388;26714.065007724636;45636.543634563226;45942.540574595165;46145.76927115477;46232.76883615561;47203.76398117987;36535.81732091322;37554.436683450316;38269.78813834139;38318.425223622224;38556.614433855306;36836.81581592075;36951.26097477982;36968.07579810526;37674;37872.053198670255;28990.42019159563;29426.705732942395;34390;34526.48210276798;37494;31254.531182032744;31781.04656860298;31814.840925795223;31843.522347164348;31849.522257166624;20708.482287942923;22257.666135007665;23995.880020599787;24106.638400423657;24328.51342973166 04:58:51 INFO - PROCESS | 1660 | |2;jquery.html;393.28618557318464;396.82738008966095;397.5666523489396;399.5564922935543;408.428200519273;19926.60146797063;20118.798812011875;20939;20978.685319720218;21062.473438164052;18526.795758275737;20098.59802803947;20762.688559671617;21147.365579032652;21151.682724759103;4980.750962451872;5325.573954083664;5327.760250788704;5327.786888524454;5331.760070796823;5139.614528910347;5257.815976440826;5333.919991200135;5416.187571864212;5448.618596698233;4582.358469814229;5406;5417.26866872973;5423.755930983096;5441.619086663935;5418.83743487696;5433.755481003364;5553.666779993213;5870.823875283706;5879.794207202727;5383.838484845461;5388.299521062237;5658.179563963244;5678.858028549278;5742.224799652077;13303.667408314774;13309.334533273297;17471;24694.629580556306;25187.62218566723;1904.552430178907;2001.6196922584809;2102.5059111108876;2155.859869108503;2212.0267082483656;9517.095875891779;9547.61809527618;9947;10070.748231294203;10213;1693.1957320272895;1785.5625371783867;1790.4807605794304;1793.6775834847099;1880.1633273193434;117401;117726.41136794261;123487;125647.74352256452;132592.3370383142;10465.319754215952;10835.674929751962;11511.48198331077;11606.129540284344;11636.76726465466;15265.23673816327;15349.6162595936;15386;15400.30698618565;15406.22968851553;6693.631850248185;8302.8339433211;8403.579821008925;8549.914500855037;8568.10034946334;8300.501969881705;8629;8644.827103457896;8753.606087726068;8761.299096072298;3871.6128387161057;4515.548445155524;4621.168189725881;4627.945555933068;4681.625469962395;1662.393226472329;1861.86036047297;1931.642646110474;1937.331620590922;1951.1902560437259;704.5490885833071;714.921358650548;827.2182787266096;893.7854914820397;940.8682784410188;16170.595735106717;18578.907105464656;19317.22731090769;20090.397288081087;20281.087351069236;944.527736131934;954.5227386306847;957.4877440569262;994.1549682769588;1010.9898901011043;10677.733056673489;10843.78312433747;10861.83707244392;10895;10935.671929842118;580794;588032;595873;600717;602427;130870.03694944394;133333;140858;145027;149751;1037.3983089807894;1041.4116024446164;1041.6093964763213;1041.7864337810881;1053.510117795229;17789.733154002442;18176.45470635883;18219.635607288044;18220.635587288445;18357.81642183561;46234.53765462437;49418;50839.74580127076;51646.74176629093;51885.22172167496;73314.26685733075;79909.20090799018;80227.19772802197;80538;81136;5428.538574221077;5432.456754324694;7926.920730792618;8080.757577272691;8307.501549907025;7941.6029198541;10427.530761115617;12127.968007998;12434.191777534432;12473.688157796129;10837.073990022807;10897.836532451862;11102.666919992413;11259.718507037389;11315.943420282845;9583.945765965878;10358.741031474274;10848.945755271174;11093.889061109285;11131.72170695739;10799.892001079888;11198.272112312674;11472.770544589228;11532.365719885487;11608.30350178992;7949.8410031797885;8036.1160272368825;8149.470284431493;8241;8250.38122140854;9751.51242437861;10159.390436573829;10770.838437423288;11404.600838970597;11483.598074067368 04:58:51 INFO - PROCESS | 1660 | |3;mootools.html;1420.5454254638519;1603.7434010558309;1610.2190437637744;1879.4737473507414;2002.099055425059;7534.962325188373;8364;8532.317414606832;8536.274416674582;8541.188587084232;8128.187181281884;8358.707445239417;8368.623411946463;8374.539400332978;8391.992960844707;1995.5310502032012;2408.4701365699516;2412.6139817629096;2417.625268083441;2428.7935525480266;2377.702787151606;2403.2549909528016;2408.819338549607;2418.806495480357;2422.6123820188773;2368.786809187173;2391.5695174868515;2397.4725560376687;2404.8917798699013;2405.050005247926;2314.9189778357677;2348.0725113580124;2348.3894187511287;2349.6240601503764;2360.0205914545445;2312.364099872532;2339.3683705399617;2339.859608423483;2340.2628172125724;2341.3678306857223;7897.605119744047;8267.62795674196;8485.872711909326;8488.618012189467;8506.659733610648;5095.515925987025;5321.148616221386;5333.813316533902;5348.3849357323625;5364.249005139283;3391.71170450512;3448.0690213642174;3539.026767638894;3555.1645363339594;3560.0209942265824;973.3575839945602;984.7587341101404;990.6235630460451;1036.9429681367521;1041.2138835179467;11183.440827958651;11290.604828830952;11305.152113591514;11311.434428278635;11325.037371823322;3864.632859878307;4531.977340113278;4558.316252562142;4616.515265897099;4646.95353046472;8915.554222288989;8923.776905577413;8925.821483570422;8926;8926.955365223263;4406.801693923781;5142.6914385137015;5195.220716892496;5228.9738551307;5235.188545775411;4181.268278051329;4343.804528796211;4354.085642015149;4514.954850451453;4774.40319960005;3719.2933342664805;3819.6371344722206;3857.1514266861245;3858.2862170498547;3874.903127421837;1191.4102519253024;1201.3632774629461;1208.9274643521242;1216.9330686812311;1226.7730469862925;910.9544522773967;912.4570880326195;930.4789317981952;948.3029972969862;978.3396207559869;5838.153467747238;5866.384029676822;5946.940530594638;5959.702014899238;6087.086936959491;436.99491170308164;445.3431188996205;468.29989166196754;477.9187538118528;480.3131521923613;3604.5494313210847;3954.149857780591;4011.4183443400543;4040.777757223381;4056.858009969637;8185.508869467851;10331.690049298533;10432.321899076536;10480.47597620116;10522.368657880552;10393.84409233862;10414.895851041545;10542.947285263677;10543.20925930543;10561.313514621525;6134.9693251534345;6143.447089761854;7879.172686867911;7947.801304967422;8119.376124775069;5029.673071250357;5700.116481945305;5923.674197918984;5925.585209035326;6047.274327080779;8914.37599368038;9501.667441639785;9538.284628653018;9627.374220675634;9652.855207171757;9261.212796912338;9682.225421966366;9711.465869377253;9741.366811157535;9825.705228843146;6671.799846004627;6929.445644348541;7086.433085353263;7371;7372.59450730215;8046.637901294339;8058.476199047043;8129.674813007414;8131.918680813116;8142.918570814216;6930.653467326512;7210.4407820617025;7379.557226566423;7439.8140046499275;7453.739119130804;7132.714691412493;7199.676014579251;7222.602756848424;7366.300201480851;7387.556746595222;6831.351021652935;6855.143107111611;6913.619750913748;6929.5842249465195;6929.757458488929;4701.341812146371;4749.073930583503;4786.401699787527;4905.166121759238;5074.974625126851;4368.235558777138;4762.904741905073;4795.760211989456;4922.1140194765285;4994.425641051181 04:58:51 INFO - PROCESS | 1660 | |4;prototype.html;303.1904814146228;311.5778120646524;321.6590414160989;324.11516559791767;324.6980308313268;4118.58814118588;4321.5894490023475;5076.1370567003605;5189.117849965505;5220.765065572049;4736.739479328636;5137.280780690707;5142.0487209866205;5156.819511317105;5214;3291.7531185161088;3922.8035449187987;3958.148997965437;3961.009747563102;3991.9800400997956;3847.403652433877;3851.306764782338;3996.9400458993136;4007.6192761687594;4071.9592804071876;3315.0606894966472;3418.572678415198;3756.1219390304846;3858.630186283871;3933.370660694275;3420.648843706734;3985.382265748799;3996.72022958393;4007.73949693269;4021.8994525136814;3504.9123771905906;3518.7888726676483;3520.436730123168;3525.1539630488755;3533.7666035577417;4139.379093136024;4290.4636920384955;4431.0251744616135;4720.716756994557;5022.974885125551;4039.444813746729;4057.0871553900433;4336.457942757155;4379.627731642814;4490.730556166641;5087.931534377785;5371.973140134274;5373;5420.7018613976215;5437.728113594344;3545.1846075402545;3784.2620688965662;3794.582595914447;3813.733038687293;3870.509853706315;4597.7011494252765;5184.818531351385;5459.3994660587705;5699.601027928046;5785.929603023496;3847.5382954045135;3852.5376954765607;3854.9614503854605;3865.729398942103;3893.9221215576094;3623.601403845575;3658.8719394821055;3882.9710126816417;3893.201893611803;3903.3559462688904;3853.113783829735;4143.523494798076;4396.3845061690745;4425.601695847389;4468.463784345834;4194.853180138681;5247.501487358695;5384.326959130109;5416.891662166813;5436.266104075899;1270.5235536673747;1367.8700523450257;1369.5685858954398;1418.2625034981752;1437.8849692024612;918.6646873890982;920.6639576554643;937.6905621145006;941.4795105904053;941.5527624378366;4474.731516109044;4964.826231081895;5268.1834315681135;5500.4499550044675;5523.585731070186;6059.031074773329;6091.786787462417;6097.298810636834;6100.664463454553;6118.235220597426;4522.706024108423;5354.41101478837;5482.91775623366;5506.926149400829;5527.944720552743;3214.9196270092966;3241.8379081046323;3243.61076670801;3247.253131779704;3251.6910893465083;5814.767409303665;6168.938310616926;6375.968120159369;6386.616802991835;6392.4566411854585;6226.62640241587;6266.216722909637;6276.246850377893;6283.246010478681;6284.334651317405;5421.241026256327;6259.937400625936;6285.908414184525;6349.8730025400155;6397.616143031526;4855.465898751205;5386.757595908115;5841.678707671119;6077.875593015307;6146.293176284785;5314.548263397654;5724.169995350734;5974;5991.131285963424;6000.489958353589;6075.27096748393;6326.6836658165985;6348.682565871595;6368.872622547616;6380.7447702091395;3505.106197919514;4011.3782363733667;4020.3768415895584;4040.464623443073;4096.692748043849;5384.784608615768;5535.972320138373;5968.46283834457;6101.420365065312;6132.509399248139;4385.539518350655;5387.841614052997;5503.8357040115325;5508.3114610673665;5510.283663123768;5435.809746659006;5436.184572314184;5456.727163641882;5461.508464238238;5461.781528738965;4282.678799090143;4560.840370587013;5349.572034237174;5398.4614384900115;5413.215083812904;4853.902921941471;5477.589180811376;5478.616496845182;5494.2857428534035;5513.944860551504;4369.956300437082;5385.219143224289;5397.7045509078325;5415.431379705232;5423.701696406735 04:58:51 INFO - PROCESS | 1660 | |5;yui.html;650.2164891305976;720.2869159532062;743.6950850151443;747.0400535312118;747.5850902748975;14327.498537551188;14436.639084022878;14820.851791482082;14835.851641483581;14937.253137343143;13650.590482285548;13828.239446830421;13862.722745545083;13866.237356945365;13875.653108672288;732.3226015935265;755.6599530211399;905.6196397513061;915.0894859614692;933.1648174883475;938.6761567259318;943.2878176976408;945.7730144765276;947.4930911962101;952.2715122930963;989.6783545347755;992.3400938375971;993.5181437003048;996.7607774134192;997.8652881860964;745.5116898431509;948.2745699539857;981.6073570571757;985.5515740338129;986.4525188520369;730.1384366447589;730.7551970090026;731.2066407947383;732.092205664977;733.0177562066856;14644.780328295084;14659.34032968519;14690.412383504647;14699.19154446505;14714.0435871668;12048.216865903689;12468.127231093828;12576.874231257661;12734.981201503857;12815.231086134769;9785.850898316332;10045.246606504541;10285.17718582519;10393.168546516335;10499.895001049968;3446.190145315849;3487.9476807847905;3513.9297214055578;3535.505029295901;3744.4196149596855;73737.63131184364;76302.61848690722;76425;76491;76628.46743065164;9132.702106359007;9425;9462.101100395452;9486.193673537826;9577.185939195246;21589.892050539962;21604.379671524697;21605.45986350354;21671.891640542013;21686.89156554207;7343.559386436831;7396.0754905636795;7421.740239091714;7437.070366204224;7491.775246742606;3993.84024639017;3994.3409337458993;4070.816813243411;4127.360259159775;4432.180046691373;3555.5022296878456;3560.537130173061;3592.7305452091196;3596.4605309203307;3599.028262369198;392.5309255439779;620.7237779188308;639.5970538560676;641.8523739539932;650.4211251985737;490.6393800556588;534.8983693098298;582.8895953209143;583.5681595619211;597.4712379544135;7877.448578599443;8073.7577872663915;8087.393445491494;8257.21556452136;8328.33373330132;570.093551253509;583.6323116436618;599.065457885691;602.7558838670345;613.9048447490646;3866.2460820140363;3930.665893399036;3951.664108550748;3987.202559488114;4071.206114807584;90803;95056.52471737597;97051;97731.53402698966;98773;84079.5796021016;84159;84640.57679711562;85380.14619853722;85907.14092859116;74485.25514744892;75581.62209188919;75776.6335049747;75896.24103759002;75990.8601370991;7644.197359277196;7706;7885.881711774443;7889.447738658238;7914.802129946797;46050.53949460597;46440.767796160806;46603;46802.76598616985;46993.3552325667;64621.67689161524;65427;69428.3760649625;70364;70567.64716176386;10114.8482772757;10131.898681013095;10185;10441;10726.570937162425;11018.173636977099;11108.277961932448;11183.440827958731;11552.768944620891;12318.445669944695;10625.840612390666;10684.09185219265;10708.143348531943;10894.455277236266;10899.673009809718;8680.262177714965;10359.585616575252;10836.67489975302;10866.022058014816;11179;11006.614768483065;11173.162012848907;11421.657350279505;11714.707132321419;11721.179517433698;9395.624175032923;9412.858807117762;9547.713568592953;9923.156531694887;10034.849477257992;8968.955155224443;9325.393849399767;9497.287703422133;9499.667511637059;9548.18840398574 04:58:51 INFO - PROCESS | 1660 | __end_tp_report 04:58:51 INFO - PROCESS | 1660 | __start_cc_report 04:58:51 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1370 04:58:51 INFO - PROCESS | 1660 | __end_cc_report 04:58:51 INFO - PROCESS | 1660 | __startTimestamp1461585531293__endTimestamp 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:58:51 INFO - PROCESS | 1660 | Number of tests: 6 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5647.83 Median:5826.07 stddev:641.20 (11.0%) stddev-sans-first:617.71 04:58:51 INFO - PROCESS | 1660 | Values: 3367.8 3390.3 3417.3 3426.8 3433.3 5533.7 5578.6 5583.6 5604.5 5619.4 5580.0 5585.0 5700.1 5727.8 5740.1 4845.2 4951.1 5060.9 5070.6 5074.1 4322.7 5136.0 5138.4 5178.5 5202.4 5025.7 5090.1 5093.4 5113.4 5121.6 5078.8 5089.2 5089.4 5103.6 5109.9 5029.1 5036.2 5049.5 5056.2 5132.6 5459.2 5506.0 5514.6 5582.1 5590.1 6040.3 6124.4 6129.7 6150.4 6162.1 6060.6 6085.1 6137.5 6231.5 6246.4 5608.5 5634.2 5654.7 5711.4 5725.1 6285.1 6295.5 6331.1 6338.7 6345.9 5948.1 6016.2 6017.6 6029.7 6030.9 6098.9 6191.6 6197.5 6210.8 6219.2 6039.5 6058.2 6061.1 6079.9 6139.0 5850.2 6035.7 6061.3 6109.4 6120.7 4810.6 4812.1 4872.4 4891.2 4891.3 4352.9 4362.7 4371.3 4399.0 4424.7 5500.4 5610.9 5615.2 5625.2 5635.6 6122.0 6128.7 6245.2 6259.9 6262.7 5590.9 5593.4 5594.7 5601.4 5657.1 5230.7 5246.6 5319.3 5328.7 5329.7 6144.1 6285.3 6311.9 6312.7 6316.5 6206.2 6245.8 6249.6 6254.9 6260.4 6158.7 6251.0 6251.9 6260.5 6261.5 6063.3 6111.4 6118.2 6130.0 6148.4 6184.0 6195.8 6217.8 6227.1 6235.5 5799.7 5950.3 5958.4 5985.6 6052.0 5912.9 5982.5 5994.6 6020.4 6039.2 5619.9 5809.5 5813.8 5824.1 5829.8 5844.1 5943.0 5993.1 6016.4 6076.1 4964.4 5807.8 5826.1 5867.9 5917.9 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:62466.77 Median:36686.32 stddev:137960.61 (376.1%) stddev-sans-first:138269.25 04:58:51 INFO - PROCESS | 1660 | Values: 529.4 532.8 565.6 583.6 585.5 26784.6 30565.8 30580.7 30585.2 30592.4 33398.8 39214.6 40138.8 40162.4 40285.0 9305.9 9608.9 9628.1 9634.9 9636.0 9451.8 9621.8 9632.7 9643.6 9645.4 9572.6 9621.1 9650.9 9729.9 9730.3 9652.1 9758.2 9760.4 9772.0 9813.1 9550.0 9678.1 9679.2 9681.8 9683.0 40311.8 40778.0 40797.8 40823.4 40890.0 3182.8 3239.4 3284.3 3396.0 3497.2 23114.0 27969.9 28509.7 28706.3 28744.6 2708.9 2783.1 2797.7 2798.8 2799.1 387989.0 461061.0 465922.0 466074.0 466586.3 33095.5 55899.2 57566.0 57571.7 57611.1 34618.3 34940.0 34967.7 35009.8 35041.8 14304.6 14488.0 14517.9 14528.3 14534.1 12303.6 12361.9 12367.7 12373.8 12381.8 7959.2 8049.9 8148.8 8167.9 8218.6 2955.5 3419.6 3437.9 3443.0 3443.7 993.2 1011.7 1034.3 1085.6 1116.2 27097.3 29189.1 29354.4 29809.4 29913.7 1078.0 1095.5 1103.7 1114.6 1124.5 15089.0 15183.2 15227.5 15297.8 15381.7 637877.0 646077.8 646326.0 646719.0 646803.0 48724.0 57206.1 59506.7 59544.7 59644.4 1333.0 1337.3 1337.8 1478.9 1488.3 27802.6 28218.0 28978.9 28997.9 29599.4 48202.5 48504.0 48569.0 48579.8 48738.0 420499.8 421126.0 421326.0 421955.0 422127.0 22703.1 23981.3 25097.9 26388.0 26714.1 45636.5 45942.5 46145.8 46232.8 47203.8 36535.8 37554.4 38269.8 38318.4 38556.6 36836.8 36951.3 36968.1 37674.0 37872.1 28990.4 29426.7 34390.0 34526.5 37494.0 31254.5 31781.0 31814.8 31843.5 31849.5 20708.5 22257.7 23995.9 24106.6 24328.5 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34920.40 Median:11653.77 stddev:99673.91 (855.3%) stddev-sans-first:99919.81 04:58:51 INFO - PROCESS | 1660 | Values: 393.3 396.8 397.6 399.6 408.4 19926.6 20118.8 20939.0 20978.7 21062.5 18526.8 20098.6 20762.7 21147.4 21151.7 4980.8 5325.6 5327.8 5327.8 5331.8 5139.6 5257.8 5333.9 5416.2 5448.6 4582.4 5406.0 5417.3 5423.8 5441.6 5418.8 5433.8 5553.7 5870.8 5879.8 5383.8 5388.3 5658.2 5678.9 5742.2 13303.7 13309.3 17471.0 24694.6 25187.6 1904.6 2001.6 2102.5 2155.9 2212.0 9517.1 9547.6 9947.0 10070.7 10213.0 1693.2 1785.6 1790.5 1793.7 1880.2 117401.0 117726.4 123487.0 125647.7 132592.3 10465.3 10835.7 11511.5 11606.1 11636.8 15265.2 15349.6 15386.0 15400.3 15406.2 6693.6 8302.8 8403.6 8549.9 8568.1 8300.5 8629.0 8644.8 8753.6 8761.3 3871.6 4515.5 4621.2 4627.9 4681.6 1662.4 1861.9 1931.6 1937.3 1951.2 704.5 714.9 827.2 893.8 940.9 16170.6 18578.9 19317.2 20090.4 20281.1 944.5 954.5 957.5 994.2 1011.0 10677.7 10843.8 10861.8 10895.0 10935.7 580794.0 588032.0 595873.0 600717.0 602427.0 130870.0 133333.0 140858.0 145027.0 149751.0 1037.4 1041.4 1041.6 1041.8 1053.5 17789.7 18176.5 18219.6 18220.6 18357.8 46234.5 49418.0 50839.7 51646.7 51885.2 73314.3 79909.2 80227.2 80538.0 81136.0 5428.5 5432.5 7926.9 8080.8 8307.5 7941.6 10427.5 12128.0 12434.2 12473.7 10837.1 10897.8 11102.7 11259.7 11315.9 9583.9 10358.7 10848.9 11093.9 11131.7 10799.9 11198.3 11472.8 11532.4 11608.3 7949.8 8036.1 8149.5 8241.0 8250.4 9751.5 10159.4 10770.8 11404.6 11483.6 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5485.52 Median:5012.05 stddev:3042.88 (60.7%) stddev-sans-first:3036.08 04:58:51 INFO - PROCESS | 1660 | Values: 1420.5 1603.7 1610.2 1879.5 2002.1 7535.0 8364.0 8532.3 8536.3 8541.2 8128.2 8358.7 8368.6 8374.5 8392.0 1995.5 2408.5 2412.6 2417.6 2428.8 2377.7 2403.3 2408.8 2418.8 2422.6 2368.8 2391.6 2397.5 2404.9 2405.1 2314.9 2348.1 2348.4 2349.6 2360.0 2312.4 2339.4 2339.9 2340.3 2341.4 7897.6 8267.6 8485.9 8488.6 8506.7 5095.5 5321.1 5333.8 5348.4 5364.2 3391.7 3448.1 3539.0 3555.2 3560.0 973.4 984.8 990.6 1036.9 1041.2 11183.4 11290.6 11305.2 11311.4 11325.0 3864.6 4532.0 4558.3 4616.5 4647.0 8915.6 8923.8 8925.8 8926.0 8927.0 4406.8 5142.7 5195.2 5229.0 5235.2 4181.3 4343.8 4354.1 4515.0 4774.4 3719.3 3819.6 3857.2 3858.3 3874.9 1191.4 1201.4 1208.9 1216.9 1226.8 911.0 912.5 930.5 948.3 978.3 5838.2 5866.4 5946.9 5959.7 6087.1 437.0 445.3 468.3 477.9 480.3 3604.5 3954.1 4011.4 4040.8 4056.9 8185.5 10331.7 10432.3 10480.5 10522.4 10393.8 10414.9 10542.9 10543.2 10561.3 6135.0 6143.4 7879.2 7947.8 8119.4 5029.7 5700.1 5923.7 5925.6 6047.3 8914.4 9501.7 9538.3 9627.4 9652.9 9261.2 9682.2 9711.5 9741.4 9825.7 6671.8 6929.4 7086.4 7371.0 7372.6 8046.6 8058.5 8129.7 8131.9 8142.9 6930.7 7210.4 7379.6 7439.8 7453.7 7132.7 7199.7 7222.6 7366.3 7387.6 6831.4 6855.1 6913.6 6929.6 6929.8 4701.3 4749.1 4786.4 4905.2 5075.0 4368.2 4762.9 4795.8 4922.1 4994.4 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4549.86 Median:5112.61 stddev:1442.47 (28.2%) stddev-sans-first:1410.86 04:58:51 INFO - PROCESS | 1660 | Values: 303.2 311.6 321.7 324.1 324.7 4118.6 4321.6 5076.1 5189.1 5220.8 4736.7 5137.3 5142.0 5156.8 5214.0 3291.8 3922.8 3958.1 3961.0 3992.0 3847.4 3851.3 3996.9 4007.6 4072.0 3315.1 3418.6 3756.1 3858.6 3933.4 3420.6 3985.4 3996.7 4007.7 4021.9 3504.9 3518.8 3520.4 3525.2 3533.8 4139.4 4290.5 4431.0 4720.7 5023.0 4039.4 4057.1 4336.5 4379.6 4490.7 5087.9 5372.0 5373.0 5420.7 5437.7 3545.2 3784.3 3794.6 3813.7 3870.5 4597.7 5184.8 5459.4 5699.6 5785.9 3847.5 3852.5 3855.0 3865.7 3893.9 3623.6 3658.9 3883.0 3893.2 3903.4 3853.1 4143.5 4396.4 4425.6 4468.5 4194.9 5247.5 5384.3 5416.9 5436.3 1270.5 1367.9 1369.6 1418.3 1437.9 918.7 920.7 937.7 941.5 941.6 4474.7 4964.8 5268.2 5500.4 5523.6 6059.0 6091.8 6097.3 6100.7 6118.2 4522.7 5354.4 5482.9 5506.9 5527.9 3214.9 3241.8 3243.6 3247.3 3251.7 5814.8 6168.9 6376.0 6386.6 6392.5 6226.6 6266.2 6276.2 6283.2 6284.3 5421.2 6259.9 6285.9 6349.9 6397.6 4855.5 5386.8 5841.7 6077.9 6146.3 5314.5 5724.2 5974.0 5991.1 6000.5 6075.3 6326.7 6348.7 6368.9 6380.7 3505.1 4011.4 4020.4 4040.5 4096.7 5384.8 5536.0 5968.5 6101.4 6132.5 4385.5 5387.8 5503.8 5508.3 5510.3 5435.8 5436.2 5456.7 5461.5 5461.8 4282.7 4560.8 5349.6 5398.5 5413.2 4853.9 5477.6 5478.6 5494.3 5513.9 4370.0 5385.2 5397.7 5415.4 5423.7 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18405.64 Median:630.16 stddev:26396.81 (4188.9%) stddev-sans-first:26437.20 04:58:51 INFO - PROCESS | 1660 | Values: 650.2 720.3 743.7 747.0 747.6 14327.5 14436.6 14820.9 14835.9 14937.3 13650.6 13828.2 13862.7 13866.2 13875.7 732.3 755.7 905.6 915.1 933.2 938.7 943.3 945.8 947.5 952.3 989.7 992.3 993.5 996.8 997.9 745.5 948.3 981.6 985.6 986.5 730.1 730.8 731.2 732.1 733.0 14644.8 14659.3 14690.4 14699.2 14714.0 12048.2 12468.1 12576.9 12735.0 12815.2 9785.9 10045.2 10285.2 10393.2 10499.9 3446.2 3487.9 3513.9 3535.5 3744.4 73737.6 76302.6 76425.0 76491.0 76628.5 9132.7 9425.0 9462.1 9486.2 9577.2 21589.9 21604.4 21605.5 21671.9 21686.9 7343.6 7396.1 7421.7 7437.1 7491.8 3993.8 3994.3 4070.8 4127.4 4432.2 3555.5 3560.5 3592.7 3596.5 3599.0 392.5 620.7 639.6 641.9 650.4 490.6 534.9 582.9 583.6 597.5 7877.4 8073.8 8087.4 8257.2 8328.3 570.1 583.6 599.1 602.8 613.9 3866.2 3930.7 3951.7 3987.2 4071.2 90803.0 95056.5 97051.0 97731.5 98773.0 84079.6 84159.0 84640.6 85380.1 85907.1 74485.3 75581.6 75776.6 75896.2 75990.9 7644.2 7706.0 7885.9 7889.4 7914.8 46050.5 46440.8 46603.0 46802.8 46993.4 64621.7 65427.0 69428.4 70364.0 70567.6 10114.8 10131.9 10185.0 10441.0 10726.6 11018.2 11108.3 11183.4 11552.8 12318.4 10625.8 10684.1 10708.1 10894.5 10899.7 8680.3 10359.6 10836.7 10866.0 11179.0 11006.6 11173.2 11421.7 11714.7 11721.2 9395.6 9412.9 9547.7 9923.2 10034.8 8969.0 9325.4 9497.3 9499.7 9548.2 04:58:51 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:58:51 INFO - PROCESS | 1660 | 04:58:51 INFO - TEST-INFO | 1660: exit 0 04:58:51 INFO - TEST-OK | dromaeo_css | took 1115033ms 04:58:51 INFO - TEST-START | kraken 04:58:51 INFO - operating with platform_type : mac_ 04:58:51 INFO - Initialising browser for kraken test... 04:58:51 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpkrvxSU/profile http://localhost:49275/getInfo.html) 04:58:53 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 04:58:53 INFO - PROCESS | 1669 | colorDepth:24 04:58:53 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 04:58:53 INFO - PROCESS | 1669 | __metrics 04:58:54 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" 04:58:54 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:58:54 INFO - TEST-INFO | 1669: exit 0 04:58:54 INFO - Browser initialized. 04:58:54 INFO - Running cycle 1/1 for kraken test... 04:58:54 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpkrvxSU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:06 INFO - PROCESS | 1671 | RSS: Main: 239661056 04:59:06 INFO - PROCESS | 1671 | 04:59:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 04:59:10 INFO - PROCESS | 1671 | RSS: Main: 286351360 04:59:10 INFO - PROCESS | 1671 | 04:59:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 04:59:13 INFO - PROCESS | 1671 | RSS: Main: 299888640 04:59:13 INFO - PROCESS | 1671 | 04:59:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 04:59:17 INFO - PROCESS | 1671 | RSS: Main: 387465216 04:59:17 INFO - PROCESS | 1671 | 04:59:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 04:59:20 INFO - PROCESS | 1671 | RSS: Main: 286846976 04:59:20 INFO - PROCESS | 1671 | 04:59:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:59:23 INFO - PROCESS | 1671 | RSS: Main: 291901440 04:59:23 INFO - PROCESS | 1671 | 04:59:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:26 INFO - PROCESS | 1671 | RSS: Main: 328818688 04:59:26 INFO - PROCESS | 1671 | 04:59:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:29 INFO - PROCESS | 1671 | RSS: Main: 330346496 04:59:29 INFO - PROCESS | 1671 | 04:59:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 04:59:33 INFO - PROCESS | 1671 | RSS: Main: 331436032 04:59:33 INFO - PROCESS | 1671 | 04:59:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:59:35 INFO - PROCESS | 1671 | RSS: Main: 321314816 04:59:35 INFO - PROCESS | 1671 | 04:59:37 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:59:37 INFO - PROCESS | 1671 | RSS: Main: 328101888 04:59:37 INFO - PROCESS | 1671 | 04:59:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:59:40 INFO - PROCESS | 1671 | RSS: Main: 330289152 04:59:40 INFO - PROCESS | 1671 | 04:59:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:59:44 INFO - PROCESS | 1671 | RSS: Main: 334737408 04:59:44 INFO - PROCESS | 1671 | 04:59:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:59:47 INFO - PROCESS | 1671 | RSS: Main: 332677120 04:59:47 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 04:59:49 INFO - PROCESS | 1671 | RSS: Main: 397889536 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | __start_tp_report 04:59:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 04:59:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 04:59:49 INFO - PROCESS | 1671 | |i|pagename|runs| 04:59:49 INFO - PROCESS | 1671 | |0;ai-astar;113;114;106;113;113;113;112;113;107;113 04:59:49 INFO - PROCESS | 1671 | |1;audio-beat-detection;157;119;144;199;148;141;142;116;172;119 04:59:49 INFO - PROCESS | 1671 | |2;audio-dft;185;186;186;263;226;184;183;186;183;205 04:59:49 INFO - PROCESS | 1671 | |3;audio-fft;78;71;88;94;57;59;59;83;82;56 04:59:49 INFO - PROCESS | 1671 | |4;audio-oscillator;88;99;139;77;79;83;125;80;80;129 04:59:49 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;86;89;86;88;86;90;86;88;85 04:59:49 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;93;93;93;93;94;95;97;94 04:59:49 INFO - PROCESS | 1671 | |7;imaging-desaturate;88;85;85;85;84;86;86;87;84;86 04:59:49 INFO - PROCESS | 1671 | |8;json-parse-financial;60;63;61;60;59;60;59;78;60;61 04:59:49 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;62;63;73;70;74;69;67;67;67;69 04:59:49 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;83;84;85;82;79;81;82;83;84 04:59:49 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;136;138;135;139;149;150;149;181;154 04:59:49 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;171;171;171;170;172;152;210;219;222;168 04:59:49 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;71;69;68;68;71;67;66;63;68;67 04:59:49 INFO - PROCESS | 1671 | __end_tp_report 04:59:49 INFO - PROCESS | 1671 | __start_cc_report 04:59:49 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2108 04:59:49 INFO - PROCESS | 1671 | __end_cc_report 04:59:49 INFO - PROCESS | 1671 | __startTimestamp1461585589856__endTimestamp 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | ------- Summary: start ------- 04:59:49 INFO - PROCESS | 1671 | Number of tests: 14 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.79 (2.5%) stddev-sans-first:2.92 04:59:49 INFO - PROCESS | 1671 | Values: 113.0 114.0 106.0 113.0 113.0 113.0 112.0 113.0 107.0 113.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:146.00 stddev:25.85 (17.7%) stddev-sans-first:27.09 04:59:49 INFO - PROCESS | 1671 | Values: 157.0 119.0 144.0 199.0 148.0 141.0 142.0 116.0 172.0 119.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:198.70 Median:186.00 stddev:26.46 (14.2%) stddev-sans-first:27.59 04:59:49 INFO - PROCESS | 1671 | Values: 185.0 186.0 186.0 263.0 226.0 184.0 183.0 186.0 183.0 205.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:72.70 Median:80.00 stddev:14.19 (17.7%) stddev-sans-first:14.92 04:59:49 INFO - PROCESS | 1671 | Values: 78.0 71.0 88.0 94.0 57.0 59.0 59.0 83.0 82.0 56.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:80.00 stddev:23.91 (29.9%) stddev-sans-first:25.09 04:59:49 INFO - PROCESS | 1671 | Values: 88.0 99.0 139.0 77.0 79.0 83.0 125.0 80.0 80.0 129.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.84 (2.1%) stddev-sans-first:1.69 04:59:49 INFO - PROCESS | 1671 | Values: 90.0 86.0 89.0 86.0 88.0 86.0 90.0 86.0 88.0 85.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:93.50 stddev:1.32 (1.4%) stddev-sans-first:1.36 04:59:49 INFO - PROCESS | 1671 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 94.0 95.0 97.0 94.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.00 04:59:49 INFO - PROCESS | 1671 | Values: 88.0 85.0 85.0 85.0 84.0 86.0 86.0 87.0 84.0 86.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:60.50 stddev:5.70 (9.4%) stddev-sans-first:6.00 04:59:49 INFO - PROCESS | 1671 | Values: 60.0 63.0 61.0 60.0 59.0 60.0 59.0 78.0 60.0 61.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:69.00 stddev:3.81 (5.5%) stddev-sans-first:3.35 04:59:49 INFO - PROCESS | 1671 | Values: 62.0 63.0 73.0 70.0 74.0 69.0 67.0 67.0 67.0 69.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.50 stddev:1.77 (2.1%) stddev-sans-first:1.81 04:59:49 INFO - PROCESS | 1671 | Values: 84.0 83.0 84.0 85.0 82.0 79.0 81.0 82.0 83.0 84.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:149.00 stddev:13.67 (9.2%) stddev-sans-first:14.25 04:59:49 INFO - PROCESS | 1671 | Values: 140.0 136.0 138.0 135.0 139.0 149.0 150.0 149.0 181.0 154.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.60 Median:171.50 stddev:24.61 (14.4%) stddev-sans-first:25.75 04:59:49 INFO - PROCESS | 1671 | Values: 171.0 171.0 171.0 170.0 172.0 152.0 210.0 219.0 222.0 168.0 04:59:49 INFO - PROCESS | 1671 | 04:59:49 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:2.35 (3.5%) stddev-sans-first:2.19 04:59:49 INFO - PROCESS | 1671 | Values: 71.0 69.0 68.0 68.0 71.0 67.0 66.0 63.0 68.0 67.0 04:59:49 INFO - PROCESS | 1671 | -------- Summary: end -------- 04:59:49 INFO - PROCESS | 1671 | 04:59:50 INFO - TEST-INFO | 1671: exit 0 04:59:50 INFO - TEST-OK | kraken | took 58793ms 04:59:50 INFO - SUITE-END | took 1173s 04:59:51 INFO - Completed test suite (00:19:34) 04:59:51 INFO - javascript benchmark 04:59:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.7979321240728, 3390.2541440883015, 3417.2652879630873, 3426.845791939363, 3433.313337332534, 5533.723313834313, 5578.63738856974, 5583.637063590869, 5604.467575580318, 5619.409961953992, 5579.995600791855, 5584.972075139619, 5700.116481945295, 5727.8281651550415, 5740.138979153129, 4845.1790468483905, 4951.133551628451, 5060.924086138702, 5070.569001634847, 5074.086664400406, 4322.654187664979, 5135.9743201283945, 5138.383393992726, 5178.48215178481, 5202.4277329493725, 5025.7235852028125, 5090.05833920724, 5093.363329583802, 5113.386393632751, 5121.615878809085, 5078.822241221539, 5089.160288552384, 5089.389273287229, 5103.591712662978, 5109.897802043975, 5029.119904016819, 5036.169032109698, 5049.520295571915, 5056.241563765428, 5132.640715149941, 5459.181122831567, 5506.0, 5514.61397702157, 5582.078956972095, 5590.133529302964, 6040.305364883051, 6124.418180272867, 6129.69351532426, 6150.415710507495, 6162.106494558308, 6060.636361818261, 6085.056816193497, 6137.478314343286, 6231.501479881644, 6246.437820596168, 5608.46719561641, 5634.183043458716, 5654.74553645087, 5711.428857114297, 5725.083986562214, 6285.088662143963, 6295.464885484694, 6331.0819931110655, 6338.651374174372, 6345.904811427832, 5948.078047902582, 6016.188256805954, 6017.608855424383, 6029.668368239789, 6030.939690603038, 6098.91636565712, 6191.566590338633, 6197.5351848611535, 6210.813675589739, 6219.222597175353, 6039.5470339723815, 6058.220696595731, 6061.090836374579, 6079.878402432015, 6139.017757158833, 5850.239468869105, 6035.698215089228, 6061.272647282354, 6109.419605137505, 6120.693965301717, 4810.63920205979, 4812.06164797868, 4872.4153101628035, 4891.241857512091, 4891.315215869781, 4352.912941741148, 4362.694611377173, 4371.322445021026, 4398.978005109954, 4424.734515929054, 5500.367457742411, 5610.915836262459, 5615.185798059259, 5625.212470254168, 5635.605507614428, 6121.971262793518, 6128.686460748404, 6245.156903817927, 6259.935810912156, 6262.6868656566985, 5590.944090559124, 5593.356763972114, 5594.748236329375, 5601.439856014366, 5657.12314591239, 5230.686158830483, 5246.580273578104, 5319.28189694394, 5328.733563321818, 5329.706866122323, 6144.126041557279, 6285.277193122849, 6311.873762524724, 6312.652804095819, 6316.494680425463, 6206.193194884636, 6245.781397651061, 6249.593776404519, 6254.906176407267, 6260.373962603703, 6158.722857471335, 6250.968745156245, 6251.906221406592, 6260.530460215593, 6261.499080073674, 6063.302720187235, 6111.434441111609, 6118.162361256178, 6130.031268545553, 6148.354422785543, 6183.96908015457, 6195.752169913153, 6217.844553886189, 6227.1282020516255, 6235.46998505114, 5799.739011744566, 5950.315713692808, 5958.418735007836, 5985.610935289193, 6052.0, 5912.852178695567, 5982.521398288213, 5994.610350327215, 6020.397960203944, 6039.154518367344, 5619.8595035124445, 5809.477147056785, 5813.82558523245, 5824.097264923944, 5829.8251052468495, 5844.064949608042, 5943.019401798655, 5993.101034844633, 6016.398360163948, 6076.118962750465, 4964.429090654621, 5807.82576522705, 5826.067829147316, 5867.911981320198, 5917.881642367042], "value": 5606.824918657587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.3832684922066, 532.7549327309438, 565.6097292867921, 583.6206465797231, 585.5286494372028, 26784.598231026524, 30565.84717076417, 30580.69419305806, 30585.235369115726, 30592.38815223697, 33398.833005835, 39214.60785392145, 40138.7784893342, 40162.39756403649, 40285.0, 9305.906940930605, 9608.903910960871, 9628.13346798788, 9634.903650963506, 9636.036396360378, 9451.810963780754, 9621.75945601362, 9632.711018669452, 9643.614255429775, 9645.37305075168, 9572.617095316178, 9621.134097931184, 9650.855237171412, 9729.854052189223, 9730.318877678565, 9652.083052110038, 9758.170555502791, 9760.414375137443, 9771.951140244253, 9813.067758562926, 9549.952250238704, 9678.128968392879, 9679.177269932065, 9681.806363872754, 9683.03169683033, 40311.7984410079, 40778.0, 40797.79601102005, 40823.387649185286, 40890.0, 3182.80903145812, 3239.433099207653, 3284.3267130238246, 3396.0491062502538, 3497.2480916603054, 23114.0, 27969.86015069932, 28509.71490285112, 28706.282342941384, 28744.568831467546, 2708.9187324380105, 2783.1233161554046, 2797.678266999301, 2798.8180768250204, 2799.0623141247706, 387988.9999999972, 461061.0, 465922.0, 466074.0, 466586.3413658607, 33095.50356744651, 55899.161512577346, 57566.0, 57571.71214143903, 57611.13583296254, 34618.30763384768, 34940.0, 34967.65032349695, 35009.774657886854, 35041.824790875886, 14304.642383940276, 14488.0, 14517.927410363092, 14528.346224419927, 14534.05528640635, 12303.630891073282, 12361.876381236252, 12367.6908077297, 12373.75252494945, 12381.814272785916, 7959.243871832166, 8049.879251811228, 8148.837023259502, 8167.918320816834, 8218.63016164274, 2955.497565413885, 3419.5725534308212, 3437.8796742114405, 3443.0, 3443.741719371057, 993.1558175550869, 1011.7420057885192, 1034.2713627464439, 1085.5766251162054, 1116.1907616977626, 27097.322566935985, 29189.124326270245, 29354.412911741645, 29809.40381192364, 29913.700862991092, 1077.978440431187, 1095.4686976816238, 1103.7130346110068, 1114.6210288501948, 1124.4995976790412, 15089.0, 15183.240837958057, 15227.543173704807, 15297.849142630675, 15381.692366152614, 637877.0, 646077.769611149, 646326.0, 646719.0, 646803.0, 48724.0, 57206.14190787142, 59506.70246648739, 59544.70227648834, 59644.40355596475, 1332.9800052999212, 1337.338017681254, 1337.7859542473352, 1478.8816894648403, 1488.2633096617244, 27802.582961256, 28218.0, 28978.85510572518, 28997.855010724812, 29599.408011840074, 48202.517974819806, 48504.0, 48569.02861942812, 48579.757101215684, 48738.0, 420499.79500204604, 421126.0, 421326.0, 421955.0, 422127.0, 22703.091876325423, 23981.280561583182, 25097.87451062733, 26388.0, 26714.065007724636, 45636.543634563226, 45942.540574595165, 46145.76927115477, 46232.76883615561, 47203.76398117987, 36535.81732091322, 37554.436683450316, 38269.78813834139, 38318.425223622224, 38556.614433855306, 36836.81581592075, 36951.26097477982, 36968.07579810526, 37674.0, 37872.053198670255, 28990.42019159563, 29426.705732942395, 34390.0, 34526.48210276798, 37494.0, 31254.531182032744, 31781.04656860298, 31814.840925795223, 31843.522347164348, 31849.522257166624, 20708.482287942923, 22257.666135007665, 23995.880020599787, 24106.638400423657, 24328.51342973166], "value": 17927.960155729797, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.28618557318464, 396.82738008966095, 397.5666523489396, 399.5564922935543, 408.428200519273, 19926.60146797063, 20118.798812011875, 20939.0, 20978.685319720218, 21062.473438164052, 18526.795758275737, 20098.59802803947, 20762.688559671617, 21147.365579032652, 21151.682724759103, 4980.750962451872, 5325.573954083664, 5327.760250788704, 5327.786888524454, 5331.760070796823, 5139.614528910347, 5257.815976440826, 5333.919991200135, 5416.187571864212, 5448.618596698233, 4582.358469814229, 5406.0, 5417.26866872973, 5423.755930983096, 5441.619086663935, 5418.83743487696, 5433.755481003364, 5553.666779993213, 5870.823875283706, 5879.794207202727, 5383.838484845461, 5388.299521062237, 5658.179563963244, 5678.858028549278, 5742.224799652077, 13303.667408314774, 13309.334533273297, 17471.0, 24694.629580556306, 25187.62218566723, 1904.552430178907, 2001.6196922584809, 2102.5059111108876, 2155.859869108503, 2212.0267082483656, 9517.095875891779, 9547.61809527618, 9947.0, 10070.748231294203, 10213.0, 1693.1957320272895, 1785.5625371783867, 1790.4807605794304, 1793.6775834847099, 1880.1633273193434, 117401.0, 117726.41136794261, 123487.0, 125647.74352256452, 132592.3370383142, 10465.319754215952, 10835.674929751962, 11511.48198331077, 11606.129540284344, 11636.76726465466, 15265.23673816327, 15349.6162595936, 15386.0, 15400.30698618565, 15406.22968851553, 6693.631850248185, 8302.8339433211, 8403.579821008925, 8549.914500855037, 8568.10034946334, 8300.501969881705, 8629.0, 8644.827103457896, 8753.606087726068, 8761.299096072298, 3871.6128387161057, 4515.548445155524, 4621.168189725881, 4627.945555933068, 4681.625469962395, 1662.393226472329, 1861.86036047297, 1931.642646110474, 1937.331620590922, 1951.1902560437259, 704.5490885833071, 714.921358650548, 827.2182787266096, 893.7854914820397, 940.8682784410188, 16170.595735106717, 18578.907105464656, 19317.22731090769, 20090.397288081087, 20281.087351069236, 944.527736131934, 954.5227386306847, 957.4877440569262, 994.1549682769588, 1010.9898901011043, 10677.733056673489, 10843.78312433747, 10861.83707244392, 10895.0, 10935.671929842118, 580794.0, 588032.0, 595873.0, 600717.0, 602427.0, 130870.03694944394, 133333.0, 140858.0, 145027.0, 149751.0, 1037.3983089807894, 1041.4116024446164, 1041.6093964763213, 1041.7864337810881, 1053.510117795229, 17789.733154002442, 18176.45470635883, 18219.635607288044, 18220.635587288445, 18357.81642183561, 46234.53765462437, 49418.0, 50839.74580127076, 51646.74176629093, 51885.22172167496, 73314.26685733075, 79909.20090799018, 80227.19772802197, 80538.0, 81136.0, 5428.538574221077, 5432.456754324694, 7926.920730792618, 8080.757577272691, 8307.501549907025, 7941.6029198541, 10427.530761115617, 12127.968007998, 12434.191777534432, 12473.688157796129, 10837.073990022807, 10897.836532451862, 11102.666919992413, 11259.718507037389, 11315.943420282845, 9583.945765965878, 10358.741031474274, 10848.945755271174, 11093.889061109285, 11131.72170695739, 10799.892001079888, 11198.272112312674, 11472.770544589228, 11532.365719885487, 11608.30350178992, 7949.8410031797885, 8036.1160272368825, 8149.470284431493, 8241.0, 8250.38122140854, 9751.51242437861, 10159.390436573829, 10770.838437423288, 11404.600838970597, 11483.598074067368], "value": 9249.394648386095, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.5454254638519, 1603.7434010558309, 1610.2190437637744, 1879.4737473507414, 2002.099055425059, 7534.962325188373, 8364.0, 8532.317414606832, 8536.274416674582, 8541.188587084232, 8128.187181281884, 8358.707445239417, 8368.623411946463, 8374.539400332978, 8391.992960844707, 1995.5310502032012, 2408.4701365699516, 2412.6139817629096, 2417.625268083441, 2428.7935525480266, 2377.702787151606, 2403.2549909528016, 2408.819338549607, 2418.806495480357, 2422.6123820188773, 2368.786809187173, 2391.5695174868515, 2397.4725560376687, 2404.8917798699013, 2405.050005247926, 2314.9189778357677, 2348.0725113580124, 2348.3894187511287, 2349.6240601503764, 2360.0205914545445, 2312.364099872532, 2339.3683705399617, 2339.859608423483, 2340.2628172125724, 2341.3678306857223, 7897.605119744047, 8267.62795674196, 8485.872711909326, 8488.618012189467, 8506.659733610648, 5095.515925987025, 5321.148616221386, 5333.813316533902, 5348.3849357323625, 5364.249005139283, 3391.71170450512, 3448.0690213642174, 3539.026767638894, 3555.1645363339594, 3560.0209942265824, 973.3575839945602, 984.7587341101404, 990.6235630460451, 1036.9429681367521, 1041.2138835179467, 11183.440827958651, 11290.604828830952, 11305.152113591514, 11311.434428278635, 11325.037371823322, 3864.632859878307, 4531.977340113278, 4558.316252562142, 4616.515265897099, 4646.95353046472, 8915.554222288989, 8923.776905577413, 8925.821483570422, 8926.0, 8926.955365223263, 4406.801693923781, 5142.6914385137015, 5195.220716892496, 5228.9738551307, 5235.188545775411, 4181.268278051329, 4343.804528796211, 4354.085642015149, 4514.954850451453, 4774.40319960005, 3719.2933342664805, 3819.6371344722206, 3857.1514266861245, 3858.2862170498547, 3874.903127421837, 1191.4102519253024, 1201.3632774629461, 1208.9274643521242, 1216.9330686812311, 1226.7730469862925, 910.9544522773967, 912.4570880326195, 930.4789317981952, 948.3029972969862, 978.3396207559869, 5838.153467747238, 5866.384029676822, 5946.940530594638, 5959.702014899238, 6087.086936959491, 436.99491170308164, 445.3431188996205, 468.29989166196754, 477.9187538118528, 480.3131521923613, 3604.5494313210847, 3954.149857780591, 4011.4183443400543, 4040.777757223381, 4056.858009969637, 8185.508869467851, 10331.690049298533, 10432.321899076536, 10480.47597620116, 10522.368657880552, 10393.84409233862, 10414.895851041545, 10542.947285263677, 10543.20925930543, 10561.313514621525, 6134.9693251534345, 6143.447089761854, 7879.172686867911, 7947.801304967422, 8119.376124775069, 5029.673071250357, 5700.116481945305, 5923.674197918984, 5925.585209035326, 6047.274327080779, 8914.37599368038, 9501.667441639785, 9538.284628653018, 9627.374220675634, 9652.855207171757, 9261.212796912338, 9682.225421966366, 9711.465869377253, 9741.366811157535, 9825.705228843146, 6671.799846004627, 6929.445644348541, 7086.433085353263, 7371.0, 7372.59450730215, 8046.637901294339, 8058.476199047043, 8129.674813007414, 8131.918680813116, 8142.918570814216, 6930.653467326512, 7210.4407820617025, 7379.557226566423, 7439.8140046499275, 7453.739119130804, 7132.714691412493, 7199.676014579251, 7222.602756848424, 7366.300201480851, 7387.556746595222, 6831.351021652935, 6855.143107111611, 6913.619750913748, 6929.5842249465195, 6929.757458488929, 4701.341812146371, 4749.073930583503, 4786.401699787527, 4905.166121759238, 5074.974625126851, 4368.235558777138, 4762.904741905073, 4795.760211989456, 4922.1140194765285, 4994.425641051181], "value": 4376.611223218364, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.1904814146228, 311.5778120646524, 321.6590414160989, 324.11516559791767, 324.6980308313268, 4118.58814118588, 4321.5894490023475, 5076.1370567003605, 5189.117849965505, 5220.765065572049, 4736.739479328636, 5137.280780690707, 5142.0487209866205, 5156.819511317105, 5214.0, 3291.7531185161088, 3922.8035449187987, 3958.148997965437, 3961.009747563102, 3991.9800400997956, 3847.403652433877, 3851.306764782338, 3996.9400458993136, 4007.6192761687594, 4071.9592804071876, 3315.0606894966472, 3418.572678415198, 3756.1219390304846, 3858.630186283871, 3933.370660694275, 3420.648843706734, 3985.382265748799, 3996.72022958393, 4007.73949693269, 4021.8994525136814, 3504.9123771905906, 3518.7888726676483, 3520.436730123168, 3525.1539630488755, 3533.7666035577417, 4139.379093136024, 4290.4636920384955, 4431.0251744616135, 4720.716756994557, 5022.974885125551, 4039.444813746729, 4057.0871553900433, 4336.457942757155, 4379.627731642814, 4490.730556166641, 5087.931534377785, 5371.973140134274, 5373.0, 5420.7018613976215, 5437.728113594344, 3545.1846075402545, 3784.2620688965662, 3794.582595914447, 3813.733038687293, 3870.509853706315, 4597.7011494252765, 5184.818531351385, 5459.3994660587705, 5699.601027928046, 5785.929603023496, 3847.5382954045135, 3852.5376954765607, 3854.9614503854605, 3865.729398942103, 3893.9221215576094, 3623.601403845575, 3658.8719394821055, 3882.9710126816417, 3893.201893611803, 3903.3559462688904, 3853.113783829735, 4143.523494798076, 4396.3845061690745, 4425.601695847389, 4468.463784345834, 4194.853180138681, 5247.501487358695, 5384.326959130109, 5416.891662166813, 5436.266104075899, 1270.5235536673747, 1367.8700523450257, 1369.5685858954398, 1418.2625034981752, 1437.8849692024612, 918.6646873890982, 920.6639576554643, 937.6905621145006, 941.4795105904053, 941.5527624378366, 4474.731516109044, 4964.826231081895, 5268.1834315681135, 5500.4499550044675, 5523.585731070186, 6059.031074773329, 6091.786787462417, 6097.298810636834, 6100.664463454553, 6118.235220597426, 4522.706024108423, 5354.41101478837, 5482.91775623366, 5506.926149400829, 5527.944720552743, 3214.9196270092966, 3241.8379081046323, 3243.61076670801, 3247.253131779704, 3251.6910893465083, 5814.767409303665, 6168.938310616926, 6375.968120159369, 6386.616802991835, 6392.4566411854585, 6226.62640241587, 6266.216722909637, 6276.246850377893, 6283.246010478681, 6284.334651317405, 5421.241026256327, 6259.937400625936, 6285.908414184525, 6349.8730025400155, 6397.616143031526, 4855.465898751205, 5386.757595908115, 5841.678707671119, 6077.875593015307, 6146.293176284785, 5314.548263397654, 5724.169995350734, 5974.0, 5991.131285963424, 6000.489958353589, 6075.27096748393, 6326.6836658165985, 6348.682565871595, 6368.872622547616, 6380.7447702091395, 3505.106197919514, 4011.3782363733667, 4020.3768415895584, 4040.464623443073, 4096.692748043849, 5384.784608615768, 5535.972320138373, 5968.46283834457, 6101.420365065312, 6132.509399248139, 4385.539518350655, 5387.841614052997, 5503.8357040115325, 5508.3114610673665, 5510.283663123768, 5435.809746659006, 5436.184572314184, 5456.727163641882, 5461.508464238238, 5461.781528738965, 4282.678799090143, 4560.840370587013, 5349.572034237174, 5398.4614384900115, 5413.215083812904, 4853.902921941471, 5477.589180811376, 5478.616496845182, 5494.2857428534035, 5513.944860551504, 4369.956300437082, 5385.219143224289, 5397.7045509078325, 5415.431379705232, 5423.701696406735], "value": 4105.743219435116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.2164891305976, 720.2869159532062, 743.6950850151443, 747.0400535312118, 747.5850902748975, 14327.498537551188, 14436.639084022878, 14820.851791482082, 14835.851641483581, 14937.253137343143, 13650.590482285548, 13828.239446830421, 13862.722745545083, 13866.237356945365, 13875.653108672288, 732.3226015935265, 755.6599530211399, 905.6196397513061, 915.0894859614692, 933.1648174883475, 938.6761567259318, 943.2878176976408, 945.7730144765276, 947.4930911962101, 952.2715122930963, 989.6783545347755, 992.3400938375971, 993.5181437003048, 996.7607774134192, 997.8652881860964, 745.5116898431509, 948.2745699539857, 981.6073570571757, 985.5515740338129, 986.4525188520369, 730.1384366447589, 730.7551970090026, 731.2066407947383, 732.092205664977, 733.0177562066856, 14644.780328295084, 14659.34032968519, 14690.412383504647, 14699.19154446505, 14714.0435871668, 12048.216865903689, 12468.127231093828, 12576.874231257661, 12734.981201503857, 12815.231086134769, 9785.850898316332, 10045.246606504541, 10285.17718582519, 10393.168546516335, 10499.895001049968, 3446.190145315849, 3487.9476807847905, 3513.9297214055578, 3535.505029295901, 3744.4196149596855, 73737.63131184364, 76302.61848690722, 76425.0, 76491.0, 76628.46743065164, 9132.702106359007, 9425.0, 9462.101100395452, 9486.193673537826, 9577.185939195246, 21589.892050539962, 21604.379671524697, 21605.45986350354, 21671.891640542013, 21686.89156554207, 7343.559386436831, 7396.0754905636795, 7421.740239091714, 7437.070366204224, 7491.775246742606, 3993.84024639017, 3994.3409337458993, 4070.816813243411, 4127.360259159775, 4432.180046691373, 3555.5022296878456, 3560.537130173061, 3592.7305452091196, 3596.4605309203307, 3599.028262369198, 392.5309255439779, 620.7237779188308, 639.5970538560676, 641.8523739539932, 650.4211251985737, 490.6393800556588, 534.8983693098298, 582.8895953209143, 583.5681595619211, 597.4712379544135, 7877.448578599443, 8073.7577872663915, 8087.393445491494, 8257.21556452136, 8328.33373330132, 570.093551253509, 583.6323116436618, 599.065457885691, 602.7558838670345, 613.9048447490646, 3866.2460820140363, 3930.665893399036, 3951.664108550748, 3987.202559488114, 4071.206114807584, 90803.0, 95056.52471737597, 97051.0, 97731.53402698966, 98773.0, 84079.5796021016, 84159.0, 84640.57679711562, 85380.14619853722, 85907.14092859116, 74485.25514744892, 75581.62209188919, 75776.6335049747, 75896.24103759002, 75990.8601370991, 7644.197359277196, 7706.0, 7885.881711774443, 7889.447738658238, 7914.802129946797, 46050.53949460597, 46440.767796160806, 46603.0, 46802.76598616985, 46993.3552325667, 64621.67689161524, 65427.0, 69428.3760649625, 70364.0, 70567.64716176386, 10114.8482772757, 10131.898681013095, 10185.0, 10441.0, 10726.570937162425, 11018.173636977099, 11108.277961932448, 11183.440827958731, 11552.768944620891, 12318.445669944695, 10625.840612390666, 10684.09185219265, 10708.143348531943, 10894.455277236266, 10899.673009809718, 8680.262177714965, 10359.585616575252, 10836.67489975302, 10866.022058014816, 11179.0, 11006.614768483065, 11173.162012848907, 11421.657350279505, 11714.707132321419, 11721.179517433698, 9395.624175032923, 9412.858807117762, 9547.713568592953, 9923.156531694887, 10034.849477257992, 8968.955155224443, 9325.393849399767, 9497.287703422133, 9499.667511637059, 9548.18840398574], "value": 6843.496809421092, "unit": "score"}], "name": "dromaeo_css", "value": 6966.832126230379, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 106.0, 113.0, 113.0, 113.0, 112.0, 113.0, 107.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 144.0, 199.0, 148.0, 141.0, 142.0, 116.0, 172.0, 119.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 263.0, 226.0, 184.0, 183.0, 186.0, 183.0, 205.0], "value": 198.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 88.0, 94.0, 57.0, 59.0, 59.0, 83.0, 82.0, 56.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 99.0, 139.0, 77.0, 79.0, 83.0, 125.0, 80.0, 80.0, 129.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 88.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 95.0, 97.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 85.0, 84.0, 86.0, 86.0, 87.0, 84.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 61.0, 60.0, 59.0, 60.0, 59.0, 78.0, 60.0, 61.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 73.0, 70.0, 74.0, 69.0, 67.0, 67.0, 67.0, 69.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 85.0, 82.0, 79.0, 81.0, 82.0, 83.0, 84.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 138.0, 135.0, 139.0, 149.0, 150.0, 149.0, 181.0, 154.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 171.0, 170.0, 172.0, 152.0, 210.0, 219.0, 222.0, 168.0], "value": 182.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 68.0, 68.0, 71.0, 67.0, 66.0, 63.0, 68.0, 67.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1503.8999999999996, "alertThreshold": 2.0}]} 04:59:51 INFO - Return code: 0 04:59:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:59:51 ERROR - # TBPL SUCCESS # 04:59:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:59:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:59:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:59:51 INFO - 2016-04-25 04:59:51.187 screenresolution[1675:9963] starting screenresolution argv=screenresolution get 04:59:51 INFO - 2016-04-25 04:59:51.190 screenresolution[1675:9963] Display 0: 1600x1200x32@60 04:59:51 INFO - 2016-04-25 04:59:51.195 screenresolution[1676:9966] starting screenresolution argv=screenresolution list 04:59:51 INFO - Available Modes on Display 0 04:59:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:59:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:59:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:59:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:59:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:59:51 INFO - Intel Iris: 04:59:51 INFO - Chipset Model: Intel Iris 04:59:51 INFO - Type: GPU 04:59:51 INFO - Bus: Built-In 04:59:51 INFO - VRAM (Dynamic, Max): 1536 MB 04:59:51 INFO - Vendor: Intel (0x8086) 04:59:51 INFO - Device ID: 0x0a2e 04:59:51 INFO - Revision ID: 0x0009 04:59:51 INFO - Displays: 04:59:51 INFO - Display: 04:59:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:59:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:59:51 INFO - Display Serial Number: 1600x1200 60 04:59:51 INFO - Main Display: Yes 04:59:51 INFO - Mirror: Off 04:59:51 INFO - Online: Yes 04:59:51 INFO - Rotation: Supported 04:59:51 INFO - Return code: 0 04:59:51 INFO - Running post-action listener: _resource_record_post_action 04:59:51 INFO - Running post-run listener: _resource_record_post_run 04:59:52 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 38016512; Write bytes: 694760448; Read time: 395; Write time: 2025 04:59:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380510720; Write bytes: 374720000; Read time: 28455; Write time: 695 04:59:52 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37062144; Write bytes: 296791552; Read time: 381; Write time: 1282 04:59:52 INFO - Running post-run listener: _upload_blobber_files 04:59:52 INFO - Blob upload gear active. 04:59:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:59:52 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 04:59:52 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:59:52 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:59:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:59:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:59:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:59:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:53 INFO - (blobuploader) - INFO - Done attempting. 04:59:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:59:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:59:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:54 INFO - (blobuploader) - INFO - Done attempting. 04:59:54 INFO - (blobuploader) - INFO - Iteration through files over. 04:59:54 INFO - Return code: 0 04:59:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:59:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:59:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68b17e2a3e80a546d962dfafe2b9f0d597cf22ecb2b337ef6250314af7aa38c280e876ed897e78e94df9565fd05e897588cd311590ea4d738b6d9ac78d7b0110", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6861917344e1e7ec7b2a9de6d915906fb99fd7c51b66fdd8802bf083a8f59af78ea0333eb7ad9a2bfefd2a7e33d22aca8be5a043de49da31aa9255d0fdd4bbe5"} 04:59:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:59:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:59:54 INFO - Contents: 04:59:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68b17e2a3e80a546d962dfafe2b9f0d597cf22ecb2b337ef6250314af7aa38c280e876ed897e78e94df9565fd05e897588cd311590ea4d738b6d9ac78d7b0110", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6861917344e1e7ec7b2a9de6d915906fb99fd7c51b66fdd8802bf083a8f59af78ea0333eb7ad9a2bfefd2a7e33d22aca8be5a043de49da31aa9255d0fdd4bbe5"} 04:59:54 INFO - Running post-run listener: copy_logs_to_upload_dir 04:59:54 INFO - Copying logs to upload dir... 04:59:54 INFO - mkdir: /builds/slave/test/build/upload/logs 04:59:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.993289 ========= master_lag: 2.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-25 04:59:56.724356) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 04:59:56.731245) ========= 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.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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/Fx-Team/sha512/68b17e2a3e80a546d962dfafe2b9f0d597cf22ecb2b337ef6250314af7aa38c280e876ed897e78e94df9565fd05e897588cd311590ea4d738b6d9ac78d7b0110", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6861917344e1e7ec7b2a9de6d915906fb99fd7c51b66fdd8802bf083a8f59af78ea0333eb7ad9a2bfefd2a7e33d22aca8be5a043de49da31aa9255d0fdd4bbe5"} build_url:https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011102 build_url: 'https://queue.taskcluster.net/v1/task/PjtwZpumTIiv-dJ_EymBEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68b17e2a3e80a546d962dfafe2b9f0d597cf22ecb2b337ef6250314af7aa38c280e876ed897e78e94df9565fd05e897588cd311590ea4d738b6d9ac78d7b0110", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6861917344e1e7ec7b2a9de6d915906fb99fd7c51b66fdd8802bf083a8f59af78ea0333eb7ad9a2bfefd2a7e33d22aca8be5a043de49da31aa9255d0fdd4bbe5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 04:59:56.761502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:59:56.761834) ========= 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.04bu7Hw1BZ/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.MRHF175GNN/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.005164 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:59:56.929545) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 04:59:56.929876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 04:59:56.930549) ========= ========= Total master_lag: 3.30 =========