builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1462528356.33 results: success (0) buildid: 20160506004122 builduid: 9e6ebc887f074f1d85ea0ae2d2748219 revision: b17fa0c2f2e4537942a5d91b543c825f124564fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.330715) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.331105) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.331438) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.348490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.348765) ========= 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.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-05-06 02:52:36-- 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% 150M=0s 2016-05-06 02:52:36 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119852 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.485011) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.485329) ========= 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.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.020787 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:36.533167) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 02:52:36.533491) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b17fa0c2f2e4537942a5d91b543c825f124564fc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b17fa0c2f2e4537942a5d91b543c825f124564fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-05-06 02:52:36,603 truncating revision to first 12 chars 2016-05-06 02:52:36,603 Setting DEBUG logging. 2016-05-06 02:52:36,603 attempt 1/10 2016-05-06 02:52:36,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b17fa0c2f2e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 02:52:37,699 unpacking tar archive at: mozilla-aurora-b17fa0c2f2e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 02:52:37.941785) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:37.942100) ========= script_repo_revision: b17fa0c2f2e4537942a5d91b543c825f124564fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:37.942504) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:37.942779) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 02:52:37.956248) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 02:52:37.956533) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:52:38 INFO - MultiFileLogger online at 20160506 02:52:38 in /builds/slave/test 02:52:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:52:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:52:38 INFO - {'append_to_log': False, 02:52:38 INFO - 'base_work_dir': '/builds/slave/test', 02:52:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:52:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:52:38 INFO - 'branch': 'Mozilla-Aurora', 02:52:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:52:38 INFO - 'config_files': ('talos/mac_config.py',), 02:52:38 INFO - 'default_actions': ('clobber', 02:52:38 INFO - 'read-buildbot-config', 02:52:38 INFO - 'download-and-extract', 02:52:38 INFO - 'populate-webroot', 02:52:38 INFO - 'create-virtualenv', 02:52:38 INFO - 'install', 02:52:38 INFO - 'run-tests'), 02:52:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:52:38 INFO - 'download_minidump_stackwalk': True, 02:52:38 INFO - 'download_symbols': 'ondemand', 02:52:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:52:38 INFO - 'tooltool.py': '/tools/tooltool.py', 02:52:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:52:38 INFO - '/tools/misc-python/virtualenv.py')}, 02:52:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:52:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:52:38 INFO - 'installer_path': 'installer.exe', 02:52:38 INFO - 'log_level': 'info', 02:52:38 INFO - 'log_name': 'talos', 02:52:38 INFO - 'log_to_console': True, 02:52:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:52:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:52:38 INFO - 'opt_config_files': (), 02:52:38 INFO - 'pip_index': False, 02:52:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:52:38 INFO - 'cmd': ('bash', 02:52:38 INFO - '-c', 02:52:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:52:38 INFO - 'enabled': True, 02:52:38 INFO - 'halt_on_failure': False, 02:52:38 INFO - 'name': 'check_screen_resolution'},), 02:52:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:52:38 INFO - 'cmd': ('bash', 02:52:38 INFO - '-c', 02:52:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:52:38 INFO - 'enabled': True, 02:52:38 INFO - 'halt_on_failure': False, 02:52:38 INFO - 'name': 'check_screen_resolution'},), 02:52:38 INFO - 'run_cmd_checks_enabled': True, 02:52:38 INFO - 'sps_profile': False, 02:52:38 INFO - 'sps_profile_interval': 0, 02:52:38 INFO - 'suite': 'dromaeojs', 02:52:38 INFO - 'system_bits': '32', 02:52:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:52:38 INFO - 'title': 't-yosemite-r7-0170', 02:52:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:52:38 INFO - 'use_talos_json': True, 02:52:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:52:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:52:38 INFO - 'work_dir': 'build'} 02:52:38 INFO - ##### 02:52:38 INFO - ##### Running clobber step. 02:52:38 INFO - ##### 02:52:38 INFO - Running pre-action listener: _resource_record_pre_action 02:52:38 INFO - Running main action method: clobber 02:52:38 INFO - rmtree: /builds/slave/test/build 02:52:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:52:38 INFO - Running post-action listener: _resource_record_post_action 02:52:38 INFO - ##### 02:52:38 INFO - ##### Running read-buildbot-config step. 02:52:38 INFO - ##### 02:52:38 INFO - Running pre-action listener: _resource_record_pre_action 02:52:38 INFO - Running main action method: read_buildbot_config 02:52:38 INFO - Using buildbot properties: 02:52:38 INFO - { 02:52:38 INFO - "project": "", 02:52:38 INFO - "product": "firefox", 02:52:38 INFO - "script_repo_revision": "production", 02:52:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:52:38 INFO - "repository": "", 02:52:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:52:38 INFO - "buildid": "20160506004122", 02:52:38 INFO - "stage_platform": "macosx64", 02:52:38 INFO - "basedir": "/builds/slave/test", 02:52:38 INFO - "nightly_build": "True", 02:52:38 INFO - "buildnumber": 2, 02:52:38 INFO - "slavename": "t-yosemite-r7-0170", 02:52:38 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:52:38 INFO - "platform": "yosemite_r7", 02:52:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:52:38 INFO - "branch": "mozilla-aurora", 02:52:38 INFO - "repo_path": "releases/mozilla-aurora", 02:52:38 INFO - "revision": "b17fa0c2f2e4537942a5d91b543c825f124564fc", 02:52:38 INFO - "pgo_build": "False", 02:52:38 INFO - "builduid": "9e6ebc887f074f1d85ea0ae2d2748219", 02:52:38 INFO - "slavebuilddir": "test" 02:52:38 INFO - } 02:52:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:52:38 INFO - Running post-action listener: _resource_record_post_action 02:52:38 INFO - ##### 02:52:38 INFO - ##### Running download-and-extract step. 02:52:38 INFO - ##### 02:52:38 INFO - Running pre-action listener: _resource_record_pre_action 02:52:38 INFO - Running main action method: download_and_extract 02:52:38 INFO - mkdir: /builds/slave/test/build/tests 02:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:38 INFO - https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:52:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:52:39 INFO - Downloaded 1199 bytes. 02:52:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:52:39 INFO - Using the following test package requirements: 02:52:39 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:52:39 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:52:39 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:52:39 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'jsshell-mac.zip'], 02:52:39 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:52:39 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:52:39 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:52:39 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:52:39 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:52:39 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:52:39 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:52:39 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:39 INFO - https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:52:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:52:40 INFO - Downloaded 18113200 bytes. 02:52:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:52:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:52:44 INFO - Return code: 0 02:52:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:44 INFO - https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:52:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:52:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:52:45 INFO - Downloaded 18113200 bytes. 02:52:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:52:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:52:48 INFO - Return code: 0 02:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:48 INFO - https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:52:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:52:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:52:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:52:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:52:49 INFO - Downloaded 10840145 bytes. 02:52:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:52:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:52:49 INFO - Return code: 0 02:52: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')]} 02:52:49 INFO - https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:52:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:52:52 INFO - Downloaded 114210657 bytes. 02:52:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:52 INFO - mkdir: /builds/slave/test/properties 02:52:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:52:52 INFO - Writing to file /builds/slave/test/properties/build_url 02:52:52 INFO - Contents: 02:52:52 INFO - build_url:https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:52:53 INFO - Running post-action listener: _resource_record_post_action 02:52:53 INFO - Running post-action listener: set_extra_try_arguments 02:52:53 INFO - ##### 02:52:53 INFO - ##### Running populate-webroot step. 02:52:53 INFO - ##### 02:52:53 INFO - Running pre-action listener: _resource_record_pre_action 02:52:53 INFO - Running main action method: populate_webroot 02:52:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:52:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:52:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:52:53 INFO - '--tppagecycles', 02:52:53 INFO - '7'], 02:52:53 INFO - 'tests': ['tsvgm']}}, 02:52:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:52:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:52:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:52:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:52:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['damp', 'tps']}, 02:52:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'tests': ['damp', 'tps']}, 02:52:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['dromaeo_dom']}, 02:52:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:52:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'other-e10s': {'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['a11yr', 02:52:53 INFO - 'ts_paint', 02:52:53 INFO - 'tpaint', 02:52:53 INFO - 'sessionrestore', 02:52:53 INFO - 'sessionrestore_no_auto_restore', 02:52:53 INFO - 'tabpaint']}, 02:52:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['tsvgx', 02:52:53 INFO - 'tsvgr_opacity', 02:52:53 INFO - 'tart', 02:52:53 INFO - 'tscrollx', 02:52:53 INFO - 'cart']}, 02:52:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:52:53 INFO - 'tsvgr_opacity', 02:52:53 INFO - 'tart', 02:52:53 INFO - 'tscrollx', 02:52:53 INFO - 'cart']}, 02:52:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'talos_options': ['--disable-e10s'], 02:52:53 INFO - 'tests': ['tp5o']}, 02:52:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'tests': ['tp5o']}, 02:52:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'talos_options': ['--disable-e10s', 02:52:53 INFO - '--xperf_path', 02:52:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:53 INFO - 'tests': ['tp5n']}, 02:52:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:52:53 INFO - 'talos_options': ['--xperf_path', 02:52:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:53 INFO - 'tests': ['tp5n']}}, 02:52:53 INFO - 'talos.zip': {'path': '', 02:52:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:52:53 INFO - Running post-action listener: _resource_record_post_action 02:52:53 INFO - ##### 02:52:53 INFO - ##### Running create-virtualenv step. 02:52:53 INFO - ##### 02:52:53 INFO - Running pre-action listener: _resource_record_pre_action 02:52:53 INFO - Running main action method: create_virtualenv 02:52:53 INFO - Creating virtualenv /builds/slave/test/build/venv 02:52:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:52:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:52:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:52:53 INFO - Using real prefix '/tools/python27' 02:52:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:52:54 INFO - Installing distribute.............................................................................................................................................................................................done. 02:52:57 INFO - Installing pip.................done. 02:52:57 INFO - Return code: 0 02:52:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:57 INFO - 'HOME': '/Users/cltbld', 02:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:57 INFO - 'LOGNAME': 'cltbld', 02:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:57 INFO - 'MOZ_NO_REMOTE': '1', 02:52:57 INFO - 'NO_EM_RESTART': '1', 02:52:57 INFO - 'PAGER': '/bin/cat', 02:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:57 INFO - 'PWD': '/builds/slave/test', 02:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:57 INFO - 'SHELL': '/bin/bash', 02:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:52:57 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:57 INFO - 'USER': 'cltbld', 02:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:57 INFO - 'XPC_FLAGS': '0x0', 02:52:57 INFO - 'XPC_SERVICE_NAME': '0', 02:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:52:57 INFO - Cleaning up... 02:52:57 INFO - Return code: 0 02:52:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:57 INFO - 'HOME': '/Users/cltbld', 02:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:57 INFO - 'LOGNAME': 'cltbld', 02:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:57 INFO - 'MOZ_NO_REMOTE': '1', 02:52:57 INFO - 'NO_EM_RESTART': '1', 02:52:57 INFO - 'PAGER': '/bin/cat', 02:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:57 INFO - 'PWD': '/builds/slave/test', 02:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:57 INFO - 'SHELL': '/bin/bash', 02:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:52:57 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:57 INFO - 'USER': 'cltbld', 02:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:57 INFO - 'XPC_FLAGS': '0x0', 02:52:57 INFO - 'XPC_SERVICE_NAME': '0', 02:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:58 INFO - Downloading/unpacking psutil>=0.7.1 02:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:53:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:01 INFO - Installing collected packages: psutil 02:53:01 INFO - Running setup.py install for psutil 02:53:01 INFO - building 'psutil._psutil_osx' extension 02:53:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:53:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:53:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:53:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:53:01 INFO - building 'psutil._psutil_posix' extension 02:53:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:53:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:53:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:53:01 INFO - ^ 02:53:01 INFO - 1 warning generated. 02:53:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:53:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:01 INFO - Successfully installed psutil 02:53:01 INFO - Cleaning up... 02:53:01 INFO - Return code: 0 02:53:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:53: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')]} 02:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53: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')]} 02:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:01 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:01 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 02:53:01 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 02:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:01 INFO - 'HOME': '/Users/cltbld', 02:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:01 INFO - 'LOGNAME': 'cltbld', 02:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:01 INFO - 'MOZ_NO_REMOTE': '1', 02:53:01 INFO - 'NO_EM_RESTART': '1', 02:53:01 INFO - 'PAGER': '/bin/cat', 02:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:01 INFO - 'PWD': '/builds/slave/test', 02:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:01 INFO - 'SHELL': '/bin/bash', 02:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:01 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:01 INFO - 'USER': 'cltbld', 02:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:01 INFO - 'XPC_FLAGS': '0x0', 02:53:01 INFO - 'XPC_SERVICE_NAME': '0', 02:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:53:04 INFO - Installing collected packages: mozsystemmonitor 02:53:04 INFO - Running setup.py install for mozsystemmonitor 02:53:04 INFO - Successfully installed mozsystemmonitor 02:53:04 INFO - Cleaning up... 02:53:04 INFO - Return code: 0 02:53:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:04 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:04 INFO - 'HOME': '/Users/cltbld', 02:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:04 INFO - 'LOGNAME': 'cltbld', 02:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:04 INFO - 'MOZ_NO_REMOTE': '1', 02:53:04 INFO - 'NO_EM_RESTART': '1', 02:53:04 INFO - 'PAGER': '/bin/cat', 02:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:04 INFO - 'PWD': '/builds/slave/test', 02:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:04 INFO - 'SHELL': '/bin/bash', 02:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:04 INFO - 'USER': 'cltbld', 02:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:04 INFO - 'XPC_FLAGS': '0x0', 02:53:04 INFO - 'XPC_SERVICE_NAME': '0', 02:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:05 INFO - Downloading/unpacking blobuploader==1.2.4 02:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:07 INFO - Downloading blobuploader-1.2.4.tar.gz 02:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:53:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:53:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:08 INFO - Downloading docopt-0.6.1.tar.gz 02:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:53:08 INFO - Installing collected packages: blobuploader, requests, docopt 02:53:08 INFO - Running setup.py install for blobuploader 02:53:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:53:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:53:09 INFO - Running setup.py install for requests 02:53:09 INFO - Running setup.py install for docopt 02:53:09 INFO - Successfully installed blobuploader requests docopt 02:53:09 INFO - Cleaning up... 02:53:09 INFO - Return code: 0 02:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:09 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:09 INFO - 'HOME': '/Users/cltbld', 02:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:09 INFO - 'LOGNAME': 'cltbld', 02:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:09 INFO - 'MOZ_NO_REMOTE': '1', 02:53:09 INFO - 'NO_EM_RESTART': '1', 02:53:09 INFO - 'PAGER': '/bin/cat', 02:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:09 INFO - 'PWD': '/builds/slave/test', 02:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:09 INFO - 'SHELL': '/bin/bash', 02:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:09 INFO - 'USER': 'cltbld', 02:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:09 INFO - 'XPC_FLAGS': '0x0', 02:53:09 INFO - 'XPC_SERVICE_NAME': '0', 02:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9MrI5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QFSSdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tSiDrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-kmo8RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-02QdbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-GxjIxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Uw6X5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-wi26Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:53:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1fSZNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-07lxXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5OnEX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8gRcFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-AOxk_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-lxiujq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tyyIKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-febiUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:53:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-xSS83B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:53:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:53:11 INFO - Running setup.py install for manifestparser 02:53:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:53:12 INFO - Running setup.py install for mozcrash 02:53:12 INFO - Running setup.py install for mozdebug 02:53:12 INFO - Running setup.py install for mozdevice 02:53:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:53:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:53:12 INFO - Running setup.py install for mozfile 02:53:12 INFO - Running setup.py install for mozhttpd 02:53:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:53:12 INFO - Running setup.py install for mozinfo 02:53:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:53:12 INFO - Running setup.py install for mozInstall 02:53:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Running setup.py install for mozleak 02:53:13 INFO - Running setup.py install for mozlog 02:53:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Running setup.py install for moznetwork 02:53:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Running setup.py install for mozprocess 02:53:13 INFO - Running setup.py install for mozprofile 02:53:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:53:13 INFO - Running setup.py install for mozrunner 02:53:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:53:14 INFO - Running setup.py install for mozscreenshot 02:53:14 INFO - Running setup.py install for moztest 02:53:14 INFO - Running setup.py install for mozversion 02:53:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:53:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:53:14 INFO - Cleaning up... 02:53:14 INFO - Return code: 0 02:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:14 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:14 INFO - 'HOME': '/Users/cltbld', 02:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:14 INFO - 'LOGNAME': 'cltbld', 02:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:14 INFO - 'MOZ_NO_REMOTE': '1', 02:53:14 INFO - 'NO_EM_RESTART': '1', 02:53:14 INFO - 'PAGER': '/bin/cat', 02:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:14 INFO - 'PWD': '/builds/slave/test', 02:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:14 INFO - 'SHELL': '/bin/bash', 02:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:14 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:14 INFO - 'USER': 'cltbld', 02:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:14 INFO - 'XPC_FLAGS': '0x0', 02:53:14 INFO - 'XPC_SERVICE_NAME': '0', 02:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:53:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-c4o2LP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:53:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-S9lsu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:53:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-PUwuAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Hh74Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TjrG55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QpOxlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zdcRjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5ET6v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-m5MOwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-u9TnZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:53:15 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)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-KHvr1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-w2aWK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:53:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:53:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-R3rMAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:53:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VBLmeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-xvuAad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:53:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zQBsES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:53:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TS7fCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:53:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:53: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 02:53: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 02:53: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 02:53: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 02:53: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 02:53: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 02:53:18 INFO - Downloading blessings-1.6.tar.gz 02:53:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:53:19 INFO - Installing collected packages: blessings 02:53:19 INFO - Running setup.py install for blessings 02:53:19 INFO - Successfully installed blessings 02:53:19 INFO - Cleaning up... 02:53:19 INFO - Return code: 0 02:53:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:53:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:53:19 INFO - Reading from file tmpfile_stdout 02:53:19 INFO - Current package versions: 02:53:19 INFO - blessings == 1.6 02:53:19 INFO - blobuploader == 1.2.4 02:53:19 INFO - docopt == 0.6.1 02:53:19 INFO - manifestparser == 1.1 02:53:19 INFO - mozInstall == 1.12 02:53:19 INFO - mozcrash == 0.17 02:53:19 INFO - mozdebug == 0.1 02:53:19 INFO - mozdevice == 0.48 02:53:19 INFO - mozfile == 1.2 02:53:19 INFO - mozhttpd == 0.7 02:53:19 INFO - mozinfo == 0.9 02:53:19 INFO - mozleak == 0.1 02:53:19 INFO - mozlog == 3.1 02:53:19 INFO - moznetwork == 0.27 02:53:19 INFO - mozprocess == 0.22 02:53:19 INFO - mozprofile == 0.28 02:53:19 INFO - mozrunner == 6.11 02:53:19 INFO - mozscreenshot == 0.1 02:53:19 INFO - mozsystemmonitor == 0.0 02:53:19 INFO - moztest == 0.7 02:53:19 INFO - mozversion == 1.4 02:53:19 INFO - psutil == 3.1.1 02:53:19 INFO - requests == 1.2.3 02:53:19 INFO - wsgiref == 0.1.2 02:53:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:19 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:19 INFO - 'HOME': '/Users/cltbld', 02:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:19 INFO - 'LOGNAME': 'cltbld', 02:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:19 INFO - 'MOZ_NO_REMOTE': '1', 02:53:19 INFO - 'NO_EM_RESTART': '1', 02:53:19 INFO - 'PAGER': '/bin/cat', 02:53:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:19 INFO - 'PWD': '/builds/slave/test', 02:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:19 INFO - 'SHELL': '/bin/bash', 02:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:19 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:19 INFO - 'USER': 'cltbld', 02:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:19 INFO - 'XPC_FLAGS': '0x0', 02:53:19 INFO - 'XPC_SERVICE_NAME': '0', 02:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:53:19 INFO - Cleaning up... 02:53:19 INFO - Return code: 0 02:53:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:19 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 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:53:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:19 INFO - 'HOME': '/Users/cltbld', 02:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:19 INFO - 'LOGNAME': 'cltbld', 02:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:19 INFO - 'MOZ_NO_REMOTE': '1', 02:53:19 INFO - 'NO_EM_RESTART': '1', 02:53:19 INFO - 'PAGER': '/bin/cat', 02:53:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:19 INFO - 'PWD': '/builds/slave/test', 02:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:19 INFO - 'SHELL': '/bin/bash', 02:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:19 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:19 INFO - 'USER': 'cltbld', 02:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:19 INFO - 'XPC_FLAGS': '0x0', 02:53:19 INFO - 'XPC_SERVICE_NAME': '0', 02:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:20 INFO - Downloading/unpacking jsonschema 02:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:53:22 INFO - Downloading/unpacking functools32 (from jsonschema) 02:53: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 02:53: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 02:53: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 02:53: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 02:53: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 02:53: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 02:53:22 INFO - Downloading functools32-3.2.3-2.tar.gz 02:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:53:22 INFO - warning: no files found matching '*.txt' 02:53:22 INFO - no previously-included directories found matching 'build' 02:53:22 INFO - no previously-included directories found matching 'dist' 02:53:22 INFO - no previously-included directories found matching '.git*' 02:53:22 INFO - Installing collected packages: jsonschema, functools32 02:53:23 INFO - Running setup.py install for functools32 02:53:23 INFO - warning: no files found matching '*.txt' 02:53:23 INFO - no previously-included directories found matching 'build' 02:53:23 INFO - no previously-included directories found matching 'dist' 02:53:23 INFO - no previously-included directories found matching '.git*' 02:53:23 INFO - Successfully installed jsonschema functools32 02:53:23 INFO - Cleaning up... 02:53:23 INFO - Return code: 0 02:53:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:53: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')]} 02:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53: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')]} 02:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b39da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd132c89f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, '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.m5AZjHSCUd/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.f8c203HNKf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:53:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:23 INFO - 'HOME': '/Users/cltbld', 02:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:23 INFO - 'LOGNAME': 'cltbld', 02:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:23 INFO - 'MOZ_NO_REMOTE': '1', 02:53:23 INFO - 'NO_EM_RESTART': '1', 02:53:23 INFO - 'PAGER': '/bin/cat', 02:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:23 INFO - 'PWD': '/builds/slave/test', 02:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:23 INFO - 'SHELL': '/bin/bash', 02:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:53:23 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:23 INFO - 'USER': 'cltbld', 02:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:23 INFO - 'XPC_FLAGS': '0x0', 02:53:23 INFO - 'XPC_SERVICE_NAME': '0', 02:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:23 INFO - Downloading/unpacking flake8 02:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:53:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:53:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:53:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:53:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:53:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:53:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:53:27 INFO - no previously-included directories found matching 'docs/_build' 02:53:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:53:27 INFO - Running setup.py install for pep8 02:53:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:53:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:53:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:53:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:53:27 INFO - no previously-included directories found matching 'docs/_build' 02:53:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:53:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:53:27 INFO - Cleaning up... 02:53:27 INFO - Return code: 0 02:53:27 INFO - Running post-action listener: _resource_record_post_action 02:53:27 INFO - Running post-action listener: _start_resource_monitoring 02:53:27 INFO - Starting resource monitoring. 02:53:27 INFO - ##### 02:53:27 INFO - ##### Running install step. 02:53:27 INFO - ##### 02:53:27 INFO - Running pre-action listener: _resource_record_pre_action 02:53:27 INFO - Running main action method: install 02:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:53:27 INFO - Reading from file tmpfile_stdout 02:53:27 INFO - Detecting whether we're running mozinstall >=1.0... 02:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:53:27 INFO - Reading from file tmpfile_stdout 02:53:27 INFO - Output received: 02:53:27 INFO - Usage: mozinstall [options] installer 02:53:27 INFO - Options: 02:53:27 INFO - -h, --help show this help message and exit 02:53:27 INFO - -d DEST, --destination=DEST 02:53:27 INFO - Directory to install application into. [default: 02:53:27 INFO - "/builds/slave/test"] 02:53:27 INFO - --app=APP Application being installed. [default: firefox] 02:53:27 INFO - mkdir: /builds/slave/test/build/application 02:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:54:00 INFO - Reading from file tmpfile_stdout 02:54:00 INFO - Output received: 02:54:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:54:00 INFO - Running post-action listener: _resource_record_post_action 02:54:00 INFO - ##### 02:54:00 INFO - ##### Running run-tests step. 02:54:00 INFO - ##### 02:54:00 INFO - Running pre-action listener: _resource_record_pre_action 02:54:00 INFO - Running main action method: run_tests 02:54:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:54:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:54:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:54:00 INFO - 2016-05-06 02:54:00.114 screenresolution[1910:8966] starting screenresolution argv=screenresolution get 02:54:00 INFO - 2016-05-06 02:54:00.117 screenresolution[1910:8966] Display 0: 1600x1200x32@60 02:54:00 INFO - 2016-05-06 02:54:00.123 screenresolution[1911:8968] starting screenresolution argv=screenresolution list 02:54:00 INFO - Available Modes on Display 0 02:54:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:54:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:54:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:54:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:54:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:54:00 INFO - Intel Iris: 02:54:00 INFO - Chipset Model: Intel Iris 02:54:00 INFO - Type: GPU 02:54:00 INFO - Bus: Built-In 02:54:00 INFO - VRAM (Dynamic, Max): 1536 MB 02:54:00 INFO - Vendor: Intel (0x8086) 02:54:00 INFO - Device ID: 0x0a2e 02:54:00 INFO - Revision ID: 0x0009 02:54:00 INFO - Displays: 02:54:00 INFO - Display: 02:54:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:54:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:54:00 INFO - Display Serial Number: 1600x1200 60 02:54:00 INFO - Main Display: Yes 02:54:00 INFO - Mirror: Off 02:54:00 INFO - Online: Yes 02:54:00 INFO - Rotation: Supported 02:54:00 INFO - Return code: 0 02:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:54:00 INFO - Python 2.7.3 02:54:00 INFO - Return code: 0 02:54:00 INFO - grabbing minidump binary from tooltool 02:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:00 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 0x10b23ce00>, '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 0x10b0a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b237b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:54:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:54:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:54:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:54:00 INFO - Return code: 0 02:54:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:54:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:54:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:54:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:54:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:54:01 INFO - Return code: 0 02:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5AZjHSCUd/Render', 02:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:01 INFO - 'HOME': '/Users/cltbld', 02:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:01 INFO - 'LOGNAME': 'cltbld', 02:54:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:54:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:01 INFO - 'MOZ_NO_REMOTE': '1', 02:54:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:54:01 INFO - 'NO_EM_RESTART': '1', 02:54:01 INFO - 'PAGER': '/bin/cat', 02:54:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:01 INFO - 'PWD': '/builds/slave/test', 02:54:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:01 INFO - 'SHELL': '/bin/bash', 02:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8c203HNKf/Listeners', 02:54:01 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 02:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:01 INFO - 'USER': 'cltbld', 02:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:01 INFO - 'XPC_FLAGS': '0x0', 02:54:01 INFO - 'XPC_SERVICE_NAME': '0', 02:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:54:01 INFO - mozversion application_buildid: 20160506004122 02:54:01 INFO - mozversion application_changeset: b17fa0c2f2e4537942a5d91b543c825f124564fc 02:54:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:54:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:54:01 INFO - mozversion application_name: Firefox 02:54:01 INFO - mozversion application_remotingname: firefox-dev 02:54:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:54:01 INFO - mozversion application_vendor: Mozilla 02:54:01 INFO - mozversion application_version: 48.0a2 02:54:01 INFO - mozversion platform_buildid: 20160506004122 02:54:01 INFO - mozversion platform_changeset: b17fa0c2f2e4537942a5d91b543c825f124564fc 02:54:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:54:01 INFO - mozversion platform_version: 48.0a2 02:54:01 INFO - using testdate: 1462528441 02:54:01 INFO - actual date: 1462528441 02:54:01 INFO - starting webserver on 'localhost:49446' 02:54:01 INFO - SUITE-START | Running 2 tests 02:54:01 INFO - TEST-START | dromaeo_css 02:54:01 INFO - operating with platform_type : mac_ 02:54:01 INFO - Initialising browser for dromaeo_css test... 02:54:01 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp10TiYt/profile http://localhost:49446/getInfo.html) 02:54:04 INFO - PROCESS | 1929 | __metrics Screen width/height:1600/1200 02:54:04 INFO - PROCESS | 1929 | colorDepth:24 02:54:04 INFO - PROCESS | 1929 | Browser inner width/height: 1024/689 02:54:04 INFO - PROCESS | 1929 | __metrics 02:54:04 INFO - PROCESS | 1929 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:54:04 ERROR - PROCESS | 1929 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:54:05 INFO - TEST-INFO | 1929: exit 0 02:54:05 INFO - Browser initialized. 02:54:05 INFO - Running cycle 1/1 for dromaeo_css test... 02:54:05 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp10TiYt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:54:17 INFO - PROCESS | 1932 | RSS: Main: 211554304 02:54:17 INFO - PROCESS | 1932 | 02:55:07 INFO - PROCESS | 1932 | 1462528507948 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:57:19 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49446/tests/dromaeo/cssquery-ext.html) 02:57:21 INFO - PROCESS | 1932 | RSS: Main: 366104576 02:57:21 INFO - PROCESS | 1932 | 03:00:24 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-ext.html (next: http://localhost:49446/tests/dromaeo/cssquery-jquery.html) 03:00:25 INFO - PROCESS | 1932 | RSS: Main: 357441536 03:00:25 INFO - PROCESS | 1932 | 03:03:27 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49446/tests/dromaeo/cssquery-mootools.html) 03:03:27 INFO - PROCESS | 1932 | RSS: Main: 359358464 03:03:27 INFO - PROCESS | 1932 | 03:06:30 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49446/tests/dromaeo/cssquery-prototype.html) 03:06:30 INFO - PROCESS | 1932 | RSS: Main: 355581952 03:06:30 INFO - PROCESS | 1932 | 03:09:34 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49446/tests/dromaeo/cssquery-yui.html) 03:09:34 INFO - PROCESS | 1932 | RSS: Main: 328728576 03:09:34 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49446/tests/dromaeo/cssquery-yui.html (next: http://localhost:49446/tests/dromaeo/cssquery-dojo.html) 03:12:37 INFO - PROCESS | 1932 | RSS: Main: 367075328 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | __start_tp_report 03:12:37 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 03:12:37 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 03:12:37 INFO - PROCESS | 1932 | |i|pagename|runs| 03:12:37 INFO - PROCESS | 1932 | |0;dojo.html;3364.0748794081633;3419.0597585663954;3432.9485057724137;3452.447608382656;3459.8789042383505;5633.746481408336;5663.745131469083;5670.291213598301;5694.003549378863;5717.113847353667;4717.646176536757;5687.744051517681;5716.79991200307;5721.31344238692;5752.827415177541;4256.659467242629;4331.3719510670935;5063.493650634926;5142.51146141116;5200.349956255468;5142.305788718512;5213.947860521384;5220.086484865153;5230.581553475732;5234.214867769827;5112.846614601568;5163.380394352665;5198.740062996835;5207.713575753332;5208.661437006583;5194.013137503862;5245.26566280721;5252.6323157379;5259.158534634478;5292.206169074631;5043.646944713872;5078.46676099008;5139.408967968657;5162.716050617215;5166.9224961625605;5561.833145005657;5581.413951535112;5583.441655834425;5590.413006634326;5593.496585307342;6173.351798061229;6218.22272215973;6229.221347331583;6233.750649973996;6236;6101.237345331833;6142.232220972379;6177.537131786823;6239.875202495925;6252.968735156295;5840.153177789197;5860;5863.912041319383;5871.9706401468575;5878.853028674274;6250.74997000124;6273.96863015682;6307.968460157762;6308.400701933309;6310.305866354697;6027.21646185993;6125.785597504066;6140.8771824563255;6159.753609855646;6198.845028874224;6150.538709596798;6181.690915454209;6237.064440333987;6273.12176295319;6314.147590075281;6226.844328891723;6237.220347456568;6240.438360547572;6240.812775616739;6273.7176827042895;6207.224096987877;6226.782062627786;6263.091851681481;6285.497160227171;6299.0866324382705;4826.927596086061;4902.681325713817;4906.092372911023;4906.533879281463;4942.74019453871;4428.269335559597;4455.1980643484485;4471.23988921884;4483.036147228361;4485.820567177341;5670.886582268414;5685.374608793029;5686.601937864392;5784.479396854219;5793.420657934173;5192.1952097424955;6214.316425193225;6295;6302.716377763011;6304.810855674337;5188.8584511407425;5648.406917273709;5727.713614319267;5728.427157284238;5742.856428589319;5201.245819356172;5321;5321.6540924839765;5404.594655400861;5422.240886275925;6306.684665766693;6349.49204063683;6356.364363563606;6360.109584658059;6365.522585806176;6229.4393504584805;6235.688215589294;6257.561970662192;6258.3741625838875;6299.11812346281;6217.595856269328;6292.779752708633;6334.778282760081;6336.778212762531;6346.2067241594805;6199.318075011655;6212.254529456494;6214.036824292242;6232.314445410911;6245;6295.213098362705;6297.653629050446;6300.527460440394;6312.968435157794;6324.209473815773;4802.711837289774;5838.416158384298;6044.063170208625;6084.178635884099;6112.868254062901;6106.582013105306;6124.564025909317;6131.254333328352;6132.233470816148;6132.324915438508;5955.791547295824;5991.2510936132985;5993.0710739835395;6000.759969601167;6001.219841420588;5848.239728835224;6018.458338749533;6021.578489505692;6026.487748541248;6122.051082082285;5856.853578660397;5888.116782482662;5942.257217847769;5977.432143946318;5996.370381110095 03:12:37 INFO - PROCESS | 1932 | |1;ext.html;500.97244651544173;529.9046171689096;551.5146670929583;575.5913301555895;593.8099808061422;27274.318142046457;31335.903243386485;39035.60964390359;40376;40696.59303406965;44986.55013449865;45425.772871135596;45547;45578.772106139506;45756.771216143956;9537.04629537046;9782.461964591945;9782.853257201148;9784.070513301225;9793.412395256308;8455.365847561427;8674.175953284428;11297.774044519105;11553.653390398302;11561.653150405502;11438.48526816291;11587.884121158808;11596.478158482847;11608.361540115291;11640.825387619192;8449.281811046068;8651.307895368356;11417.200795944287;11539.48072336747;11562.537498500093;8601.913980860174;8742.86885696715;11178.496967636476;11430.942845285803;11456.255343402652;39581.80209098936;39983.8000809997;40098.59901401007;40152.39771403431;40278.39582406266;3262.2659901522206;3291.4075466416034;3594.892153235407;3654.0682126057686;3845.2117315950163;28409.573856391962;28671.61347359222;28810.85594572035;29447.411051779058;29904.55143172854;2723.1149876290283;2749.1615057407485;2763.6545431821023;2903.071017274473;2904.0561817409425;495520;495831;496556;497017;497811;63695.36304636894;63956;64345;64364.35635643677;64432;66492;66711.66644166845;66724;66741.66629166823;66852.665736671;16922.23849926756;17458.825411745973;17521.474355769347;17571.385001524886;17665.11674416274;11899.286042837457;12167.817482737766;13086.934565327303;13477.528286509925;13549.458021679022;17194.968301901677;17343.306267749147;17419.47741567755;17422.128893555524;17497.56256093608;6321.356447323729;6353.777617783356;6452.064450654629;6464.931222009006;6465.870682586322;869.8434281829332;966.1690945786618;1078.46615925116;1090.4384242115316;1095.901368876805;28797.71202287992;30093.90914952003;30114.397712045637;30261;30329.95681506112;1085.3921803789904;1087.1194332590626;1109.6670998700358;1174.7826652069396;1204.5001324450254;15105.773413398807;15349.6162595936;15430.845691542941;15679;15788.447404340794;657264;663820;668141;670414;670444;56721;56853;56891.431085689444;57003;57178.428215718144;1311.9016073794503;1348.2315080404271;1350.92569908654;1468.7503124468865;1475.3803402571161;25184.37039074038;28092.488438945133;28975.5653665191;29620.703792962657;29730.702692972794;45797.31304030376;46147.538524615666;46229;46263.07473850571;46339.30491042569;446672;449235;449609;449736;450490.7475462602;25583;26510.337241569116;26553.867230664495;26605.467890641692;37671;43012.05361383356;43608.12783744371;44751.328730068424;44897;45154.77422612997;35454.645453545134;35463.82268088643;36673;36688.63311366852;37407.81296093611;35891.64108358883;36077.81961090283;36528.26943461169;36754.26491470102;36799.58404991311;32607.18482037968;36559.35482903223;37255.627443725214;37697.62302376941;37985.29066192083;32703;32748.50877236891;37257.62742372542;38688.80655596704;38867.80566097151;29653.962111326;29872.402551949275;29968.25079373033;30133.547996780504;30469.390612188075 03:12:37 INFO - PROCESS | 1932 | |2;jquery.html;337.9003194057753;390.79287977371996;402.99452865101574;408.96728261739065;411.36649559678096;20446.693299600516;20681.793182068173;21949.67075493867;22025.779742202594;22435.887820560874;19708.802911970877;20755.68866467004;20775.480612984684;20955.68566471504;22034.88982555089;5330.600204984622;5803.303603567557;6182.319944806068;6185.319614842364;6189.566730328879;3955.9208815823667;4193.972476743203;5820.650760954335;6121.816345509619;6164.7534098636;5287.709175995319;6021.397860213988;6140.570160088796;6144.41628045335;6145.661988590626;6319.304876463585;6650.035744816999;6659.034440006172;6665;6666.666666666647;3825;4056.513218413809;4057.7565346079036;4285.657147428229;5671.631343962629;13493.730125397436;13740.725185496334;13855.16868987864;14009.0193686442;14034.859651403458;2123.522207503315;2183.6724491326268;2243.4054975431523;2297.9655305170436;2301.953960920789;9195.586198621078;9730;10439.478026098664;11106.833397499044;15124.546263612;1716.7596536484903;1719.9570010749705;1742.7298768690873;1790.7578265135228;1800.522861441719;88016;105642.47178764056;108644;114879.42560287229;114889;11772.70568235801;11783.528658853722;11891.583794567148;11915.046796256278;11951;15580;15686.294116764773;15722.213889305489;15724.68550628981;15740.212989350486;8914.86627700585;8920.464772113693;8932.19610235082;8935;9053.637854485747;8836.160564746338;8845.51349675774;8858.247049000778;8921.15249051339;8979;4673.7429441381055;4696.7416792076765;4704.764761761899;4724.126036683225;4753.786079626425;1810.9275628974956;1839.3715231391984;1857.2292498613194;1914.387396033256;2004.3085135628185;906.6554709210458;914.9039350868196;922.5802259971697;947.2564037230878;964.0744884910564;20298.28955986563;21257;21475;21533.46166345854;21710.89144554267;796.5459687977797;810.9107998120202;991.8760154980628;1005.6631028605499;1064.69656147999;8164.306033987177;11014.50434730439;11205.327680339205;11319.660410187707;11354.921282478152;536312.3184384053;569575;614136;634447;635258;102551;125389;127598.36200819122;134750.30499390155;141626.58373416134;378.53062202868244;488.44073535802016;972.7908499672604;1008.8587597736323;1010.3988127064385;10809.729756756144;10943.671689849318;11444.427778611203;11460.312381256817;11800.881991180322;42802.571974281105;51810.96378072493;52733.47266527286;52894;54706;69909.65045174741;72797;73076;76027;77497;7932.762017139495;8224.342052635655;8333.499990000619;8392.748217553482;8712.564371781258;11631.302121872715;12263.202891812003;12426.254424734545;12642.620721378373;12664.98680105571;10629.25595208328;10869.021788039114;10872.836907446554;10957.561697532328;11030.952059554651;10483.633072842718;10623.468826558486;10732.409717465614;10838.729031774268;10950.34297942126;10674.306170098918;10828.02547770673;11187.776244474902;11252.887471125185;11255.04332131778;7999.60001999886;8047.114817369968;8195.79510512218;8357.08072112055;8573.871391929251;10633.723953125675;10797.62208322705;10878.292910960763;11096.223264371416;11185.32888026721 03:12:37 INFO - PROCESS | 1932 | |3;mootools.html;1380.2339701465685;1388.8680575345338;1559.3372816552965;1752.2815645585313;1954.4527532290954;7832;8220.177982201776;8225;8235.588220588976;8252.958735206323;7950.960245198766;8432.451890627104;8698.130186981298;8704.521251331174;8713.651453941851;2390.3307074019217;2478.6405971134177;2480.045182604692;2481.019997101144;2481.565725997952;2436.597961336386;2484.8881800319027;2488.377905523619;2489.215896992451;2490.950180996379;2431.209856796548;2456.226288719057;2466.519028789387;2471.258622413278;2481.5409149307347;2004.7193392925003;2061.907214175358;2447.730749617541;2459.655648209252;2466.3094333586623;2420.2739178246457;2437.3662847659557;2448.1309135257084;2449.816263780224;2456.2017344363153;8759.211670949646;8838.29293656506;8870.911290887074;8882.733517994471;8894.688685896028;5229.424763276036;5304.469553044703;5452.890942181174;5458.4814442627885;5472.425395333512;3315.419801534722;3367.4275373186615;3424.366492198926;3455.809930453824;3462.0133262020204;1046.4192373232852;1049.4070849969742;1062.6705721226444;1066.2536224642781;1075.3440401355167;11673.416329183507;11787.58743443984;11797.29216247028;11827.88172118285;11860.584879529262;4666.60333871618;4814.15752243356;4898.8775280618265;5140.254663073833;5150.639455238097;9153.77115572116;9187.402818816889;9242.445453272825;9250.72247832566;9260.027697091844;5309.867253318621;5355.919661205085;5361.973190134025;5365.168398898177;5365.704886231217;4255.893602659897;4438.578335058164;4965.478624744422;4986.601071914238;5012.997400519911;3843.8462461501786;3868.7485313454536;3899.76601403911;3933.449317095609;3981.064449854282;1223.3149436315691;1234.0991076514015;1238.962831115068;1249.2566922681171;1250.3498180945858;640.9391107844747;792.0772300270236;901.454619954931;913.5706218077493;957.6169532187181;4429.889252768707;5474.562035037187;5724.313082430135;5762.250907382014;5781.797637082766;911.6262332443666;957;994.1599348550463;1001.7645853224486;1034.886162522122;3703.4259689748133;3781.300459415017;3802.068493219179;3832.1186127190344;3832.597577254404;10314.587416503256;10740.462976851282;10816.080633146114;10848.132149428027;10860.022597966063;10242.487875606339;10804.729881752863;10810.297330673638;10813.56745730162;10845.674629761119;7858.546168958761;8053.31546818527;8068.878966815502;8199.303059240032;8251.133630968781;5555.361343194843;5588.441155884379;5615.185798059177;5619.241402410622;5811.157382179645;9661.516924153624;9747.512624368612;9932.652357167754;9933.602655893683;9937.65218217359;9473.952630236805;9959.502024898582;9962.402255864672;10070.144037756872;10073.84889226677;7243.674034668347;7454.813629659302;7461.141968673672;7462;7467.178610352966;8045.195480451908;8052.8792068117855;8059.798505037186;8103.8379232413845;8218.342532597497;7261.128664560287;7307.3058059484265;7333.889991650236;7445.962770186331;7519.097708275042;7099.716011359487;7202.927970720435;7206.207317195;7238.022866912899;7259.6370181491775;6872.140982377203;6897.137857767779;6971.825704357431;6972.79081627552;6983.1620205575655;4269.829206831692;4362.018545827291;4618.884527886829;4708.552687494683;4838.419389673261;4812.542808433193;4877.853664390074;4883.047805678001;4893.605377194611;4903.4606193317995 03:12:37 INFO - PROCESS | 1932 | |4;prototype.html;270.8984130950893;318.51426574473925;325.63854121924675;327.9016295111466;331.7130681960105;4967.130752118378;5288.682679039255;5329.973350133244;5373.650712703677;5380.91928621071;4989.875253118674;5017.749112544368;5257.13257312543;5272.578193744501;5312.203169524573;3898.629630185135;3939.5863434339412;4030.2544029354517;4108.506979162505;4149.170165966819;3623.963760362389;4022.235775202717;4095.9385609215888;4097.119119389331;4174.728642638234;3398.24741959014;4022.6178513041214;4055.229506393776;4061.3704875744306;4074.7147699661036;3892.3577609694507;3940.8571514260975;4092.815823287959;4130.607592278729;4141.875743727693;3138.2154461384653;3318.9170270743425;3507.4037413639744;3565.5008298838184;3632.8728494502566;4354.586314300136;4521.779119637712;4901.215805471107;5148.4079330876675;5193.272941788153;3958.762474251554;4458.509563947963;4600.470945841238;4695.248760198386;4742.857714268543;4601.539846015406;4899.325505870597;5521.696306703129;5551.472610102034;5615.971920140373;3357.8153201573905;3986.880393588197;4017.718759686794;4023.5574086850716;4026.859059932888;4858.081057982077;5609.775608975636;5831.416858314177;5873.588848780587;5924.04506512854;4086.5504794472586;4086.959130408658;4115.938260926088;4117.402976568381;4121.29937910556;3781.1681430085337;4110.794460277034;4116.217918595457;4150.813213405404;4183.226103170923;4351.369051487517;4646.303054541778;4663.27719203524;4681.016986432887;4711.199096153662;5206.817761378409;5609.298837645294;5639.407862174495;5703.8859222816145;5730.570207234474;1149.5516748468103;1322.5833862333334;1459.569427019032;1464.6777708904024;1470.206088712105;748.9475736698488;906.8141031088611;925.3938670171049;952.2572393533055;980.7401038724744;4639.628829693616;5411.918821217685;5640.294963129609;5795;5810.477057064884;6314.684265786784;6376.632007075168;6393.776217832354;6400.871982560416;6455.419012288822;5079.425378132791;5701.71491425427;5713.600047996683;5763.711814409263;5777.94222057774;2830.929226769347;3042.787004909679;3056.2206637307345;3315.254067834766;3322.1595535817014;5816.680082595498;6354.3963323484195;6645.368689974444;6809.625470599165;6810.6254156020905;6195.876082478325;6448.935510644834;6518.543701940914;6545.639989800607;6647.368499992493;6609.867802644017;6613.603183808987;6615.536912416084;6630.53586248958;6664.0670306158145;4295.24833154203;5297.54970827484;5381.327334083239;5489.505944465017;5699;5263.710495922761;5615.943840561542;5697.943020569741;5785.82642520725;5855.00464920973;6170.660613666291;6192.102145188832;6199.225096862892;6200.193974783249;6216.222972128484;3392.9830350848088;3988.880333589997;4131.058402550796;4151.837485504064;4166.708330416963;5188.377394712658;5859.765609375577;5862.648241105548;5878.738529016421;5893.351731309467;5050.823221187241;5332.33345831771;5464.480874316933;5524.254225679482;5534.4742249486235;5622.823104945693;5625.718714064362;5666.518345940642;5677.716114194356;5756.862509932181;4702.459217190067;5485.369182544058;5490.231367608458;5584.804531841366;5585.078462053716;5105.92341114891;5302.072137375867;5476.835694929158;5613.859653508695;5666.886662266649;4652.32541281519;4664.4112517553085;4758.406668365818;5469.398366179637;5682.403347648437 03:12:37 INFO - PROCESS | 1932 | |5;yui.html;671.4661843834153;741.6514238307993;744.4491076603315;744.6016381236038;754.9053871885764;14528;14687.485937992173;14829.184394858305;14838.961272710902;14867.925660371697;13897.652558686037;14148;14191.148531088142;14193.645158871033;14198.14811111334;705.2524324216347;892.857142857143;897.9910200897973;907.7095329494564;907.9682211122612;723.1250187273406;723.3453724379445;723.9095113110861;726.8546290741847;726.8982342472058;925.0934084597098;954.374884450686;956.3353469338813;956.6938579654513;956.9856452153223;948.8434408322653;953.50894289441;953.9904600954005;955.7276176289794;962.7833737409062;549.7745924171071;554.5840619535348;703.2088899987514;705.1502938958542;707.9150501939748;14602.707945841024;14682.70634587313;14753;14755.18846463444;14777.039492432958;12429.129960902741;12469.376531173404;12483.251004939733;12723.427445764963;12814.42335094923;8534.615942282611;9930;9993.500324983721;10120.240981926385;10250;3424.503447000196;3491.458823882277;3517.736169787276;3576.982115089434;3715.145516531186;80376.59811700904;83029;83575.5821220896;83619.5819020901;83749.58125209335;9489.952550237344;9618.567164477616;9750.171235444925;9824.852627210455;10016.749581260527;22135.889320553295;22143.889280553816;22157.113715451524;22165.44586385321;22170.224042158447;7314.48798584094;7362.300581444754;7412.18465968743;7451.478396512301;7645.579493127821;4042.514898212233;4125.97937010319;4227.175700738327;4249.638780703613;4391.407160033418;3600.3519366513756;3687.188818459988;3690.446433035066;3690.6493883081066;3694.5012423323046;604.2326245667977;627.3663599764271;629.1663545801739;636.6752955992478;647.4658406814303;556.3629644057531;578.8147792706378;579.9420057994251;584.9268841394826;611.0955785437577;8038.758837234892;8070.354371650254;8102.0277566692375;8220.301274391744;8257;512.1651707716399;520.1495554767981;524.2581746828196;565.5220394573324;584.9590528662952;3825.5026846509777;3834.1373191031794;3992.2215168041953;4038.5355684096703;4192.245395828719;98320.50839745898;103027;103110.48444757878;103347.4832625832;106669;85954.57022714846;86805;87766;87817;89920;72025.27974720186;72041.91937120947;72654.54690906152;74625.88061179087;75051.24948750442;8071.959640201761;8077.515349079074;8152.021757389151;8299.626516806878;8321.08468068524;46814;46860;46967.82580435516;48180.759096204296;48911.02177956492;66665.33334666786;69984.30015699778;70339;71000;71661.56676866538;10612.375441016275;11022.889771102507;11050.502727377127;11162.832557511807;11681.824772628248;11688.532458701557;11799.941000294944;12185;12425.254484730945;12507.312097834707;11480.96671299587;11569.768604627692;11855.34795586251;11903;11990.640280791591;9364.050324148144;9386.95306523463;11246.606368777053;11328.150388721044;11557.82663260035;10836.458177090955;10957.287776294515;11121.610743623934;11162.944185279022;11302.83045754298;9379.531023448937;9390.342676012613;9665.3234273603;9910.851337230091;10004.949480304464;9355.953220234127;9492.050794920453;9525.904740952501;9646.951765241129;9758.414495130315 03:12:37 INFO - PROCESS | 1932 | __end_tp_report 03:12:37 INFO - PROCESS | 1932 | __start_cc_report 03:12:37 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1427 03:12:37 INFO - PROCESS | 1932 | __end_cc_report 03:12:37 INFO - PROCESS | 1932 | __startTimestamp1462529557804__endTimestamp 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | ------- Summary: start ------- 03:12:37 INFO - PROCESS | 1932 | Number of tests: 6 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5712.91 Median:5955.79 stddev:664.65 (11.2%) stddev-sans-first:640.64 03:12:37 INFO - PROCESS | 1932 | Values: 3364.1 3419.1 3432.9 3452.4 3459.9 5633.7 5663.7 5670.3 5694.0 5717.1 4717.6 5687.7 5716.8 5721.3 5752.8 4256.7 4331.4 5063.5 5142.5 5200.3 5142.3 5213.9 5220.1 5230.6 5234.2 5112.8 5163.4 5198.7 5207.7 5208.7 5194.0 5245.3 5252.6 5259.2 5292.2 5043.6 5078.5 5139.4 5162.7 5166.9 5561.8 5581.4 5583.4 5590.4 5593.5 6173.4 6218.2 6229.2 6233.8 6236.0 6101.2 6142.2 6177.5 6239.9 6253.0 5840.2 5860.0 5863.9 5872.0 5878.9 6250.7 6274.0 6308.0 6308.4 6310.3 6027.2 6125.8 6140.9 6159.8 6198.8 6150.5 6181.7 6237.1 6273.1 6314.1 6226.8 6237.2 6240.4 6240.8 6273.7 6207.2 6226.8 6263.1 6285.5 6299.1 4826.9 4902.7 4906.1 4906.5 4942.7 4428.3 4455.2 4471.2 4483.0 4485.8 5670.9 5685.4 5686.6 5784.5 5793.4 5192.2 6214.3 6295.0 6302.7 6304.8 5188.9 5648.4 5727.7 5728.4 5742.9 5201.2 5321.0 5321.7 5404.6 5422.2 6306.7 6349.5 6356.4 6360.1 6365.5 6229.4 6235.7 6257.6 6258.4 6299.1 6217.6 6292.8 6334.8 6336.8 6346.2 6199.3 6212.3 6214.0 6232.3 6245.0 6295.2 6297.7 6300.5 6313.0 6324.2 4802.7 5838.4 6044.1 6084.2 6112.9 6106.6 6124.6 6131.3 6132.2 6132.3 5955.8 5991.3 5993.1 6000.8 6001.2 5848.2 6018.5 6021.6 6026.5 6122.1 5856.9 5888.1 5942.3 5977.4 5996.4 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:67623.89 Median:36616.18 stddev:145638.98 (397.7%) stddev-sans-first:145960.34 03:12:37 INFO - PROCESS | 1932 | Values: 501.0 529.9 551.5 575.6 593.8 27274.3 31335.9 39035.6 40376.0 40696.6 44986.6 45425.8 45547.0 45578.8 45756.8 9537.0 9782.5 9782.9 9784.1 9793.4 8455.4 8674.2 11297.8 11553.7 11561.7 11438.5 11587.9 11596.5 11608.4 11640.8 8449.3 8651.3 11417.2 11539.5 11562.5 8601.9 8742.9 11178.5 11430.9 11456.3 39581.8 39983.8 40098.6 40152.4 40278.4 3262.3 3291.4 3594.9 3654.1 3845.2 28409.6 28671.6 28810.9 29447.4 29904.6 2723.1 2749.2 2763.7 2903.1 2904.1 495520.0 495831.0 496556.0 497017.0 497811.0 63695.4 63956.0 64345.0 64364.4 64432.0 66492.0 66711.7 66724.0 66741.7 66852.7 16922.2 17458.8 17521.5 17571.4 17665.1 11899.3 12167.8 13086.9 13477.5 13549.5 17195.0 17343.3 17419.5 17422.1 17497.6 6321.4 6353.8 6452.1 6464.9 6465.9 869.8 966.2 1078.5 1090.4 1095.9 28797.7 30093.9 30114.4 30261.0 30330.0 1085.4 1087.1 1109.7 1174.8 1204.5 15105.8 15349.6 15430.8 15679.0 15788.4 657264.0 663820.0 668141.0 670414.0 670444.0 56721.0 56853.0 56891.4 57003.0 57178.4 1311.9 1348.2 1350.9 1468.8 1475.4 25184.4 28092.5 28975.6 29620.7 29730.7 45797.3 46147.5 46229.0 46263.1 46339.3 446672.0 449235.0 449609.0 449736.0 450490.7 25583.0 26510.3 26553.9 26605.5 37671.0 43012.1 43608.1 44751.3 44897.0 45154.8 35454.6 35463.8 36673.0 36688.6 37407.8 35891.6 36077.8 36528.3 36754.3 36799.6 32607.2 36559.4 37255.6 37697.6 37985.3 32703.0 32748.5 37257.6 38688.8 38867.8 29654.0 29872.4 29968.3 30133.5 30469.4 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:34044.34 Median:12139.15 stddev:99776.46 (821.9%) stddev-sans-first:100024.26 03:12:37 INFO - PROCESS | 1932 | Values: 337.9 390.8 403.0 409.0 411.4 20446.7 20681.8 21949.7 22025.8 22435.9 19708.8 20755.7 20775.5 20955.7 22034.9 5330.6 5803.3 6182.3 6185.3 6189.6 3955.9 4194.0 5820.7 6121.8 6164.8 5287.7 6021.4 6140.6 6144.4 6145.7 6319.3 6650.0 6659.0 6665.0 6666.7 3825.0 4056.5 4057.8 4285.7 5671.6 13493.7 13740.7 13855.2 14009.0 14034.9 2123.5 2183.7 2243.4 2298.0 2302.0 9195.6 9730.0 10439.5 11106.8 15124.5 1716.8 1720.0 1742.7 1790.8 1800.5 88016.0 105642.5 108644.0 114879.4 114889.0 11772.7 11783.5 11891.6 11915.0 11951.0 15580.0 15686.3 15722.2 15724.7 15740.2 8914.9 8920.5 8932.2 8935.0 9053.6 8836.2 8845.5 8858.2 8921.2 8979.0 4673.7 4696.7 4704.8 4724.1 4753.8 1810.9 1839.4 1857.2 1914.4 2004.3 906.7 914.9 922.6 947.3 964.1 20298.3 21257.0 21475.0 21533.5 21710.9 796.5 810.9 991.9 1005.7 1064.7 8164.3 11014.5 11205.3 11319.7 11354.9 536312.3 569575.0 614136.0 634447.0 635258.0 102551.0 125389.0 127598.4 134750.3 141626.6 378.5 488.4 972.8 1008.9 1010.4 10809.7 10943.7 11444.4 11460.3 11800.9 42802.6 51811.0 52733.5 52894.0 54706.0 69909.7 72797.0 73076.0 76027.0 77497.0 7932.8 8224.3 8333.5 8392.7 8712.6 11631.3 12263.2 12426.3 12642.6 12665.0 10629.3 10869.0 10872.8 10957.6 11031.0 10483.6 10623.5 10732.4 10838.7 10950.3 10674.3 10828.0 11187.8 11252.9 11255.0 7999.6 8047.1 8195.8 8357.1 8573.9 10633.7 10797.6 10878.3 11096.2 11185.3 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5629.50 Median:4976.04 stddev:3142.85 (63.2%) stddev-sans-first:3135.44 03:12:37 INFO - PROCESS | 1932 | Values: 1380.2 1388.9 1559.3 1752.3 1954.5 7832.0 8220.2 8225.0 8235.6 8253.0 7951.0 8432.5 8698.1 8704.5 8713.7 2390.3 2478.6 2480.0 2481.0 2481.6 2436.6 2484.9 2488.4 2489.2 2491.0 2431.2 2456.2 2466.5 2471.3 2481.5 2004.7 2061.9 2447.7 2459.7 2466.3 2420.3 2437.4 2448.1 2449.8 2456.2 8759.2 8838.3 8870.9 8882.7 8894.7 5229.4 5304.5 5452.9 5458.5 5472.4 3315.4 3367.4 3424.4 3455.8 3462.0 1046.4 1049.4 1062.7 1066.3 1075.3 11673.4 11787.6 11797.3 11827.9 11860.6 4666.6 4814.2 4898.9 5140.3 5150.6 9153.8 9187.4 9242.4 9250.7 9260.0 5309.9 5355.9 5362.0 5365.2 5365.7 4255.9 4438.6 4965.5 4986.6 5013.0 3843.8 3868.7 3899.8 3933.4 3981.1 1223.3 1234.1 1239.0 1249.3 1250.3 640.9 792.1 901.5 913.6 957.6 4429.9 5474.6 5724.3 5762.3 5781.8 911.6 957.0 994.2 1001.8 1034.9 3703.4 3781.3 3802.1 3832.1 3832.6 10314.6 10740.5 10816.1 10848.1 10860.0 10242.5 10804.7 10810.3 10813.6 10845.7 7858.5 8053.3 8068.9 8199.3 8251.1 5555.4 5588.4 5615.2 5619.2 5811.2 9661.5 9747.5 9932.7 9933.6 9937.7 9474.0 9959.5 9962.4 10070.1 10073.8 7243.7 7454.8 7461.1 7462.0 7467.2 8045.2 8052.9 8059.8 8103.8 8218.3 7261.1 7307.3 7333.9 7446.0 7519.1 7099.7 7202.9 7206.2 7238.0 7259.6 6872.1 6897.1 6971.8 6972.8 6983.2 4269.8 4362.0 4618.9 4708.6 4838.4 4812.5 4877.9 4883.0 4893.6 4903.5 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4686.04 Median:5200.05 stddev:1483.13 (28.5%) stddev-sans-first:1449.80 03:12:37 INFO - PROCESS | 1932 | Values: 270.9 318.5 325.6 327.9 331.7 4967.1 5288.7 5330.0 5373.7 5380.9 4989.9 5017.7 5257.1 5272.6 5312.2 3898.6 3939.6 4030.3 4108.5 4149.2 3624.0 4022.2 4095.9 4097.1 4174.7 3398.2 4022.6 4055.2 4061.4 4074.7 3892.4 3940.9 4092.8 4130.6 4141.9 3138.2 3318.9 3507.4 3565.5 3632.9 4354.6 4521.8 4901.2 5148.4 5193.3 3958.8 4458.5 4600.5 4695.2 4742.9 4601.5 4899.3 5521.7 5551.5 5616.0 3357.8 3986.9 4017.7 4023.6 4026.9 4858.1 5609.8 5831.4 5873.6 5924.0 4086.6 4087.0 4115.9 4117.4 4121.3 3781.2 4110.8 4116.2 4150.8 4183.2 4351.4 4646.3 4663.3 4681.0 4711.2 5206.8 5609.3 5639.4 5703.9 5730.6 1149.6 1322.6 1459.6 1464.7 1470.2 748.9 906.8 925.4 952.3 980.7 4639.6 5411.9 5640.3 5795.0 5810.5 6314.7 6376.6 6393.8 6400.9 6455.4 5079.4 5701.7 5713.6 5763.7 5777.9 2830.9 3042.8 3056.2 3315.3 3322.2 5816.7 6354.4 6645.4 6809.6 6810.6 6195.9 6448.9 6518.5 6545.6 6647.4 6609.9 6613.6 6615.5 6630.5 6664.1 4295.2 5297.5 5381.3 5489.5 5699.0 5263.7 5615.9 5697.9 5785.8 5855.0 6170.7 6192.1 6199.2 6200.2 6216.2 3393.0 3988.9 4131.1 4151.8 4166.7 5188.4 5859.8 5862.6 5878.7 5893.4 5050.8 5332.3 5464.5 5524.3 5534.5 5622.8 5625.7 5666.5 5677.7 5756.9 4702.5 5485.4 5490.2 5584.8 5585.1 5105.9 5302.1 5476.8 5613.9 5666.9 4652.3 4664.4 4758.4 5469.4 5682.4 03:12:37 INFO - PROCESS | 1932 | 03:12:37 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:18990.44 Median:584.94 stddev:27588.14 (4716.4%) stddev-sans-first:27631.24 03:12:37 INFO - PROCESS | 1932 | Values: 671.5 741.7 744.4 744.6 754.9 14528.0 14687.5 14829.2 14839.0 14867.9 13897.7 14148.0 14191.1 14193.6 14198.1 705.3 892.9 898.0 907.7 908.0 723.1 723.3 723.9 726.9 726.9 925.1 954.4 956.3 956.7 957.0 948.8 953.5 954.0 955.7 962.8 549.8 554.6 703.2 705.2 707.9 14602.7 14682.7 14753.0 14755.2 14777.0 12429.1 12469.4 12483.3 12723.4 12814.4 8534.6 9930.0 9993.5 10120.2 10250.0 3424.5 3491.5 3517.7 3577.0 3715.1 80376.6 83029.0 83575.6 83619.6 83749.6 9490.0 9618.6 9750.2 9824.9 10016.7 22135.9 22143.9 22157.1 22165.4 22170.2 7314.5 7362.3 7412.2 7451.5 7645.6 4042.5 4126.0 4227.2 4249.6 4391.4 3600.4 3687.2 3690.4 3690.6 3694.5 604.2 627.4 629.2 636.7 647.5 556.4 578.8 579.9 584.9 611.1 8038.8 8070.4 8102.0 8220.3 8257.0 512.2 520.1 524.3 565.5 585.0 3825.5 3834.1 3992.2 4038.5 4192.2 98320.5 103027.0 103110.5 103347.5 106669.0 85954.6 86805.0 87766.0 87817.0 89920.0 72025.3 72041.9 72654.5 74625.9 75051.2 8072.0 8077.5 8152.0 8299.6 8321.1 46814.0 46860.0 46967.8 48180.8 48911.0 66665.3 69984.3 70339.0 71000.0 71661.6 10612.4 11022.9 11050.5 11162.8 11681.8 11688.5 11799.9 12185.0 12425.3 12507.3 11481.0 11569.8 11855.3 11903.0 11990.6 9364.1 9387.0 11246.6 11328.2 11557.8 10836.5 10957.3 11121.6 11162.9 11302.8 9379.5 9390.3 9665.3 9910.9 10004.9 9356.0 9492.1 9525.9 9647.0 9758.4 03:12:37 INFO - PROCESS | 1932 | -------- Summary: end -------- 03:12:37 INFO - PROCESS | 1932 | 03:12:38 INFO - TEST-INFO | 1932: exit 0 03:12:38 INFO - TEST-OK | dromaeo_css | took 1116678ms 03:12:38 INFO - TEST-START | kraken 03:12:38 INFO - operating with platform_type : mac_ 03:12:38 INFO - Initialising browser for kraken test... 03:12:38 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpiiuQ0J/profile http://localhost:49446/getInfo.html) 03:12:40 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 03:12:40 INFO - PROCESS | 1937 | colorDepth:24 03:12:40 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 03:12:40 INFO - PROCESS | 1937 | __metrics 03:12:40 INFO - PROCESS | 1937 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:12:40 ERROR - PROCESS | 1937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:12:41 INFO - TEST-INFO | 1937: exit 0 03:12:41 INFO - Browser initialized. 03:12:41 INFO - Running cycle 1/1 for kraken test... 03:12:41 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpiiuQ0J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:53 INFO - PROCESS | 1940 | RSS: Main: 211722240 03:12:53 INFO - PROCESS | 1940 | 03:12:56 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection) 03:12:56 INFO - PROCESS | 1940 | RSS: Main: 270721024 03:12:56 INFO - PROCESS | 1940 | 03:12:59 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-dft) 03:13:00 INFO - PROCESS | 1940 | RSS: Main: 284393472 03:13:00 INFO - PROCESS | 1940 | 03:13:03 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-fft) 03:13:04 INFO - PROCESS | 1940 | RSS: Main: 373465088 03:13:04 INFO - PROCESS | 1940 | 03:13:06 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator) 03:13:06 INFO - PROCESS | 1940 | RSS: Main: 277925888 03:13:06 INFO - PROCESS | 1940 | 03:13:08 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:13:09 INFO - PROCESS | 1940 | RSS: Main: 278396928 03:13:09 INFO - PROCESS | 1940 | 03:13:12 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom) 03:13:12 INFO - PROCESS | 1940 | RSS: Main: 317493248 03:13:12 INFO - PROCESS | 1940 | 03:13:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate) 03:13:16 INFO - PROCESS | 1940 | RSS: Main: 316993536 03:13:16 INFO - PROCESS | 1940 | 03:13:19 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial) 03:13:19 INFO - PROCESS | 1940 | RSS: Main: 315002880 03:13:19 INFO - PROCESS | 1940 | 03:13:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:13:22 INFO - PROCESS | 1940 | RSS: Main: 307830784 03:13:22 INFO - PROCESS | 1940 | 03:13:24 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:13:24 INFO - PROCESS | 1940 | RSS: Main: 314339328 03:13:24 INFO - PROCESS | 1940 | 03:13:26 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:13:27 INFO - PROCESS | 1940 | RSS: Main: 317202432 03:13:27 INFO - PROCESS | 1940 | 03:13:30 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:13:31 INFO - PROCESS | 1940 | RSS: Main: 322539520 03:13:31 INFO - PROCESS | 1940 | 03:13:34 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:13:34 INFO - PROCESS | 1940 | RSS: Main: 320036864 03:13:34 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49446/tests/kraken/driver.html?testName=ai-astar) 03:13:36 INFO - PROCESS | 1940 | RSS: Main: 384753664 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | __start_tp_report 03:13:36 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 03:13:36 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 03:13:36 INFO - PROCESS | 1940 | |i|pagename|runs| 03:13:36 INFO - PROCESS | 1940 | |0;ai-astar;113;114;111;116;114;114;114;114;105;115 03:13:36 INFO - PROCESS | 1940 | |1;audio-beat-detection;162;135;147;167;119;143;116;151;160;120 03:13:36 INFO - PROCESS | 1940 | |2;audio-dft;185;187;185;188;184;186;182;186;186;185 03:13:36 INFO - PROCESS | 1940 | |3;audio-fft;73;85;107;188;66;55;56;62;137;57 03:13:36 INFO - PROCESS | 1940 | |4;audio-oscillator;96;98;141;76;81;81;126;80;77;135 03:13:36 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;90;87;90;88;89;86;90;88;90;86 03:13:36 INFO - PROCESS | 1940 | |6;imaging-darkroom;119;151;150;95;94;94;95;94;94;94 03:13:36 INFO - PROCESS | 1940 | |7;imaging-desaturate;86;84;86;86;86;86;86;84;84;86 03:13:36 INFO - PROCESS | 1940 | |8;json-parse-financial;59;63;65;62;63;66;63;79;59;57 03:13:36 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;64;69;78;76;72;68;68;68;67;70 03:13:36 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;81;82;89;103;108;102;80;79;83;86 03:13:36 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;164;170;163;145;145;134;147;125;175;133 03:13:36 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;171;174;227;158;207;172;169;167;168;166 03:13:36 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;68;67;69;67;68;69;67;69;68;67 03:13:36 INFO - PROCESS | 1940 | __end_tp_report 03:13:36 INFO - PROCESS | 1940 | __start_cc_report 03:13:36 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2019 03:13:36 INFO - PROCESS | 1940 | __end_cc_report 03:13:36 INFO - PROCESS | 1940 | __startTimestamp1462529616687__endTimestamp 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | ------- Summary: start ------- 03:13:36 INFO - PROCESS | 1940 | Number of tests: 14 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.09 (2.7%) stddev-sans-first:3.28 03:13:36 INFO - PROCESS | 1940 | Values: 113.0 114.0 111.0 116.0 114.0 114.0 114.0 114.0 105.0 115.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.00 stddev:18.84 (12.6%) stddev-sans-first:18.54 03:13:36 INFO - PROCESS | 1940 | Values: 162.0 135.0 147.0 167.0 119.0 143.0 116.0 151.0 160.0 120.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:1.65 (0.9%) stddev-sans-first:1.74 03:13:36 INFO - PROCESS | 1940 | Values: 185.0 187.0 185.0 188.0 184.0 186.0 182.0 186.0 186.0 185.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:88.60 Median:59.50 stddev:43.75 (73.5%) stddev-sans-first:46.04 03:13:36 INFO - PROCESS | 1940 | Values: 73.0 85.0 107.0 188.0 66.0 55.0 56.0 62.0 137.0 57.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:80.50 stddev:25.43 (31.6%) stddev-sans-first:26.95 03:13:36 INFO - PROCESS | 1940 | Values: 96.0 98.0 141.0 76.0 81.0 81.0 126.0 80.0 77.0 135.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.50 stddev:1.65 (1.8%) stddev-sans-first:1.64 03:13:36 INFO - PROCESS | 1940 | Values: 90.0 87.0 90.0 88.0 89.0 86.0 90.0 88.0 90.0 86.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:108.00 Median:94.00 stddev:23.69 (25.2%) stddev-sans-first:24.79 03:13:36 INFO - PROCESS | 1940 | Values: 119.0 151.0 150.0 95.0 94.0 94.0 95.0 94.0 94.0 94.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 03:13:36 INFO - PROCESS | 1940 | Values: 86.0 84.0 86.0 86.0 86.0 86.0 86.0 84.0 84.0 86.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.00 stddev:6.10 (9.7%) stddev-sans-first:6.23 03:13:36 INFO - PROCESS | 1940 | Values: 59.0 63.0 65.0 62.0 63.0 66.0 63.0 79.0 59.0 57.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:69.50 stddev:4.24 (6.1%) stddev-sans-first:3.91 03:13:36 INFO - PROCESS | 1940 | Values: 64.0 69.0 78.0 76.0 72.0 68.0 68.0 68.0 67.0 70.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:81.50 stddev:10.87 (13.3%) stddev-sans-first:11.11 03:13:36 INFO - PROCESS | 1940 | Values: 81.0 82.0 89.0 103.0 108.0 102.0 80.0 79.0 83.0 86.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:155.00 stddev:17.06 (11.0%) stddev-sans-first:17.34 03:13:36 INFO - PROCESS | 1940 | Values: 164.0 170.0 163.0 145.0 145.0 134.0 147.0 125.0 175.0 133.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:171.50 stddev:21.57 (12.6%) stddev-sans-first:22.74 03:13:36 INFO - PROCESS | 1940 | Values: 171.0 174.0 227.0 158.0 207.0 172.0 169.0 167.0 168.0 166.0 03:13:36 INFO - PROCESS | 1940 | 03:13:36 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 03:13:36 INFO - PROCESS | 1940 | Values: 68.0 67.0 69.0 67.0 68.0 69.0 67.0 69.0 68.0 67.0 03:13:36 INFO - PROCESS | 1940 | -------- Summary: end -------- 03:13:36 INFO - PROCESS | 1940 | 03:13:37 INFO - TEST-INFO | 1940: exit 0 03:13:37 INFO - TEST-OK | kraken | took 59021ms 03:13:37 INFO - SUITE-END | took 1175s 03:13:37 INFO - Completed test suite (00:19:36) 03:13:37 INFO - javascript benchmark 03:13:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3364.0748794081633, 3419.0597585663954, 3432.9485057724137, 3452.447608382656, 3459.8789042383505, 5633.746481408336, 5663.745131469083, 5670.291213598301, 5694.003549378863, 5717.113847353667, 4717.646176536757, 5687.744051517681, 5716.79991200307, 5721.31344238692, 5752.827415177541, 4256.659467242629, 4331.3719510670935, 5063.493650634926, 5142.51146141116, 5200.349956255468, 5142.305788718512, 5213.947860521384, 5220.086484865153, 5230.581553475732, 5234.214867769827, 5112.846614601568, 5163.380394352665, 5198.740062996835, 5207.713575753332, 5208.661437006583, 5194.013137503862, 5245.26566280721, 5252.6323157379, 5259.158534634478, 5292.206169074631, 5043.646944713872, 5078.46676099008, 5139.408967968657, 5162.716050617215, 5166.9224961625605, 5561.833145005657, 5581.413951535112, 5583.441655834425, 5590.413006634326, 5593.496585307342, 6173.351798061229, 6218.22272215973, 6229.221347331583, 6233.750649973996, 6236.0, 6101.237345331833, 6142.232220972379, 6177.537131786823, 6239.875202495925, 6252.968735156295, 5840.153177789197, 5860.0, 5863.912041319383, 5871.9706401468575, 5878.853028674274, 6250.74997000124, 6273.96863015682, 6307.968460157762, 6308.400701933309, 6310.305866354697, 6027.21646185993, 6125.785597504066, 6140.8771824563255, 6159.753609855646, 6198.845028874224, 6150.538709596798, 6181.690915454209, 6237.064440333987, 6273.12176295319, 6314.147590075281, 6226.844328891723, 6237.220347456568, 6240.438360547572, 6240.812775616739, 6273.7176827042895, 6207.224096987877, 6226.782062627786, 6263.091851681481, 6285.497160227171, 6299.0866324382705, 4826.927596086061, 4902.681325713817, 4906.092372911023, 4906.533879281463, 4942.74019453871, 4428.269335559597, 4455.1980643484485, 4471.23988921884, 4483.036147228361, 4485.820567177341, 5670.886582268414, 5685.374608793029, 5686.601937864392, 5784.479396854219, 5793.420657934173, 5192.1952097424955, 6214.316425193225, 6295.0, 6302.716377763011, 6304.810855674337, 5188.8584511407425, 5648.406917273709, 5727.713614319267, 5728.427157284238, 5742.856428589319, 5201.245819356172, 5321.0, 5321.6540924839765, 5404.594655400861, 5422.240886275925, 6306.684665766693, 6349.49204063683, 6356.364363563606, 6360.109584658059, 6365.522585806176, 6229.4393504584805, 6235.688215589294, 6257.561970662192, 6258.3741625838875, 6299.11812346281, 6217.595856269328, 6292.779752708633, 6334.778282760081, 6336.778212762531, 6346.2067241594805, 6199.318075011655, 6212.254529456494, 6214.036824292242, 6232.314445410911, 6245.0, 6295.213098362705, 6297.653629050446, 6300.527460440394, 6312.968435157794, 6324.209473815773, 4802.711837289774, 5838.416158384298, 6044.063170208625, 6084.178635884099, 6112.868254062901, 6106.582013105306, 6124.564025909317, 6131.254333328352, 6132.233470816148, 6132.324915438508, 5955.791547295824, 5991.2510936132985, 5993.0710739835395, 6000.759969601167, 6001.219841420588, 5848.239728835224, 6018.458338749533, 6021.578489505692, 6026.487748541248, 6122.051082082285, 5856.853578660397, 5888.116782482662, 5942.257217847769, 5977.432143946318, 5996.370381110095], "value": 5670.784248541874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.97244651544173, 529.9046171689096, 551.5146670929583, 575.5913301555895, 593.8099808061422, 27274.318142046457, 31335.903243386485, 39035.60964390359, 40376.0, 40696.59303406965, 44986.55013449865, 45425.772871135596, 45547.0, 45578.772106139506, 45756.771216143956, 9537.04629537046, 9782.461964591945, 9782.853257201148, 9784.070513301225, 9793.412395256308, 8455.365847561427, 8674.175953284428, 11297.774044519105, 11553.653390398302, 11561.653150405502, 11438.48526816291, 11587.884121158808, 11596.478158482847, 11608.361540115291, 11640.825387619192, 8449.281811046068, 8651.307895368356, 11417.200795944287, 11539.48072336747, 11562.537498500093, 8601.913980860174, 8742.86885696715, 11178.496967636476, 11430.942845285803, 11456.255343402652, 39581.80209098936, 39983.8000809997, 40098.59901401007, 40152.39771403431, 40278.39582406266, 3262.2659901522206, 3291.4075466416034, 3594.892153235407, 3654.0682126057686, 3845.2117315950163, 28409.573856391962, 28671.61347359222, 28810.85594572035, 29447.411051779058, 29904.55143172854, 2723.1149876290283, 2749.1615057407485, 2763.6545431821023, 2903.071017274473, 2904.0561817409425, 495520.0, 495831.0, 496556.0, 497017.0, 497811.0, 63695.36304636894, 63956.0, 64345.0, 64364.35635643677, 64432.0, 66492.0, 66711.66644166845, 66724.0, 66741.66629166823, 66852.665736671, 16922.23849926756, 17458.825411745973, 17521.474355769347, 17571.385001524886, 17665.11674416274, 11899.286042837457, 12167.817482737766, 13086.934565327303, 13477.528286509925, 13549.458021679022, 17194.968301901677, 17343.306267749147, 17419.47741567755, 17422.128893555524, 17497.56256093608, 6321.356447323729, 6353.777617783356, 6452.064450654629, 6464.931222009006, 6465.870682586322, 869.8434281829332, 966.1690945786618, 1078.46615925116, 1090.4384242115316, 1095.901368876805, 28797.71202287992, 30093.90914952003, 30114.397712045637, 30261.0, 30329.95681506112, 1085.3921803789904, 1087.1194332590626, 1109.6670998700358, 1174.7826652069396, 1204.5001324450254, 15105.773413398807, 15349.6162595936, 15430.845691542941, 15679.0, 15788.447404340794, 657264.0, 663820.0, 668141.0, 670414.0, 670444.0, 56721.0, 56853.0, 56891.431085689444, 57003.0, 57178.428215718144, 1311.9016073794503, 1348.2315080404271, 1350.92569908654, 1468.7503124468865, 1475.3803402571161, 25184.37039074038, 28092.488438945133, 28975.5653665191, 29620.703792962657, 29730.702692972794, 45797.31304030376, 46147.538524615666, 46229.0, 46263.07473850571, 46339.30491042569, 446672.0, 449235.0, 449609.0, 449736.0, 450490.7475462602, 25583.0, 26510.337241569116, 26553.867230664495, 26605.467890641692, 37671.0, 43012.05361383356, 43608.12783744371, 44751.328730068424, 44897.0, 45154.77422612997, 35454.645453545134, 35463.82268088643, 36673.0, 36688.63311366852, 37407.81296093611, 35891.64108358883, 36077.81961090283, 36528.26943461169, 36754.26491470102, 36799.58404991311, 32607.18482037968, 36559.35482903223, 37255.627443725214, 37697.62302376941, 37985.29066192083, 32703.0, 32748.50877236891, 37257.62742372542, 38688.80655596704, 38867.80566097151, 29653.962111326, 29872.402551949275, 29968.25079373033, 30133.547996780504, 30469.390612188075], "value": 20040.43238063927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9003194057753, 390.79287977371996, 402.99452865101574, 408.96728261739065, 411.36649559678096, 20446.693299600516, 20681.793182068173, 21949.67075493867, 22025.779742202594, 22435.887820560874, 19708.802911970877, 20755.68866467004, 20775.480612984684, 20955.68566471504, 22034.88982555089, 5330.600204984622, 5803.303603567557, 6182.319944806068, 6185.319614842364, 6189.566730328879, 3955.9208815823667, 4193.972476743203, 5820.650760954335, 6121.816345509619, 6164.7534098636, 5287.709175995319, 6021.397860213988, 6140.570160088796, 6144.41628045335, 6145.661988590626, 6319.304876463585, 6650.035744816999, 6659.034440006172, 6665.0, 6666.666666666647, 3825.0, 4056.513218413809, 4057.7565346079036, 4285.657147428229, 5671.631343962629, 13493.730125397436, 13740.725185496334, 13855.16868987864, 14009.0193686442, 14034.859651403458, 2123.522207503315, 2183.6724491326268, 2243.4054975431523, 2297.9655305170436, 2301.953960920789, 9195.586198621078, 9730.0, 10439.478026098664, 11106.833397499044, 15124.546263612, 1716.7596536484903, 1719.9570010749705, 1742.7298768690873, 1790.7578265135228, 1800.522861441719, 88016.0, 105642.47178764056, 108644.0, 114879.42560287229, 114889.0, 11772.70568235801, 11783.528658853722, 11891.583794567148, 11915.046796256278, 11951.0, 15580.0, 15686.294116764773, 15722.213889305489, 15724.68550628981, 15740.212989350486, 8914.86627700585, 8920.464772113693, 8932.19610235082, 8935.0, 9053.637854485747, 8836.160564746338, 8845.51349675774, 8858.247049000778, 8921.15249051339, 8979.0, 4673.7429441381055, 4696.7416792076765, 4704.764761761899, 4724.126036683225, 4753.786079626425, 1810.9275628974956, 1839.3715231391984, 1857.2292498613194, 1914.387396033256, 2004.3085135628185, 906.6554709210458, 914.9039350868196, 922.5802259971697, 947.2564037230878, 964.0744884910564, 20298.28955986563, 21257.0, 21475.0, 21533.46166345854, 21710.89144554267, 796.5459687977797, 810.9107998120202, 991.8760154980628, 1005.6631028605499, 1064.69656147999, 8164.306033987177, 11014.50434730439, 11205.327680339205, 11319.660410187707, 11354.921282478152, 536312.3184384053, 569575.0, 614136.0, 634447.0, 635258.0, 102551.0, 125389.0, 127598.36200819122, 134750.30499390155, 141626.58373416134, 378.53062202868244, 488.44073535802016, 972.7908499672604, 1008.8587597736323, 1010.3988127064385, 10809.729756756144, 10943.671689849318, 11444.427778611203, 11460.312381256817, 11800.881991180322, 42802.571974281105, 51810.96378072493, 52733.47266527286, 52894.0, 54706.0, 69909.65045174741, 72797.0, 73076.0, 76027.0, 77497.0, 7932.762017139495, 8224.342052635655, 8333.499990000619, 8392.748217553482, 8712.564371781258, 11631.302121872715, 12263.202891812003, 12426.254424734545, 12642.620721378373, 12664.98680105571, 10629.25595208328, 10869.021788039114, 10872.836907446554, 10957.561697532328, 11030.952059554651, 10483.633072842718, 10623.468826558486, 10732.409717465614, 10838.729031774268, 10950.34297942126, 10674.306170098918, 10828.02547770673, 11187.776244474902, 11252.887471125185, 11255.04332131778, 7999.60001999886, 8047.114817369968, 8195.79510512218, 8357.08072112055, 8573.871391929251, 10633.723953125675, 10797.62208322705, 10878.292910960763, 11096.223264371416, 11185.32888026721], "value": 9179.344860513074, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.2339701465685, 1388.8680575345338, 1559.3372816552965, 1752.2815645585313, 1954.4527532290954, 7832.0, 8220.177982201776, 8225.0, 8235.588220588976, 8252.958735206323, 7950.960245198766, 8432.451890627104, 8698.130186981298, 8704.521251331174, 8713.651453941851, 2390.3307074019217, 2478.6405971134177, 2480.045182604692, 2481.019997101144, 2481.565725997952, 2436.597961336386, 2484.8881800319027, 2488.377905523619, 2489.215896992451, 2490.950180996379, 2431.209856796548, 2456.226288719057, 2466.519028789387, 2471.258622413278, 2481.5409149307347, 2004.7193392925003, 2061.907214175358, 2447.730749617541, 2459.655648209252, 2466.3094333586623, 2420.2739178246457, 2437.3662847659557, 2448.1309135257084, 2449.816263780224, 2456.2017344363153, 8759.211670949646, 8838.29293656506, 8870.911290887074, 8882.733517994471, 8894.688685896028, 5229.424763276036, 5304.469553044703, 5452.890942181174, 5458.4814442627885, 5472.425395333512, 3315.419801534722, 3367.4275373186615, 3424.366492198926, 3455.809930453824, 3462.0133262020204, 1046.4192373232852, 1049.4070849969742, 1062.6705721226444, 1066.2536224642781, 1075.3440401355167, 11673.416329183507, 11787.58743443984, 11797.29216247028, 11827.88172118285, 11860.584879529262, 4666.60333871618, 4814.15752243356, 4898.8775280618265, 5140.254663073833, 5150.639455238097, 9153.77115572116, 9187.402818816889, 9242.445453272825, 9250.72247832566, 9260.027697091844, 5309.867253318621, 5355.919661205085, 5361.973190134025, 5365.168398898177, 5365.704886231217, 4255.893602659897, 4438.578335058164, 4965.478624744422, 4986.601071914238, 5012.997400519911, 3843.8462461501786, 3868.7485313454536, 3899.76601403911, 3933.449317095609, 3981.064449854282, 1223.3149436315691, 1234.0991076514015, 1238.962831115068, 1249.2566922681171, 1250.3498180945858, 640.9391107844747, 792.0772300270236, 901.454619954931, 913.5706218077493, 957.6169532187181, 4429.889252768707, 5474.562035037187, 5724.313082430135, 5762.250907382014, 5781.797637082766, 911.6262332443666, 957.0, 994.1599348550463, 1001.7645853224486, 1034.886162522122, 3703.4259689748133, 3781.300459415017, 3802.068493219179, 3832.1186127190344, 3832.597577254404, 10314.587416503256, 10740.462976851282, 10816.080633146114, 10848.132149428027, 10860.022597966063, 10242.487875606339, 10804.729881752863, 10810.297330673638, 10813.56745730162, 10845.674629761119, 7858.546168958761, 8053.31546818527, 8068.878966815502, 8199.303059240032, 8251.133630968781, 5555.361343194843, 5588.441155884379, 5615.185798059177, 5619.241402410622, 5811.157382179645, 9661.516924153624, 9747.512624368612, 9932.652357167754, 9933.602655893683, 9937.65218217359, 9473.952630236805, 9959.502024898582, 9962.402255864672, 10070.144037756872, 10073.84889226677, 7243.674034668347, 7454.813629659302, 7461.141968673672, 7462.0, 7467.178610352966, 8045.195480451908, 8052.8792068117855, 8059.798505037186, 8103.8379232413845, 8218.342532597497, 7261.128664560287, 7307.3058059484265, 7333.889991650236, 7445.962770186331, 7519.097708275042, 7099.716011359487, 7202.927970720435, 7206.207317195, 7238.022866912899, 7259.6370181491775, 6872.140982377203, 6897.137857767779, 6971.825704357431, 6972.79081627552, 6983.1620205575655, 4269.829206831692, 4362.018545827291, 4618.884527886829, 4708.552687494683, 4838.419389673261, 4812.542808433193, 4877.853664390074, 4883.047805678001, 4893.605377194611, 4903.4606193317995], "value": 4540.041741498645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.8984130950893, 318.51426574473925, 325.63854121924675, 327.9016295111466, 331.7130681960105, 4967.130752118378, 5288.682679039255, 5329.973350133244, 5373.650712703677, 5380.91928621071, 4989.875253118674, 5017.749112544368, 5257.13257312543, 5272.578193744501, 5312.203169524573, 3898.629630185135, 3939.5863434339412, 4030.2544029354517, 4108.506979162505, 4149.170165966819, 3623.963760362389, 4022.235775202717, 4095.9385609215888, 4097.119119389331, 4174.728642638234, 3398.24741959014, 4022.6178513041214, 4055.229506393776, 4061.3704875744306, 4074.7147699661036, 3892.3577609694507, 3940.8571514260975, 4092.815823287959, 4130.607592278729, 4141.875743727693, 3138.2154461384653, 3318.9170270743425, 3507.4037413639744, 3565.5008298838184, 3632.8728494502566, 4354.586314300136, 4521.779119637712, 4901.215805471107, 5148.4079330876675, 5193.272941788153, 3958.762474251554, 4458.509563947963, 4600.470945841238, 4695.248760198386, 4742.857714268543, 4601.539846015406, 4899.325505870597, 5521.696306703129, 5551.472610102034, 5615.971920140373, 3357.8153201573905, 3986.880393588197, 4017.718759686794, 4023.5574086850716, 4026.859059932888, 4858.081057982077, 5609.775608975636, 5831.416858314177, 5873.588848780587, 5924.04506512854, 4086.5504794472586, 4086.959130408658, 4115.938260926088, 4117.402976568381, 4121.29937910556, 3781.1681430085337, 4110.794460277034, 4116.217918595457, 4150.813213405404, 4183.226103170923, 4351.369051487517, 4646.303054541778, 4663.27719203524, 4681.016986432887, 4711.199096153662, 5206.817761378409, 5609.298837645294, 5639.407862174495, 5703.8859222816145, 5730.570207234474, 1149.5516748468103, 1322.5833862333334, 1459.569427019032, 1464.6777708904024, 1470.206088712105, 748.9475736698488, 906.8141031088611, 925.3938670171049, 952.2572393533055, 980.7401038724744, 4639.628829693616, 5411.918821217685, 5640.294963129609, 5795.0, 5810.477057064884, 6314.684265786784, 6376.632007075168, 6393.776217832354, 6400.871982560416, 6455.419012288822, 5079.425378132791, 5701.71491425427, 5713.600047996683, 5763.711814409263, 5777.94222057774, 2830.929226769347, 3042.787004909679, 3056.2206637307345, 3315.254067834766, 3322.1595535817014, 5816.680082595498, 6354.3963323484195, 6645.368689974444, 6809.625470599165, 6810.6254156020905, 6195.876082478325, 6448.935510644834, 6518.543701940914, 6545.639989800607, 6647.368499992493, 6609.867802644017, 6613.603183808987, 6615.536912416084, 6630.53586248958, 6664.0670306158145, 4295.24833154203, 5297.54970827484, 5381.327334083239, 5489.505944465017, 5699.0, 5263.710495922761, 5615.943840561542, 5697.943020569741, 5785.82642520725, 5855.00464920973, 6170.660613666291, 6192.102145188832, 6199.225096862892, 6200.193974783249, 6216.222972128484, 3392.9830350848088, 3988.880333589997, 4131.058402550796, 4151.837485504064, 4166.708330416963, 5188.377394712658, 5859.765609375577, 5862.648241105548, 5878.738529016421, 5893.351731309467, 5050.823221187241, 5332.33345831771, 5464.480874316933, 5524.254225679482, 5534.4742249486235, 5622.823104945693, 5625.718714064362, 5666.518345940642, 5677.716114194356, 5756.862509932181, 4702.459217190067, 5485.369182544058, 5490.231367608458, 5584.804531841366, 5585.078462053716, 5105.92341114891, 5302.072137375867, 5476.835694929158, 5613.859653508695, 5666.886662266649, 4652.32541281519, 4664.4112517553085, 4758.406668365818, 5469.398366179637, 5682.403347648437], "value": 4216.489817532578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.4661843834153, 741.6514238307993, 744.4491076603315, 744.6016381236038, 754.9053871885764, 14528.0, 14687.485937992173, 14829.184394858305, 14838.961272710902, 14867.925660371697, 13897.652558686037, 14148.0, 14191.148531088142, 14193.645158871033, 14198.14811111334, 705.2524324216347, 892.857142857143, 897.9910200897973, 907.7095329494564, 907.9682211122612, 723.1250187273406, 723.3453724379445, 723.9095113110861, 726.8546290741847, 726.8982342472058, 925.0934084597098, 954.374884450686, 956.3353469338813, 956.6938579654513, 956.9856452153223, 948.8434408322653, 953.50894289441, 953.9904600954005, 955.7276176289794, 962.7833737409062, 549.7745924171071, 554.5840619535348, 703.2088899987514, 705.1502938958542, 707.9150501939748, 14602.707945841024, 14682.70634587313, 14753.0, 14755.18846463444, 14777.039492432958, 12429.129960902741, 12469.376531173404, 12483.251004939733, 12723.427445764963, 12814.42335094923, 8534.615942282611, 9930.0, 9993.500324983721, 10120.240981926385, 10250.0, 3424.503447000196, 3491.458823882277, 3517.736169787276, 3576.982115089434, 3715.145516531186, 80376.59811700904, 83029.0, 83575.5821220896, 83619.5819020901, 83749.58125209335, 9489.952550237344, 9618.567164477616, 9750.171235444925, 9824.852627210455, 10016.749581260527, 22135.889320553295, 22143.889280553816, 22157.113715451524, 22165.44586385321, 22170.224042158447, 7314.48798584094, 7362.300581444754, 7412.18465968743, 7451.478396512301, 7645.579493127821, 4042.514898212233, 4125.97937010319, 4227.175700738327, 4249.638780703613, 4391.407160033418, 3600.3519366513756, 3687.188818459988, 3690.446433035066, 3690.6493883081066, 3694.5012423323046, 604.2326245667977, 627.3663599764271, 629.1663545801739, 636.6752955992478, 647.4658406814303, 556.3629644057531, 578.8147792706378, 579.9420057994251, 584.9268841394826, 611.0955785437577, 8038.758837234892, 8070.354371650254, 8102.0277566692375, 8220.301274391744, 8257.0, 512.1651707716399, 520.1495554767981, 524.2581746828196, 565.5220394573324, 584.9590528662952, 3825.5026846509777, 3834.1373191031794, 3992.2215168041953, 4038.5355684096703, 4192.245395828719, 98320.50839745898, 103027.0, 103110.48444757878, 103347.4832625832, 106669.0, 85954.57022714846, 86805.0, 87766.0, 87817.0, 89920.0, 72025.27974720186, 72041.91937120947, 72654.54690906152, 74625.88061179087, 75051.24948750442, 8071.959640201761, 8077.515349079074, 8152.021757389151, 8299.626516806878, 8321.08468068524, 46814.0, 46860.0, 46967.82580435516, 48180.759096204296, 48911.02177956492, 66665.33334666786, 69984.30015699778, 70339.0, 71000.0, 71661.56676866538, 10612.375441016275, 11022.889771102507, 11050.502727377127, 11162.832557511807, 11681.824772628248, 11688.532458701557, 11799.941000294944, 12185.0, 12425.254484730945, 12507.312097834707, 11480.96671299587, 11569.768604627692, 11855.34795586251, 11903.0, 11990.640280791591, 9364.050324148144, 9386.95306523463, 11246.606368777053, 11328.150388721044, 11557.82663260035, 10836.458177090955, 10957.287776294515, 11121.610743623934, 11162.944185279022, 11302.83045754298, 9379.531023448937, 9390.342676012613, 9665.3234273603, 9910.851337230091, 10004.949480304464, 9355.953220234127, 9492.050794920453, 9525.904740952501, 9646.951765241129, 9758.414495130315], "value": 6885.55114589454, "unit": "score"}], "name": "dromaeo_css", "value": 7184.436045906334, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 111.0, 116.0, 114.0, 114.0, 114.0, 114.0, 105.0, 115.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 135.0, 147.0, 167.0, 119.0, 143.0, 116.0, 151.0, 160.0, 120.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 188.0, 184.0, 186.0, 182.0, 186.0, 186.0, 185.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 85.0, 107.0, 188.0, 66.0, 55.0, 56.0, 62.0, 137.0, 57.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 141.0, 76.0, 81.0, 81.0, 126.0, 80.0, 77.0, 135.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 89.0, 86.0, 90.0, 88.0, 90.0, 86.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 151.0, 150.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 108.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 86.0, 86.0, 86.0, 86.0, 84.0, 84.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 65.0, 62.0, 63.0, 66.0, 63.0, 79.0, 59.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 78.0, 76.0, 72.0, 68.0, 68.0, 68.0, 67.0, 70.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 89.0, 103.0, 108.0, 102.0, 80.0, 79.0, 83.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 163.0, 145.0, 145.0, 134.0, 147.0, 125.0, 175.0, 133.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 227.0, 158.0, 207.0, 172.0, 169.0, 167.0, 168.0, 166.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 67.0, 68.0, 69.0, 67.0, 69.0, 68.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1528.7, "alertThreshold": 2.0}]} 03:13:37 INFO - Return code: 0 03:13:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:13:37 ERROR - # TBPL SUCCESS # 03:13:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:13:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:13:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:13:37 INFO - 2016-05-06 03:13:37.993 screenresolution[1944:10757] starting screenresolution argv=screenresolution get 03:13:37 INFO - 2016-05-06 03:13:37.996 screenresolution[1944:10757] Display 0: 1600x1200x32@60 03:13:38 INFO - 2016-05-06 03:13:38.002 screenresolution[1945:10760] starting screenresolution argv=screenresolution list 03:13:38 INFO - Available Modes on Display 0 03:13:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:13:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:13:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:13:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:13:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:13:38 INFO - Intel Iris: 03:13:38 INFO - Chipset Model: Intel Iris 03:13:38 INFO - Type: GPU 03:13:38 INFO - Bus: Built-In 03:13:38 INFO - VRAM (Dynamic, Max): 1536 MB 03:13:38 INFO - Vendor: Intel (0x8086) 03:13:38 INFO - Device ID: 0x0a2e 03:13:38 INFO - Revision ID: 0x0009 03:13:38 INFO - Displays: 03:13:38 INFO - Display: 03:13:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:13:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:13:38 INFO - Display Serial Number: 1600x1200 60 03:13:38 INFO - Main Display: Yes 03:13:38 INFO - Mirror: Off 03:13:38 INFO - Online: Yes 03:13:38 INFO - Rotation: Supported 03:13:38 INFO - Return code: 0 03:13:38 INFO - Running post-action listener: _resource_record_post_action 03:13:38 INFO - Running post-run listener: _resource_record_post_run 03:13:39 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 1863680; Write bytes: 649837568; Read time: 20; Write time: 1910 03:13:39 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346526208; Write bytes: 346125824; Read time: 27657; Write time: 634 03:13:39 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 1028096; Write bytes: 292128768; Read time: 16; Write time: 1237 03:13:39 INFO - Running post-run listener: _upload_blobber_files 03:13:39 INFO - Blob upload gear active. 03:13:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:13:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:13:39 INFO - (blobuploader) - INFO - Open directory for files ... 03:13:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:13:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:13:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:40 INFO - (blobuploader) - INFO - Done attempting. 03:13:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:13:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:13:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:13:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:13:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:13:41 INFO - (blobuploader) - INFO - Done attempting. 03:13:41 INFO - (blobuploader) - INFO - Iteration through files over. 03:13:41 INFO - Return code: 0 03:13:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:13:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:13:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae3907c4beef7101c8765ce16ceb4728884f436a14b735d4564077a2af3603f6c31b044c39763cd13763682344f6dade7feb5f7081d37b90c86aef5cc734aa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfcaf913da1d165da941c37cd05fac0e95011caa241b7c7726c12dcc5c0dc04903ef630929c7212e3d8247b50298c8da4a8b66aa22bd9dbbb53bd41e58667e97"} 03:13:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:13:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:13:41 INFO - Contents: 03:13:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae3907c4beef7101c8765ce16ceb4728884f436a14b735d4564077a2af3603f6c31b044c39763cd13763682344f6dade7feb5f7081d37b90c86aef5cc734aa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfcaf913da1d165da941c37cd05fac0e95011caa241b7c7726c12dcc5c0dc04903ef630929c7212e3d8247b50298c8da4a8b66aa22bd9dbbb53bd41e58667e97"} 03:13:41 INFO - Running post-run listener: copy_logs_to_upload_dir 03:13:41 INFO - Copying logs to upload dir... 03:13:41 INFO - mkdir: /builds/slave/test/build/upload/logs 03:13:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.760437 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 03:13:41.750749) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:13:41.754006) ========= 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.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae3907c4beef7101c8765ce16ceb4728884f436a14b735d4564077a2af3603f6c31b044c39763cd13763682344f6dade7feb5f7081d37b90c86aef5cc734aa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfcaf913da1d165da941c37cd05fac0e95011caa241b7c7726c12dcc5c0dc04903ef630929c7212e3d8247b50298c8da4a8b66aa22bd9dbbb53bd41e58667e97"} build_url:https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/YglshEYoRpOUPgiZFXfohQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dae3907c4beef7101c8765ce16ceb4728884f436a14b735d4564077a2af3603f6c31b044c39763cd13763682344f6dade7feb5f7081d37b90c86aef5cc734aa3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfcaf913da1d165da941c37cd05fac0e95011caa241b7c7726c12dcc5c0dc04903ef630929c7212e3d8247b50298c8da4a8b66aa22bd9dbbb53bd41e58667e97"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:13:41.786625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:13:41.787337) ========= 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.m5AZjHSCUd/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.f8c203HNKf/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005371 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:13:41.840055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:13:41.840785) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:13:41.841434) ========= ========= Total master_lag: 0.16 =========