builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1462530070.49 results: success (0) buildid: 20160505123841 builduid: 1457772db7ef4c1982b4796b67a4914d revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.489761) ========= 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:10.490185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.490452) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.509320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.509637) ========= 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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.35M=0.001s 2016-05-06 03:21:10 (9.35 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081250 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.609231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.609638) ========= 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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.027540 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:10.659692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 03:21:10.660048) ========= 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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:10,728 truncating revision to first 12 chars 2016-05-06 03:21:10,728 Setting DEBUG logging. 2016-05-06 03:21:10,728 attempt 1/10 2016-05-06 03:21:10,728 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:12,182 unpacking tar archive at: mozilla-aurora-0123e9f41ced/testing/mozharness/ program finished with exit code 0 elapsedTime=1.701563 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 03:21:12.380278) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:12.380824) ========= script_repo_revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:12.381426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:12.381851) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:12.396355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 03:21:12.396804) ========= /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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:12 INFO - MultiFileLogger online at 20160506 03:21:12 in /builds/slave/test 03:21:12 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:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:12 INFO - {'append_to_log': False, 03:21:12 INFO - 'base_work_dir': '/builds/slave/test', 03:21:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:21:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:12 INFO - 'branch': 'Mozilla-Aurora', 03:21:12 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:12 INFO - 'config_files': ('talos/mac_config.py',), 03:21:12 INFO - 'default_actions': ('clobber', 03:21:12 INFO - 'read-buildbot-config', 03:21:12 INFO - 'download-and-extract', 03:21:12 INFO - 'populate-webroot', 03:21:12 INFO - 'create-virtualenv', 03:21:12 INFO - 'install', 03:21:12 INFO - 'run-tests'), 03:21:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:12 INFO - 'download_minidump_stackwalk': True, 03:21:12 INFO - 'download_symbols': 'ondemand', 03:21:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:12 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:12 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:12 INFO - 'installer_path': 'installer.exe', 03:21:12 INFO - 'log_level': 'info', 03:21:12 INFO - 'log_name': 'talos', 03:21:12 INFO - 'log_to_console': True, 03:21:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:12 INFO - 'opt_config_files': (), 03:21:12 INFO - 'pip_index': False, 03:21:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:12 INFO - 'cmd': ('bash', 03:21:12 INFO - '-c', 03:21:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:12 INFO - 'enabled': True, 03:21:12 INFO - 'halt_on_failure': False, 03:21:12 INFO - 'name': 'check_screen_resolution'},), 03:21:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:12 INFO - 'cmd': ('bash', 03:21:12 INFO - '-c', 03:21:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:12 INFO - 'enabled': True, 03:21:12 INFO - 'halt_on_failure': False, 03:21:12 INFO - 'name': 'check_screen_resolution'},), 03:21:12 INFO - 'run_cmd_checks_enabled': True, 03:21:12 INFO - 'sps_profile': False, 03:21:12 INFO - 'sps_profile_interval': 0, 03:21:12 INFO - 'suite': 'dromaeojs', 03:21:12 INFO - 'system_bits': '32', 03:21:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:12 INFO - 'title': 't-yosemite-r7-0132', 03:21:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:12 INFO - 'use_talos_json': True, 03:21:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:12 INFO - 'work_dir': 'build'} 03:21:12 INFO - ##### 03:21:12 INFO - ##### Running clobber step. 03:21:12 INFO - ##### 03:21:12 INFO - Running pre-action listener: _resource_record_pre_action 03:21:12 INFO - Running main action method: clobber 03:21:12 INFO - rmtree: /builds/slave/test/build 03:21:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:14 INFO - Running post-action listener: _resource_record_post_action 03:21:14 INFO - ##### 03:21:14 INFO - ##### Running read-buildbot-config step. 03:21:14 INFO - ##### 03:21:14 INFO - Running pre-action listener: _resource_record_pre_action 03:21:14 INFO - Running main action method: read_buildbot_config 03:21:14 INFO - Using buildbot properties: 03:21:14 INFO - { 03:21:14 INFO - "project": "", 03:21:14 INFO - "product": "firefox", 03:21:14 INFO - "script_repo_revision": "production", 03:21:14 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:21:14 INFO - "repository": "", 03:21:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:21:14 INFO - "buildid": "20160505123841", 03:21:14 INFO - "pgo_build": "False", 03:21:14 INFO - "basedir": "/builds/slave/test", 03:21:14 INFO - "buildnumber": 0, 03:21:14 INFO - "slavename": "t-yosemite-r7-0132", 03:21:14 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:21:14 INFO - "platform": "yosemite_r7", 03:21:14 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:21:14 INFO - "branch": "mozilla-aurora", 03:21:14 INFO - "repo_path": "releases/mozilla-aurora", 03:21:14 INFO - "revision": "0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b", 03:21:14 INFO - "stage_platform": "macosx64", 03:21:14 INFO - "builduid": "1457772db7ef4c1982b4796b67a4914d", 03:21:14 INFO - "slavebuilddir": "test" 03:21:14 INFO - } 03:21:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:21:14 INFO - Running post-action listener: _resource_record_post_action 03:21:14 INFO - ##### 03:21:14 INFO - ##### Running download-and-extract step. 03:21:14 INFO - ##### 03:21:14 INFO - Running pre-action listener: _resource_record_pre_action 03:21:14 INFO - Running main action method: download_and_extract 03:21:14 INFO - mkdir: /builds/slave/test/build/tests 03:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:14 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:14 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:14 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:14 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:14 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:15 INFO - Downloaded 1199 bytes. 03:21:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:15 INFO - Using the following test package requirements: 03:21:15 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:15 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:21:15 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:21:15 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'jsshell-mac.zip'], 03:21:15 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:21:15 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:15 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:21:15 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:21:15 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:21:15 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:15 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:21:15 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:15 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:15 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:15 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:15 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:15 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:17 INFO - Downloaded 18114223 bytes. 03:21:17 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:17 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:20 INFO - Return code: 0 03:21:20 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:20 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:20 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:20 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:20 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:20 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:20 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:21 INFO - Downloaded 18114223 bytes. 03:21:21 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:21 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:25 INFO - Return code: 0 03:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:25 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:25 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:25 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:25 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:25 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:26 INFO - Downloaded 10840145 bytes. 03:21:26 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:26 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:26 INFO - Return code: 0 03:21:26 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:26 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:26 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:26 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:26 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:26 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:29 INFO - Downloaded 114081343 bytes. 03:21:29 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:29 INFO - mkdir: /builds/slave/test/properties 03:21:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:29 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:29 INFO - Contents: 03:21:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:21:30 INFO - Running post-action listener: _resource_record_post_action 03:21:30 INFO - Running post-action listener: set_extra_try_arguments 03:21:30 INFO - ##### 03:21:30 INFO - ##### Running populate-webroot step. 03:21:30 INFO - ##### 03:21:30 INFO - Running pre-action listener: _resource_record_pre_action 03:21:30 INFO - Running main action method: populate_webroot 03:21:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:30 INFO - '--tppagecycles', 03:21:30 INFO - '7'], 03:21:30 INFO - 'tests': ['tsvgm']}}, 03:21:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['damp', 'tps']}, 03:21:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['damp', 'tps']}, 03:21:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['dromaeo_dom']}, 03:21:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['a11yr', 03:21:30 INFO - 'ts_paint', 03:21:30 INFO - 'tpaint', 03:21:30 INFO - 'sessionrestore', 03:21:30 INFO - 'sessionrestore_no_auto_restore', 03:21:30 INFO - 'tabpaint']}, 03:21:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tsvgx', 03:21:30 INFO - 'tsvgr_opacity', 03:21:30 INFO - 'tart', 03:21:30 INFO - 'tscrollx', 03:21:30 INFO - 'cart']}, 03:21:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:30 INFO - 'tsvgr_opacity', 03:21:30 INFO - 'tart', 03:21:30 INFO - 'tscrollx', 03:21:30 INFO - 'cart']}, 03:21:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s'], 03:21:30 INFO - 'tests': ['tp5o']}, 03:21:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'tests': ['tp5o']}, 03:21:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--disable-e10s', 03:21:30 INFO - '--xperf_path', 03:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:30 INFO - 'tests': ['tp5n']}, 03:21:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:30 INFO - 'talos_options': ['--xperf_path', 03:21:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:30 INFO - 'tests': ['tp5n']}}, 03:21:30 INFO - 'talos.zip': {'path': '', 03:21:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:30 INFO - Running post-action listener: _resource_record_post_action 03:21:30 INFO - ##### 03:21:30 INFO - ##### Running create-virtualenv step. 03:21:30 INFO - ##### 03:21:30 INFO - Running pre-action listener: _resource_record_pre_action 03:21:30 INFO - Running main action method: create_virtualenv 03:21:30 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:30 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:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:30 INFO - Using real prefix '/tools/python27' 03:21:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:31 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:34 INFO - Installing pip.................done. 03:21:34 INFO - Return code: 0 03:21:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:34 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:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:34 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:34 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:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:34 INFO - 'HOME': '/Users/cltbld', 03:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:34 INFO - 'LOGNAME': 'cltbld', 03:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:34 INFO - 'MOZ_NO_REMOTE': '1', 03:21:34 INFO - 'NO_EM_RESTART': '1', 03:21:34 INFO - 'PAGER': '/bin/cat', 03:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:34 INFO - 'PWD': '/builds/slave/test', 03:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:34 INFO - 'SHELL': '/bin/bash', 03:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:34 INFO - 'USER': 'cltbld', 03:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:34 INFO - 'XPC_FLAGS': '0x0', 03:21:34 INFO - 'XPC_SERVICE_NAME': '0', 03:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:34 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:34 INFO - Cleaning up... 03:21:34 INFO - Return code: 0 03:21:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:21:34 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:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:34 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:34 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:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:34 INFO - 'HOME': '/Users/cltbld', 03:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:34 INFO - 'LOGNAME': 'cltbld', 03:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:34 INFO - 'MOZ_NO_REMOTE': '1', 03:21:34 INFO - 'NO_EM_RESTART': '1', 03:21:34 INFO - 'PAGER': '/bin/cat', 03:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:34 INFO - 'PWD': '/builds/slave/test', 03:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:34 INFO - 'SHELL': '/bin/bash', 03:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:34 INFO - 'USER': 'cltbld', 03:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:34 INFO - 'XPC_FLAGS': '0x0', 03:21:34 INFO - 'XPC_SERVICE_NAME': '0', 03:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:34 INFO - Downloading/unpacking psutil>=0.7.1 03:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:37 INFO - Installing collected packages: psutil 03:21:37 INFO - Running setup.py install for psutil 03:21:37 INFO - building 'psutil._psutil_osx' extension 03:21:37 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:38 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:38 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:38 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:38 INFO - building 'psutil._psutil_posix' extension 03:21:38 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:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:38 INFO - ^ 03:21:38 INFO - 1 warning generated. 03:21:38 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:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:38 INFO - Successfully installed psutil 03:21:38 INFO - Cleaning up... 03:21:39 INFO - Return code: 0 03:21:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:39 INFO - 'HOME': '/Users/cltbld', 03:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:39 INFO - 'LOGNAME': 'cltbld', 03:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:39 INFO - 'MOZ_NO_REMOTE': '1', 03:21:39 INFO - 'NO_EM_RESTART': '1', 03:21:39 INFO - 'PAGER': '/bin/cat', 03:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:39 INFO - 'PWD': '/builds/slave/test', 03:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:39 INFO - 'SHELL': '/bin/bash', 03:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:39 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:39 INFO - 'USER': 'cltbld', 03:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:39 INFO - 'XPC_FLAGS': '0x0', 03:21:39 INFO - 'XPC_SERVICE_NAME': '0', 03:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:41 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:41 INFO - Installing collected packages: mozsystemmonitor 03:21:41 INFO - Running setup.py install for mozsystemmonitor 03:21:41 INFO - Successfully installed mozsystemmonitor 03:21:41 INFO - Cleaning up... 03:21:42 INFO - Return code: 0 03:21:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:42 INFO - 'HOME': '/Users/cltbld', 03:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:42 INFO - 'LOGNAME': 'cltbld', 03:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:42 INFO - 'MOZ_NO_REMOTE': '1', 03:21:42 INFO - 'NO_EM_RESTART': '1', 03:21:42 INFO - 'PAGER': '/bin/cat', 03:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:42 INFO - 'PWD': '/builds/slave/test', 03:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:42 INFO - 'SHELL': '/bin/bash', 03:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:42 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:42 INFO - 'USER': 'cltbld', 03:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:42 INFO - 'XPC_FLAGS': '0x0', 03:21:42 INFO - 'XPC_SERVICE_NAME': '0', 03:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:42 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:44 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:44 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:44 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:44 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:44 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:44 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:44 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:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:45 INFO - Downloading docopt-0.6.1.tar.gz 03:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:46 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:46 INFO - Running setup.py install for blobuploader 03:21:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:46 INFO - Running setup.py install for requests 03:21:46 INFO - Running setup.py install for docopt 03:21:46 INFO - Successfully installed blobuploader requests docopt 03:21:46 INFO - Cleaning up... 03:21:46 INFO - Return code: 0 03:21:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:46 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:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:46 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:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:46 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:46 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:21:46 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:21:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:46 INFO - 'HOME': '/Users/cltbld', 03:21:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:46 INFO - 'LOGNAME': 'cltbld', 03:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:46 INFO - 'MOZ_NO_REMOTE': '1', 03:21:46 INFO - 'NO_EM_RESTART': '1', 03:21:46 INFO - 'PAGER': '/bin/cat', 03:21:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:46 INFO - 'PWD': '/builds/slave/test', 03:21:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:46 INFO - 'SHELL': '/bin/bash', 03:21:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:46 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:46 INFO - 'USER': 'cltbld', 03:21:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:46 INFO - 'XPC_FLAGS': '0x0', 03:21:46 INFO - 'XPC_SERVICE_NAME': '0', 03:21:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-fK7JHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-5alBQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MCJL4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_4rtau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-E9ym6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-x1u1Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-oyUkHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kHzvXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:47 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZxDkPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cSRzwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4lFPGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-v9cfu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-yBms98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rEDFwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ndaJQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-9Fn4VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:48 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cjNnNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:48 INFO - Running setup.py install for manifestparser 03:21:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:49 INFO - Running setup.py install for mozcrash 03:21:49 INFO - Running setup.py install for mozdebug 03:21:49 INFO - Running setup.py install for mozdevice 03:21:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:49 INFO - Running setup.py install for mozfile 03:21:49 INFO - Running setup.py install for mozhttpd 03:21:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:49 INFO - Running setup.py install for mozinfo 03:21:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:49 INFO - Running setup.py install for mozInstall 03:21:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Running setup.py install for mozleak 03:21:50 INFO - Running setup.py install for mozlog 03:21:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Running setup.py install for moznetwork 03:21:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Running setup.py install for mozprocess 03:21:50 INFO - Running setup.py install for mozprofile 03:21:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:50 INFO - Running setup.py install for mozrunner 03:21:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:51 INFO - Running setup.py install for mozscreenshot 03:21:51 INFO - Running setup.py install for moztest 03:21:51 INFO - Running setup.py install for mozversion 03:21:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:51 INFO - Cleaning up... 03:21:51 INFO - Return code: 0 03:21:51 INFO - Installing None 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', '-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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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', '-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:21:51 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:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 03:21:51 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-lIJP8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:51 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:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-bVAD5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:51 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:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:51 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-6KwNQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4HOvti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MTMJhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GRs_Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-piQuit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-nvG_P_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-dWsmlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ItegDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CoHzet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:52 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:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:52 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-SFidZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QAeNLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:53 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:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-pr5bWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:53 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:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ysNrIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:53 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:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Tnb3Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:53 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:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rr0AJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:53 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:21:53 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:21:53 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:21:53 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:21:53 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:21:53 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:21:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:53 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:53 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 - 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:53 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:53 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:53 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 - Downloading blessings-1.6.tar.gz 03:21:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:56 INFO - Installing collected packages: blessings 03:21:56 INFO - Running setup.py install for blessings 03:21:56 INFO - Successfully installed blessings 03:21:56 INFO - Cleaning up... 03:21:56 INFO - Return code: 0 03:21:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:56 INFO - Reading from file tmpfile_stdout 03:21:56 INFO - Current package versions: 03:21:56 INFO - blessings == 1.6 03:21:56 INFO - blobuploader == 1.2.4 03:21:56 INFO - docopt == 0.6.1 03:21:56 INFO - manifestparser == 1.1 03:21:56 INFO - mozInstall == 1.12 03:21:56 INFO - mozcrash == 0.17 03:21:56 INFO - mozdebug == 0.1 03:21:56 INFO - mozdevice == 0.48 03:21:56 INFO - mozfile == 1.2 03:21:56 INFO - mozhttpd == 0.7 03:21:56 INFO - mozinfo == 0.9 03:21:56 INFO - mozleak == 0.1 03:21:56 INFO - mozlog == 3.1 03:21:56 INFO - moznetwork == 0.27 03:21:56 INFO - mozprocess == 0.22 03:21:56 INFO - mozprofile == 0.28 03:21:56 INFO - mozrunner == 6.11 03:21:56 INFO - mozscreenshot == 0.1 03:21:56 INFO - mozsystemmonitor == 0.0 03:21:56 INFO - moztest == 0.7 03:21:56 INFO - mozversion == 1.4 03:21:56 INFO - psutil == 3.1.1 03:21:56 INFO - requests == 1.2.3 03:21:56 INFO - wsgiref == 0.1.2 03:21:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:56 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:56 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:21:56 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:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:56 INFO - 'HOME': '/Users/cltbld', 03:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:56 INFO - 'LOGNAME': 'cltbld', 03:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:56 INFO - 'MOZ_NO_REMOTE': '1', 03:21:56 INFO - 'NO_EM_RESTART': '1', 03:21:56 INFO - 'PAGER': '/bin/cat', 03:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:56 INFO - 'PWD': '/builds/slave/test', 03:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:56 INFO - 'SHELL': '/bin/bash', 03:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:56 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:56 INFO - 'USER': 'cltbld', 03:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:56 INFO - 'XPC_FLAGS': '0x0', 03:21:56 INFO - 'XPC_SERVICE_NAME': '0', 03:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 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:21:56 INFO - Cleaning up... 03:21:56 INFO - Return code: 0 03:21:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:56 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:56 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:21:56 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:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:56 INFO - 'HOME': '/Users/cltbld', 03:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:56 INFO - 'LOGNAME': 'cltbld', 03:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:56 INFO - 'MOZ_NO_REMOTE': '1', 03:21:56 INFO - 'NO_EM_RESTART': '1', 03:21:56 INFO - 'PAGER': '/bin/cat', 03:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:56 INFO - 'PWD': '/builds/slave/test', 03:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:56 INFO - 'SHELL': '/bin/bash', 03:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:21:56 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:56 INFO - 'USER': 'cltbld', 03:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:56 INFO - 'XPC_FLAGS': '0x0', 03:21:56 INFO - 'XPC_SERVICE_NAME': '0', 03:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:57 INFO - Downloading/unpacking jsonschema 03:21:57 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:57 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 - 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:57 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:57 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:57 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:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:59 INFO - Downloading/unpacking functools32 (from jsonschema) 03:21:59 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:59 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:59 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:59 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:59 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:59 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:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:00 INFO - warning: no files found matching '*.txt' 03:22:00 INFO - no previously-included directories found matching 'build' 03:22:00 INFO - no previously-included directories found matching 'dist' 03:22:00 INFO - no previously-included directories found matching '.git*' 03:22:00 INFO - Installing collected packages: jsonschema, functools32 03:22:00 INFO - Running setup.py install for functools32 03:22:00 INFO - warning: no files found matching '*.txt' 03:22:00 INFO - no previously-included directories found matching 'build' 03:22:00 INFO - no previously-included directories found matching 'dist' 03:22:00 INFO - no previously-included directories found matching '.git*' 03:22:00 INFO - Successfully installed jsonschema functools32 03:22:00 INFO - Cleaning up... 03:22:00 INFO - Return code: 0 03:22:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:00 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 0x109257a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109267420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81404ab420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, '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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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:00 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:00 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:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:00 INFO - 'HOME': '/Users/cltbld', 03:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:00 INFO - 'LOGNAME': 'cltbld', 03:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:00 INFO - 'MOZ_NO_REMOTE': '1', 03:22:00 INFO - 'NO_EM_RESTART': '1', 03:22:00 INFO - 'PAGER': '/bin/cat', 03:22:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:00 INFO - 'PWD': '/builds/slave/test', 03:22:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:00 INFO - 'SHELL': '/bin/bash', 03:22:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:22:00 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:22:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:00 INFO - 'USER': 'cltbld', 03:22:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:00 INFO - 'XPC_FLAGS': '0x0', 03:22:00 INFO - 'XPC_SERVICE_NAME': '0', 03:22:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:00 INFO - Downloading/unpacking flake8 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:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:22:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:03 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:03 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:03 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:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:22:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:22:03 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:03 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:03 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:03 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:03 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:03 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:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:22:03 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:03 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:03 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:03 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:03 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:03 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:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:04 INFO - no previously-included directories found matching 'docs/_build' 03:22:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:22:04 INFO - Running setup.py install for pep8 03:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:04 INFO - no previously-included directories found matching 'docs/_build' 03:22:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:22:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:22:04 INFO - Cleaning up... 03:22:04 INFO - Return code: 0 03:22:04 INFO - Running post-action listener: _resource_record_post_action 03:22:04 INFO - Running post-action listener: _start_resource_monitoring 03:22:04 INFO - Starting resource monitoring. 03:22:04 INFO - ##### 03:22:04 INFO - ##### Running install step. 03:22:04 INFO - ##### 03:22:04 INFO - Running pre-action listener: _resource_record_pre_action 03:22:04 INFO - Running main action method: install 03:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:04 INFO - Reading from file tmpfile_stdout 03:22:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:05 INFO - Reading from file tmpfile_stdout 03:22:05 INFO - Output received: 03:22:05 INFO - Usage: mozinstall [options] installer 03:22:05 INFO - Options: 03:22:05 INFO - -h, --help show this help message and exit 03:22:05 INFO - -d DEST, --destination=DEST 03:22:05 INFO - Directory to install application into. [default: 03:22:05 INFO - "/builds/slave/test"] 03:22:05 INFO - --app=APP Application being installed. [default: firefox] 03:22:05 INFO - mkdir: /builds/slave/test/build/application 03:22:05 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:05 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:37 INFO - Reading from file tmpfile_stdout 03:22:37 INFO - Output received: 03:22:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:37 INFO - Running post-action listener: _resource_record_post_action 03:22:37 INFO - ##### 03:22:37 INFO - ##### Running run-tests step. 03:22:37 INFO - ##### 03:22:37 INFO - Running pre-action listener: _resource_record_pre_action 03:22:37 INFO - Running main action method: run_tests 03:22:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:37 INFO - 2016-05-06 03:22:37.083 screenresolution[1638:7557] starting screenresolution argv=screenresolution get 03:22:37 INFO - 2016-05-06 03:22:37.086 screenresolution[1638:7557] Display 0: 1600x1200x32@60 03:22:37 INFO - 2016-05-06 03:22:37.091 screenresolution[1639:7560] starting screenresolution argv=screenresolution list 03:22:37 INFO - Available Modes on Display 0 03:22:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:37 INFO - Intel Iris: 03:22:37 INFO - Chipset Model: Intel Iris 03:22:37 INFO - Type: GPU 03:22:37 INFO - Bus: Built-In 03:22:37 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:37 INFO - Vendor: Intel (0x8086) 03:22:37 INFO - Device ID: 0x0a2e 03:22:37 INFO - Revision ID: 0x0009 03:22:37 INFO - Displays: 03:22:37 INFO - Display: 03:22:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:37 INFO - Display Serial Number: 1600x1200 60 03:22:37 INFO - Main Display: Yes 03:22:37 INFO - Mirror: Off 03:22:37 INFO - Online: Yes 03:22:37 INFO - Rotation: Supported 03:22:37 INFO - Return code: 0 03:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:37 INFO - Python 2.7.3 03:22:37 INFO - Return code: 0 03:22:37 INFO - grabbing minidump binary from tooltool 03:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:37 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 0x1090f6e00>, '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 0x108f5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:37 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:37 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:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:37 INFO - Return code: 0 03:22:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:22:38 INFO - Return code: 0 03:22:38 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-0132', '--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:38 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-0132 --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:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KVWSx4uHfN/Render', 03:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:38 INFO - 'HOME': '/Users/cltbld', 03:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:38 INFO - 'LOGNAME': 'cltbld', 03:22:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:38 INFO - 'MOZ_NO_REMOTE': '1', 03:22:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:38 INFO - 'NO_EM_RESTART': '1', 03:22:38 INFO - 'PAGER': '/bin/cat', 03:22:38 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:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:38 INFO - 'PWD': '/builds/slave/test', 03:22:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:38 INFO - 'SHELL': '/bin/bash', 03:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Am8qOhUYY/Listeners', 03:22:38 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 03:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:38 INFO - 'USER': 'cltbld', 03:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:38 INFO - 'XPC_FLAGS': '0x0', 03:22:38 INFO - 'XPC_SERVICE_NAME': '0', 03:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:38 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-0132', '--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:38 INFO - mozversion application_buildid: 20160505123841 03:22:38 INFO - mozversion application_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:22:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:38 INFO - mozversion application_name: Firefox 03:22:38 INFO - mozversion application_remotingname: firefox-dev 03:22:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:38 INFO - mozversion application_vendor: Mozilla 03:22:38 INFO - mozversion application_version: 48.0a2 03:22:38 INFO - mozversion platform_buildid: 20160505123841 03:22:38 INFO - mozversion platform_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:22:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:38 INFO - mozversion platform_version: 48.0a2 03:22:38 INFO - using testdate: 1462530158 03:22:38 INFO - actual date: 1462530158 03:22:38 INFO - starting webserver on 'localhost:49275' 03:22:38 INFO - SUITE-START | Running 2 tests 03:22:38 INFO - TEST-START | dromaeo_css 03:22:38 INFO - operating with platform_type : mac_ 03:22:38 INFO - Initialising browser for dromaeo_css test... 03:22:38 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpwQ_3KB/profile http://localhost:49275/getInfo.html) 03:22:41 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 03:22:41 INFO - PROCESS | 1657 | colorDepth:24 03:22:41 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 03:22:41 INFO - PROCESS | 1657 | __metrics 03:22:41 INFO - PROCESS | 1657 | 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:41 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:22:41 INFO - TEST-INFO | 1657: exit 0 03:22:41 INFO - Browser initialized. 03:22:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:41 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpwQ_3KB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:53 INFO - PROCESS | 1659 | RSS: Main: 211263488 03:22:53 INFO - PROCESS | 1659 | 03:23:45 INFO - PROCESS | 1659 | 1462530225262 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:25:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:25:57 INFO - PROCESS | 1659 | RSS: Main: 291307520 03:25:57 INFO - PROCESS | 1659 | 03:29:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:29:00 INFO - PROCESS | 1659 | RSS: Main: 351932416 03:29:00 INFO - PROCESS | 1659 | 03:32:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:32:03 INFO - PROCESS | 1659 | RSS: Main: 355700736 03:32:03 INFO - PROCESS | 1659 | 03:35:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:35:06 INFO - PROCESS | 1659 | RSS: Main: 354881536 03:35:06 INFO - PROCESS | 1659 | 03:38:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:38:10 INFO - PROCESS | 1659 | RSS: Main: 326045696 03:38:10 INFO - PROCESS | 1659 | 03:41:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:41:13 INFO - PROCESS | 1659 | RSS: Main: 363040768 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | __start_tp_report 03:41:13 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 03:41:13 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 03:41:13 INFO - PROCESS | 1659 | |i|pagename|runs| 03:41:13 INFO - PROCESS | 1659 | |0;dojo.html;2923.63454568179;3170.6036745406823;3455.032590874553;3458.602260740014;3496.265784185321;5671.744771485278;5679.545636349092;5679.602427830054;5685.914711279334;5697.316322041361;5673.8297851064435;5715.3141623005195;5715.857103572413;5730.255066841305;5740.5981581289325;5068.391792984847;5080.41575218849;5170.146925757245;5177.223416487529;5228.73856307185;4289.528151903288;5210.973945130269;5212.817551385702;5215.426303106655;5217.765200565984;5175.2754614353835;5208.791648334062;5213.58291336694;5231.189165679307;5236.659617124875;5199.870003249911;5231.973840130813;5243.764030618631;5259.053370393328;5290.39160496544;5074.010567939254;5088.949110508885;5096.617753668453;5154.896902061975;5172.250023746573;5577.079781835992;5587.38538760736;5645.463680950304;5652.265205523256;5658.519025882805;6230.00319948806;6259.937400626027;6261.718222679945;6266.498680105627;6292.2134733158355;6102.389761023906;6212.782552610637;6297.118403423525;6307.0539419087045;6328.303886572473;5697.971510142505;5722.4277572242445;5723.456271654186;5736.885262294814;5742.339630942453;5248.422673505911;6292.685365731787;6312.084747711556;6330.620162790247;6334.968325158345;4411.911761764747;6004.219451471281;6086.056661217518;6106.419890110521;6169.444749972524;6241.812745617639;6242.438180563771;6253.187085678833;6269.028300613412;6279.277883043417;6179.907301390483;6181.258249010058;6189.597676151036;6194.38056194386;6205.162303089025;5238.266642670029;5706.087026075808;6117.143599896018;6125.754381768298;6161.199044124235;4872.19608764557;4887.731174785421;4892.3884514435695;4928.605711543068;4947.579455746302;4404.4494438195225;4407.911841763147;4414.690971631955;4419.18245124653;4456.46522417312;5684.459976302244;5709.286339207599;5713.971430142906;5715;5722.713864306768;6118.862843290023;6127.433875357673;6195.380461953859;6208.50331973441;6210.782622608187;5721.313442386941;5728.05487094633;5728.198052272685;5734.4552267535355;5743.655380677173;5303.840884773463;5354.384245811703;5408.8377348679605;5412.485813847757;5422.15956526739;6218.9378106218355;6221.502279817604;6240.843978900564;6269.090981807703;6281.466075383643;6195.380461953949;6197.783077592443;6203.751849925952;6247.875042499034;6254.312025677263;6309.3690630936535;6316.305206427198;6333.20834895638;6340.207474065742;6343.492520598249;6137.3862613738265;6166.260048794174;6170.413810687977;6178.9382106180165;6194.81415557534;6007.729652165751;6143.232095988002;6197.225346831646;6235.3452887446165;6282;6078.756849726138;6079.513638908965;6115.023766269247;6123.4351415035735;6134.252905685562;6077.908831367445;6091.42131497507;6092.664903430354;6126.938730612636;6127.050307202391;5028.74856257193;5827.388124246893;5919.792807251725;6005.90991135142;6077.604955677867;5967.403259673998;5991.071383935497;6002.879942401215;6006.819795406145;6039.577229594062;5891.3224979127945;5939.792107276225;6000.759969601167;6018.849528761641;6045.365236650265 03:41:13 INFO - PROCESS | 1659 | |1;ext.html;511.5370589616396;524.3995625009367;528.5877015927578;590.9746589666928;599.0774207720111;31545;32280.031599052036;38853.99999999996;39468.60531394685;39522.8023859881;42869.14261714764;43341.133177336436;43481.91295217621;43644.345334820006;43689.50483961283;8646.783830404227;9503.097205765476;9721.854172187423;9735.075167859042;9751.707448776513;11398.71503212418;11703.94148029263;11705.59030433935;11717.589884354049;11722.589709360174;11615.709607259801;11817.35004574748;11822.704432389173;11830.112741544375;11865.347405892673;8537;8726.432781869158;8750.693725719633;11614.303141811477;11781.234219775688;8719.04090550039;11389.54441822326;11619.128565357632;11638.476268567934;11648.94175529117;39587.40618890719;40172.598274017175;40311;40335.7983210085;40356.59643403558;3284.1625385526536;3365.478350855621;3465.9653403466145;3562.7149828013958;3629.237860049393;27351.316217094733;27432.7655255512;28055.212546306193;28111.437771244666;28367.57448638272;2678.651775269222;2716.8641567921723;2769.3076730817297;2811.325281932342;2829.8443585602786;447856;448903;449250;450437;450646;61278.38721612816;61619;61780;61799;61804.07293890595;65153.67423162854;65477.67261163663;65585.68828623401;65595.34404655892;65610.67194663997;17155.313787448613;17467.82532174662;17469.301227950993;17471.91264043697;17473;12072.275663460221;12294.262344259372;12780.233186008683;12965.092443528862;12992.220466771834;13170.407331670098;16592.08743519094;16623.916880415763;16797;17045.892017018854;7230.686180422214;7330.047093877762;7332.120145582458;7371.705131794668;7397.889031664529;920.3235621817959;999.7550600103021;1011.1253765492943;1037.5849660136007;1114.9386783726973;25520.872395637904;29666;30205.546916796266;30683.232919176753;31310;825.6986200037063;1053.1837825685002;1064.43052966664;1068.257560995101;1121.6130434999911;11765.823512647317;12418.379081045912;14659.633509162142;14952.70094598102;15040;530003;534905;536762;538108.7134771927;540918;54829;57869;58022;58066;58070.4192958065;1386.1821525300122;1415.8301003879394;1503.3911265937313;1604.9598760030858;1629.3319738906991;24531.754682452945;29027;29240.70759292465;29670.406591868472;29768;48045.5195448055;48431;48599.75700121477;48689.26966095441;48726.512734872194;366827.33172667935;368996;369682;369914;452249;25506.85219165105;26078.86960565185;26228.8688556556;26264.737352626995;26353.9458421661;41800;43189.78405107954;44385.33421998737;44656;44953.77523112494;28366.574501382907;31014.914477993163;36456.453153202194;36540.35568399375;36923.815380922926;32657.673423265463;36030.63969360378;36161.1919404034;36653.63346366502;36798.44802327914;36158.276834463686;36684;36878.63121368859;37059.07352316106;37172.81413592915;33122.66877331292;33180;33347.666523334454;33606.831965840014;33847.49228761521;20646.690299645215;24642.137525186532;24793.528905953543;25197.496050078527;25377.74622253754 03:41:13 INFO - PROCESS | 1659 | |2;jquery.html;399.1279055264248;401.161572313864;402.07121549221284;403.7759043730728;413.37993010484274;19894.40316790495;20020.499487512818;20785.272515461962;20865.374038778824;20912.581748365024;19771;20936.16255349784;21289.68065479015;21509.46226344342;21522.354329370104;5944.791932282382;6293.46505547029;6302.653354065547;6314.179156709644;6320.577869979241;5147.536721695046;5934.525237980973;6254.8123556293385;6269.968650156743;6271.498280137601;6212.875742485147;6246.968765156168;6247.843803904893;6251.218597675291;6258.123862659231;6176.567640265183;6243.56295059346;6803.693833777467;6814.829629259259;6818.28408017156;5773.566982476289;6002.609830361012;6026.126211699323;6045.818625441199;6059.18201042858;25650.973961041534;26357.94568217269;26579.468410631678;26665.600015999775;26955.46089078227;2211.491356987886;2223.9221627243132;2230.431240033781;2244.472548950995;2245.5396643688005;10121.746956326077;10309.896901030968;10336;12578.371081445892;13975.691843684805;1692.779938607973;1718.9656206875793;1724.611962308483;1732.376344515986;1753.6229710612151;125052;133403.33298333542;135586;137937;140250;11540;11774.88225117755;11825.408729563487;11842.82235766447;11919.88080119205;15468.84531154674;15552;15554.533363999099;15580.766288505682;15584.532464026097;8958.05940376251;8969.14793094655;8970.641174353083;8971.775705607412;9073.45559266446;8941.150590693873;8944.597493112826;8945.776355591162;9022.548872556346;9051.773705657279;4603.332516785114;4659.860204193879;4663.440387153495;4679.719216847;4721.6458765592715;1709.7347962507654;2001.7197592337084;2002.329219711413;2008.236869989424;2012.8892910890042;874.2874557235832;900.7297810656777;924.5007695844168;929.3076657889916;1013.8695354679627;20797;21405.464863378227;21413.46633416459;21483.355499335044;21816.563668726536;952.6998995316453;973.9026097390205;981.4209616326401;987.6345752071779;994.5773046455228;10819.459027048617;11292.54829806815;11294.774104517863;11312.94343528277;11321.150913681508;615863;633418;638524;654062;661102;124655.3767231158;136976;147422.52577474088;149228.25385873002;153352;857.652650676477;1031.7884833609091;1046.6232156423682;1052.578968412641;1054.9525271362654;18130;18660.813391865908;18933;18976.905115474336;19154.23383064662;44879;58216.44231198521;58444.70777646255;58960.410395895495;59276;81070.59464702639;81162.78255826286;81209;83400;83757.26236348588;7639.732609358646;8213.507189568645;8277.420580559303;8554.615042323236;8606.82786344282;10337.276390652582;12581.874181258436;12604.49582016709;12934.41795119203;13024.088313817942;10781.191410644076;11223.0460410866;11347.886521134684;11406.600768973047;11458.19792614509;10886.23796334249;10956.068734157687;10975.725606859893;11244.493997770283;11367.602133925273;11281.943590281997;11398.259113157617;11744.647660570197;11754;11763.588274410355;8308.626111825104;8314.459560128573;8336;8471.364647651573;8631.749330562357;10671.626493073016;11312.15158863072;11328.207025508465;11550.191486595857;11575.88424115748 03:41:13 INFO - PROCESS | 1659 | |3;mootools.html;1423.0536693099089;1592.721273777089;1615.9676806463872;1833.3858157517232;1961.0782932021946;7833.6083195840265;8668.306535477162;8801.95599022004;8805.91194088059;8806.955965220164;8417.116202798694;8656.697015604455;8664.696735614254;8674.696385626505;8678.826423471526;2031.7358743363343;2431.6960379952507;2446.498467814094;2450.375154335642;2454.447749256421;2406.133791834947;2440.414300567869;2443.926682199537;2447.9755202448014;2449.8162637802147;2401.939951501209;2428.9676887322817;2429.6962879640046;2435.7198922124007;2438.9512209755794;2353.9646905296436;2384.1059602649007;2392.8803559822027;2393.8563686178886;2397.4725560376687;2317.5944209763393;2397.5924092904074;2401.639754036891;2403.567357875582;2404.7835694787377;7832.56920869352;8048.919510804875;8661.480311181349;8830.779230519223;8843.955780221058;4204.600562946515;5411.945880541223;5419;5420.159875219348;5427.99582077317;3389.5932488101585;3509.929801403983;3559.3771090059136;3570.767900086486;3577.2130131371055;1031.5925209542263;1051.3061379489577;1056.646023582101;1060.3849767135043;1080.7892460970113;11483.023942964857;11618.012468940151;11648.883511164864;11830.526778928832;11846.526138954518;4716.174669432836;4898;4972.627052971042;4975.502449755068;5020.422651395136;10228.53971571281;10258.743531411625;10274.33216840903;10289.897101028893;10294.279400441897;5383.488568585978;5496.780128794803;5507;5523;5524.088525393346;4826.372571565674;5014.824481143142;5025.246213068069;5057.418396884332;5073.264376665436;3895.3377925752125;3908.863189788344;3994.940075898864;4013.678905687538;4015.217032678658;1110.3393480878865;1305.099481357879;1310.5216595942413;1313.678148853537;1326.157889740022;853.620139038122;887.658251573149;904.0372003815914;907.7458311672742;925.5233554719325;5756.884862302731;5826.650400975955;5977.342492325841;6009.579329446984;6154.7230374633245;941.4727752458645;975.7853272280087;986.5856340337076;990.7126933189438;1031.169908223887;3976.264391087658;4039.071013666873;4112.814923328458;4126.112885729583;4271.231178387858;10634.77700064487;11000.174986876016;11015.339079655247;11130.332180069221;11147.331160130418;10792.406417647106;10951.616693415692;11053.834192487118;11064.778704426028;11082.501287442085;8176.959115204386;8270.793230169174;8381.958090209631;8462.492250464991;8472.745817625604;6014.939850601438;6079.209702738793;6162;6201.658908760061;6213;9742.025797420201;10002.44986525748;10333.793324133625;10338.172946164139;10360.430176340373;10356.585736570754;10386.896131038895;10388.584456621651;10436.16510679159;10570.682879513626;7488.988986486755;7560.395168386626;7642;7702.884456733042;7703.768886933401;8358.955130608674;8382.790430239293;8449.493030418194;8475.618597163266;8512.70205542803;7482.289182527651;7598.886016709644;7634.923650763421;7697.807554811175;7785.961070194613;7485.513441626277;7508.0990281166605;7515.661795219337;7521.360684341892;7579.696812127453;6822.454203663794;6924.446044316342;6933.653317334012;6935.375816176568;6942.479314051366;4174.77038762871;4880.804767809248;4921.876953076202;4928.728919909296;5101.056304583664;4862.270659401118;4945.925811112908;4965.4289756678445;5040.395152581714;5211.322528071327 03:41:13 INFO - PROCESS | 1659 | |4;prototype.html;310.2461019722076;321.43106701138987;326.34730538922156;327.9016295111466;327.9967200327996;4470.30710239913;5335.626506144571;5348.491893270133;5404.891902161964;5420.647657902234;4022.6178513041214;5073.112205364065;5125.179971204608;5183.637145399824;5380.21986811912;3597.244578638476;4060.8375664973364;4100.405441211023;4124.6906482013965;4157.605027522381;3918.74528155669;4075.0831063010733;4154.106867023589;4156.272652285853;4158.708890377675;3432.399330117232;3850.2299540091954;3860.7104467165077;3894.53265608126;4134.834606615732;3542.4332106863035;4013.7190396671954;4035.233305671913;4084.550699423061;4097.303458412062;3099.2798996556903;3571.285742851414;3582.1223800168864;3599.3881040223223;3600.3339382214376;4060.4112403701592;5171.689698618095;5173.482651734834;5185.585153187736;5211.765470553834;3941.2264480983386;4714.717116972993;4739.028499157665;4747.335373047776;4771.188897887333;4183.644390226835;5242.97378513105;5256.94861027796;5631.915521267184;5776.938222741954;3596.7841929484316;3829.2915810574855;3962.0689138052535;4008.532619284327;4017.8392864285393;4777.856664300077;5160.574529970871;5394.811181608625;5475.260839786618;5967.731452084667;3897.649211570972;4006;4075.5357945745877;4087.141700242982;4095.262852686545;4108.014076621649;4114.897127571775;4115.8765237042335;4140.3375459926265;4159.875203743893;4237.745735255895;4368.533420922986;4595.690228284922;4742.146413645508;4750.767139444525;4916.8770780730765;5464.28964234647;5656.2929633795775;5676.347220069662;5687.971560142173;1423.7721964485634;1460.5034288342015;1509.3358922074253;1520.0575643101126;1522.7030729007959;942.1050002497655;964.5418426247477;1016.192127258831;1018.8777346718441;1025.333533203409;4648.790804413809;5126;5654.6889921054735;5661.037623603997;5673;6007.429294217042;6360.77737279193;6378.298387177407;6398.040293955851;6460.289368169498;4609.7695115244105;4867.780949857265;5663.007579656332;5679.914801277984;5703.657780533182;2804.9902035267287;2942.3379739558854;3285.3265080658407;3330.9514648491004;3344.2642618623863;6365.204349456318;6584.946408574605;6595.373439523238;6625.124302283122;6706.295838936939;5228.869278267997;5602.6918519481005;5876.265466816648;6076;6300.338464461257;6264.029075493215;6597.637129957802;6599.901001484982;6613.537052406382;6619.2056953164;5533.6956467393065;5752.626079304831;6122.418370254819;6280.371962803683;6329.873402531832;5817.5055120313555;5875.294964604275;6261.329870908101;6277.183966084563;6308.179936608211;5603.607747457639;6285.968570157303;6554.901676474952;6570.441512471493;6614.107095542233;4192.832286708497;4272.530021697548;4309.6552275818485;4328.264195086906;4333.978330108329;5735.741891614971;6040.818775436744;6072.696365181635;6300;6323.494120470259;5684.545236381162;6011.188489553854;6043.939560604338;6059.787907423219;6126.938730612636;5297.841064768063;6039.882621714997;6075.817725468243;6104.725287362168;6119.602225855305;5040.34475518195;5161.812783059992;5186;5264.2630031794815;5325.307709997831;5365.382980957083;5548.195511650869;5559.749811258584;5577.637453565505;5607.5513958882375;4639.628829693549;4674.836380726658;5454.618176727591;5512.586556008396;5561 03:41:13 INFO - PROCESS | 1659 | |5;yui.html;673.6362364323264;737.3400806278381;745.8396444764375;751.4214055177514;766.3447752171891;12655.114142010063;12942.741145177102;12968.481260749582;16433.09617971011;17111.91444042778;15773.60565985851;15848.524544263662;15951.52145435636;15956.521304360887;15960.962537435058;733.7798660401885;750.1335956969682;939.1266122506083;941.0213378086781;942.9009953954804;762.2415696382108;765.2194761343426;767.6046835879526;900.594732370436;954.6944977607169;989.1789814454022;993.3841018568497;995.4773743784535;995.9601615935354;1002.2232769603579;823.81875987283;983.9163671087966;988.2686811759319;988.3476905242542;989.6239429016999;568.729853319674;703.5286358140058;732.29333692986;736.9815754606124;741.4402126394591;15170.772438413433;15191.544253672408;15211.543653690407;15214.239288035664;15216.771748423782;12580.245185288912;12594;12834.101612887102;12835.93582032084;13044.347782610927;9896.653617123366;9931.702048938543;9986.251031172691;10289.897101028968;10710.839337409945;2992.880284788606;3497.283056973314;3516.9472457913153;3564.215872508044;3754.8498060077836;72566;72638;74061;75741;78706.60646696787;9155.725328240163;9662.613495460244;9898.208143348658;9988.450635215133;10545.367277963347;22086.0183875178;22100.88949555274;22109.557808843732;22126.668099978513;22240.777592224193;7621;7638.69445222196;7708.267714567107;7740.109887363025;7756.495827771289;4124.484439445069;4215.515215750167;4226.070264541796;4293.978530107392;4310.094880075157;3562.4325296869297;3588.9950813772184;3597.8560857565926;3629.4374371972385;3636.127329440968;564.8249042796746;619.294004834489;632.718440294074;652.8041587523724;665.4809248785955;492.16332235200304;521.8032084922443;556.2296219735695;590.365357240959;603.5895590998076;8040.236177563239;8099.959500202462;8119.512829230265;8227.259546640831;8244.093149753522;527.4040334422078;557.7072037180512;574.9794115445035;589.4959809363019;594.8275000249965;3789.8673546425744;3818.0073180973145;3849.3071247175217;3923.8234279457492;3956.8987756236534;93621;93861;95715.52142239244;96314;96323;77796;80689.59655201687;81142.59428702937;81259;81821;64693;66971.66514167495;66980;67160.66419667967;70555.29444705487;7931.762047138595;8018.3585313173635;8027.638756255866;8098;8149.470284431493;39594.62432254074;40822.9794255146;41612.58387416087;42558.57441425546;46422.53577464182;59480;61557.384426155164;63280.68359658172;64372.678136609014;64927.35072649213;11024.889751102708;11067.225294229649;11145.777084458428;11434.828477572677;12104.031677465957;11708.297502149899;12048.337341446304;12116.939415302868;12201.69495762613;12549.372531373578;11075.889241107809;11388.658340249805;11422.48598813072;11648.475818588353;11775.175737698279;11234.494447749707;11341.26281791681;11534.884651153381;11711.180217385043;11968;11087.33475991443;11106.055985241022;11220.831687524857;11351.602693905674;12233.449494772578;7978.481398709066;9316.702062955605;9529.285303602119;9765.259146568222;9924.801503969737;9170.862437063583;9403.811306477584;9436;9534.427934323963;9607 03:41:13 INFO - PROCESS | 1659 | __end_tp_report 03:41:13 INFO - PROCESS | 1659 | __start_cc_report 03:41:13 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,844 03:41:13 INFO - PROCESS | 1659 | __end_cc_report 03:41:13 INFO - PROCESS | 1659 | __startTimestamp1462531273143__endTimestamp 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | ------- Summary: start ------- 03:41:13 INFO - PROCESS | 1659 | Number of tests: 6 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5694.05 Median:5919.79 stddev:660.35 (11.2%) stddev-sans-first:625.59 03:41:13 INFO - PROCESS | 1659 | Values: 2923.6 3170.6 3455.0 3458.6 3496.3 5671.7 5679.5 5679.6 5685.9 5697.3 5673.8 5715.3 5715.9 5730.3 5740.6 5068.4 5080.4 5170.1 5177.2 5228.7 4289.5 5211.0 5212.8 5215.4 5217.8 5175.3 5208.8 5213.6 5231.2 5236.7 5199.9 5232.0 5243.8 5259.1 5290.4 5074.0 5088.9 5096.6 5154.9 5172.3 5577.1 5587.4 5645.5 5652.3 5658.5 6230.0 6259.9 6261.7 6266.5 6292.2 6102.4 6212.8 6297.1 6307.1 6328.3 5698.0 5722.4 5723.5 5736.9 5742.3 5248.4 6292.7 6312.1 6330.6 6335.0 4411.9 6004.2 6086.1 6106.4 6169.4 6241.8 6242.4 6253.2 6269.0 6279.3 6179.9 6181.3 6189.6 6194.4 6205.2 5238.3 5706.1 6117.1 6125.8 6161.2 4872.2 4887.7 4892.4 4928.6 4947.6 4404.4 4407.9 4414.7 4419.2 4456.5 5684.5 5709.3 5714.0 5715.0 5722.7 6118.9 6127.4 6195.4 6208.5 6210.8 5721.3 5728.1 5728.2 5734.5 5743.7 5303.8 5354.4 5408.8 5412.5 5422.2 6218.9 6221.5 6240.8 6269.1 6281.5 6195.4 6197.8 6203.8 6247.9 6254.3 6309.4 6316.3 6333.2 6340.2 6343.5 6137.4 6166.3 6170.4 6178.9 6194.8 6007.7 6143.2 6197.2 6235.3 6282.0 6078.8 6079.5 6115.0 6123.4 6134.3 6077.9 6091.4 6092.7 6126.9 6127.1 5028.7 5827.4 5919.8 6005.9 6077.6 5967.4 5991.1 6002.9 6006.8 6039.6 5891.3 5939.8 6000.8 6018.8 6045.4 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:60411.81 Median:3514.34 stddev:122645.05 (3489.8%) stddev-sans-first:122906.65 03:41:13 INFO - PROCESS | 1659 | Values: 511.5 524.4 528.6 591.0 599.1 31545.0 32280.0 38854.0 39468.6 39522.8 42869.1 43341.1 43481.9 43644.3 43689.5 8646.8 9503.1 9721.9 9735.1 9751.7 11398.7 11703.9 11705.6 11717.6 11722.6 11615.7 11817.4 11822.7 11830.1 11865.3 8537.0 8726.4 8750.7 11614.3 11781.2 8719.0 11389.5 11619.1 11638.5 11648.9 39587.4 40172.6 40311.0 40335.8 40356.6 3284.2 3365.5 3466.0 3562.7 3629.2 27351.3 27432.8 28055.2 28111.4 28367.6 2678.7 2716.9 2769.3 2811.3 2829.8 447856.0 448903.0 449250.0 450437.0 450646.0 61278.4 61619.0 61780.0 61799.0 61804.1 65153.7 65477.7 65585.7 65595.3 65610.7 17155.3 17467.8 17469.3 17471.9 17473.0 12072.3 12294.3 12780.2 12965.1 12992.2 13170.4 16592.1 16623.9 16797.0 17045.9 7230.7 7330.0 7332.1 7371.7 7397.9 920.3 999.8 1011.1 1037.6 1114.9 25520.9 29666.0 30205.5 30683.2 31310.0 825.7 1053.2 1064.4 1068.3 1121.6 11765.8 12418.4 14659.6 14952.7 15040.0 530003.0 534905.0 536762.0 538108.7 540918.0 54829.0 57869.0 58022.0 58066.0 58070.4 1386.2 1415.8 1503.4 1605.0 1629.3 24531.8 29027.0 29240.7 29670.4 29768.0 48045.5 48431.0 48599.8 48689.3 48726.5 366827.3 368996.0 369682.0 369914.0 452249.0 25506.9 26078.9 26228.9 26264.7 26353.9 41800.0 43189.8 44385.3 44656.0 44953.8 28366.6 31014.9 36456.5 36540.4 36923.8 32657.7 36030.6 36161.2 36653.6 36798.4 36158.3 36684.0 36878.6 37059.1 37172.8 33122.7 33180.0 33347.7 33606.8 33847.5 20646.7 24642.1 24793.5 25197.5 25377.7 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:37516.04 Median:2217.71 stddev:107345.38 (4840.4%) stddev-sans-first:107610.34 03:41:13 INFO - PROCESS | 1659 | Values: 399.1 401.2 402.1 403.8 413.4 19894.4 20020.5 20785.3 20865.4 20912.6 19771.0 20936.2 21289.7 21509.5 21522.4 5944.8 6293.5 6302.7 6314.2 6320.6 5147.5 5934.5 6254.8 6270.0 6271.5 6212.9 6247.0 6247.8 6251.2 6258.1 6176.6 6243.6 6803.7 6814.8 6818.3 5773.6 6002.6 6026.1 6045.8 6059.2 25651.0 26357.9 26579.5 26665.6 26955.5 2211.5 2223.9 2230.4 2244.5 2245.5 10121.7 10309.9 10336.0 12578.4 13975.7 1692.8 1719.0 1724.6 1732.4 1753.6 125052.0 133403.3 135586.0 137937.0 140250.0 11540.0 11774.9 11825.4 11842.8 11919.9 15468.8 15552.0 15554.5 15580.8 15584.5 8958.1 8969.1 8970.6 8971.8 9073.5 8941.2 8944.6 8945.8 9022.5 9051.8 4603.3 4659.9 4663.4 4679.7 4721.6 1709.7 2001.7 2002.3 2008.2 2012.9 874.3 900.7 924.5 929.3 1013.9 20797.0 21405.5 21413.5 21483.4 21816.6 952.7 973.9 981.4 987.6 994.6 10819.5 11292.5 11294.8 11312.9 11321.2 615863.0 633418.0 638524.0 654062.0 661102.0 124655.4 136976.0 147422.5 149228.3 153352.0 857.7 1031.8 1046.6 1052.6 1055.0 18130.0 18660.8 18933.0 18976.9 19154.2 44879.0 58216.4 58444.7 58960.4 59276.0 81070.6 81162.8 81209.0 83400.0 83757.3 7639.7 8213.5 8277.4 8554.6 8606.8 10337.3 12581.9 12604.5 12934.4 13024.1 10781.2 11223.0 11347.9 11406.6 11458.2 10886.2 10956.1 10975.7 11244.5 11367.6 11281.9 11398.3 11744.6 11754.0 11763.6 8308.6 8314.5 8336.0 8471.4 8631.7 10671.6 11312.2 11328.2 11550.2 11575.9 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5794.16 Median:4889.40 stddev:3244.81 (66.4%) stddev-sans-first:3237.29 03:41:13 INFO - PROCESS | 1659 | Values: 1423.1 1592.7 1616.0 1833.4 1961.1 7833.6 8668.3 8802.0 8805.9 8807.0 8417.1 8656.7 8664.7 8674.7 8678.8 2031.7 2431.7 2446.5 2450.4 2454.4 2406.1 2440.4 2443.9 2448.0 2449.8 2401.9 2429.0 2429.7 2435.7 2439.0 2354.0 2384.1 2392.9 2393.9 2397.5 2317.6 2397.6 2401.6 2403.6 2404.8 7832.6 8048.9 8661.5 8830.8 8844.0 4204.6 5411.9 5419.0 5420.2 5428.0 3389.6 3509.9 3559.4 3570.8 3577.2 1031.6 1051.3 1056.6 1060.4 1080.8 11483.0 11618.0 11648.9 11830.5 11846.5 4716.2 4898.0 4972.6 4975.5 5020.4 10228.5 10258.7 10274.3 10289.9 10294.3 5383.5 5496.8 5507.0 5523.0 5524.1 4826.4 5014.8 5025.2 5057.4 5073.3 3895.3 3908.9 3994.9 4013.7 4015.2 1110.3 1305.1 1310.5 1313.7 1326.2 853.6 887.7 904.0 907.7 925.5 5756.9 5826.7 5977.3 6009.6 6154.7 941.5 975.8 986.6 990.7 1031.2 3976.3 4039.1 4112.8 4126.1 4271.2 10634.8 11000.2 11015.3 11130.3 11147.3 10792.4 10951.6 11053.8 11064.8 11082.5 8177.0 8270.8 8382.0 8462.5 8472.7 6014.9 6079.2 6162.0 6201.7 6213.0 9742.0 10002.4 10333.8 10338.2 10360.4 10356.6 10386.9 10388.6 10436.2 10570.7 7489.0 7560.4 7642.0 7702.9 7703.8 8359.0 8382.8 8449.5 8475.6 8512.7 7482.3 7598.9 7634.9 7697.8 7786.0 7485.5 7508.1 7515.7 7521.4 7579.7 6822.5 6924.4 6933.7 6935.4 6942.5 4174.8 4880.8 4921.9 4928.7 5101.1 4862.3 4945.9 4965.4 5040.4 5211.3 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4727.65 Median:5172.59 stddev:1490.08 (28.8%) stddev-sans-first:1456.90 03:41:13 INFO - PROCESS | 1659 | Values: 310.2 321.4 326.3 327.9 328.0 4470.3 5335.6 5348.5 5404.9 5420.6 4022.6 5073.1 5125.2 5183.6 5380.2 3597.2 4060.8 4100.4 4124.7 4157.6 3918.7 4075.1 4154.1 4156.3 4158.7 3432.4 3850.2 3860.7 3894.5 4134.8 3542.4 4013.7 4035.2 4084.6 4097.3 3099.3 3571.3 3582.1 3599.4 3600.3 4060.4 5171.7 5173.5 5185.6 5211.8 3941.2 4714.7 4739.0 4747.3 4771.2 4183.6 5243.0 5256.9 5631.9 5776.9 3596.8 3829.3 3962.1 4008.5 4017.8 4777.9 5160.6 5394.8 5475.3 5967.7 3897.6 4006.0 4075.5 4087.1 4095.3 4108.0 4114.9 4115.9 4140.3 4159.9 4237.7 4368.5 4595.7 4742.1 4750.8 4916.9 5464.3 5656.3 5676.3 5688.0 1423.8 1460.5 1509.3 1520.1 1522.7 942.1 964.5 1016.2 1018.9 1025.3 4648.8 5126.0 5654.7 5661.0 5673.0 6007.4 6360.8 6378.3 6398.0 6460.3 4609.8 4867.8 5663.0 5679.9 5703.7 2805.0 2942.3 3285.3 3331.0 3344.3 6365.2 6584.9 6595.4 6625.1 6706.3 5228.9 5602.7 5876.3 6076.0 6300.3 6264.0 6597.6 6599.9 6613.5 6619.2 5533.7 5752.6 6122.4 6280.4 6329.9 5817.5 5875.3 6261.3 6277.2 6308.2 5603.6 6286.0 6554.9 6570.4 6614.1 4192.8 4272.5 4309.7 4328.3 4334.0 5735.7 6040.8 6072.7 6300.0 6323.5 5684.5 6011.2 6043.9 6059.8 6126.9 5297.8 6039.9 6075.8 6104.7 6119.6 5040.3 5161.8 5186.0 5264.3 5325.3 5365.4 5548.2 5559.7 5577.6 5607.6 4639.6 4674.8 5454.6 5512.6 5561.0 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17933.43 Median:30041.79 stddev:25050.25 (83.4%) stddev-sans-first:25086.99 03:41:13 INFO - PROCESS | 1659 | Values: 673.6 737.3 745.8 751.4 766.3 12655.1 12942.7 12968.5 16433.1 17111.9 15773.6 15848.5 15951.5 15956.5 15961.0 733.8 750.1 939.1 941.0 942.9 762.2 765.2 767.6 900.6 954.7 989.2 993.4 995.5 996.0 1002.2 823.8 983.9 988.3 988.3 989.6 568.7 703.5 732.3 737.0 741.4 15170.8 15191.5 15211.5 15214.2 15216.8 12580.2 12594.0 12834.1 12835.9 13044.3 9896.7 9931.7 9986.3 10289.9 10710.8 2992.9 3497.3 3516.9 3564.2 3754.8 72566.0 72638.0 74061.0 75741.0 78706.6 9155.7 9662.6 9898.2 9988.5 10545.4 22086.0 22100.9 22109.6 22126.7 22240.8 7621.0 7638.7 7708.3 7740.1 7756.5 4124.5 4215.5 4226.1 4294.0 4310.1 3562.4 3589.0 3597.9 3629.4 3636.1 564.8 619.3 632.7 652.8 665.5 492.2 521.8 556.2 590.4 603.6 8040.2 8100.0 8119.5 8227.3 8244.1 527.4 557.7 575.0 589.5 594.8 3789.9 3818.0 3849.3 3923.8 3956.9 93621.0 93861.0 95715.5 96314.0 96323.0 77796.0 80689.6 81142.6 81259.0 81821.0 64693.0 66971.7 66980.0 67160.7 70555.3 7931.8 8018.4 8027.6 8098.0 8149.5 39594.6 40823.0 41612.6 42558.6 46422.5 59480.0 61557.4 63280.7 64372.7 64927.4 11024.9 11067.2 11145.8 11434.8 12104.0 11708.3 12048.3 12116.9 12201.7 12549.4 11075.9 11388.7 11422.5 11648.5 11775.2 11234.5 11341.3 11534.9 11711.2 11968.0 11087.3 11106.1 11220.8 11351.6 12233.4 7978.5 9316.7 9529.3 9765.3 9924.8 9170.9 9403.8 9436.0 9534.4 9607.0 03:41:13 INFO - PROCESS | 1659 | -------- Summary: end -------- 03:41:13 INFO - PROCESS | 1659 | 03:41:13 INFO - TEST-INFO | 1659: exit 0 03:41:13 INFO - TEST-OK | dromaeo_css | took 1114995ms 03:41:13 INFO - TEST-START | kraken 03:41:13 INFO - operating with platform_type : mac_ 03:41:13 INFO - Initialising browser for kraken test... 03:41:13 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpaMZ7SV/profile http://localhost:49275/getInfo.html) 03:41:15 INFO - PROCESS | 1692 | __metrics Screen width/height:1600/1200 03:41:15 INFO - PROCESS | 1692 | colorDepth:24 03:41:15 INFO - PROCESS | 1692 | Browser inner width/height: 1024/689 03:41:15 INFO - PROCESS | 1692 | __metrics 03:41:16 INFO - PROCESS | 1692 | 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:16 ERROR - PROCESS | 1692 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:41:16 INFO - TEST-INFO | 1692: exit 0 03:41:16 INFO - Browser initialized. 03:41:16 INFO - Running cycle 1/1 for kraken test... 03:41:16 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpaMZ7SV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:28 INFO - PROCESS | 1694 | RSS: Main: 211136512 03:41:28 INFO - PROCESS | 1694 | 03:41:31 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:32 INFO - PROCESS | 1694 | RSS: Main: 269656064 03:41:32 INFO - PROCESS | 1694 | 03:41:34 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:41:35 INFO - PROCESS | 1694 | RSS: Main: 284143616 03:41:35 INFO - PROCESS | 1694 | 03:41:38 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:41:39 INFO - PROCESS | 1694 | RSS: Main: 371720192 03:41:39 INFO - PROCESS | 1694 | 03:41:41 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:41:42 INFO - PROCESS | 1694 | RSS: Main: 277688320 03:41:42 INFO - PROCESS | 1694 | 03:41:44 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:44 INFO - PROCESS | 1694 | RSS: Main: 279511040 03:41:44 INFO - PROCESS | 1694 | 03:41:47 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:48 INFO - PROCESS | 1694 | RSS: Main: 317120512 03:41:48 INFO - PROCESS | 1694 | 03:41:51 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:51 INFO - PROCESS | 1694 | RSS: Main: 317300736 03:41:51 INFO - PROCESS | 1694 | 03:41:54 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:41:55 INFO - PROCESS | 1694 | RSS: Main: 316575744 03:41:55 INFO - PROCESS | 1694 | 03:41:56 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:57 INFO - PROCESS | 1694 | RSS: Main: 307396608 03:41:57 INFO - PROCESS | 1694 | 03:41:59 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:00 INFO - PROCESS | 1694 | RSS: Main: 314818560 03:42:00 INFO - PROCESS | 1694 | 03:42:02 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:03 INFO - PROCESS | 1694 | RSS: Main: 316968960 03:42:03 INFO - PROCESS | 1694 | 03:42:05 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:06 INFO - PROCESS | 1694 | RSS: Main: 322809856 03:42:06 INFO - PROCESS | 1694 | 03:42:09 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:10 INFO - PROCESS | 1694 | RSS: Main: 320434176 03:42:10 INFO - PROCESS | 1694 | 03:42:11 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:42:12 INFO - PROCESS | 1694 | RSS: Main: 384241664 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | __start_tp_report 03:42:12 INFO - PROCESS | 1694 | _x_x_mozilla_page_load 03:42:12 INFO - PROCESS | 1694 | _x_x_mozilla_page_load_details 03:42:12 INFO - PROCESS | 1694 | |i|pagename|runs| 03:42:12 INFO - PROCESS | 1694 | |0;ai-astar;116;115;109;114;114;115;116;115;103;114 03:42:12 INFO - PROCESS | 1694 | |1;audio-beat-detection;163;148;167;158;119;142;120;151;160;148 03:42:12 INFO - PROCESS | 1694 | |2;audio-dft;185;182;186;183;186;187;184;185;184;185 03:42:12 INFO - PROCESS | 1694 | |3;audio-fft;72;77;74;151;72;79;88;89;159;66 03:42:12 INFO - PROCESS | 1694 | |4;audio-oscillator;91;90;142;78;84;95;125;79;75;131 03:42:12 INFO - PROCESS | 1694 | |5;imaging-gaussian-blur;88;93;134;86;87;84;88;86;91;127 03:42:12 INFO - PROCESS | 1694 | |6;imaging-darkroom;130;150;94;93;93;95;94;94;93;95 03:42:12 INFO - PROCESS | 1694 | |7;imaging-desaturate;86;86;85;85;83;84;83;84;84;85 03:42:12 INFO - PROCESS | 1694 | |8;json-parse-financial;59;64;66;63;63;63;65;84;61;57 03:42:12 INFO - PROCESS | 1694 | |9;json-stringify-tinderbox;64;63;71;76;123;108;104;56;68;96 03:42:12 INFO - PROCESS | 1694 | |10;stanford-crypto-aes;75;106;107;81;82;101;106;103;92;84 03:42:12 INFO - PROCESS | 1694 | |11;stanford-crypto-ccm;138;134;135;132;133;133;135;134;190;132 03:42:12 INFO - PROCESS | 1694 | |12;stanford-crypto-pbkdf2;170;169;167;168;171;169;165;217;173;166 03:42:12 INFO - PROCESS | 1694 | |13;stanford-crypto-sha256-iterative;68;68;71;71;67;67;67;69;67;66 03:42:12 INFO - PROCESS | 1694 | __end_tp_report 03:42:12 INFO - PROCESS | 1694 | __start_cc_report 03:42:12 INFO - PROCESS | 1694 | _x_x_mozilla_cycle_collect,2052 03:42:12 INFO - PROCESS | 1694 | __end_cc_report 03:42:12 INFO - PROCESS | 1694 | __startTimestamp1462531332080__endTimestamp 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | ------- Summary: start ------- 03:42:12 INFO - PROCESS | 1694 | Number of tests: 14 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#0] ai-astar Cycles:10 Average:113.10 Median:115.00 stddev:4.07 (3.5%) stddev-sans-first:4.18 03:42:12 INFO - PROCESS | 1694 | Values: 116.0 115.0 109.0 114.0 114.0 115.0 116.0 115.0 103.0 114.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:154.50 stddev:16.66 (10.8%) stddev-sans-first:16.71 03:42:12 INFO - PROCESS | 1694 | Values: 163.0 148.0 167.0 158.0 119.0 142.0 120.0 151.0 160.0 148.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 03:42:12 INFO - PROCESS | 1694 | Values: 185.0 182.0 186.0 183.0 186.0 187.0 184.0 185.0 184.0 185.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#3] audio-fft Cycles:10 Average:92.70 Median:75.50 stddev:33.64 (44.6%) stddev-sans-first:34.84 03:42:12 INFO - PROCESS | 1694 | Values: 72.0 77.0 74.0 151.0 72.0 79.0 88.0 89.0 159.0 66.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#4] audio-oscillator Cycles:10 Average:99.00 Median:81.50 stddev:24.39 (29.9%) stddev-sans-first:25.69 03:42:12 INFO - PROCESS | 1694 | Values: 91.0 90.0 142.0 78.0 84.0 95.0 125.0 79.0 75.0 131.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:87.50 stddev:18.23 (20.8%) stddev-sans-first:19.08 03:42:12 INFO - PROCESS | 1694 | Values: 88.0 93.0 134.0 86.0 87.0 84.0 88.0 86.0 91.0 127.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:94.00 stddev:20.02 (21.3%) stddev-sans-first:18.72 03:42:12 INFO - PROCESS | 1694 | Values: 130.0 150.0 94.0 93.0 93.0 95.0 94.0 94.0 93.0 95.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.08 (1.3%) stddev-sans-first:1.00 03:42:12 INFO - PROCESS | 1694 | Values: 86.0 86.0 85.0 85.0 83.0 84.0 83.0 84.0 84.0 85.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:63.50 stddev:7.37 (11.6%) stddev-sans-first:7.54 03:42:12 INFO - PROCESS | 1694 | Values: 59.0 64.0 66.0 63.0 63.0 63.0 65.0 84.0 61.0 57.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#9] json-stringify-tinderbox Cycles:10 Average:82.90 Median:66.00 stddev:22.96 (34.8%) stddev-sans-first:23.31 03:42:12 INFO - PROCESS | 1694 | Values: 64.0 63.0 71.0 76.0 123.0 108.0 104.0 56.0 68.0 96.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:78.00 stddev:12.31 (15.8%) stddev-sans-first:11.04 03:42:12 INFO - PROCESS | 1694 | Values: 75.0 106.0 107.0 81.0 82.0 101.0 106.0 103.0 92.0 84.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:134.50 stddev:17.80 (13.2%) stddev-sans-first:18.87 03:42:12 INFO - PROCESS | 1694 | Values: 138.0 134.0 135.0 132.0 133.0 133.0 135.0 134.0 190.0 132.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:169.50 stddev:15.47 (9.1%) stddev-sans-first:16.35 03:42:12 INFO - PROCESS | 1694 | Values: 170.0 169.0 167.0 168.0 171.0 169.0 165.0 217.0 173.0 166.0 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - PROCESS | 1694 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.73 (2.5%) stddev-sans-first:1.83 03:42:12 INFO - PROCESS | 1694 | Values: 68.0 68.0 71.0 71.0 67.0 67.0 67.0 69.0 67.0 66.0 03:42:12 INFO - PROCESS | 1694 | -------- Summary: end -------- 03:42:12 INFO - PROCESS | 1694 | 03:42:12 INFO - TEST-INFO | 1694: exit 0 03:42:13 INFO - TEST-OK | kraken | took 59288ms 03:42:13 INFO - SUITE-END | took 1174s 03:42:13 INFO - Completed test suite (00:19:34) 03:42:13 INFO - javascript benchmark 03:42:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2923.63454568179, 3170.6036745406823, 3455.032590874553, 3458.602260740014, 3496.265784185321, 5671.744771485278, 5679.545636349092, 5679.602427830054, 5685.914711279334, 5697.316322041361, 5673.8297851064435, 5715.3141623005195, 5715.857103572413, 5730.255066841305, 5740.5981581289325, 5068.391792984847, 5080.41575218849, 5170.146925757245, 5177.223416487529, 5228.73856307185, 4289.528151903288, 5210.973945130269, 5212.817551385702, 5215.426303106655, 5217.765200565984, 5175.2754614353835, 5208.791648334062, 5213.58291336694, 5231.189165679307, 5236.659617124875, 5199.870003249911, 5231.973840130813, 5243.764030618631, 5259.053370393328, 5290.39160496544, 5074.010567939254, 5088.949110508885, 5096.617753668453, 5154.896902061975, 5172.250023746573, 5577.079781835992, 5587.38538760736, 5645.463680950304, 5652.265205523256, 5658.519025882805, 6230.00319948806, 6259.937400626027, 6261.718222679945, 6266.498680105627, 6292.2134733158355, 6102.389761023906, 6212.782552610637, 6297.118403423525, 6307.0539419087045, 6328.303886572473, 5697.971510142505, 5722.4277572242445, 5723.456271654186, 5736.885262294814, 5742.339630942453, 5248.422673505911, 6292.685365731787, 6312.084747711556, 6330.620162790247, 6334.968325158345, 4411.911761764747, 6004.219451471281, 6086.056661217518, 6106.419890110521, 6169.444749972524, 6241.812745617639, 6242.438180563771, 6253.187085678833, 6269.028300613412, 6279.277883043417, 6179.907301390483, 6181.258249010058, 6189.597676151036, 6194.38056194386, 6205.162303089025, 5238.266642670029, 5706.087026075808, 6117.143599896018, 6125.754381768298, 6161.199044124235, 4872.19608764557, 4887.731174785421, 4892.3884514435695, 4928.605711543068, 4947.579455746302, 4404.4494438195225, 4407.911841763147, 4414.690971631955, 4419.18245124653, 4456.46522417312, 5684.459976302244, 5709.286339207599, 5713.971430142906, 5715.0, 5722.713864306768, 6118.862843290023, 6127.433875357673, 6195.380461953859, 6208.50331973441, 6210.782622608187, 5721.313442386941, 5728.05487094633, 5728.198052272685, 5734.4552267535355, 5743.655380677173, 5303.840884773463, 5354.384245811703, 5408.8377348679605, 5412.485813847757, 5422.15956526739, 6218.9378106218355, 6221.502279817604, 6240.843978900564, 6269.090981807703, 6281.466075383643, 6195.380461953949, 6197.783077592443, 6203.751849925952, 6247.875042499034, 6254.312025677263, 6309.3690630936535, 6316.305206427198, 6333.20834895638, 6340.207474065742, 6343.492520598249, 6137.3862613738265, 6166.260048794174, 6170.413810687977, 6178.9382106180165, 6194.81415557534, 6007.729652165751, 6143.232095988002, 6197.225346831646, 6235.3452887446165, 6282.0, 6078.756849726138, 6079.513638908965, 6115.023766269247, 6123.4351415035735, 6134.252905685562, 6077.908831367445, 6091.42131497507, 6092.664903430354, 6126.938730612636, 6127.050307202391, 5028.74856257193, 5827.388124246893, 5919.792807251725, 6005.90991135142, 6077.604955677867, 5967.403259673998, 5991.071383935497, 6002.879942401215, 6006.819795406145, 6039.577229594062, 5891.3224979127945, 5939.792107276225, 6000.759969601167, 6018.849528761641, 6045.365236650265], "value": 5652.135267958045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.5370589616396, 524.3995625009367, 528.5877015927578, 590.9746589666928, 599.0774207720111, 31545.0, 32280.031599052036, 38853.99999999996, 39468.60531394685, 39522.8023859881, 42869.14261714764, 43341.133177336436, 43481.91295217621, 43644.345334820006, 43689.50483961283, 8646.783830404227, 9503.097205765476, 9721.854172187423, 9735.075167859042, 9751.707448776513, 11398.71503212418, 11703.94148029263, 11705.59030433935, 11717.589884354049, 11722.589709360174, 11615.709607259801, 11817.35004574748, 11822.704432389173, 11830.112741544375, 11865.347405892673, 8537.0, 8726.432781869158, 8750.693725719633, 11614.303141811477, 11781.234219775688, 8719.04090550039, 11389.54441822326, 11619.128565357632, 11638.476268567934, 11648.94175529117, 39587.40618890719, 40172.598274017175, 40311.0, 40335.7983210085, 40356.59643403558, 3284.1625385526536, 3365.478350855621, 3465.9653403466145, 3562.7149828013958, 3629.237860049393, 27351.316217094733, 27432.7655255512, 28055.212546306193, 28111.437771244666, 28367.57448638272, 2678.651775269222, 2716.8641567921723, 2769.3076730817297, 2811.325281932342, 2829.8443585602786, 447856.0, 448903.0, 449250.0, 450437.0, 450646.0, 61278.38721612816, 61619.0, 61780.0, 61799.0, 61804.07293890595, 65153.67423162854, 65477.67261163663, 65585.68828623401, 65595.34404655892, 65610.67194663997, 17155.313787448613, 17467.82532174662, 17469.301227950993, 17471.91264043697, 17473.0, 12072.275663460221, 12294.262344259372, 12780.233186008683, 12965.092443528862, 12992.220466771834, 13170.407331670098, 16592.08743519094, 16623.916880415763, 16797.0, 17045.892017018854, 7230.686180422214, 7330.047093877762, 7332.120145582458, 7371.705131794668, 7397.889031664529, 920.3235621817959, 999.7550600103021, 1011.1253765492943, 1037.5849660136007, 1114.9386783726973, 25520.872395637904, 29666.0, 30205.546916796266, 30683.232919176753, 31310.0, 825.6986200037063, 1053.1837825685002, 1064.43052966664, 1068.257560995101, 1121.6130434999911, 11765.823512647317, 12418.379081045912, 14659.633509162142, 14952.70094598102, 15040.0, 530003.0, 534905.0, 536762.0, 538108.7134771927, 540918.0, 54829.0, 57869.0, 58022.0, 58066.0, 58070.4192958065, 1386.1821525300122, 1415.8301003879394, 1503.3911265937313, 1604.9598760030858, 1629.3319738906991, 24531.754682452945, 29027.0, 29240.70759292465, 29670.406591868472, 29768.0, 48045.5195448055, 48431.0, 48599.75700121477, 48689.26966095441, 48726.512734872194, 366827.33172667935, 368996.0, 369682.0, 369914.0, 452249.0, 25506.85219165105, 26078.86960565185, 26228.8688556556, 26264.737352626995, 26353.9458421661, 41800.0, 43189.78405107954, 44385.33421998737, 44656.0, 44953.77523112494, 28366.574501382907, 31014.914477993163, 36456.453153202194, 36540.35568399375, 36923.815380922926, 32657.673423265463, 36030.63969360378, 36161.1919404034, 36653.63346366502, 36798.44802327914, 36158.276834463686, 36684.0, 36878.63121368859, 37059.07352316106, 37172.81413592915, 33122.66877331292, 33180.0, 33347.666523334454, 33606.831965840014, 33847.49228761521, 20646.690299645215, 24642.137525186532, 24793.528905953543, 25197.496050078527, 25377.74622253754], "value": 19477.387969238916, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.1279055264248, 401.161572313864, 402.07121549221284, 403.7759043730728, 413.37993010484274, 19894.40316790495, 20020.499487512818, 20785.272515461962, 20865.374038778824, 20912.581748365024, 19771.0, 20936.16255349784, 21289.68065479015, 21509.46226344342, 21522.354329370104, 5944.791932282382, 6293.46505547029, 6302.653354065547, 6314.179156709644, 6320.577869979241, 5147.536721695046, 5934.525237980973, 6254.8123556293385, 6269.968650156743, 6271.498280137601, 6212.875742485147, 6246.968765156168, 6247.843803904893, 6251.218597675291, 6258.123862659231, 6176.567640265183, 6243.56295059346, 6803.693833777467, 6814.829629259259, 6818.28408017156, 5773.566982476289, 6002.609830361012, 6026.126211699323, 6045.818625441199, 6059.18201042858, 25650.973961041534, 26357.94568217269, 26579.468410631678, 26665.600015999775, 26955.46089078227, 2211.491356987886, 2223.9221627243132, 2230.431240033781, 2244.472548950995, 2245.5396643688005, 10121.746956326077, 10309.896901030968, 10336.0, 12578.371081445892, 13975.691843684805, 1692.779938607973, 1718.9656206875793, 1724.611962308483, 1732.376344515986, 1753.6229710612151, 125052.0, 133403.33298333542, 135586.0, 137937.0, 140250.0, 11540.0, 11774.88225117755, 11825.408729563487, 11842.82235766447, 11919.88080119205, 15468.84531154674, 15552.0, 15554.533363999099, 15580.766288505682, 15584.532464026097, 8958.05940376251, 8969.14793094655, 8970.641174353083, 8971.775705607412, 9073.45559266446, 8941.150590693873, 8944.597493112826, 8945.776355591162, 9022.548872556346, 9051.773705657279, 4603.332516785114, 4659.860204193879, 4663.440387153495, 4679.719216847, 4721.6458765592715, 1709.7347962507654, 2001.7197592337084, 2002.329219711413, 2008.236869989424, 2012.8892910890042, 874.2874557235832, 900.7297810656777, 924.5007695844168, 929.3076657889916, 1013.8695354679627, 20797.0, 21405.464863378227, 21413.46633416459, 21483.355499335044, 21816.563668726536, 952.6998995316453, 973.9026097390205, 981.4209616326401, 987.6345752071779, 994.5773046455228, 10819.459027048617, 11292.54829806815, 11294.774104517863, 11312.94343528277, 11321.150913681508, 615863.0, 633418.0, 638524.0, 654062.0, 661102.0, 124655.3767231158, 136976.0, 147422.52577474088, 149228.25385873002, 153352.0, 857.652650676477, 1031.7884833609091, 1046.6232156423682, 1052.578968412641, 1054.9525271362654, 18130.0, 18660.813391865908, 18933.0, 18976.905115474336, 19154.23383064662, 44879.0, 58216.44231198521, 58444.70777646255, 58960.410395895495, 59276.0, 81070.59464702639, 81162.78255826286, 81209.0, 83400.0, 83757.26236348588, 7639.732609358646, 8213.507189568645, 8277.420580559303, 8554.615042323236, 8606.82786344282, 10337.276390652582, 12581.874181258436, 12604.49582016709, 12934.41795119203, 13024.088313817942, 10781.191410644076, 11223.0460410866, 11347.886521134684, 11406.600768973047, 11458.19792614509, 10886.23796334249, 10956.068734157687, 10975.725606859893, 11244.493997770283, 11367.602133925273, 11281.943590281997, 11398.259113157617, 11744.647660570197, 11754.0, 11763.588274410355, 8308.626111825104, 8314.459560128573, 8336.0, 8471.364647651573, 8631.749330562357, 10671.626493073016, 11312.15158863072, 11328.207025508465, 11550.191486595857, 11575.88424115748], "value": 9941.71390757878, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.0536693099089, 1592.721273777089, 1615.9676806463872, 1833.3858157517232, 1961.0782932021946, 7833.6083195840265, 8668.306535477162, 8801.95599022004, 8805.91194088059, 8806.955965220164, 8417.116202798694, 8656.697015604455, 8664.696735614254, 8674.696385626505, 8678.826423471526, 2031.7358743363343, 2431.6960379952507, 2446.498467814094, 2450.375154335642, 2454.447749256421, 2406.133791834947, 2440.414300567869, 2443.926682199537, 2447.9755202448014, 2449.8162637802147, 2401.939951501209, 2428.9676887322817, 2429.6962879640046, 2435.7198922124007, 2438.9512209755794, 2353.9646905296436, 2384.1059602649007, 2392.8803559822027, 2393.8563686178886, 2397.4725560376687, 2317.5944209763393, 2397.5924092904074, 2401.639754036891, 2403.567357875582, 2404.7835694787377, 7832.56920869352, 8048.919510804875, 8661.480311181349, 8830.779230519223, 8843.955780221058, 4204.600562946515, 5411.945880541223, 5419.0, 5420.159875219348, 5427.99582077317, 3389.5932488101585, 3509.929801403983, 3559.3771090059136, 3570.767900086486, 3577.2130131371055, 1031.5925209542263, 1051.3061379489577, 1056.646023582101, 1060.3849767135043, 1080.7892460970113, 11483.023942964857, 11618.012468940151, 11648.883511164864, 11830.526778928832, 11846.526138954518, 4716.174669432836, 4898.0, 4972.627052971042, 4975.502449755068, 5020.422651395136, 10228.53971571281, 10258.743531411625, 10274.33216840903, 10289.897101028893, 10294.279400441897, 5383.488568585978, 5496.780128794803, 5507.0, 5523.0, 5524.088525393346, 4826.372571565674, 5014.824481143142, 5025.246213068069, 5057.418396884332, 5073.264376665436, 3895.3377925752125, 3908.863189788344, 3994.940075898864, 4013.678905687538, 4015.217032678658, 1110.3393480878865, 1305.099481357879, 1310.5216595942413, 1313.678148853537, 1326.157889740022, 853.620139038122, 887.658251573149, 904.0372003815914, 907.7458311672742, 925.5233554719325, 5756.884862302731, 5826.650400975955, 5977.342492325841, 6009.579329446984, 6154.7230374633245, 941.4727752458645, 975.7853272280087, 986.5856340337076, 990.7126933189438, 1031.169908223887, 3976.264391087658, 4039.071013666873, 4112.814923328458, 4126.112885729583, 4271.231178387858, 10634.77700064487, 11000.174986876016, 11015.339079655247, 11130.332180069221, 11147.331160130418, 10792.406417647106, 10951.616693415692, 11053.834192487118, 11064.778704426028, 11082.501287442085, 8176.959115204386, 8270.793230169174, 8381.958090209631, 8462.492250464991, 8472.745817625604, 6014.939850601438, 6079.209702738793, 6162.0, 6201.658908760061, 6213.0, 9742.025797420201, 10002.44986525748, 10333.793324133625, 10338.172946164139, 10360.430176340373, 10356.585736570754, 10386.896131038895, 10388.584456621651, 10436.16510679159, 10570.682879513626, 7488.988986486755, 7560.395168386626, 7642.0, 7702.884456733042, 7703.768886933401, 8358.955130608674, 8382.790430239293, 8449.493030418194, 8475.618597163266, 8512.70205542803, 7482.289182527651, 7598.886016709644, 7634.923650763421, 7697.807554811175, 7785.961070194613, 7485.513441626277, 7508.0990281166605, 7515.661795219337, 7521.360684341892, 7579.696812127453, 6822.454203663794, 6924.446044316342, 6933.653317334012, 6935.375816176568, 6942.479314051366, 4174.77038762871, 4880.804767809248, 4921.876953076202, 4928.728919909296, 5101.056304583664, 4862.270659401118, 4945.925811112908, 4965.4289756678445, 5040.395152581714, 5211.322528071327], "value": 4658.815979656557, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.2461019722076, 321.43106701138987, 326.34730538922156, 327.9016295111466, 327.9967200327996, 4470.30710239913, 5335.626506144571, 5348.491893270133, 5404.891902161964, 5420.647657902234, 4022.6178513041214, 5073.112205364065, 5125.179971204608, 5183.637145399824, 5380.21986811912, 3597.244578638476, 4060.8375664973364, 4100.405441211023, 4124.6906482013965, 4157.605027522381, 3918.74528155669, 4075.0831063010733, 4154.106867023589, 4156.272652285853, 4158.708890377675, 3432.399330117232, 3850.2299540091954, 3860.7104467165077, 3894.53265608126, 4134.834606615732, 3542.4332106863035, 4013.7190396671954, 4035.233305671913, 4084.550699423061, 4097.303458412062, 3099.2798996556903, 3571.285742851414, 3582.1223800168864, 3599.3881040223223, 3600.3339382214376, 4060.4112403701592, 5171.689698618095, 5173.482651734834, 5185.585153187736, 5211.765470553834, 3941.2264480983386, 4714.717116972993, 4739.028499157665, 4747.335373047776, 4771.188897887333, 4183.644390226835, 5242.97378513105, 5256.94861027796, 5631.915521267184, 5776.938222741954, 3596.7841929484316, 3829.2915810574855, 3962.0689138052535, 4008.532619284327, 4017.8392864285393, 4777.856664300077, 5160.574529970871, 5394.811181608625, 5475.260839786618, 5967.731452084667, 3897.649211570972, 4006.0, 4075.5357945745877, 4087.141700242982, 4095.262852686545, 4108.014076621649, 4114.897127571775, 4115.8765237042335, 4140.3375459926265, 4159.875203743893, 4237.745735255895, 4368.533420922986, 4595.690228284922, 4742.146413645508, 4750.767139444525, 4916.8770780730765, 5464.28964234647, 5656.2929633795775, 5676.347220069662, 5687.971560142173, 1423.7721964485634, 1460.5034288342015, 1509.3358922074253, 1520.0575643101126, 1522.7030729007959, 942.1050002497655, 964.5418426247477, 1016.192127258831, 1018.8777346718441, 1025.333533203409, 4648.790804413809, 5126.0, 5654.6889921054735, 5661.037623603997, 5673.0, 6007.429294217042, 6360.77737279193, 6378.298387177407, 6398.040293955851, 6460.289368169498, 4609.7695115244105, 4867.780949857265, 5663.007579656332, 5679.914801277984, 5703.657780533182, 2804.9902035267287, 2942.3379739558854, 3285.3265080658407, 3330.9514648491004, 3344.2642618623863, 6365.204349456318, 6584.946408574605, 6595.373439523238, 6625.124302283122, 6706.295838936939, 5228.869278267997, 5602.6918519481005, 5876.265466816648, 6076.0, 6300.338464461257, 6264.029075493215, 6597.637129957802, 6599.901001484982, 6613.537052406382, 6619.2056953164, 5533.6956467393065, 5752.626079304831, 6122.418370254819, 6280.371962803683, 6329.873402531832, 5817.5055120313555, 5875.294964604275, 6261.329870908101, 6277.183966084563, 6308.179936608211, 5603.607747457639, 6285.968570157303, 6554.901676474952, 6570.441512471493, 6614.107095542233, 4192.832286708497, 4272.530021697548, 4309.6552275818485, 4328.264195086906, 4333.978330108329, 5735.741891614971, 6040.818775436744, 6072.696365181635, 6300.0, 6323.494120470259, 5684.545236381162, 6011.188489553854, 6043.939560604338, 6059.787907423219, 6126.938730612636, 5297.841064768063, 6039.882621714997, 6075.817725468243, 6104.725287362168, 6119.602225855305, 5040.34475518195, 5161.812783059992, 5186.0, 5264.2630031794815, 5325.307709997831, 5365.382980957083, 5548.195511650869, 5559.749811258584, 5577.637453565505, 5607.5513958882375, 4639.628829693549, 4674.836380726658, 5454.618176727591, 5512.586556008396, 5561.0], "value": 4269.114740220507, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.6362364323264, 737.3400806278381, 745.8396444764375, 751.4214055177514, 766.3447752171891, 12655.114142010063, 12942.741145177102, 12968.481260749582, 16433.09617971011, 17111.91444042778, 15773.60565985851, 15848.524544263662, 15951.52145435636, 15956.521304360887, 15960.962537435058, 733.7798660401885, 750.1335956969682, 939.1266122506083, 941.0213378086781, 942.9009953954804, 762.2415696382108, 765.2194761343426, 767.6046835879526, 900.594732370436, 954.6944977607169, 989.1789814454022, 993.3841018568497, 995.4773743784535, 995.9601615935354, 1002.2232769603579, 823.81875987283, 983.9163671087966, 988.2686811759319, 988.3476905242542, 989.6239429016999, 568.729853319674, 703.5286358140058, 732.29333692986, 736.9815754606124, 741.4402126394591, 15170.772438413433, 15191.544253672408, 15211.543653690407, 15214.239288035664, 15216.771748423782, 12580.245185288912, 12594.0, 12834.101612887102, 12835.93582032084, 13044.347782610927, 9896.653617123366, 9931.702048938543, 9986.251031172691, 10289.897101028968, 10710.839337409945, 2992.880284788606, 3497.283056973314, 3516.9472457913153, 3564.215872508044, 3754.8498060077836, 72566.0, 72638.0, 74061.0, 75741.0, 78706.60646696787, 9155.725328240163, 9662.613495460244, 9898.208143348658, 9988.450635215133, 10545.367277963347, 22086.0183875178, 22100.88949555274, 22109.557808843732, 22126.668099978513, 22240.777592224193, 7621.0, 7638.69445222196, 7708.267714567107, 7740.109887363025, 7756.495827771289, 4124.484439445069, 4215.515215750167, 4226.070264541796, 4293.978530107392, 4310.094880075157, 3562.4325296869297, 3588.9950813772184, 3597.8560857565926, 3629.4374371972385, 3636.127329440968, 564.8249042796746, 619.294004834489, 632.718440294074, 652.8041587523724, 665.4809248785955, 492.16332235200304, 521.8032084922443, 556.2296219735695, 590.365357240959, 603.5895590998076, 8040.236177563239, 8099.959500202462, 8119.512829230265, 8227.259546640831, 8244.093149753522, 527.4040334422078, 557.7072037180512, 574.9794115445035, 589.4959809363019, 594.8275000249965, 3789.8673546425744, 3818.0073180973145, 3849.3071247175217, 3923.8234279457492, 3956.8987756236534, 93621.0, 93861.0, 95715.52142239244, 96314.0, 96323.0, 77796.0, 80689.59655201687, 81142.59428702937, 81259.0, 81821.0, 64693.0, 66971.66514167495, 66980.0, 67160.66419667967, 70555.29444705487, 7931.762047138595, 8018.3585313173635, 8027.638756255866, 8098.0, 8149.470284431493, 39594.62432254074, 40822.9794255146, 41612.58387416087, 42558.57441425546, 46422.53577464182, 59480.0, 61557.384426155164, 63280.68359658172, 64372.678136609014, 64927.35072649213, 11024.889751102708, 11067.225294229649, 11145.777084458428, 11434.828477572677, 12104.031677465957, 11708.297502149899, 12048.337341446304, 12116.939415302868, 12201.69495762613, 12549.372531373578, 11075.889241107809, 11388.658340249805, 11422.48598813072, 11648.475818588353, 11775.175737698279, 11234.494447749707, 11341.26281791681, 11534.884651153381, 11711.180217385043, 11968.0, 11087.33475991443, 11106.055985241022, 11220.831687524857, 11351.602693905674, 12233.449494772578, 7978.481398709066, 9316.702062955605, 9529.285303602119, 9765.259146568222, 9924.801503969737, 9170.862437063583, 9403.811306477584, 9436.0, 9534.427934323963, 9607.0], "value": 6864.384898090711, "unit": "score"}], "name": "dromaeo_css", "value": 7284.679943901188, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 109.0, 114.0, 114.0, 115.0, 116.0, 115.0, 103.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 148.0, 167.0, 158.0, 119.0, 142.0, 120.0, 151.0, 160.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 186.0, 183.0, 186.0, 187.0, 184.0, 185.0, 184.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 74.0, 151.0, 72.0, 79.0, 88.0, 89.0, 159.0, 66.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 142.0, 78.0, 84.0, 95.0, 125.0, 79.0, 75.0, 131.0], "value": 99.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 134.0, 86.0, 87.0, 84.0, 88.0, 86.0, 91.0, 127.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 150.0, 94.0, 93.0, 93.0, 95.0, 94.0, 94.0, 93.0, 95.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 83.0, 84.0, 83.0, 84.0, 84.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 66.0, 63.0, 63.0, 63.0, 65.0, 84.0, 61.0, 57.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 71.0, 76.0, 123.0, 108.0, 104.0, 56.0, 68.0, 96.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 106.0, 107.0, 81.0, 82.0, 101.0, 106.0, 103.0, 92.0, 84.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 135.0, 132.0, 133.0, 133.0, 135.0, 134.0, 190.0, 132.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 167.0, 168.0, 171.0, 169.0, 165.0, 217.0, 173.0, 166.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 71.0, 67.0, 67.0, 67.0, 69.0, 67.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1543.3999999999999, "alertThreshold": 2.0}]} 03:42:13 INFO - Return code: 0 03:42:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:42:13 ERROR - # TBPL SUCCESS # 03:42:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:13 INFO - 2016-05-06 03:42:13.382 screenresolution[1698:9997] starting screenresolution argv=screenresolution get 03:42:13 INFO - 2016-05-06 03:42:13.385 screenresolution[1698:9997] Display 0: 1600x1200x32@60 03:42:13 INFO - 2016-05-06 03:42:13.391 screenresolution[1699:10000] starting screenresolution argv=screenresolution list 03:42:13 INFO - Available Modes on Display 0 03:42:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:13 INFO - Intel Iris: 03:42:13 INFO - Chipset Model: Intel Iris 03:42:13 INFO - Type: GPU 03:42:13 INFO - Bus: Built-In 03:42:13 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:13 INFO - Vendor: Intel (0x8086) 03:42:13 INFO - Device ID: 0x0a2e 03:42:13 INFO - Revision ID: 0x0009 03:42:13 INFO - Displays: 03:42:13 INFO - Display: 03:42:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:13 INFO - Display Serial Number: 1600x1200 60 03:42:13 INFO - Main Display: Yes 03:42:13 INFO - Mirror: Off 03:42:13 INFO - Online: Yes 03:42:13 INFO - Rotation: Supported 03:42:13 INFO - Return code: 0 03:42:13 INFO - Running post-action listener: _resource_record_post_action 03:42:13 INFO - Running post-run listener: _resource_record_post_run 03:42:14 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37434880; Write bytes: 653500928; Read time: 395; Write time: 1790 03:42:14 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351072768; Write bytes: 346187776; Read time: 27071; Write time: 635 03:42:14 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35616256; Write bytes: 296524288; Read time: 373; Write time: 1125 03:42:14 INFO - Running post-run listener: _upload_blobber_files 03:42:14 INFO - Blob upload gear active. 03:42:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:14 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:14 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:14 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:15 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:15 INFO - (blobuploader) - INFO - Done attempting. 03:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:16 INFO - (blobuploader) - INFO - Done attempting. 03:42:16 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:16 INFO - Return code: 0 03:42:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ccbd0d811fa1ce2f8dc388018c39276bac10a8b0e10ce12421fac418b8464e930c14765d808670433a19287b52b2d3dcd5be0536492222500998023fe217486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74ad7896859a66fc372d8ff71277b12ff868ebfcbb55afc5f7761b3cc29b21b14855aba670004f9868b101c7126f5b5631e74ef64e8ed5a3af0962bdf97c7463"} 03:42:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:16 INFO - Contents: 03:42:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ccbd0d811fa1ce2f8dc388018c39276bac10a8b0e10ce12421fac418b8464e930c14765d808670433a19287b52b2d3dcd5be0536492222500998023fe217486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74ad7896859a66fc372d8ff71277b12ff868ebfcbb55afc5f7761b3cc29b21b14855aba670004f9868b101c7126f5b5631e74ef64e8ed5a3af0962bdf97c7463"} 03:42:16 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:16 INFO - Copying logs to upload dir... 03:42:16 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.441503 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 03:42:16.873056) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:16.876819) ========= 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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/6ccbd0d811fa1ce2f8dc388018c39276bac10a8b0e10ce12421fac418b8464e930c14765d808670433a19287b52b2d3dcd5be0536492222500998023fe217486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74ad7896859a66fc372d8ff71277b12ff868ebfcbb55afc5f7761b3cc29b21b14855aba670004f9868b101c7126f5b5631e74ef64e8ed5a3af0962bdf97c7463"} 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.011023 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/6ccbd0d811fa1ce2f8dc388018c39276bac10a8b0e10ce12421fac418b8464e930c14765d808670433a19287b52b2d3dcd5be0536492222500998023fe217486", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74ad7896859a66fc372d8ff71277b12ff868ebfcbb55afc5f7761b3cc29b21b14855aba670004f9868b101c7126f5b5631e74ef64e8ed5a3af0962bdf97c7463"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:16.904875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:16.905210) ========= 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.KVWSx4uHfN/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.4Am8qOhUYY/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.004925 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:16.953552) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:42:16.953860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:42:16.954180) ========= ========= Total master_lag: 0.15 =========