builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0076 starttime: 1462530088.08 results: success (0) buildid: 20160505123841 builduid: 1457772db7ef4c1982b4796b67a4914d revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.082945) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.083367) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.083660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.100926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.101217) ========= 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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:28-- 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% 154M=0s 2016-05-06 03:21:28 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126423 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.244291) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.244619) ========= 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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.020382 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:28.294743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 03:21:28.295352) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --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 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:28,367 truncating revision to first 12 chars 2016-05-06 03:21:28,367 Setting DEBUG logging. 2016-05-06 03:21:28,367 attempt 1/10 2016-05-06 03:21:28,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0123e9f41ced?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 03:21:29,128 unpacking tar archive at: mozilla-aurora-0123e9f41ced/testing/mozharness/ program finished with exit code 0 elapsedTime=1.005164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 03:21:29.317763) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:29.318183) ========= script_repo_revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:29.318679) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:29.318992) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:29.332397) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 03:21:29.332804) ========= /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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:29 INFO - MultiFileLogger online at 20160506 03:21:29 in /builds/slave/test 03:21:29 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 03:21:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:29 INFO - {'append_to_log': False, 03:21:29 INFO - 'base_work_dir': '/builds/slave/test', 03:21:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:21:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:29 INFO - 'branch': 'Mozilla-Aurora', 03:21:29 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:29 INFO - 'config_files': ('talos/mac_config.py',), 03:21:29 INFO - 'default_actions': ('clobber', 03:21:29 INFO - 'read-buildbot-config', 03:21:29 INFO - 'download-and-extract', 03:21:29 INFO - 'populate-webroot', 03:21:29 INFO - 'create-virtualenv', 03:21:29 INFO - 'install', 03:21:29 INFO - 'run-tests'), 03:21:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:29 INFO - 'download_minidump_stackwalk': True, 03:21:29 INFO - 'download_symbols': 'ondemand', 03:21:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:29 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:29 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:29 INFO - 'installer_path': 'installer.exe', 03:21:29 INFO - 'log_level': 'info', 03:21:29 INFO - 'log_name': 'talos', 03:21:29 INFO - 'log_to_console': True, 03:21:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:29 INFO - 'opt_config_files': (), 03:21:29 INFO - 'pip_index': False, 03:21:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:29 INFO - 'cmd': ('bash', 03:21:29 INFO - '-c', 03:21:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:29 INFO - 'enabled': True, 03:21:29 INFO - 'halt_on_failure': False, 03:21:29 INFO - 'name': 'check_screen_resolution'},), 03:21:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:29 INFO - 'cmd': ('bash', 03:21:29 INFO - '-c', 03:21:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:29 INFO - 'enabled': True, 03:21:29 INFO - 'halt_on_failure': False, 03:21:29 INFO - 'name': 'check_screen_resolution'},), 03:21:29 INFO - 'run_cmd_checks_enabled': True, 03:21:29 INFO - 'sps_profile': False, 03:21:29 INFO - 'sps_profile_interval': 0, 03:21:29 INFO - 'suite': 'dromaeojs', 03:21:29 INFO - 'system_bits': '32', 03:21:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:29 INFO - 'title': 't-yosemite-r7-0076', 03:21:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:29 INFO - 'use_talos_json': True, 03:21:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:29 INFO - 'work_dir': 'build'} 03:21:29 INFO - ##### 03:21:29 INFO - ##### Running clobber step. 03:21:29 INFO - ##### 03:21:29 INFO - Running pre-action listener: _resource_record_pre_action 03:21:29 INFO - Running main action method: clobber 03:21:29 INFO - rmtree: /builds/slave/test/build 03:21:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:31 INFO - Running post-action listener: _resource_record_post_action 03:21:31 INFO - ##### 03:21:31 INFO - ##### Running read-buildbot-config step. 03:21:31 INFO - ##### 03:21:31 INFO - Running pre-action listener: _resource_record_pre_action 03:21:31 INFO - Running main action method: read_buildbot_config 03:21:31 INFO - Using buildbot properties: 03:21:31 INFO - { 03:21:31 INFO - "project": "", 03:21:31 INFO - "product": "firefox", 03:21:31 INFO - "script_repo_revision": "production", 03:21:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:21:31 INFO - "repository": "", 03:21:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:21:31 INFO - "buildid": "20160505123841", 03:21:31 INFO - "pgo_build": "False", 03:21:31 INFO - "basedir": "/builds/slave/test", 03:21:31 INFO - "buildnumber": 1, 03:21:31 INFO - "slavename": "t-yosemite-r7-0076", 03:21:31 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:21:31 INFO - "platform": "yosemite_r7", 03:21:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:21:31 INFO - "branch": "mozilla-aurora", 03:21:31 INFO - "repo_path": "releases/mozilla-aurora", 03:21:31 INFO - "revision": "0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b", 03:21:31 INFO - "stage_platform": "macosx64", 03:21:31 INFO - "builduid": "1457772db7ef4c1982b4796b67a4914d", 03:21:31 INFO - "slavebuilddir": "test" 03:21:31 INFO - } 03:21:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:21:31 INFO - Running post-action listener: _resource_record_post_action 03:21:31 INFO - ##### 03:21:31 INFO - ##### Running download-and-extract step. 03:21:31 INFO - ##### 03:21:31 INFO - Running pre-action listener: _resource_record_pre_action 03:21:31 INFO - Running main action method: download_and_extract 03:21:31 INFO - mkdir: /builds/slave/test/build/tests 03:21:31 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')]} 03:21:31 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:21:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:32 INFO - Downloaded 1199 bytes. 03:21:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:32 INFO - Using the following test package requirements: 03:21:32 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:32 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:21:32 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:21:32 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'jsshell-mac.zip'], 03:21:32 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:21:32 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:32 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:21:32 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:21:32 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:21:32 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:32 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:21:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:32 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:33 INFO - Downloaded 18114223 bytes. 03:21:33 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'] 03:21:33 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 03:21:36 INFO - Return code: 0 03:21:36 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 03:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:36 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:37 INFO - Downloaded 18114223 bytes. 03:21:37 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'] 03:21:37 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 03:21:41 INFO - Return code: 0 03:21:41 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')]} 03:21:41 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:21:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:21:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:21:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:42 INFO - Downloaded 10840145 bytes. 03:21:42 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'] 03:21:42 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 03:21:42 INFO - Return code: 0 03:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:42 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:21:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/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 03:21:45 INFO - Downloaded 114081343 bytes. 03:21:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:45 INFO - mkdir: /builds/slave/test/properties 03:21:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:45 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:45 INFO - Contents: 03:21:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:47 INFO - Running post-action listener: _resource_record_post_action 03:21:47 INFO - Running post-action listener: set_extra_try_arguments 03:21:47 INFO - ##### 03:21:47 INFO - ##### Running populate-webroot step. 03:21:47 INFO - ##### 03:21:47 INFO - Running pre-action listener: _resource_record_pre_action 03:21:47 INFO - Running main action method: populate_webroot 03:21:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:47 INFO - '--tppagecycles', 03:21:47 INFO - '7'], 03:21:47 INFO - 'tests': ['tsvgm']}}, 03:21:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['damp', 'tps']}, 03:21:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'tests': ['damp', 'tps']}, 03:21:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['dromaeo_dom']}, 03:21:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['a11yr', 03:21:47 INFO - 'ts_paint', 03:21:47 INFO - 'tpaint', 03:21:47 INFO - 'sessionrestore', 03:21:47 INFO - 'sessionrestore_no_auto_restore', 03:21:47 INFO - 'tabpaint']}, 03:21:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['tsvgx', 03:21:47 INFO - 'tsvgr_opacity', 03:21:47 INFO - 'tart', 03:21:47 INFO - 'tscrollx', 03:21:47 INFO - 'cart']}, 03:21:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:47 INFO - 'tsvgr_opacity', 03:21:47 INFO - 'tart', 03:21:47 INFO - 'tscrollx', 03:21:47 INFO - 'cart']}, 03:21:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'talos_options': ['--disable-e10s'], 03:21:47 INFO - 'tests': ['tp5o']}, 03:21:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'tests': ['tp5o']}, 03:21:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'talos_options': ['--disable-e10s', 03:21:47 INFO - '--xperf_path', 03:21:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:47 INFO - 'tests': ['tp5n']}, 03:21:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:47 INFO - 'talos_options': ['--xperf_path', 03:21:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:47 INFO - 'tests': ['tp5n']}}, 03:21:47 INFO - 'talos.zip': {'path': '', 03:21:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:47 INFO - Running post-action listener: _resource_record_post_action 03:21:47 INFO - ##### 03:21:47 INFO - ##### Running create-virtualenv step. 03:21:47 INFO - ##### 03:21:47 INFO - Running pre-action listener: _resource_record_pre_action 03:21:47 INFO - Running main action method: create_virtualenv 03:21:47 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:47 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 03:21:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:47 INFO - Using real prefix '/tools/python27' 03:21:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:51 INFO - Installing pip.................done. 03:21:51 INFO - Return code: 0 03:21:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:51 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:51 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 03:21:51 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 03:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:51 INFO - 'HOME': '/Users/cltbld', 03:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:51 INFO - 'LOGNAME': 'cltbld', 03:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:51 INFO - 'MOZ_NO_REMOTE': '1', 03:21:51 INFO - 'NO_EM_RESTART': '1', 03:21:51 INFO - 'PAGER': '/bin/cat', 03:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:51 INFO - 'PWD': '/builds/slave/test', 03:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:51 INFO - 'SHELL': '/bin/bash', 03:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:21:51 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:51 INFO - 'USER': 'cltbld', 03:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:51 INFO - 'XPC_FLAGS': '0x0', 03:21:51 INFO - 'XPC_SERVICE_NAME': '0', 03:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:51 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 03:21:51 INFO - Cleaning up... 03:21:51 INFO - Return code: 0 03:21:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:51 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:51 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 03:21:51 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 03:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:51 INFO - 'HOME': '/Users/cltbld', 03:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:51 INFO - 'LOGNAME': 'cltbld', 03:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:51 INFO - 'MOZ_NO_REMOTE': '1', 03:21:51 INFO - 'NO_EM_RESTART': '1', 03:21:51 INFO - 'PAGER': '/bin/cat', 03:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:51 INFO - 'PWD': '/builds/slave/test', 03:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:51 INFO - 'SHELL': '/bin/bash', 03:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:21:51 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:51 INFO - 'USER': 'cltbld', 03:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:51 INFO - 'XPC_FLAGS': '0x0', 03:21:51 INFO - 'XPC_SERVICE_NAME': '0', 03:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:51 INFO - Downloading/unpacking psutil>=0.7.1 03:21:51 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 03:21:51 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 03:21:51 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 03:21:51 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 03:21:51 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 03:21:51 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 03:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:54 INFO - Installing collected packages: psutil 03:21:54 INFO - Running setup.py install for psutil 03:21:54 INFO - building 'psutil._psutil_osx' extension 03:21:54 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 03:21:54 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 03:21:54 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 03:21:54 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 03:21:54 INFO - building 'psutil._psutil_posix' extension 03:21:54 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 03:21:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:54 INFO - ^ 03:21:54 INFO - 1 warning generated. 03:21:54 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 03:21:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:54 INFO - Successfully installed psutil 03:21:54 INFO - Cleaning up... 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:21:55 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')]} 03:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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')]} 03:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:55 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 03:21:55 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 03:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:21:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:21:57 INFO - Installing collected packages: mozsystemmonitor 03:21:57 INFO - Running setup.py install for mozsystemmonitor 03:21:57 INFO - Successfully installed mozsystemmonitor 03:21:57 INFO - Cleaning up... 03:21:58 INFO - Return code: 0 03:21:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:58 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')]} 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:58 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')]} 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:58 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:21:58 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 03:21:58 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 03:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:58 INFO - 'HOME': '/Users/cltbld', 03:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:58 INFO - 'LOGNAME': 'cltbld', 03:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:58 INFO - 'MOZ_NO_REMOTE': '1', 03:21:58 INFO - 'NO_EM_RESTART': '1', 03:21:58 INFO - 'PAGER': '/bin/cat', 03:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:58 INFO - 'PWD': '/builds/slave/test', 03:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:58 INFO - 'SHELL': '/bin/bash', 03:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:21:58 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:58 INFO - 'USER': 'cltbld', 03:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:58 INFO - 'XPC_FLAGS': '0x0', 03:21:58 INFO - 'XPC_SERVICE_NAME': '0', 03:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:21: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 03:22:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:00 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 03:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 INFO - Downloading docopt-0.6.1.tar.gz 03:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:01 INFO - Running setup.py install for blobuploader 03:22:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:02 INFO - Running setup.py install for requests 03:22:02 INFO - Running setup.py install for docopt 03:22:02 INFO - Successfully installed blobuploader requests docopt 03:22:02 INFO - Cleaning up... 03:22:02 INFO - Return code: 0 03:22:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:22:02 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 03:22:02 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 03:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:02 INFO - 'HOME': '/Users/cltbld', 03:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:02 INFO - 'LOGNAME': 'cltbld', 03:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:02 INFO - 'MOZ_NO_REMOTE': '1', 03:22:02 INFO - 'NO_EM_RESTART': '1', 03:22:02 INFO - 'PAGER': '/bin/cat', 03:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:02 INFO - 'PWD': '/builds/slave/test', 03:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:02 INFO - 'SHELL': '/bin/bash', 03:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:02 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:02 INFO - 'USER': 'cltbld', 03:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:02 INFO - 'XPC_FLAGS': '0x0', 03:22:02 INFO - 'XPC_SERVICE_NAME': '0', 03:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-MIefPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-imcIKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-ScTUYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-fSPQar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-UrwAmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-pYk6YO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-5dxfyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-qfngp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:03 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-fIE_qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-i2A_zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-xXnOI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-S7W9Qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-DV5KYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-jXpw_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-LmVjyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-iVfjt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:04 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-6KNsKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:04 INFO - Running setup.py install for manifestparser 03:22:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:05 INFO - Running setup.py install for mozcrash 03:22:05 INFO - Running setup.py install for mozdebug 03:22:05 INFO - Running setup.py install for mozdevice 03:22:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:05 INFO - Running setup.py install for mozfile 03:22:05 INFO - Running setup.py install for mozhttpd 03:22:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:05 INFO - Running setup.py install for mozinfo 03:22:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:05 INFO - Running setup.py install for mozInstall 03:22:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Running setup.py install for mozleak 03:22:06 INFO - Running setup.py install for mozlog 03:22:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Running setup.py install for moznetwork 03:22:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Running setup.py install for mozprocess 03:22:06 INFO - Running setup.py install for mozprofile 03:22:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Running setup.py install for mozrunner 03:22:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:06 INFO - Running setup.py install for mozscreenshot 03:22:07 INFO - Running setup.py install for moztest 03:22:07 INFO - Running setup.py install for mozversion 03:22:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:07 INFO - Cleaning up... 03:22:07 INFO - Return code: 0 03:22:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:07 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')]} 03:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 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')]} 03:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:22:07 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 03:22:07 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 03:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:07 INFO - 'HOME': '/Users/cltbld', 03:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:07 INFO - 'LOGNAME': 'cltbld', 03:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:07 INFO - 'MOZ_NO_REMOTE': '1', 03:22:07 INFO - 'NO_EM_RESTART': '1', 03:22:07 INFO - 'PAGER': '/bin/cat', 03:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:07 INFO - 'PWD': '/builds/slave/test', 03:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:07 INFO - 'SHELL': '/bin/bash', 03:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:07 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:07 INFO - 'USER': 'cltbld', 03:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:07 INFO - 'XPC_FLAGS': '0x0', 03:22:07 INFO - 'XPC_SERVICE_NAME': '0', 03:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:07 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-ztHkqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:07 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)) 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:07 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-KP_rlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:07 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)) 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:07 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-YlbgKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-QoQ_Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-oEn2SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-9ZiEUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-0sBAAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-NUaIWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-XuQDeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-eZN5kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-idXnnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:08 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)) 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:08 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-JkABOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:09 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)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-C0IaSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:09 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)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-_RZLtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:09 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)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-pSG7MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:09 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)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-4FDjpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:09 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)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-8EIp6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 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)) 03:22:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:09 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 03:22:09 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 03:22:09 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 03:22:09 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 03:22:09 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 03:22:09 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 03:22:11 INFO - Downloading blessings-1.6.tar.gz 03:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:12 INFO - Installing collected packages: blessings 03:22:12 INFO - Running setup.py install for blessings 03:22:12 INFO - Successfully installed blessings 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:12 INFO - Reading from file tmpfile_stdout 03:22:12 INFO - Current package versions: 03:22:12 INFO - blessings == 1.6 03:22:12 INFO - blobuploader == 1.2.4 03:22:12 INFO - docopt == 0.6.1 03:22:12 INFO - manifestparser == 1.1 03:22:12 INFO - mozInstall == 1.12 03:22:12 INFO - mozcrash == 0.17 03:22:12 INFO - mozdebug == 0.1 03:22:12 INFO - mozdevice == 0.48 03:22:12 INFO - mozfile == 1.2 03:22:12 INFO - mozhttpd == 0.7 03:22:12 INFO - mozinfo == 0.9 03:22:12 INFO - mozleak == 0.1 03:22:12 INFO - mozlog == 3.1 03:22:12 INFO - moznetwork == 0.27 03:22:12 INFO - mozprocess == 0.22 03:22:12 INFO - mozprofile == 0.28 03:22:12 INFO - mozrunner == 6.11 03:22:12 INFO - mozscreenshot == 0.1 03:22:12 INFO - mozsystemmonitor == 0.0 03:22:12 INFO - moztest == 0.7 03:22:12 INFO - mozversion == 1.4 03:22:12 INFO - psutil == 3.1.1 03:22:12 INFO - requests == 1.2.3 03:22:12 INFO - wsgiref == 0.1.2 03:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:22:12 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 03:22:12 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 03:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:12 INFO - 'HOME': '/Users/cltbld', 03:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PAGER': '/bin/cat', 03:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:12 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - 'XPC_FLAGS': '0x0', 03:22:12 INFO - 'XPC_SERVICE_NAME': '0', 03:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 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)) 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:22:12 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 03:22:12 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 03:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:12 INFO - 'HOME': '/Users/cltbld', 03:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PAGER': '/bin/cat', 03:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:12 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - 'XPC_FLAGS': '0x0', 03:22:12 INFO - 'XPC_SERVICE_NAME': '0', 03:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:13 INFO - Downloading/unpacking jsonschema 03:22:13 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 03:22:13 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 03:22:13 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 03:22:13 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 03:22:13 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 03:22:13 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 03:22:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:15 INFO - Downloading/unpacking functools32 (from jsonschema) 03:22:15 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 03:22:15 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 03:22:15 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 03:22:15 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 03:22:15 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 03:22:15 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 03:22:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:16 INFO - warning: no files found matching '*.txt' 03:22:16 INFO - no previously-included directories found matching 'build' 03:22:16 INFO - no previously-included directories found matching 'dist' 03:22:16 INFO - no previously-included directories found matching '.git*' 03:22:16 INFO - Installing collected packages: jsonschema, functools32 03:22:16 INFO - Running setup.py install for functools32 03:22:16 INFO - warning: no files found matching '*.txt' 03:22:16 INFO - no previously-included directories found matching 'build' 03:22:16 INFO - no previously-included directories found matching 'dist' 03:22:16 INFO - no previously-included directories found matching '.git*' 03:22:16 INFO - Successfully installed jsonschema functools32 03:22:16 INFO - Cleaning up... 03:22:16 INFO - Return code: 0 03:22:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:22:16 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')]} 03:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:16 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')]} 03:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:16 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 0x10855ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10856b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9153c75c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, '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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 03:22:16 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 03:22:16 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 03:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:16 INFO - 'HOME': '/Users/cltbld', 03:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:16 INFO - 'LOGNAME': 'cltbld', 03:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:16 INFO - 'MOZ_NO_REMOTE': '1', 03:22:16 INFO - 'NO_EM_RESTART': '1', 03:22:16 INFO - 'PAGER': '/bin/cat', 03:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:16 INFO - 'PWD': '/builds/slave/test', 03:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:16 INFO - 'SHELL': '/bin/bash', 03:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:16 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:16 INFO - 'USER': 'cltbld', 03:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:16 INFO - 'XPC_FLAGS': '0x0', 03:22:16 INFO - 'XPC_SERVICE_NAME': '0', 03:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:16 INFO - Downloading/unpacking flake8 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:22:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:22:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:22:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:20 INFO - no previously-included directories found matching 'docs/_build' 03:22:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:22:20 INFO - Running setup.py install for pep8 03:22:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:20 INFO - no previously-included directories found matching 'docs/_build' 03:22:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:22:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:22:20 INFO - Cleaning up... 03:22:20 INFO - Return code: 0 03:22:20 INFO - Running post-action listener: _resource_record_post_action 03:22:20 INFO - Running post-action listener: _start_resource_monitoring 03:22:20 INFO - Starting resource monitoring. 03:22:20 INFO - ##### 03:22:20 INFO - ##### Running install step. 03:22:20 INFO - ##### 03:22:20 INFO - Running pre-action listener: _resource_record_pre_action 03:22:20 INFO - Running main action method: install 03:22:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:21 INFO - Reading from file tmpfile_stdout 03:22:21 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:21 INFO - Reading from file tmpfile_stdout 03:22:21 INFO - Output received: 03:22:21 INFO - Usage: mozinstall [options] installer 03:22:21 INFO - Options: 03:22:21 INFO - -h, --help show this help message and exit 03:22:21 INFO - -d DEST, --destination=DEST 03:22:21 INFO - Directory to install application into. [default: 03:22:21 INFO - "/builds/slave/test"] 03:22:21 INFO - --app=APP Application being installed. [default: firefox] 03:22:21 INFO - mkdir: /builds/slave/test/build/application 03:22:21 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'] 03:22:21 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 03:22:52 INFO - Reading from file tmpfile_stdout 03:22:52 INFO - Output received: 03:22:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:52 INFO - Running post-action listener: _resource_record_post_action 03:22:52 INFO - ##### 03:22:52 INFO - ##### Running run-tests step. 03:22:52 INFO - ##### 03:22:52 INFO - Running pre-action listener: _resource_record_pre_action 03:22:52 INFO - Running main action method: run_tests 03:22:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:52 INFO - 2016-05-06 03:22:52.943 screenresolution[1912:11696] starting screenresolution argv=screenresolution get 03:22:52 INFO - 2016-05-06 03:22:52.946 screenresolution[1912:11696] Display 0: 1600x1200x32@60 03:22:52 INFO - 2016-05-06 03:22:52.952 screenresolution[1913:11698] starting screenresolution argv=screenresolution list 03:22:52 INFO - Available Modes on Display 0 03:22:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:53 INFO - Intel Iris: 03:22:53 INFO - Chipset Model: Intel Iris 03:22:53 INFO - Type: GPU 03:22:53 INFO - Bus: Built-In 03:22:53 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:53 INFO - Vendor: Intel (0x8086) 03:22:53 INFO - Device ID: 0x0a2e 03:22:53 INFO - Revision ID: 0x0009 03:22:53 INFO - Displays: 03:22:53 INFO - Display: 03:22:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:53 INFO - Display Serial Number: 1600x1200 60 03:22:53 INFO - Main Display: Yes 03:22:53 INFO - Mirror: Off 03:22:53 INFO - Online: Yes 03:22:53 INFO - Rotation: Supported 03:22:53 INFO - Return code: 0 03:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:53 INFO - Python 2.7.3 03:22:53 INFO - Return code: 0 03:22:53 INFO - grabbing minidump binary from tooltool 03:22:53 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')]} 03:22:53 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 0x1083fae00>, '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 0x108263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:53 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 03:22:53 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 03:22:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:53 INFO - Return code: 0 03:22:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:22:54 INFO - Return code: 0 03:22:54 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/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 03:22:54 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/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --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 03:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mA9bD8hNu4/Render', 03:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:54 INFO - 'HOME': '/Users/cltbld', 03:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:54 INFO - 'LOGNAME': 'cltbld', 03:22:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:54 INFO - 'MOZ_NO_REMOTE': '1', 03:22:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:54 INFO - 'NO_EM_RESTART': '1', 03:22:54 INFO - 'PAGER': '/bin/cat', 03:22:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:54 INFO - 'PWD': '/builds/slave/test', 03:22:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:54 INFO - 'SHELL': '/bin/bash', 03:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fR1gmpXF0M/Listeners', 03:22:54 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 03:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:54 INFO - 'USER': 'cltbld', 03:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:54 INFO - 'XPC_FLAGS': '0x0', 03:22:54 INFO - 'XPC_SERVICE_NAME': '0', 03:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:54 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/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 03:22:54 INFO - mozversion application_buildid: 20160505123841 03:22:54 INFO - mozversion application_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:22:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:54 INFO - mozversion application_name: Firefox 03:22:54 INFO - mozversion application_remotingname: firefox-dev 03:22:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:54 INFO - mozversion application_vendor: Mozilla 03:22:54 INFO - mozversion application_version: 48.0a2 03:22:54 INFO - mozversion platform_buildid: 20160505123841 03:22:54 INFO - mozversion platform_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:22:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:54 INFO - mozversion platform_version: 48.0a2 03:22:54 INFO - using testdate: 1462530174 03:22:54 INFO - actual date: 1462530174 03:22:54 INFO - starting webserver on 'localhost:50917' 03:22:54 INFO - SUITE-START | Running 2 tests 03:22:54 INFO - TEST-START | dromaeo_css 03:22:54 INFO - operating with platform_type : mac_ 03:22:54 INFO - Initialising browser for dromaeo_css test... 03:22:54 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpRdXLXC/profile http://localhost:50917/getInfo.html) 03:22:56 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 03:22:56 INFO - PROCESS | 1931 | colorDepth:24 03:22:56 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 03:22:56 INFO - PROCESS | 1931 | __metrics 03:22:57 INFO - PROCESS | 1931 | 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:22:57 ERROR - PROCESS | 1931 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:22:57 INFO - TEST-INFO | 1931: exit 0 03:22:57 INFO - Browser initialized. 03:22:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:57 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpRdXLXC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:09 INFO - PROCESS | 1933 | RSS: Main: 210882560 03:23:09 INFO - PROCESS | 1933 | 03:24:00 INFO - PROCESS | 1933 | 1462530240664 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] 03:26:11 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50917/tests/dromaeo/cssquery-ext.html) 03:26:13 INFO - PROCESS | 1933 | RSS: Main: 414576640 03:26:13 INFO - PROCESS | 1933 | 03:29:17 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-ext.html (next: http://localhost:50917/tests/dromaeo/cssquery-jquery.html) 03:29:17 INFO - PROCESS | 1933 | RSS: Main: 355319808 03:29:17 INFO - PROCESS | 1933 | 03:32:19 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50917/tests/dromaeo/cssquery-mootools.html) 03:32:20 INFO - PROCESS | 1933 | RSS: Main: 355946496 03:32:20 INFO - PROCESS | 1933 | 03:35:22 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50917/tests/dromaeo/cssquery-prototype.html) 03:35:23 INFO - PROCESS | 1933 | RSS: Main: 358060032 03:35:23 INFO - PROCESS | 1933 | 03:38:26 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50917/tests/dromaeo/cssquery-yui.html) 03:38:27 INFO - PROCESS | 1933 | RSS: Main: 326848512 03:38:27 INFO - PROCESS | 1933 | 03:41:29 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50917/tests/dromaeo/cssquery-yui.html (next: http://localhost:50917/tests/dromaeo/cssquery-dojo.html) 03:41:30 INFO - PROCESS | 1933 | RSS: Main: 360767488 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | __start_tp_report 03:41:30 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 03:41:30 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 03:41:30 INFO - PROCESS | 1933 | |i|pagename|runs| 03:41:30 INFO - PROCESS | 1933 | |0;dojo.html;3412.9488057679137;3475.548178736764;3505.0536355184117;3508.1580420699024;3541.6812486876174;5776.162456443813;5776.768929242836;5776.942230577693;5781.82654520364;5801.680907550083;5772.191893134965;5807.767689292423;5810.360860305363;5822.737976791044;5829.708514574265;5146.511081447256;5184.922226166601;5202.011617792627;5215.4523775003645;5315.734213289339;5273;5280.947190528103;5282.286891269668;5283.498067683564;5294.41761406245;5219.947800521984;5266.447023062581;5268;5285.973570132144;5288.365396152467;4425.3140763181755;5256.106461901486;5282.550983166397;5286.15421532553;5286.471352864722;5097;5150.356205474316;5151.021305951857;5213.5829133669595;5216.739163041832;5590.608657393984;5625.071863142576;5645.887082258332;5677.205191273225;5702.45826646468;6268.843278918018;6316.463100636405;6317.810465686037;6324.525660575475;6332.778352757678;6152;6244.718987645567;6302.527310451735;6303.5902666327;6314.894893393684;5868.559858010666;5916;5917.08285215788;5924;5955.076963070731;6345.936540634627;6368.395002474757;6368.5860419072615;6371.139896114058;6391.552591318563;6068.453839154409;6146.200993870768;6153.077038444269;6242.2509298883515;6257.687115644199;6296.5592408530965;6308.7476500940365;6321.431071203614;6333.904991425133;6336.017917222838;6234.906476402857;6295.685215739195;6298.275698294663;6305.464035557029;6309.116723658692;6042.093685947143;6107.401224262706;6118.071005858243;6124.106319906353;6338.809835704937;4941.011797640486;4957.182064959314;4974.129527332775;4980.875478113004;4990.30135780991;4435.268180750205;4477.19410506112;4477.328400739915;4557.293619488918;4575.062112266977;5779.768809247667;5785.450382213683;5791.0734282514595;5801.361850196475;5804.245448091806;6212.751489940351;6255.343188965093;6308.7476500940365;6319.55763096588;6337.144485494491;5680.460356266148;5737.684427356452;5763.538916886639;5783.942160578425;5795.420457954255;5426.240326354314;5448.863778405572;5449.427810079964;5450.073487507133;5451.291332126798;6226.377362263737;6338.58799178052;6381.936180638227;6402.039694045744;6408.51936104794;6277;6287.559870808999;6316.210473690789;6320.810375688737;6326.019466982625;6403.3276505966205;6412.39082287182;6415.294317625151;6419.743210271537;6436.645984470899;6133.172021777003;6200.596961197508;6216.502679785516;6279.466245369194;6305.274893387319;6112.442689633966;6209.96895015522;6231.9688401559515;6300.873982520416;6310.368963103653;5075.822346217865;5528.612997090165;6192.318844927145;6193.102000210034;6196.566240363131;6125.938740612715;6131.202943617302;6181.783637572664;6184.938150618616;6203.2556093269095;5847.239858818326;5949.970250148722;5950.494207992369;5953.851153721192;6064.636121832704;6055.212822333068;6102.023676211785;6105.267367915879;6133.815985520441;6136.2943261523715;5911.970440147772;5957.136215248851;5977.521798256216;5990.910136348046;5993.850153746192 03:41:30 INFO - PROCESS | 1933 | |1;ext.html;494.5400777277131;519.1874716069349;533.943935886732;586.9933064793879;618.1593033474477;25461.490770184584;31519.369612607734;31550.36899262019;31576.526352104738;31598.526022109658;39848.20303593927;41011.179776404526;41186.97032574187;41196.794016029875;41213.58786412135;9425.057494250588;9636.277279204052;9643.80712385752;9678.661246856362;9693.176080033205;8344.874826877602;8580.785480362978;9377.718668439958;9557.23542116629;9566.043395660448;11177.552897884076;11484.598039068635;11490.597829075985;11492.942535287311;11502.137339699557;11453.484593193327;11518.94240528792;11577.479013444416;11599.478023488964;11603.651890443258;8355.791105222357;11063;11207.159463040223;11387.658370248822;11551.960323570918;38767.22465550673;39278;39376.409353859715;39396;39514.60485395138;3254.2027203335097;3301.6533264007176;3365.9902029391083;3616.3490571697084;3849.4610754494392;26264.8686756565;28067.719322806715;28783.85608071967;29345;29581.70418295811;2725.9863700681567;2730.2901245676067;2746.7115952824865;2747.7939154563287;2756.5589505678995;370165;371734;371797;372913;373140.1342993295;51444.22833657498;62770.68614656897;62825.057624135676;62826.37173628296;62951.37048629547;66656.6667166661;66681.6665916677;66697.33302667008;66858;66949.33050669529;17101.144942752813;17734.91132544329;17746.20142093609;18013.91916485015;18031.549211269823;13878.4448622056;13935.930320348334;13952.232627205602;13959.162450253018;13960.232187229596;14919;15256.313465894062;16418.343266269214;16701.99788012723;16866.746998795028;5758.913616295759;6521.739130434825;6551.868962620816;6699.665016749143;6705.530612857151;871.2898987325436;906.4968942237122;952.8808898887639;1064.2603390643583;1073.372077334768;30131.342776147085;30188.09435716932;30346.84826575853;30399.848000759855;31052.68947310498;1093.3439936038314;1123.9269447485888;1158.2702897174727;1182.2374568403332;1186.3415804228564;13241.271730054747;14815.851841481448;15619.60950976212;15717.764233536505;15914.283857226452;653186;658191;659885;662133;662645;62846.37153628497;63039.739205215636;63045;63234.73530529364;63238.367616323245;1334.9920809788548;1370.4038743146764;1398.1261711430357;1542.3213785934154;1545.8531439513229;29788.55317170201;30443.533283724213;31690.207744806565;31763.36473270568;32273.35453290968;54553.45446545484;55828.720856395455;55863;55886;55899.16151257816;587007;605070;606169;606771;607496;31926.201844954063;42239.788801055794;42654.573454266305;42660.78669606632;43210.487632932694;47958.04083918232;48127;48448.788780280775;49200;49856.50143498664;38194.809025954695;38576.42135367916;39362.81911542658;39537.60462395339;39752.60247397605;38146;38228;38369.03513176262;39352;39380.80309598434;38001.80999094987;38185.4272185923;38772;38793.224135517696;39480.60519394884;32990.75896081841;34448.13879652928;34578.135546611535;34711.826440868645;34986.475202871465;25170.496590068462;25445.491090178464;25489;25689.748950843186;26157.73842261553 03:41:30 INFO - PROCESS | 1933 | |2;jquery.html;392.6780040366899;401.39189128470355;401.71478250442186;402.38033428520083;409.44111288091744;13752.449902003907;19535.316263930767;20171;20237.08933098012;20538.870362130077;15784.0529568226;17217.483475495756;19772.703409448834;19936.202551897943;20194.293199737975;6025.216721826157;6203.689815509207;6236.282827474831;6242.563020588561;6246.843828904291;6085.695715214244;6121.908171377433;6161.845953851145;6174.5060395168275;6198.721057552421;6090.360512146227;6099.176611157482;6110.419510146529;6114.816555503342;6119.143319935212;5196.960607878421;5897.498712609433;6085.178500902388;6103.42017508336;6124.356942521015;4709.128811169944;5140.820071297524;5159.819406320761;5332.493413125786;5353.170258609922;13016.414261358166;13724.725505489934;24959.12643057484;25260.368490787878;25666.615000775004;2086.426232785981;2148.98925505373;2712.498187835257;2780.958285625717;2831.490331740286;8737.650493980233;8783.868241976375;9317.534123293875;9349.252059835197;9377.437353758796;1600.7438809790378;1699.6685646298977;1701.8638508919257;1739.0348356662048;1773.5211492896974;122494;129051;130739.34630326883;133141;136319.31840340834;10103.797924041479;11582.6525204244;11779.705507362212;11989.400529973467;11998.58004969822;14517.419303227964;15125.848741512444;15196.772048419283;15205.923970380078;15229.086254824746;7856.764297071097;8175.836483270301;8430.452020618763;8770.517621530877;8891.955540222258;8828.911710882938;8835.911640883638;8896;8930.240929520933;8946.284297256203;3842.010771992852;4208.516020657602;4624.190766615829;4664.137134630104;4666.2067448533835;1650.414102993444;1794.47063116381;1923.0961448119363;1927.951801204953;1928.4503916383762;911.9133682300171;959.1846930109491;964.8456247000587;968.2496065549255;1010.8584798128268;18821.62356752857;20760.58478830415;20777.480562986046;20812;20886.477838053866;940.4639355567261;972.7033254857195;976.8144052629978;993.9503024848729;1060.528065011078;10451.902550232267;11013.623297087865;11026;11049.116070714324;11156.553737850558;539397;565944;569065;623403;627255;129630.35184824016;135363.32318338344;137000;137372.93940590898;137917.7524632074;999.7950420163849;1030.5362586835804;1041.7031146123318;1044.8484969679355;1049.0872940541778;17821.01984390871;18220.817791822443;18467.630647387246;18706.438806835817;18879.126215998313;36221;49759;50333;51803.481965181374;52701.94596108133;71781;73543;74577.50844982962;75461;78941;7810.882836757567;7889.368850491832;8226.341892648694;8314.376421768271;8537.146285371413;9395.590661400845;10939.343639381663;12259.816102758645;12450.501979920702;13025.023123265604;10158.949205253926;10470.947645261725;10760.623378181726;10981.450927453436;11093.389863557259;8635.30917526609;10659.626913058006;10768.892311076788;10879.238453308193;11049.171312151782;11004.779904402027;11215.383153926286;11247.887521124683;11341.149413794163;11382.260153090023;8213.79465513367;8232.300254478436;8304.460210086325;8595.570221488775;8645.394822362374;10647.89352106469;10909.83635245456;11090.168237382068;11219;11418.885811141783 03:41:30 INFO - PROCESS | 1933 | |3;mootools.html;1440.279860069965;1446.7612843880756;1593.7928069350983;1800.1809176824547;1962.3524237001782;6569.375909288622;8717.738467845968;8930.37487375884;8938.329625278098;8941.597628106734;8518.957405212981;8746.781330466725;8748.737537873858;8765.47407155571;8785.341099417537;2420.2739178246497;2447.951040979179;2449.8407603505716;2451.3626457121186;2458.311672731629;2403.531311394279;2460.3972026853444;2468.049800826677;2471.4315707387314;2471.641611966264;2413.6741539892064;2443.987780061097;2454.484558242771;2454.705435347752;2459.7786199242064;2403.35992108371;2423.4789520253144;2436.9521105924446;2437.2079074300837;2446.9755302446924;2349.400902769783;2423.7697418745192;2431.161249368965;2432.0758111917357;2439.329184474266;7915.762527124196;8170.346372290202;8891.422057566302;8942.597583108776;8970;5210.088234558937;5417.079096553595;5460.31746031746;5469.617126801126;5476.58925580583;3398.932021359559;3512.613612502623;3588.3002814451193;3589.3898037333715;3592.4252119660728;969.5346233807742;990.7269159130554;1037.5071840875598;1056.6037735849056;1060.2790102730141;11740.765184696258;11885.989690876286;11892.167548271538;12166.635000949986;12206.389680516028;4821.108095002436;4851.151048566558;4940.90118197634;5019.0965626187635;5022.949770502321;10447.68656940293;10448.843267350996;10451.94774026125;10461.424621645883;10480.947595261974;5495.642783219078;5550.222968784374;5567.886422715473;5572.972135139298;5577.330720313588;4818.795301174707;4933.358663373739;4981.875453113701;5012.949870501321;5045.84862454127;3910.1397692507603;4003.8798836034966;4042.4946881639794;4068.4304197412384;4070.3487442009136;1294.346355090692;1296.9870301297055;1303.0813276639947;1316.5260506217753;1321.9933900330436;912.3203213605774;915.1123410292006;927.1470247372433;929.1916033051289;953.4898829126384;5794.1308803679785;5873.794417195378;5928.466438020599;5953.077273022688;6095.451409373178;445.21864128455;449.94150760401595;453.51926957425235;463.4022111476158;465.55306905371225;3793.0896584819443;3847.788371639587;3877.3159640169215;3935.4687117239378;3969.2656858481273;10314.1748660107;10372.42951637652;10712.250142489951;11014.118870490342;11070.335779853234;10608.151347892304;10703.357798532114;10749.408782517037;10754.139668826476;10898.891011089947;7983;8033.317168040665;8283.875741863876;8339.95830020846;8371.706990255434;5810.796622118375;5871.7651293947765;5980.43185897339;6044.33512313654;6053.97082495968;9882.357646752938;10067.899321006696;10071.69784906454;10176.135028522658;10195.388276703421;9849.458279794393;10119.038691324313;10126.8480972784;10167.44079075658;10396.58413663445;7507.587082710504;7767.6116194188935;7774.455788094779;7776.805579860549;7777.494462859896;8310.87533686983;8311.542865142475;8540.957295213733;8541.572921354033;8561.44350617208;6442.549021568445;7595.810104747426;7596.81007974805;7704.075510938723;7770.417218708506;7428.182899881117;7542.849143017219;7559.962200188964;7701.576413297098;7713.575753333621;6844.726210951506;6944.201416836926;6951.895721564281;6954.860902781815;6959.921212212115;4027.295223335963;4353.804078816397;4829.66192366531;4949.8515044547285;5001.824936127218;4840.636952228527;4896.632752543503;4915;4992.375953005874;5213.191955246943 03:41:30 INFO - PROCESS | 1933 | |4;prototype.html;309.7413659594237;317.09627561449867;324.9821259830709;325.90060031690325;326.3782494348267;5201.973990130044;5256.763445644946;5299.258103865457;5316.441773613768;5346.150771444553;4332;5215.687058776476;5239.86900327492;5254.448282930294;5256.92114618281;4069.9593004069875;4071.307877660805;4087.305158123111;4094.160697057111;4112.444819949306;3414.9829250853836;3699.7040236780995;3878.650921417072;3888.805559722017;4112.650424713903;3298.818564978925;3498;3607.9278414431697;4050.7772072536;4075.714699971003;3784.2242340320167;4024.6981476389387;4047.5142982841953;4048.1903619276263;4049.8380064797375;3573.249617580311;3593.928121437583;3597.568291805;3601.927961440783;3605.94591081134;4439;4591.931121033187;4822.734749588772;5143.559803255094;5178.482151784829;4281.443412356405;4369.279068953619;4407.765825568846;4573.405457290564;4698.248280275139;5560.805371812007;5577.832665020015;5592.245046918654;5606.747696353633;5613.186088017255;3784.2620688965662;3931.6264954829244;3958;3961.9405708914387;3963.7819919904396;5589;5772.826815195509;5883.587938894677;5897.557683173779;5932.2584676915385;3545.539079919594;4037.91924161521;4052.4326594276827;4056.3104272273786;4094.488188976378;3868.883933482;3992.281389349887;3998.820053097618;4041.3735870939463;4093.7339072960162;4110.856120035784;4611.861644150681;4618.1918164321105;4697.953020469751;4701.294805779092;4469.910601788011;4824.541668541483;5582.413846546136;5592.496675299161;5614.831555053355;1444.4727674398769;1464.5825939607162;1487.2415068315022;1493.746063169242;1509.7659862721296;850.7107583421666;995.7950879435818;1003.1423133221136;1019.8623185869961;1026.3995562595965;4224.4508213931995;4705.223638099744;4712.835050773207;4831.299461578052;5709.3434255060365;6291.213598300213;6319.115323854664;6327.367263273728;6334.619922804646;6336.651484168323;5428.4842939920645;5605.719714014364;5676.233708449389;5684.431556844283;5692.31692196939;2906.781991350615;3310.8344582770765;3313.9005829825146;3325.7384978214563;3335.266241426882;6443.730529595034;6496.155499784998;6542.967285163544;6620.966895165589;6625.933740662628;6167.982282923267;6449.484041276686;6449.6130232186215;6496.57772244812;6517.543771935919;5875.383084776037;6272.780452684134;6545.312742162005;6602.3067577904585;6604.933950660432;5259.316288882574;5848.970755146197;6054.7275372607455;6391.265004524354;6429.839254018687;5449.809256676156;6214.316425193135;6260.342664020394;6280.214973128359;6291.213598300213;5801.332846722512;5926.703664816828;5938.970305148447;6075.635461872302;6144.740328232791;4005.419214213981;4025.778582177891;4042.3734321180264;4043.9595604043584;4057.107436363995;4670.509596492319;5450.373207081237;5812.651240925558;5815.185873977562;5843.561732870136;5662.745176466987;5695.715214239189;5734.254546909042;5744.511716504144;5745.0466841274165;5008.57427118699;5700.800471983461;5705.885882282414;5722.799702010409;5736.42635736423;5221.660592061504;5318.946810531846;5581.246531718329;5621.88756224865;5664.85837854057;4599.011212589309;4893.388326459192;5148.897022059463;5310.070737620978;5315.654482458628;4826.927596086131;4937.259411088366;5326.520613144836;5591.944080559142;5605.467480589337 03:41:30 INFO - PROCESS | 1933 | |5;yui.html;646.0922404022348;656.5797889350813;658.2232965101181;734.0181509923207;761.9771406857797;14308;14345.497907573237;14790.334434950426;14799.999999999987;15143.545693629208;11407.429628518583;13898.722025559508;13977.58067257984;14004.859951400509;14046.929765351158;730.236902436952;755.4183278875263;899.6266549382032;906.9274458043358;938.8967213606531;925.8703781470599;932.4638332958542;939;939.7180845746283;943.5470973932516;963.7253382785907;977.8337682593976;978.1978777402533;978.5596481583281;978.8238117138912;754.8905408715733;943.6697155995381;976.6679329028165;977.9168770654503;988.8318985772436;719.4064896460441;725.5574099799119;725.6081715873422;726.7964969808462;729.0194688144437;14737.11577305365;14833.961622686416;14855.2572371381;14876.479323223635;14930.70138597222;12096.213746106476;12396.194247373982;12502.374881255992;12527.306629074701;12943.417546210443;9871.160951319147;10014.749631259278;10137.138343240833;10345.637902673445;10503.52734126966;3432.115507338873;3552.769070010441;3565.732570057256;3570.285942811422;3600.1539638185004;75088;76420;76974;78009.60995194988;78123;9676.371035882645;9724.465154416575;9844.172902404012;9908.851367229498;9916.900830991743;22183.778162218496;22261.777382226293;22288.442788930406;22318.772467514118;22350.888245558668;7212.675429605575;7306.926930730625;7404.96297518509;7499.8500029999095;7506.099268087864;4012.217617564547;4077.673786097105;4157.459530261047;4187.560306167808;4352.7823608819435;3494.7728397654073;3577.58857731359;3612.4039533477207;3613.457981302773;3614.493970844084;589.3546566509665;593.8129489210885;646.0406296649472;661.5336187987457;679.0459404536634;449.0928324783918;519.5168493301267;570.2045646323355;574.4054903175277;633.6134957675814;7876.645550950107;8020.639071241808;8148.429609927248;8192.508449493049;8262.380321475914;562.8798690605665;585.5666806563113;594.1414655822382;601.4707057789205;602.1479606356955;3651.6713495785507;3808.498335028199;3846.673032792188;3915.8629447969183;4071.633552980246;93049.53475232581;93873.36325014032;95311.0468895316;95808.04191957992;98967;68161.65919170472;82087;82542;82617;84002;73589.63205184047;73826.63086684639;74082;74082.25917740754;74853.87719184217;7435.851282974202;7605.125410577633;7651.770446886602;7759.030121234846;7763.0684317882215;37658.05854853651;44928.77535612301;45038.549614503434;45076.87307817331;45581.54418455773;64356;65433.67283163554;65468.69062618816;65794.68410631856;66291;10124.392536447836;10304.690859274233;10377.169826413718;10546.05085542305;11172.21794474379;10893;11006.724831879266;11171.776564468828;11248;12113.394330283627;8582.957085214533;9513.191378732885;9837.60649574009;10368.118709909742;10682.878297778621;11150.944245278723;11339.433028348714;11644.184907056764;11795.41022948832;12437.37813109359;11008.449577521253;11105.555777768799;11303.491342889754;11597.014253788186;11636.592719254786;9551.856722149034;9577.329586928849;9672.419654820733;9843.26175536852;9872.160866326443;9684.41893486393;9731.902680973382;9767.60929562838;9845.481194415404;10014.449205293487 03:41:30 INFO - PROCESS | 1933 | __end_tp_report 03:41:30 INFO - PROCESS | 1933 | __start_cc_report 03:41:30 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,2058 03:41:30 INFO - PROCESS | 1933 | __end_cc_report 03:41:30 INFO - PROCESS | 1933 | __startTimestamp1462531290170__endTimestamp 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | ------- Summary: start ------- 03:41:30 INFO - PROCESS | 1933 | Number of tests: 6 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#0] dojo.html Cycles:165 Average:5783.76 Median:5957.14 stddev:639.92 (10.7%) stddev-sans-first:614.26 03:41:30 INFO - PROCESS | 1933 | Values: 3412.9 3475.5 3505.1 3508.2 3541.7 5776.2 5776.8 5776.9 5781.8 5801.7 5772.2 5807.8 5810.4 5822.7 5829.7 5146.5 5184.9 5202.0 5215.5 5315.7 5273.0 5280.9 5282.3 5283.5 5294.4 5219.9 5266.4 5268.0 5286.0 5288.4 4425.3 5256.1 5282.6 5286.2 5286.5 5097.0 5150.4 5151.0 5213.6 5216.7 5590.6 5625.1 5645.9 5677.2 5702.5 6268.8 6316.5 6317.8 6324.5 6332.8 6152.0 6244.7 6302.5 6303.6 6314.9 5868.6 5916.0 5917.1 5924.0 5955.1 6345.9 6368.4 6368.6 6371.1 6391.6 6068.5 6146.2 6153.1 6242.3 6257.7 6296.6 6308.7 6321.4 6333.9 6336.0 6234.9 6295.7 6298.3 6305.5 6309.1 6042.1 6107.4 6118.1 6124.1 6338.8 4941.0 4957.2 4974.1 4980.9 4990.3 4435.3 4477.2 4477.3 4557.3 4575.1 5779.8 5785.5 5791.1 5801.4 5804.2 6212.8 6255.3 6308.7 6319.6 6337.1 5680.5 5737.7 5763.5 5783.9 5795.4 5426.2 5448.9 5449.4 5450.1 5451.3 6226.4 6338.6 6381.9 6402.0 6408.5 6277.0 6287.6 6316.2 6320.8 6326.0 6403.3 6412.4 6415.3 6419.7 6436.6 6133.2 6200.6 6216.5 6279.5 6305.3 6112.4 6210.0 6232.0 6300.9 6310.4 5075.8 5528.6 6192.3 6193.1 6196.6 6125.9 6131.2 6181.8 6184.9 6203.3 5847.2 5950.0 5950.5 5953.9 6064.6 6055.2 6102.0 6105.3 6133.8 6136.3 5912.0 5957.1 5977.5 5990.9 5993.9 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#1] ext.html Cycles:180 Average:68826.34 Median:205570.97 stddev:149442.48 (72.7%) stddev-sans-first:149773.64 03:41:30 INFO - PROCESS | 1933 | Values: 494.5 519.2 533.9 587.0 618.2 25461.5 31519.4 31550.4 31576.5 31598.5 39848.2 41011.2 41187.0 41196.8 41213.6 9425.1 9636.3 9643.8 9678.7 9693.2 8344.9 8580.8 9377.7 9557.2 9566.0 11177.6 11484.6 11490.6 11492.9 11502.1 11453.5 11518.9 11577.5 11599.5 11603.7 8355.8 11063.0 11207.2 11387.7 11552.0 38767.2 39278.0 39376.4 39396.0 39514.6 3254.2 3301.7 3366.0 3616.3 3849.5 26264.9 28067.7 28783.9 29345.0 29581.7 2726.0 2730.3 2746.7 2747.8 2756.6 370165.0 371734.0 371797.0 372913.0 373140.1 51444.2 62770.7 62825.1 62826.4 62951.4 66656.7 66681.7 66697.3 66858.0 66949.3 17101.1 17734.9 17746.2 18013.9 18031.5 13878.4 13935.9 13952.2 13959.2 13960.2 14919.0 15256.3 16418.3 16702.0 16866.7 5758.9 6521.7 6551.9 6699.7 6705.5 871.3 906.5 952.9 1064.3 1073.4 30131.3 30188.1 30346.8 30399.8 31052.7 1093.3 1123.9 1158.3 1182.2 1186.3 13241.3 14815.9 15619.6 15717.8 15914.3 653186.0 658191.0 659885.0 662133.0 662645.0 62846.4 63039.7 63045.0 63234.7 63238.4 1335.0 1370.4 1398.1 1542.3 1545.9 29788.6 30443.5 31690.2 31763.4 32273.4 54553.5 55828.7 55863.0 55886.0 55899.2 587007.0 605070.0 606169.0 606771.0 607496.0 31926.2 42239.8 42654.6 42660.8 43210.5 47958.0 48127.0 48448.8 49200.0 49856.5 38194.8 38576.4 39362.8 39537.6 39752.6 38146.0 38228.0 38369.0 39352.0 39380.8 38001.8 38185.4 38772.0 38793.2 39480.6 32990.8 34448.1 34578.1 34711.8 34986.5 25170.5 25445.5 25489.0 25689.7 26157.7 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#2] jquery.html Cycles:180 Average:34705.76 Median:14189.56 stddev:98451.18 (693.8%) stddev-sans-first:98693.65 03:41:30 INFO - PROCESS | 1933 | Values: 392.7 401.4 401.7 402.4 409.4 13752.4 19535.3 20171.0 20237.1 20538.9 15784.1 17217.5 19772.7 19936.2 20194.3 6025.2 6203.7 6236.3 6242.6 6246.8 6085.7 6121.9 6161.8 6174.5 6198.7 6090.4 6099.2 6110.4 6114.8 6119.1 5197.0 5897.5 6085.2 6103.4 6124.4 4709.1 5140.8 5159.8 5332.5 5353.2 13016.4 13724.7 24959.1 25260.4 25666.6 2086.4 2149.0 2712.5 2781.0 2831.5 8737.7 8783.9 9317.5 9349.3 9377.4 1600.7 1699.7 1701.9 1739.0 1773.5 122494.0 129051.0 130739.3 133141.0 136319.3 10103.8 11582.7 11779.7 11989.4 11998.6 14517.4 15125.8 15196.8 15205.9 15229.1 7856.8 8175.8 8430.5 8770.5 8892.0 8828.9 8835.9 8896.0 8930.2 8946.3 3842.0 4208.5 4624.2 4664.1 4666.2 1650.4 1794.5 1923.1 1928.0 1928.5 911.9 959.2 964.8 968.2 1010.9 18821.6 20760.6 20777.5 20812.0 20886.5 940.5 972.7 976.8 994.0 1060.5 10451.9 11013.6 11026.0 11049.1 11156.6 539397.0 565944.0 569065.0 623403.0 627255.0 129630.4 135363.3 137000.0 137372.9 137917.8 999.8 1030.5 1041.7 1044.8 1049.1 17821.0 18220.8 18467.6 18706.4 18879.1 36221.0 49759.0 50333.0 51803.5 52701.9 71781.0 73543.0 74577.5 75461.0 78941.0 7810.9 7889.4 8226.3 8314.4 8537.1 9395.6 10939.3 12259.8 12450.5 13025.0 10158.9 10470.9 10760.6 10981.5 11093.4 8635.3 10659.6 10768.9 10879.2 11049.2 11004.8 11215.4 11247.9 11341.1 11382.3 8213.8 8232.3 8304.5 8595.6 8645.4 10647.9 10909.8 11090.2 11219.0 11418.9 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#3] mootools.html Cycles:180 Average:5762.28 Median:4835.15 stddev:3251.97 (67.3%) stddev-sans-first:3244.87 03:41:30 INFO - PROCESS | 1933 | Values: 1440.3 1446.8 1593.8 1800.2 1962.4 6569.4 8717.7 8930.4 8938.3 8941.6 8519.0 8746.8 8748.7 8765.5 8785.3 2420.3 2448.0 2449.8 2451.4 2458.3 2403.5 2460.4 2468.0 2471.4 2471.6 2413.7 2444.0 2454.5 2454.7 2459.8 2403.4 2423.5 2437.0 2437.2 2447.0 2349.4 2423.8 2431.2 2432.1 2439.3 7915.8 8170.3 8891.4 8942.6 8970.0 5210.1 5417.1 5460.3 5469.6 5476.6 3398.9 3512.6 3588.3 3589.4 3592.4 969.5 990.7 1037.5 1056.6 1060.3 11740.8 11886.0 11892.2 12166.6 12206.4 4821.1 4851.2 4940.9 5019.1 5022.9 10447.7 10448.8 10451.9 10461.4 10480.9 5495.6 5550.2 5567.9 5573.0 5577.3 4818.8 4933.4 4981.9 5012.9 5045.8 3910.1 4003.9 4042.5 4068.4 4070.3 1294.3 1297.0 1303.1 1316.5 1322.0 912.3 915.1 927.1 929.2 953.5 5794.1 5873.8 5928.5 5953.1 6095.5 445.2 449.9 453.5 463.4 465.6 3793.1 3847.8 3877.3 3935.5 3969.3 10314.2 10372.4 10712.3 11014.1 11070.3 10608.2 10703.4 10749.4 10754.1 10898.9 7983.0 8033.3 8283.9 8340.0 8371.7 5810.8 5871.8 5980.4 6044.3 6054.0 9882.4 10067.9 10071.7 10176.1 10195.4 9849.5 10119.0 10126.8 10167.4 10396.6 7507.6 7767.6 7774.5 7776.8 7777.5 8310.9 8311.5 8541.0 8541.6 8561.4 6442.5 7595.8 7596.8 7704.1 7770.4 7428.2 7542.8 7560.0 7701.6 7713.6 6844.7 6944.2 6951.9 6954.9 6959.9 4027.3 4353.8 4829.7 4949.9 5001.8 4840.6 4896.6 4915.0 4992.4 5213.2 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#4] prototype.html Cycles:180 Average:4686.58 Median:5163.69 stddev:1473.80 (28.5%) stddev-sans-first:1440.86 03:41:30 INFO - PROCESS | 1933 | Values: 309.7 317.1 325.0 325.9 326.4 5202.0 5256.8 5299.3 5316.4 5346.2 4332.0 5215.7 5239.9 5254.4 5256.9 4070.0 4071.3 4087.3 4094.2 4112.4 3415.0 3699.7 3878.7 3888.8 4112.7 3298.8 3498.0 3607.9 4050.8 4075.7 3784.2 4024.7 4047.5 4048.2 4049.8 3573.2 3593.9 3597.6 3601.9 3605.9 4439.0 4591.9 4822.7 5143.6 5178.5 4281.4 4369.3 4407.8 4573.4 4698.2 5560.8 5577.8 5592.2 5606.7 5613.2 3784.3 3931.6 3958.0 3961.9 3963.8 5589.0 5772.8 5883.6 5897.6 5932.3 3545.5 4037.9 4052.4 4056.3 4094.5 3868.9 3992.3 3998.8 4041.4 4093.7 4110.9 4611.9 4618.2 4698.0 4701.3 4469.9 4824.5 5582.4 5592.5 5614.8 1444.5 1464.6 1487.2 1493.7 1509.8 850.7 995.8 1003.1 1019.9 1026.4 4224.5 4705.2 4712.8 4831.3 5709.3 6291.2 6319.1 6327.4 6334.6 6336.7 5428.5 5605.7 5676.2 5684.4 5692.3 2906.8 3310.8 3313.9 3325.7 3335.3 6443.7 6496.2 6543.0 6621.0 6625.9 6168.0 6449.5 6449.6 6496.6 6517.5 5875.4 6272.8 6545.3 6602.3 6604.9 5259.3 5849.0 6054.7 6391.3 6429.8 5449.8 6214.3 6260.3 6280.2 6291.2 5801.3 5926.7 5939.0 6075.6 6144.7 4005.4 4025.8 4042.4 4044.0 4057.1 4670.5 5450.4 5812.7 5815.2 5843.6 5662.7 5695.7 5734.3 5744.5 5745.0 5008.6 5700.8 5705.9 5722.8 5736.4 5221.7 5318.9 5581.2 5621.9 5664.9 4599.0 4893.4 5148.9 5310.1 5315.7 4826.9 4937.3 5326.5 5591.9 5605.5 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - PROCESS | 1933 | [#5] yui.html Cycles:180 Average:18122.91 Median:646.07 stddev:25788.74 (3991.7%) stddev-sans-first:25827.69 03:41:30 INFO - PROCESS | 1933 | Values: 646.1 656.6 658.2 734.0 762.0 14308.0 14345.5 14790.3 14800.0 15143.5 11407.4 13898.7 13977.6 14004.9 14046.9 730.2 755.4 899.6 906.9 938.9 925.9 932.5 939.0 939.7 943.5 963.7 977.8 978.2 978.6 978.8 754.9 943.7 976.7 977.9 988.8 719.4 725.6 725.6 726.8 729.0 14737.1 14834.0 14855.3 14876.5 14930.7 12096.2 12396.2 12502.4 12527.3 12943.4 9871.2 10014.7 10137.1 10345.6 10503.5 3432.1 3552.8 3565.7 3570.3 3600.2 75088.0 76420.0 76974.0 78009.6 78123.0 9676.4 9724.5 9844.2 9908.9 9916.9 22183.8 22261.8 22288.4 22318.8 22350.9 7212.7 7306.9 7405.0 7499.9 7506.1 4012.2 4077.7 4157.5 4187.6 4352.8 3494.8 3577.6 3612.4 3613.5 3614.5 589.4 593.8 646.0 661.5 679.0 449.1 519.5 570.2 574.4 633.6 7876.6 8020.6 8148.4 8192.5 8262.4 562.9 585.6 594.1 601.5 602.1 3651.7 3808.5 3846.7 3915.9 4071.6 93049.5 93873.4 95311.0 95808.0 98967.0 68161.7 82087.0 82542.0 82617.0 84002.0 73589.6 73826.6 74082.0 74082.3 74853.9 7435.9 7605.1 7651.8 7759.0 7763.1 37658.1 44928.8 45038.5 45076.9 45581.5 64356.0 65433.7 65468.7 65794.7 66291.0 10124.4 10304.7 10377.2 10546.1 11172.2 10893.0 11006.7 11171.8 11248.0 12113.4 8583.0 9513.2 9837.6 10368.1 10682.9 11150.9 11339.4 11644.2 11795.4 12437.4 11008.4 11105.6 11303.5 11597.0 11636.6 9551.9 9577.3 9672.4 9843.3 9872.2 9684.4 9731.9 9767.6 9845.5 10014.4 03:41:30 INFO - PROCESS | 1933 | -------- Summary: end -------- 03:41:30 INFO - PROCESS | 1933 | 03:41:30 INFO - TEST-INFO | 1933: exit 0 03:41:30 INFO - TEST-OK | dromaeo_css | took 1116160ms 03:41:30 INFO - TEST-START | kraken 03:41:30 INFO - operating with platform_type : mac_ 03:41:30 INFO - Initialising browser for kraken test... 03:41:30 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpGcN8aE/profile http://localhost:50917/getInfo.html) 03:41:32 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 03:41:32 INFO - PROCESS | 1960 | colorDepth:24 03:41:32 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 03:41:32 INFO - PROCESS | 1960 | __metrics 03:41:32 INFO - PROCESS | 1960 | 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:41:33 ERROR - PROCESS | 1960 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:41:33 INFO - TEST-INFO | 1960: exit 0 03:41:33 INFO - Browser initialized. 03:41:33 INFO - Running cycle 1/1 for kraken test... 03:41:33 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpGcN8aE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:45 INFO - PROCESS | 1962 | RSS: Main: 211456000 03:41:45 INFO - PROCESS | 1962 | 03:41:48 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50917/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:49 INFO - PROCESS | 1962 | RSS: Main: 270016512 03:41:49 INFO - PROCESS | 1962 | 03:41:51 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50917/tests/kraken/driver.html?testName=audio-dft) 03:41:52 INFO - PROCESS | 1962 | RSS: Main: 283918336 03:41:52 INFO - PROCESS | 1962 | 03:41:55 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50917/tests/kraken/driver.html?testName=audio-fft) 03:41:56 INFO - PROCESS | 1962 | RSS: Main: 373907456 03:41:56 INFO - PROCESS | 1962 | 03:41:58 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50917/tests/kraken/driver.html?testName=audio-oscillator) 03:41:58 INFO - PROCESS | 1962 | RSS: Main: 277938176 03:41:58 INFO - PROCESS | 1962 | 03:42:00 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50917/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:01 INFO - PROCESS | 1962 | RSS: Main: 278851584 03:42:01 INFO - PROCESS | 1962 | 03:42:04 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50917/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:05 INFO - PROCESS | 1962 | RSS: Main: 318066688 03:42:05 INFO - PROCESS | 1962 | 03:42:07 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50917/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:08 INFO - PROCESS | 1962 | RSS: Main: 317689856 03:42:08 INFO - PROCESS | 1962 | 03:42:11 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50917/tests/kraken/driver.html?testName=json-parse-financial) 03:42:11 INFO - PROCESS | 1962 | RSS: Main: 315629568 03:42:11 INFO - PROCESS | 1962 | 03:42:13 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50917/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:14 INFO - PROCESS | 1962 | RSS: Main: 306778112 03:42:14 INFO - PROCESS | 1962 | 03:42:15 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:16 INFO - PROCESS | 1962 | RSS: Main: 314052608 03:42:16 INFO - PROCESS | 1962 | 03:42:18 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:19 INFO - PROCESS | 1962 | RSS: Main: 316706816 03:42:19 INFO - PROCESS | 1962 | 03:42:22 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:22 INFO - PROCESS | 1962 | RSS: Main: 321875968 03:42:22 INFO - PROCESS | 1962 | 03:42:25 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:26 INFO - PROCESS | 1962 | RSS: Main: 319848448 03:42:26 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:50917/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50917/tests/kraken/driver.html?testName=ai-astar) 03:42:28 INFO - PROCESS | 1962 | RSS: Main: 384122880 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | __start_tp_report 03:42:28 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 03:42:28 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 03:42:28 INFO - PROCESS | 1962 | |i|pagename|runs| 03:42:28 INFO - PROCESS | 1962 | |0;ai-astar;115;114;109;113;113;116;115;114;106;115 03:42:28 INFO - PROCESS | 1962 | |1;audio-beat-detection;156;119;165;185;145;137;120;152;160;120 03:42:28 INFO - PROCESS | 1962 | |2;audio-dft;182;186;185;184;185;183;186;188;186;186 03:42:28 INFO - PROCESS | 1962 | |3;audio-fft;69;78;73;154;56;55;64;66;137;56 03:42:28 INFO - PROCESS | 1962 | |4;audio-oscillator;89;88;144;77;90;84;129;80;80;128 03:42:28 INFO - PROCESS | 1962 | |5;imaging-gaussian-blur;87;84;87;84;86;84;87;86;87;83 03:42:28 INFO - PROCESS | 1962 | |6;imaging-darkroom;97;93;93;93;93;94;93;93;92;93 03:42:28 INFO - PROCESS | 1962 | |7;imaging-desaturate;86;83;85;85;84;83;84;84;84;84 03:42:28 INFO - PROCESS | 1962 | |8;json-parse-financial;58;64;64;62;63;65;63;83;60;59 03:42:28 INFO - PROCESS | 1962 | |9;json-stringify-tinderbox;63;66;70;74;71;71;67;67;65;58 03:42:28 INFO - PROCESS | 1962 | |10;stanford-crypto-aes;81;81;81;79;80;81;78;81;82;85 03:42:28 INFO - PROCESS | 1962 | |11;stanford-crypto-ccm;147;149;152;134;147;152;153;142;168;152 03:42:28 INFO - PROCESS | 1962 | |12;stanford-crypto-pbkdf2;170;170;169;167;166;168;160;161;149;197 03:42:28 INFO - PROCESS | 1962 | |13;stanford-crypto-sha256-iterative;69;67;68;67;68;67;66;68;67;68 03:42:28 INFO - PROCESS | 1962 | __end_tp_report 03:42:28 INFO - PROCESS | 1962 | __start_cc_report 03:42:28 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,2125 03:42:28 INFO - PROCESS | 1962 | __end_cc_report 03:42:28 INFO - PROCESS | 1962 | __startTimestamp1462531348365__endTimestamp 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | ------- Summary: start ------- 03:42:28 INFO - PROCESS | 1962 | Number of tests: 14 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.50 stddev:3.13 (2.7%) stddev-sans-first:3.23 03:42:28 INFO - PROCESS | 1962 | Values: 115.0 114.0 109.0 113.0 113.0 116.0 115.0 114.0 106.0 115.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:154.00 stddev:22.05 (14.3%) stddev-sans-first:23.09 03:42:28 INFO - PROCESS | 1962 | Values: 156.0 119.0 165.0 185.0 145.0 137.0 120.0 152.0 160.0 120.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.73 (0.9%) stddev-sans-first:1.42 03:42:28 INFO - PROCESS | 1962 | Values: 182.0 186.0 185.0 184.0 185.0 183.0 186.0 188.0 186.0 186.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#3] audio-fft Cycles:10 Average:80.80 Median:65.00 stddev:35.15 (54.1%) stddev-sans-first:37.03 03:42:28 INFO - PROCESS | 1962 | Values: 69.0 78.0 73.0 154.0 56.0 55.0 64.0 66.0 137.0 56.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:82.00 stddev:24.72 (30.1%) stddev-sans-first:25.96 03:42:28 INFO - PROCESS | 1962 | Values: 89.0 88.0 144.0 77.0 90.0 84.0 129.0 80.0 80.0 128.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.58 (1.8%) stddev-sans-first:1.58 03:42:28 INFO - PROCESS | 1962 | Values: 87.0 84.0 87.0 84.0 86.0 84.0 87.0 86.0 87.0 83.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:1.35 (1.5%) stddev-sans-first:0.50 03:42:28 INFO - PROCESS | 1962 | Values: 97.0 93.0 93.0 93.0 93.0 94.0 93.0 93.0 92.0 93.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.92 (1.1%) stddev-sans-first:0.71 03:42:28 INFO - PROCESS | 1962 | Values: 86.0 83.0 85.0 85.0 84.0 83.0 84.0 84.0 84.0 84.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:63.50 stddev:7.03 (11.1%) stddev-sans-first:7.10 03:42:28 INFO - PROCESS | 1962 | Values: 58.0 64.0 64.0 62.0 63.0 65.0 63.0 83.0 60.0 59.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:68.50 stddev:4.61 (6.7%) stddev-sans-first:4.64 03:42:28 INFO - PROCESS | 1962 | Values: 63.0 66.0 70.0 74.0 71.0 71.0 67.0 67.0 65.0 58.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.85 (2.3%) stddev-sans-first:1.96 03:42:28 INFO - PROCESS | 1962 | Values: 81.0 81.0 81.0 79.0 80.0 81.0 78.0 81.0 82.0 85.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:152.00 stddev:8.71 (5.7%) stddev-sans-first:9.18 03:42:28 INFO - PROCESS | 1962 | Values: 147.0 149.0 152.0 134.0 147.0 152.0 153.0 142.0 168.0 152.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.50 stddev:12.15 (7.2%) stddev-sans-first:12.86 03:42:28 INFO - PROCESS | 1962 | Values: 170.0 170.0 169.0 167.0 166.0 168.0 160.0 161.0 149.0 197.0 03:42:28 INFO - PROCESS | 1962 | 03:42:28 INFO - PROCESS | 1962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.85 (1.2%) stddev-sans-first:0.71 03:42:28 INFO - PROCESS | 1962 | Values: 69.0 67.0 68.0 67.0 68.0 67.0 66.0 68.0 67.0 68.0 03:42:28 INFO - PROCESS | 1962 | -------- Summary: end -------- 03:42:28 INFO - PROCESS | 1962 | 03:42:29 INFO - TEST-INFO | 1962: exit 0 03:42:29 INFO - TEST-OK | kraken | took 58455ms 03:42:29 INFO - SUITE-END | took 1174s 03:42:29 INFO - Completed test suite (00:19:34) 03:42:29 INFO - javascript benchmark 03:42:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3412.9488057679137, 3475.548178736764, 3505.0536355184117, 3508.1580420699024, 3541.6812486876174, 5776.162456443813, 5776.768929242836, 5776.942230577693, 5781.82654520364, 5801.680907550083, 5772.191893134965, 5807.767689292423, 5810.360860305363, 5822.737976791044, 5829.708514574265, 5146.511081447256, 5184.922226166601, 5202.011617792627, 5215.4523775003645, 5315.734213289339, 5273.0, 5280.947190528103, 5282.286891269668, 5283.498067683564, 5294.41761406245, 5219.947800521984, 5266.447023062581, 5268.0, 5285.973570132144, 5288.365396152467, 4425.3140763181755, 5256.106461901486, 5282.550983166397, 5286.15421532553, 5286.471352864722, 5097.0, 5150.356205474316, 5151.021305951857, 5213.5829133669595, 5216.739163041832, 5590.608657393984, 5625.071863142576, 5645.887082258332, 5677.205191273225, 5702.45826646468, 6268.843278918018, 6316.463100636405, 6317.810465686037, 6324.525660575475, 6332.778352757678, 6152.0, 6244.718987645567, 6302.527310451735, 6303.5902666327, 6314.894893393684, 5868.559858010666, 5916.0, 5917.08285215788, 5924.0, 5955.076963070731, 6345.936540634627, 6368.395002474757, 6368.5860419072615, 6371.139896114058, 6391.552591318563, 6068.453839154409, 6146.200993870768, 6153.077038444269, 6242.2509298883515, 6257.687115644199, 6296.5592408530965, 6308.7476500940365, 6321.431071203614, 6333.904991425133, 6336.017917222838, 6234.906476402857, 6295.685215739195, 6298.275698294663, 6305.464035557029, 6309.116723658692, 6042.093685947143, 6107.401224262706, 6118.071005858243, 6124.106319906353, 6338.809835704937, 4941.011797640486, 4957.182064959314, 4974.129527332775, 4980.875478113004, 4990.30135780991, 4435.268180750205, 4477.19410506112, 4477.328400739915, 4557.293619488918, 4575.062112266977, 5779.768809247667, 5785.450382213683, 5791.0734282514595, 5801.361850196475, 5804.245448091806, 6212.751489940351, 6255.343188965093, 6308.7476500940365, 6319.55763096588, 6337.144485494491, 5680.460356266148, 5737.684427356452, 5763.538916886639, 5783.942160578425, 5795.420457954255, 5426.240326354314, 5448.863778405572, 5449.427810079964, 5450.073487507133, 5451.291332126798, 6226.377362263737, 6338.58799178052, 6381.936180638227, 6402.039694045744, 6408.51936104794, 6277.0, 6287.559870808999, 6316.210473690789, 6320.810375688737, 6326.019466982625, 6403.3276505966205, 6412.39082287182, 6415.294317625151, 6419.743210271537, 6436.645984470899, 6133.172021777003, 6200.596961197508, 6216.502679785516, 6279.466245369194, 6305.274893387319, 6112.442689633966, 6209.96895015522, 6231.9688401559515, 6300.873982520416, 6310.368963103653, 5075.822346217865, 5528.612997090165, 6192.318844927145, 6193.102000210034, 6196.566240363131, 6125.938740612715, 6131.202943617302, 6181.783637572664, 6184.938150618616, 6203.2556093269095, 5847.239858818326, 5949.970250148722, 5950.494207992369, 5953.851153721192, 6064.636121832704, 6055.212822333068, 6102.023676211785, 6105.267367915879, 6133.815985520441, 6136.2943261523715, 5911.970440147772, 5957.136215248851, 5977.521798256216, 5990.910136348046, 5993.850153746192], "value": 5743.721890763214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.5400777277131, 519.1874716069349, 533.943935886732, 586.9933064793879, 618.1593033474477, 25461.490770184584, 31519.369612607734, 31550.36899262019, 31576.526352104738, 31598.526022109658, 39848.20303593927, 41011.179776404526, 41186.97032574187, 41196.794016029875, 41213.58786412135, 9425.057494250588, 9636.277279204052, 9643.80712385752, 9678.661246856362, 9693.176080033205, 8344.874826877602, 8580.785480362978, 9377.718668439958, 9557.23542116629, 9566.043395660448, 11177.552897884076, 11484.598039068635, 11490.597829075985, 11492.942535287311, 11502.137339699557, 11453.484593193327, 11518.94240528792, 11577.479013444416, 11599.478023488964, 11603.651890443258, 8355.791105222357, 11063.0, 11207.159463040223, 11387.658370248822, 11551.960323570918, 38767.22465550673, 39278.0, 39376.409353859715, 39396.0, 39514.60485395138, 3254.2027203335097, 3301.6533264007176, 3365.9902029391083, 3616.3490571697084, 3849.4610754494392, 26264.8686756565, 28067.719322806715, 28783.85608071967, 29345.0, 29581.70418295811, 2725.9863700681567, 2730.2901245676067, 2746.7115952824865, 2747.7939154563287, 2756.5589505678995, 370165.0, 371734.0, 371797.0, 372913.0, 373140.1342993295, 51444.22833657498, 62770.68614656897, 62825.057624135676, 62826.37173628296, 62951.37048629547, 66656.6667166661, 66681.6665916677, 66697.33302667008, 66858.0, 66949.33050669529, 17101.144942752813, 17734.91132544329, 17746.20142093609, 18013.91916485015, 18031.549211269823, 13878.4448622056, 13935.930320348334, 13952.232627205602, 13959.162450253018, 13960.232187229596, 14919.0, 15256.313465894062, 16418.343266269214, 16701.99788012723, 16866.746998795028, 5758.913616295759, 6521.739130434825, 6551.868962620816, 6699.665016749143, 6705.530612857151, 871.2898987325436, 906.4968942237122, 952.8808898887639, 1064.2603390643583, 1073.372077334768, 30131.342776147085, 30188.09435716932, 30346.84826575853, 30399.848000759855, 31052.68947310498, 1093.3439936038314, 1123.9269447485888, 1158.2702897174727, 1182.2374568403332, 1186.3415804228564, 13241.271730054747, 14815.851841481448, 15619.60950976212, 15717.764233536505, 15914.283857226452, 653186.0, 658191.0, 659885.0, 662133.0, 662645.0, 62846.37153628497, 63039.739205215636, 63045.0, 63234.73530529364, 63238.367616323245, 1334.9920809788548, 1370.4038743146764, 1398.1261711430357, 1542.3213785934154, 1545.8531439513229, 29788.55317170201, 30443.533283724213, 31690.207744806565, 31763.36473270568, 32273.35453290968, 54553.45446545484, 55828.720856395455, 55863.0, 55886.0, 55899.16151257816, 587007.0, 605070.0, 606169.0, 606771.0, 607496.0, 31926.201844954063, 42239.788801055794, 42654.573454266305, 42660.78669606632, 43210.487632932694, 47958.04083918232, 48127.0, 48448.788780280775, 49200.0, 49856.50143498664, 38194.809025954695, 38576.42135367916, 39362.81911542658, 39537.60462395339, 39752.60247397605, 38146.0, 38228.0, 38369.03513176262, 39352.0, 39380.80309598434, 38001.80999094987, 38185.4272185923, 38772.0, 38793.224135517696, 39480.60519394884, 32990.75896081841, 34448.13879652928, 34578.135546611535, 34711.826440868645, 34986.475202871465, 25170.496590068462, 25445.491090178464, 25489.0, 25689.748950843186, 26157.73842261553], "value": 20276.056726425766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.6780040366899, 401.39189128470355, 401.71478250442186, 402.38033428520083, 409.44111288091744, 13752.449902003907, 19535.316263930767, 20171.0, 20237.08933098012, 20538.870362130077, 15784.0529568226, 17217.483475495756, 19772.703409448834, 19936.202551897943, 20194.293199737975, 6025.216721826157, 6203.689815509207, 6236.282827474831, 6242.563020588561, 6246.843828904291, 6085.695715214244, 6121.908171377433, 6161.845953851145, 6174.5060395168275, 6198.721057552421, 6090.360512146227, 6099.176611157482, 6110.419510146529, 6114.816555503342, 6119.143319935212, 5196.960607878421, 5897.498712609433, 6085.178500902388, 6103.42017508336, 6124.356942521015, 4709.128811169944, 5140.820071297524, 5159.819406320761, 5332.493413125786, 5353.170258609922, 13016.414261358166, 13724.725505489934, 24959.12643057484, 25260.368490787878, 25666.615000775004, 2086.426232785981, 2148.98925505373, 2712.498187835257, 2780.958285625717, 2831.490331740286, 8737.650493980233, 8783.868241976375, 9317.534123293875, 9349.252059835197, 9377.437353758796, 1600.7438809790378, 1699.6685646298977, 1701.8638508919257, 1739.0348356662048, 1773.5211492896974, 122494.0, 129051.0, 130739.34630326883, 133141.0, 136319.31840340834, 10103.797924041479, 11582.6525204244, 11779.705507362212, 11989.400529973467, 11998.58004969822, 14517.419303227964, 15125.848741512444, 15196.772048419283, 15205.923970380078, 15229.086254824746, 7856.764297071097, 8175.836483270301, 8430.452020618763, 8770.517621530877, 8891.955540222258, 8828.911710882938, 8835.911640883638, 8896.0, 8930.240929520933, 8946.284297256203, 3842.010771992852, 4208.516020657602, 4624.190766615829, 4664.137134630104, 4666.2067448533835, 1650.414102993444, 1794.47063116381, 1923.0961448119363, 1927.951801204953, 1928.4503916383762, 911.9133682300171, 959.1846930109491, 964.8456247000587, 968.2496065549255, 1010.8584798128268, 18821.62356752857, 20760.58478830415, 20777.480562986046, 20812.0, 20886.477838053866, 940.4639355567261, 972.7033254857195, 976.8144052629978, 993.9503024848729, 1060.528065011078, 10451.902550232267, 11013.623297087865, 11026.0, 11049.116070714324, 11156.553737850558, 539397.0, 565944.0, 569065.0, 623403.0, 627255.0, 129630.35184824016, 135363.32318338344, 137000.0, 137372.93940590898, 137917.7524632074, 999.7950420163849, 1030.5362586835804, 1041.7031146123318, 1044.8484969679355, 1049.0872940541778, 17821.01984390871, 18220.817791822443, 18467.630647387246, 18706.438806835817, 18879.126215998313, 36221.0, 49759.0, 50333.0, 51803.481965181374, 52701.94596108133, 71781.0, 73543.0, 74577.50844982962, 75461.0, 78941.0, 7810.882836757567, 7889.368850491832, 8226.341892648694, 8314.376421768271, 8537.146285371413, 9395.590661400845, 10939.343639381663, 12259.816102758645, 12450.501979920702, 13025.023123265604, 10158.949205253926, 10470.947645261725, 10760.623378181726, 10981.450927453436, 11093.389863557259, 8635.30917526609, 10659.626913058006, 10768.892311076788, 10879.238453308193, 11049.171312151782, 11004.779904402027, 11215.383153926286, 11247.887521124683, 11341.149413794163, 11382.260153090023, 8213.79465513367, 8232.300254478436, 8304.460210086325, 8595.570221488775, 8645.394822362374, 10647.89352106469, 10909.83635245456, 11090.168237382068, 11219.0, 11418.885811141783], "value": 9465.756768839676, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.279860069965, 1446.7612843880756, 1593.7928069350983, 1800.1809176824547, 1962.3524237001782, 6569.375909288622, 8717.738467845968, 8930.37487375884, 8938.329625278098, 8941.597628106734, 8518.957405212981, 8746.781330466725, 8748.737537873858, 8765.47407155571, 8785.341099417537, 2420.2739178246497, 2447.951040979179, 2449.8407603505716, 2451.3626457121186, 2458.311672731629, 2403.531311394279, 2460.3972026853444, 2468.049800826677, 2471.4315707387314, 2471.641611966264, 2413.6741539892064, 2443.987780061097, 2454.484558242771, 2454.705435347752, 2459.7786199242064, 2403.35992108371, 2423.4789520253144, 2436.9521105924446, 2437.2079074300837, 2446.9755302446924, 2349.400902769783, 2423.7697418745192, 2431.161249368965, 2432.0758111917357, 2439.329184474266, 7915.762527124196, 8170.346372290202, 8891.422057566302, 8942.597583108776, 8970.0, 5210.088234558937, 5417.079096553595, 5460.31746031746, 5469.617126801126, 5476.58925580583, 3398.932021359559, 3512.613612502623, 3588.3002814451193, 3589.3898037333715, 3592.4252119660728, 969.5346233807742, 990.7269159130554, 1037.5071840875598, 1056.6037735849056, 1060.2790102730141, 11740.765184696258, 11885.989690876286, 11892.167548271538, 12166.635000949986, 12206.389680516028, 4821.108095002436, 4851.151048566558, 4940.90118197634, 5019.0965626187635, 5022.949770502321, 10447.68656940293, 10448.843267350996, 10451.94774026125, 10461.424621645883, 10480.947595261974, 5495.642783219078, 5550.222968784374, 5567.886422715473, 5572.972135139298, 5577.330720313588, 4818.795301174707, 4933.358663373739, 4981.875453113701, 5012.949870501321, 5045.84862454127, 3910.1397692507603, 4003.8798836034966, 4042.4946881639794, 4068.4304197412384, 4070.3487442009136, 1294.346355090692, 1296.9870301297055, 1303.0813276639947, 1316.5260506217753, 1321.9933900330436, 912.3203213605774, 915.1123410292006, 927.1470247372433, 929.1916033051289, 953.4898829126384, 5794.1308803679785, 5873.794417195378, 5928.466438020599, 5953.077273022688, 6095.451409373178, 445.21864128455, 449.94150760401595, 453.51926957425235, 463.4022111476158, 465.55306905371225, 3793.0896584819443, 3847.788371639587, 3877.3159640169215, 3935.4687117239378, 3969.2656858481273, 10314.1748660107, 10372.42951637652, 10712.250142489951, 11014.118870490342, 11070.335779853234, 10608.151347892304, 10703.357798532114, 10749.408782517037, 10754.139668826476, 10898.891011089947, 7983.0, 8033.317168040665, 8283.875741863876, 8339.95830020846, 8371.706990255434, 5810.796622118375, 5871.7651293947765, 5980.43185897339, 6044.33512313654, 6053.97082495968, 9882.357646752938, 10067.899321006696, 10071.69784906454, 10176.135028522658, 10195.388276703421, 9849.458279794393, 10119.038691324313, 10126.8480972784, 10167.44079075658, 10396.58413663445, 7507.587082710504, 7767.6116194188935, 7774.455788094779, 7776.805579860549, 7777.494462859896, 8310.87533686983, 8311.542865142475, 8540.957295213733, 8541.572921354033, 8561.44350617208, 6442.549021568445, 7595.810104747426, 7596.81007974805, 7704.075510938723, 7770.417218708506, 7428.182899881117, 7542.849143017219, 7559.962200188964, 7701.576413297098, 7713.575753333621, 6844.726210951506, 6944.201416836926, 6951.895721564281, 6954.860902781815, 6959.921212212115, 4027.295223335963, 4353.804078816397, 4829.66192366531, 4949.8515044547285, 5001.824936127218, 4840.636952228527, 4896.632752543503, 4915.0, 4992.375953005874, 5213.191955246943], "value": 4556.24202001427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.7413659594237, 317.09627561449867, 324.9821259830709, 325.90060031690325, 326.3782494348267, 5201.973990130044, 5256.763445644946, 5299.258103865457, 5316.441773613768, 5346.150771444553, 4332.0, 5215.687058776476, 5239.86900327492, 5254.448282930294, 5256.92114618281, 4069.9593004069875, 4071.307877660805, 4087.305158123111, 4094.160697057111, 4112.444819949306, 3414.9829250853836, 3699.7040236780995, 3878.650921417072, 3888.805559722017, 4112.650424713903, 3298.818564978925, 3498.0, 3607.9278414431697, 4050.7772072536, 4075.714699971003, 3784.2242340320167, 4024.6981476389387, 4047.5142982841953, 4048.1903619276263, 4049.8380064797375, 3573.249617580311, 3593.928121437583, 3597.568291805, 3601.927961440783, 3605.94591081134, 4439.0, 4591.931121033187, 4822.734749588772, 5143.559803255094, 5178.482151784829, 4281.443412356405, 4369.279068953619, 4407.765825568846, 4573.405457290564, 4698.248280275139, 5560.805371812007, 5577.832665020015, 5592.245046918654, 5606.747696353633, 5613.186088017255, 3784.2620688965662, 3931.6264954829244, 3958.0, 3961.9405708914387, 3963.7819919904396, 5589.0, 5772.826815195509, 5883.587938894677, 5897.557683173779, 5932.2584676915385, 3545.539079919594, 4037.91924161521, 4052.4326594276827, 4056.3104272273786, 4094.488188976378, 3868.883933482, 3992.281389349887, 3998.820053097618, 4041.3735870939463, 4093.7339072960162, 4110.856120035784, 4611.861644150681, 4618.1918164321105, 4697.953020469751, 4701.294805779092, 4469.910601788011, 4824.541668541483, 5582.413846546136, 5592.496675299161, 5614.831555053355, 1444.4727674398769, 1464.5825939607162, 1487.2415068315022, 1493.746063169242, 1509.7659862721296, 850.7107583421666, 995.7950879435818, 1003.1423133221136, 1019.8623185869961, 1026.3995562595965, 4224.4508213931995, 4705.223638099744, 4712.835050773207, 4831.299461578052, 5709.3434255060365, 6291.213598300213, 6319.115323854664, 6327.367263273728, 6334.619922804646, 6336.651484168323, 5428.4842939920645, 5605.719714014364, 5676.233708449389, 5684.431556844283, 5692.31692196939, 2906.781991350615, 3310.8344582770765, 3313.9005829825146, 3325.7384978214563, 3335.266241426882, 6443.730529595034, 6496.155499784998, 6542.967285163544, 6620.966895165589, 6625.933740662628, 6167.982282923267, 6449.484041276686, 6449.6130232186215, 6496.57772244812, 6517.543771935919, 5875.383084776037, 6272.780452684134, 6545.312742162005, 6602.3067577904585, 6604.933950660432, 5259.316288882574, 5848.970755146197, 6054.7275372607455, 6391.265004524354, 6429.839254018687, 5449.809256676156, 6214.316425193135, 6260.342664020394, 6280.214973128359, 6291.213598300213, 5801.332846722512, 5926.703664816828, 5938.970305148447, 6075.635461872302, 6144.740328232791, 4005.419214213981, 4025.778582177891, 4042.3734321180264, 4043.9595604043584, 4057.107436363995, 4670.509596492319, 5450.373207081237, 5812.651240925558, 5815.185873977562, 5843.561732870136, 5662.745176466987, 5695.715214239189, 5734.254546909042, 5744.511716504144, 5745.0466841274165, 5008.57427118699, 5700.800471983461, 5705.885882282414, 5722.799702010409, 5736.42635736423, 5221.660592061504, 5318.946810531846, 5581.246531718329, 5621.88756224865, 5664.85837854057, 4599.011212589309, 4893.388326459192, 5148.897022059463, 5310.070737620978, 5315.654482458628, 4826.927596086131, 4937.259411088366, 5326.520613144836, 5591.944080559142, 5605.467480589337], "value": 4231.579997110854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.0922404022348, 656.5797889350813, 658.2232965101181, 734.0181509923207, 761.9771406857797, 14308.0, 14345.497907573237, 14790.334434950426, 14799.999999999987, 15143.545693629208, 11407.429628518583, 13898.722025559508, 13977.58067257984, 14004.859951400509, 14046.929765351158, 730.236902436952, 755.4183278875263, 899.6266549382032, 906.9274458043358, 938.8967213606531, 925.8703781470599, 932.4638332958542, 939.0, 939.7180845746283, 943.5470973932516, 963.7253382785907, 977.8337682593976, 978.1978777402533, 978.5596481583281, 978.8238117138912, 754.8905408715733, 943.6697155995381, 976.6679329028165, 977.9168770654503, 988.8318985772436, 719.4064896460441, 725.5574099799119, 725.6081715873422, 726.7964969808462, 729.0194688144437, 14737.11577305365, 14833.961622686416, 14855.2572371381, 14876.479323223635, 14930.70138597222, 12096.213746106476, 12396.194247373982, 12502.374881255992, 12527.306629074701, 12943.417546210443, 9871.160951319147, 10014.749631259278, 10137.138343240833, 10345.637902673445, 10503.52734126966, 3432.115507338873, 3552.769070010441, 3565.732570057256, 3570.285942811422, 3600.1539638185004, 75088.0, 76420.0, 76974.0, 78009.60995194988, 78123.0, 9676.371035882645, 9724.465154416575, 9844.172902404012, 9908.851367229498, 9916.900830991743, 22183.778162218496, 22261.777382226293, 22288.442788930406, 22318.772467514118, 22350.888245558668, 7212.675429605575, 7306.926930730625, 7404.96297518509, 7499.8500029999095, 7506.099268087864, 4012.217617564547, 4077.673786097105, 4157.459530261047, 4187.560306167808, 4352.7823608819435, 3494.7728397654073, 3577.58857731359, 3612.4039533477207, 3613.457981302773, 3614.493970844084, 589.3546566509665, 593.8129489210885, 646.0406296649472, 661.5336187987457, 679.0459404536634, 449.0928324783918, 519.5168493301267, 570.2045646323355, 574.4054903175277, 633.6134957675814, 7876.645550950107, 8020.639071241808, 8148.429609927248, 8192.508449493049, 8262.380321475914, 562.8798690605665, 585.5666806563113, 594.1414655822382, 601.4707057789205, 602.1479606356955, 3651.6713495785507, 3808.498335028199, 3846.673032792188, 3915.8629447969183, 4071.633552980246, 93049.53475232581, 93873.36325014032, 95311.0468895316, 95808.04191957992, 98967.0, 68161.65919170472, 82087.0, 82542.0, 82617.0, 84002.0, 73589.63205184047, 73826.63086684639, 74082.0, 74082.25917740754, 74853.87719184217, 7435.851282974202, 7605.125410577633, 7651.770446886602, 7759.030121234846, 7763.0684317882215, 37658.05854853651, 44928.77535612301, 45038.549614503434, 45076.87307817331, 45581.54418455773, 64356.0, 65433.67283163554, 65468.69062618816, 65794.68410631856, 66291.0, 10124.392536447836, 10304.690859274233, 10377.169826413718, 10546.05085542305, 11172.21794474379, 10893.0, 11006.724831879266, 11171.776564468828, 11248.0, 12113.394330283627, 8582.957085214533, 9513.191378732885, 9837.60649574009, 10368.118709909742, 10682.878297778621, 11150.944245278723, 11339.433028348714, 11644.184907056764, 11795.41022948832, 12437.37813109359, 11008.449577521253, 11105.555777768799, 11303.491342889754, 11597.014253788186, 11636.592719254786, 9551.856722149034, 9577.329586928849, 9672.419654820733, 9843.26175536852, 9872.160866326443, 9684.41893486393, 9731.902680973382, 9767.60929562838, 9845.481194415404, 10014.449205293487], "value": 6818.154821873096, "unit": "score"}], "name": "dromaeo_css", "value": 7247.5670490160865, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 109.0, 113.0, 113.0, 116.0, 115.0, 114.0, 106.0, 115.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 165.0, 185.0, 145.0, 137.0, 120.0, 152.0, 160.0, 120.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 185.0, 184.0, 185.0, 183.0, 186.0, 188.0, 186.0, 186.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 73.0, 154.0, 56.0, 55.0, 64.0, 66.0, 137.0, 56.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 144.0, 77.0, 90.0, 84.0, 129.0, 80.0, 80.0, 128.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 86.0, 87.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 85.0, 84.0, 83.0, 84.0, 84.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 64.0, 62.0, 63.0, 65.0, 63.0, 83.0, 60.0, 59.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 70.0, 74.0, 71.0, 71.0, 67.0, 67.0, 65.0, 58.0], "value": 67.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 79.0, 80.0, 81.0, 78.0, 81.0, 82.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 152.0, 134.0, 147.0, 152.0, 153.0, 142.0, 168.0, 152.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 169.0, 167.0, 166.0, 168.0, 160.0, 161.0, 149.0, 197.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 67.0, 68.0, 67.0, 66.0, 68.0, 67.0, 68.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1483.8, "alertThreshold": 2.0}]} 03:42:29 INFO - Return code: 0 03:42:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:42:29 ERROR - # TBPL SUCCESS # 03:42:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:29 INFO - 2016-05-06 03:42:29.695 screenresolution[1966:13683] starting screenresolution argv=screenresolution get 03:42:29 INFO - 2016-05-06 03:42:29.697 screenresolution[1966:13683] Display 0: 1600x1200x32@60 03:42:29 INFO - 2016-05-06 03:42:29.703 screenresolution[1967:13686] starting screenresolution argv=screenresolution list 03:42:29 INFO - Available Modes on Display 0 03:42:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:29 INFO - Intel Iris: 03:42:29 INFO - Chipset Model: Intel Iris 03:42:29 INFO - Type: GPU 03:42:29 INFO - Bus: Built-In 03:42:29 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:29 INFO - Vendor: Intel (0x8086) 03:42:29 INFO - Device ID: 0x0a2e 03:42:29 INFO - Revision ID: 0x0009 03:42:29 INFO - Displays: 03:42:29 INFO - Display: 03:42:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:29 INFO - Display Serial Number: 1600x1200 60 03:42:29 INFO - Main Display: Yes 03:42:29 INFO - Mirror: Off 03:42:29 INFO - Online: Yes 03:42:29 INFO - Rotation: Supported 03:42:29 INFO - Return code: 0 03:42:29 INFO - Running post-action listener: _resource_record_post_action 03:42:29 INFO - Running post-run listener: _resource_record_post_run 03:42:30 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 933888; Write bytes: 651972096; Read time: 13; Write time: 1779 03:42:30 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340382208; Write bytes: 335693824; Read time: 26517; Write time: 581 03:42:31 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 933888; Write bytes: 295896576; Read time: 13; Write time: 1146 03:42:31 INFO - Running post-run listener: _upload_blobber_files 03:42:31 INFO - Blob upload gear active. 03:42:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:31 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:42:31 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:42:31 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:42:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:32 INFO - (blobuploader) - INFO - Done attempting. 03:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:33 INFO - (blobuploader) - INFO - Done attempting. 03:42:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:33 INFO - Return code: 0 03:42:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3c3c139133c10098bdc189d3f950dd42ae4c2a8aaf1cee7c383ae9bc7bbd6be7104cf633cee35fafa1d6da47c7ec8c587a63f9db171efbcf62eca713b5dde69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ddfaf42cd3a045819913d68e9a4724c4d222acea307d740a51918526a4f95d256d7bd48f4f15954b80a91d89f04bb5f7e5b63f5ca1e9efe05ab1ed30fa7aa04"} 03:42:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:33 INFO - Contents: 03:42:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3c3c139133c10098bdc189d3f950dd42ae4c2a8aaf1cee7c383ae9bc7bbd6be7104cf633cee35fafa1d6da47c7ec8c587a63f9db171efbcf62eca713b5dde69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ddfaf42cd3a045819913d68e9a4724c4d222acea307d740a51918526a4f95d256d7bd48f4f15954b80a91d89f04bb5f7e5b63f5ca1e9efe05ab1ed30fa7aa04"} 03:42:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:33 INFO - Copying logs to upload dir... 03:42:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.965937 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 03:42:33.640397) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:33.641423) ========= 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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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/e3c3c139133c10098bdc189d3f950dd42ae4c2a8aaf1cee7c383ae9bc7bbd6be7104cf633cee35fafa1d6da47c7ec8c587a63f9db171efbcf62eca713b5dde69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ddfaf42cd3a045819913d68e9a4724c4d222acea307d740a51918526a4f95d256d7bd48f4f15954b80a91d89f04bb5f7e5b63f5ca1e9efe05ab1ed30fa7aa04"} build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/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/e3c3c139133c10098bdc189d3f950dd42ae4c2a8aaf1cee7c383ae9bc7bbd6be7104cf633cee35fafa1d6da47c7ec8c587a63f9db171efbcf62eca713b5dde69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ddfaf42cd3a045819913d68e9a4724c4d222acea307d740a51918526a4f95d256d7bd48f4f15954b80a91d89f04bb5f7e5b63f5ca1e9efe05ab1ed30fa7aa04"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:33.700568) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 03:42:33.700915) ========= 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.mA9bD8hNu4/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.fR1gmpXF0M/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.004879 ========= master_lag: 2.82 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 03:42:36.521866) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:42:36.522182) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:42:36.804852) ========= ========= Total master_lag: 3.27 =========