builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0196 starttime: 1462483110.46 results: success (0) buildid: 20160505123841 builduid: 1457772db7ef4c1982b4796b67a4914d revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.456518) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.456918) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.457187) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.549858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.550241) ========= 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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-05 14:18:30-- 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% 14.8M=0.001s 2016-05-05 14:18:30 (14.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.709993) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.712512) ========= 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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.030473 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:30.760948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 14:18:30.764325) ========= 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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-05 14:18:30,831 truncating revision to first 12 chars 2016-05-05 14:18:30,831 Setting DEBUG logging. 2016-05-05 14:18:30,831 attempt 1/10 2016-05-05 14:18:30,831 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-05 14:18:31,819 unpacking tar archive at: mozilla-aurora-0123e9f41ced/testing/mozharness/ program finished with exit code 0 elapsedTime=1.250302 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 14:18:32.032800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:32.033272) ========= script_repo_revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:32.033674) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:32.033942) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 14:18:32.047694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-05 14:18:32.048079) ========= /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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:18:32 INFO - MultiFileLogger online at 20160505 14:18:32 in /builds/slave/test 14:18:32 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 14:18:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:32 INFO - {'append_to_log': False, 14:18:32 INFO - 'base_work_dir': '/builds/slave/test', 14:18:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:18:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:32 INFO - 'branch': 'Mozilla-Aurora', 14:18:32 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:32 INFO - 'config_files': ('talos/mac_config.py',), 14:18:32 INFO - 'default_actions': ('clobber', 14:18:32 INFO - 'read-buildbot-config', 14:18:32 INFO - 'download-and-extract', 14:18:32 INFO - 'populate-webroot', 14:18:32 INFO - 'create-virtualenv', 14:18:32 INFO - 'install', 14:18:32 INFO - 'run-tests'), 14:18:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:32 INFO - 'download_minidump_stackwalk': True, 14:18:32 INFO - 'download_symbols': 'ondemand', 14:18:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:32 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:32 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:32 INFO - 'installer_path': 'installer.exe', 14:18:32 INFO - 'log_level': 'info', 14:18:32 INFO - 'log_name': 'talos', 14:18:32 INFO - 'log_to_console': True, 14:18:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:18:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:18:32 INFO - 'opt_config_files': (), 14:18:32 INFO - 'pip_index': False, 14:18:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:32 INFO - 'cmd': ('bash', 14:18:32 INFO - '-c', 14:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:32 INFO - 'enabled': True, 14:18:32 INFO - 'halt_on_failure': False, 14:18:32 INFO - 'name': 'check_screen_resolution'},), 14:18:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:32 INFO - 'cmd': ('bash', 14:18:32 INFO - '-c', 14:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:32 INFO - 'enabled': True, 14:18:32 INFO - 'halt_on_failure': False, 14:18:32 INFO - 'name': 'check_screen_resolution'},), 14:18:32 INFO - 'run_cmd_checks_enabled': True, 14:18:32 INFO - 'sps_profile': False, 14:18:32 INFO - 'sps_profile_interval': 0, 14:18:32 INFO - 'suite': 'dromaeojs', 14:18:32 INFO - 'system_bits': '32', 14:18:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:32 INFO - 'title': 't-yosemite-r7-0196', 14:18:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:32 INFO - 'use_talos_json': True, 14:18:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:32 INFO - 'work_dir': 'build'} 14:18:32 INFO - ##### 14:18:32 INFO - ##### Running clobber step. 14:18:32 INFO - ##### 14:18:32 INFO - Running pre-action listener: _resource_record_pre_action 14:18:32 INFO - Running main action method: clobber 14:18:32 INFO - rmtree: /builds/slave/test/build 14:18:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:34 INFO - Running post-action listener: _resource_record_post_action 14:18:34 INFO - ##### 14:18:34 INFO - ##### Running read-buildbot-config step. 14:18:34 INFO - ##### 14:18:34 INFO - Running pre-action listener: _resource_record_pre_action 14:18:34 INFO - Running main action method: read_buildbot_config 14:18:34 INFO - Using buildbot properties: 14:18:34 INFO - { 14:18:34 INFO - "project": "", 14:18:34 INFO - "product": "firefox", 14:18:34 INFO - "script_repo_revision": "production", 14:18:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:18:34 INFO - "repository": "", 14:18:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:18:34 INFO - "buildid": "20160505123841", 14:18:34 INFO - "pgo_build": "False", 14:18:34 INFO - "basedir": "/builds/slave/test", 14:18:34 INFO - "buildnumber": 1, 14:18:34 INFO - "slavename": "t-yosemite-r7-0196", 14:18:34 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:18:34 INFO - "platform": "yosemite_r7", 14:18:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:18:34 INFO - "branch": "mozilla-aurora", 14:18:34 INFO - "repo_path": "releases/mozilla-aurora", 14:18:34 INFO - "revision": "0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b", 14:18:34 INFO - "stage_platform": "macosx64", 14:18:34 INFO - "builduid": "1457772db7ef4c1982b4796b67a4914d", 14:18:34 INFO - "slavebuilddir": "test" 14:18:34 INFO - } 14:18:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 14:18:34 INFO - Running post-action listener: _resource_record_post_action 14:18:34 INFO - ##### 14:18:34 INFO - ##### Running download-and-extract step. 14:18:34 INFO - ##### 14:18:34 INFO - Running pre-action listener: _resource_record_pre_action 14:18:34 INFO - Running main action method: download_and_extract 14:18:34 INFO - mkdir: /builds/slave/test/build/tests 14:18: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')]} 14:18:34 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 14:18:34 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 14:18:34 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 14:18:34 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 14:18:34 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 14:18:35 INFO - Downloaded 1199 bytes. 14:18:35 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 14:18:35 INFO - Using the following test package requirements: 14:18:35 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 14:18:35 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 14:18:35 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 14:18:35 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'jsshell-mac.zip'], 14:18:35 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 14:18:35 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 14:18:35 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 14:18:35 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 14:18:35 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 14:18:35 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:18:35 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 14:18:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:18:35 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')]} 14:18:35 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 14:18:35 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 14:18:35 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 14:18:35 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 14:18:35 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 14:18:36 INFO - Downloaded 18114223 bytes. 14:18:36 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'] 14:18:36 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 14:18:39 INFO - Return code: 0 14:18:39 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 14:18: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')]} 14:18:39 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 14:18:39 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 14:18:39 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 14:18:39 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 14:18:39 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 14:18:40 INFO - Downloaded 18114223 bytes. 14:18:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:18:44 INFO - Return code: 0 14:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:44 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 14:18:44 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 14:18:44 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 14:18:44 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 14:18:44 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 14:18:45 INFO - Downloaded 10840145 bytes. 14:18:45 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'] 14:18:45 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 14:18:45 INFO - Return code: 0 14:18:45 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')]} 14:18:45 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 14:18:45 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 14:18:45 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 14:18:45 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 14:18:45 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 14:18:48 INFO - Downloaded 114081343 bytes. 14:18:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:18:48 INFO - mkdir: /builds/slave/test/properties 14:18:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:48 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:48 INFO - Contents: 14:18:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - Running post-action listener: set_extra_try_arguments 14:18:50 INFO - ##### 14:18:50 INFO - ##### Running populate-webroot step. 14:18:50 INFO - ##### 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: populate_webroot 14:18:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:50 INFO - '--tppagecycles', 14:18:50 INFO - '7'], 14:18:50 INFO - 'tests': ['tsvgm']}}, 14:18:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint']}, 14:18:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'cart']}, 14:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'cart']}, 14:18:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-e10s'], 14:18:50 INFO - 'tests': ['tp5o']}, 14:18:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'tests': ['tp5o']}, 14:18:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-e10s', 14:18:50 INFO - '--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}, 14:18:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:50 INFO - 'talos_options': ['--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}}, 14:18:50 INFO - 'talos.zip': {'path': '', 14:18:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - ##### 14:18:50 INFO - ##### Running create-virtualenv step. 14:18:50 INFO - ##### 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: create_virtualenv 14:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:50 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 14:18:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:50 INFO - Using real prefix '/tools/python27' 14:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:54 INFO - Installing pip.................done. 14:18:54 INFO - Return code: 0 14:18:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:54 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')]} 14:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:54 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')]} 14:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:54 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:18:54 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 14:18:54 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 14:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:54 INFO - 'HOME': '/Users/cltbld', 14:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:54 INFO - 'LOGNAME': 'cltbld', 14:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:54 INFO - 'MOZ_NO_REMOTE': '1', 14:18:54 INFO - 'NO_EM_RESTART': '1', 14:18:54 INFO - 'PAGER': '/bin/cat', 14:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:54 INFO - 'PWD': '/builds/slave/test', 14:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:54 INFO - 'SHELL': '/bin/bash', 14:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:18:54 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:54 INFO - 'USER': 'cltbld', 14:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:54 INFO - 'XPC_FLAGS': '0x0', 14:18:54 INFO - 'XPC_SERVICE_NAME': '0', 14:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:54 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 14:18:54 INFO - Cleaning up... 14:18:54 INFO - Return code: 0 14:18:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:18:54 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')]} 14:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:54 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')]} 14:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:54 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:18:54 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 14:18:54 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 14:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:54 INFO - 'HOME': '/Users/cltbld', 14:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:54 INFO - 'LOGNAME': 'cltbld', 14:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:54 INFO - 'MOZ_NO_REMOTE': '1', 14:18:54 INFO - 'NO_EM_RESTART': '1', 14:18:54 INFO - 'PAGER': '/bin/cat', 14:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:54 INFO - 'PWD': '/builds/slave/test', 14:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:54 INFO - 'SHELL': '/bin/bash', 14:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:18:54 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:54 INFO - 'USER': 'cltbld', 14:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:54 INFO - 'XPC_FLAGS': '0x0', 14:18:54 INFO - 'XPC_SERVICE_NAME': '0', 14:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:55 INFO - Downloading/unpacking psutil>=0.7.1 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:58 INFO - Installing collected packages: psutil 14:18:58 INFO - Running setup.py install for psutil 14:18:58 INFO - building 'psutil._psutil_osx' extension 14:18:58 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 14:18:58 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 14:18:58 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 14:18:58 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 14:18:59 INFO - building 'psutil._psutil_posix' extension 14:18:59 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 14:18:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:18:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:18:59 INFO - ^ 14:18:59 INFO - 1 warning generated. 14:18:59 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 14:18:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:59 INFO - Successfully installed psutil 14:18:59 INFO - Cleaning up... 14:18:59 INFO - Return code: 0 14:18:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:18:59 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')]} 14:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:59 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')]} 14:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:59 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:18:59 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 14:18:59 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 14:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:59 INFO - 'HOME': '/Users/cltbld', 14:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:59 INFO - 'LOGNAME': 'cltbld', 14:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:59 INFO - 'MOZ_NO_REMOTE': '1', 14:18:59 INFO - 'NO_EM_RESTART': '1', 14:18:59 INFO - 'PAGER': '/bin/cat', 14:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:59 INFO - 'PWD': '/builds/slave/test', 14:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:59 INFO - 'SHELL': '/bin/bash', 14:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:18:59 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:59 INFO - 'USER': 'cltbld', 14:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:59 INFO - 'XPC_FLAGS': '0x0', 14:18:59 INFO - 'XPC_SERVICE_NAME': '0', 14:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:19:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:19:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:19:02 INFO - Installing collected packages: mozsystemmonitor 14:19:02 INFO - Running setup.py install for mozsystemmonitor 14:19:02 INFO - Successfully installed mozsystemmonitor 14:19:02 INFO - Cleaning up... 14:19:02 INFO - Return code: 0 14:19:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:02 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:02 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 14:19:02 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 14:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:02 INFO - 'HOME': '/Users/cltbld', 14:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:02 INFO - 'LOGNAME': 'cltbld', 14:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:02 INFO - 'MOZ_NO_REMOTE': '1', 14:19:02 INFO - 'NO_EM_RESTART': '1', 14:19:02 INFO - 'PAGER': '/bin/cat', 14:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:02 INFO - 'PWD': '/builds/slave/test', 14:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:02 INFO - 'SHELL': '/bin/bash', 14:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:02 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:02 INFO - 'USER': 'cltbld', 14:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:02 INFO - 'XPC_FLAGS': '0x0', 14:19:02 INFO - 'XPC_SERVICE_NAME': '0', 14:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:02 INFO - Downloading/unpacking blobuploader==1.2.4 14:19: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 14:19: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 14:19: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 14:19:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19: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 14:19: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 14:19:04 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:06 INFO - Downloading docopt-0.6.1.tar.gz 14:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:06 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:06 INFO - Running setup.py install for blobuploader 14:19:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:06 INFO - Running setup.py install for requests 14:19:06 INFO - Running setup.py install for docopt 14:19:07 INFO - Successfully installed blobuploader requests docopt 14:19:07 INFO - Cleaning up... 14:19:07 INFO - Return code: 0 14:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:07 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:07 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 14:19:07 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 14:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:07 INFO - 'HOME': '/Users/cltbld', 14:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:07 INFO - 'LOGNAME': 'cltbld', 14:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:07 INFO - 'MOZ_NO_REMOTE': '1', 14:19:07 INFO - 'NO_EM_RESTART': '1', 14:19:07 INFO - 'PAGER': '/bin/cat', 14:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:07 INFO - 'PWD': '/builds/slave/test', 14:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:07 INFO - 'SHELL': '/bin/bash', 14:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:07 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:07 INFO - 'USER': 'cltbld', 14:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:07 INFO - 'XPC_FLAGS': '0x0', 14:19:07 INFO - 'XPC_SERVICE_NAME': '0', 14:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-mPzVQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-xBAbXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-21OBCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-jxLH5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-oe3k79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-YJOG9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:07 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-phr620-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-i6RGFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-LirtzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-78tCsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-fIJZAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-0c8AXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-S2F3e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-cgoMV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-qzCmbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:08 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-8Kugqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:09 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-mCn5tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:09 INFO - Running setup.py install for manifestparser 14:19:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:09 INFO - Running setup.py install for mozcrash 14:19:09 INFO - Running setup.py install for mozdebug 14:19:09 INFO - Running setup.py install for mozdevice 14:19:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:19:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:09 INFO - Running setup.py install for mozfile 14:19:09 INFO - Running setup.py install for mozhttpd 14:19:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Running setup.py install for mozinfo 14:19:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Running setup.py install for mozInstall 14:19:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Running setup.py install for mozleak 14:19:10 INFO - Running setup.py install for mozlog 14:19:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Running setup.py install for moznetwork 14:19:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:10 INFO - Running setup.py install for mozprocess 14:19:10 INFO - Running setup.py install for mozprofile 14:19:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:11 INFO - Running setup.py install for mozrunner 14:19:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:11 INFO - Running setup.py install for mozscreenshot 14:19:11 INFO - Running setup.py install for moztest 14:19:11 INFO - Running setup.py install for mozversion 14:19:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:11 INFO - Cleaning up... 14:19:11 INFO - Return code: 0 14:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:11 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:11 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 14:19:11 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 14:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:11 INFO - 'HOME': '/Users/cltbld', 14:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:11 INFO - 'LOGNAME': 'cltbld', 14:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:11 INFO - 'MOZ_NO_REMOTE': '1', 14:19:11 INFO - 'NO_EM_RESTART': '1', 14:19:11 INFO - 'PAGER': '/bin/cat', 14:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:11 INFO - 'PWD': '/builds/slave/test', 14:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:11 INFO - 'SHELL': '/bin/bash', 14:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:11 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:11 INFO - 'USER': 'cltbld', 14:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:11 INFO - 'XPC_FLAGS': '0x0', 14:19:11 INFO - 'XPC_SERVICE_NAME': '0', 14:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:11 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-hTGtkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-XF4cd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-mFdN6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-5JsP7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-TrJyJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-298quA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-uJ6myk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lNr8Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Nz0Wne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:12 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)) 14:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:12 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-J3rXVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-QVB9fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-hslByD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-VTqEKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-HXhmhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Qrzn7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-3mn6eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:13 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)) 14:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:13 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-PiYvdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:13 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)) 14:19:13 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)) 14:19:13 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)) 14:19:13 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)) 14:19:13 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)) 14:19:13 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)) 14:19:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:16 INFO - Downloading blessings-1.6.tar.gz 14:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:16 INFO - Installing collected packages: blessings 14:19:16 INFO - Running setup.py install for blessings 14:19:16 INFO - Successfully installed blessings 14:19:16 INFO - Cleaning up... 14:19:16 INFO - Return code: 0 14:19:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:16 INFO - Reading from file tmpfile_stdout 14:19:16 INFO - Current package versions: 14:19:16 INFO - blessings == 1.6 14:19:16 INFO - blobuploader == 1.2.4 14:19:16 INFO - docopt == 0.6.1 14:19:16 INFO - manifestparser == 1.1 14:19:16 INFO - mozInstall == 1.12 14:19:16 INFO - mozcrash == 0.17 14:19:16 INFO - mozdebug == 0.1 14:19:16 INFO - mozdevice == 0.48 14:19:16 INFO - mozfile == 1.2 14:19:16 INFO - mozhttpd == 0.7 14:19:16 INFO - mozinfo == 0.9 14:19:16 INFO - mozleak == 0.1 14:19:16 INFO - mozlog == 3.1 14:19:16 INFO - moznetwork == 0.27 14:19:16 INFO - mozprocess == 0.22 14:19:16 INFO - mozprofile == 0.28 14:19:16 INFO - mozrunner == 6.11 14:19:16 INFO - mozscreenshot == 0.1 14:19:16 INFO - mozsystemmonitor == 0.0 14:19:16 INFO - moztest == 0.7 14:19:16 INFO - mozversion == 1.4 14:19:16 INFO - psutil == 3.1.1 14:19:16 INFO - requests == 1.2.3 14:19:16 INFO - wsgiref == 0.1.2 14:19:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:16 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:16 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 14:19:16 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 14:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:16 INFO - 'HOME': '/Users/cltbld', 14:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:16 INFO - 'LOGNAME': 'cltbld', 14:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:16 INFO - 'MOZ_NO_REMOTE': '1', 14:19:16 INFO - 'NO_EM_RESTART': '1', 14:19:16 INFO - 'PAGER': '/bin/cat', 14:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:16 INFO - 'PWD': '/builds/slave/test', 14:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:16 INFO - 'SHELL': '/bin/bash', 14:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:16 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:16 INFO - 'USER': 'cltbld', 14:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:16 INFO - 'XPC_FLAGS': '0x0', 14:19:16 INFO - 'XPC_SERVICE_NAME': '0', 14:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 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)) 14:19:17 INFO - Cleaning up... 14:19:17 INFO - Return code: 0 14:19:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:17 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')]} 14:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:17 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')]} 14:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:17 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:17 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 14:19:17 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 14:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:17 INFO - 'HOME': '/Users/cltbld', 14:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:17 INFO - 'LOGNAME': 'cltbld', 14:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:17 INFO - 'MOZ_NO_REMOTE': '1', 14:19:17 INFO - 'NO_EM_RESTART': '1', 14:19:17 INFO - 'PAGER': '/bin/cat', 14:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:17 INFO - 'PWD': '/builds/slave/test', 14:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:17 INFO - 'SHELL': '/bin/bash', 14:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:17 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:17 INFO - 'USER': 'cltbld', 14:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:17 INFO - 'XPC_FLAGS': '0x0', 14:19:17 INFO - 'XPC_SERVICE_NAME': '0', 14:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:17 INFO - Downloading/unpacking jsonschema 14:19:17 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 14:19:17 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 14:19:17 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 14:19:17 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 14:19:17 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 14:19:17 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 14:19:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:19 INFO - Downloading/unpacking functools32 (from jsonschema) 14:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:20 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:20 INFO - warning: no files found matching '*.txt' 14:19:20 INFO - no previously-included directories found matching 'build' 14:19:20 INFO - no previously-included directories found matching 'dist' 14:19:20 INFO - no previously-included directories found matching '.git*' 14:19:20 INFO - Installing collected packages: jsonschema, functools32 14:19:20 INFO - Running setup.py install for functools32 14:19:20 INFO - warning: no files found matching '*.txt' 14:19:20 INFO - no previously-included directories found matching 'build' 14:19:20 INFO - no previously-included directories found matching 'dist' 14:19:20 INFO - no previously-included directories found matching '.git*' 14:19:20 INFO - Successfully installed jsonschema functools32 14:19:20 INFO - Cleaning up... 14:19:20 INFO - Return code: 0 14:19:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:19: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')]} 14:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:20 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 0x10b3caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3da420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5785b4c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, '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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 14:19:20 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 14:19:20 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 14:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:20 INFO - 'HOME': '/Users/cltbld', 14:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:20 INFO - 'LOGNAME': 'cltbld', 14:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:20 INFO - 'MOZ_NO_REMOTE': '1', 14:19:20 INFO - 'NO_EM_RESTART': '1', 14:19:20 INFO - 'PAGER': '/bin/cat', 14:19:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:20 INFO - 'PWD': '/builds/slave/test', 14:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:20 INFO - 'SHELL': '/bin/bash', 14:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:20 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:20 INFO - 'USER': 'cltbld', 14:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:20 INFO - 'XPC_FLAGS': '0x0', 14:19:20 INFO - 'XPC_SERVICE_NAME': '0', 14:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:20 INFO - Downloading/unpacking flake8 14:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:19:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:19:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:19:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:24 INFO - no previously-included directories found matching 'docs/_build' 14:19:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:19:24 INFO - Running setup.py install for pep8 14:19:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:24 INFO - no previously-included directories found matching 'docs/_build' 14:19:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:19:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:19:24 INFO - Cleaning up... 14:19:24 INFO - Return code: 0 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - Running post-action listener: _start_resource_monitoring 14:19:24 INFO - Starting resource monitoring. 14:19:24 INFO - ##### 14:19:24 INFO - ##### Running install step. 14:19:24 INFO - ##### 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: install 14:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:24 INFO - Reading from file tmpfile_stdout 14:19:24 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:25 INFO - Reading from file tmpfile_stdout 14:19:25 INFO - Output received: 14:19:25 INFO - Usage: mozinstall [options] installer 14:19:25 INFO - Options: 14:19:25 INFO - -h, --help show this help message and exit 14:19:25 INFO - -d DEST, --destination=DEST 14:19:25 INFO - Directory to install application into. [default: 14:19:25 INFO - "/builds/slave/test"] 14:19:25 INFO - --app=APP Application being installed. [default: firefox] 14:19:25 INFO - mkdir: /builds/slave/test/build/application 14:19:25 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'] 14:19:25 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 14:19:56 INFO - Reading from file tmpfile_stdout 14:19:56 INFO - Output received: 14:19:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:19:56 INFO - Running post-action listener: _resource_record_post_action 14:19:56 INFO - ##### 14:19:56 INFO - ##### Running run-tests step. 14:19:56 INFO - ##### 14:19:56 INFO - Running pre-action listener: _resource_record_pre_action 14:19:56 INFO - Running main action method: run_tests 14:19:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:56 INFO - 2016-05-05 14:19:56.313 screenresolution[1638:7341] starting screenresolution argv=screenresolution get 14:19:56 INFO - 2016-05-05 14:19:56.315 screenresolution[1638:7341] Display 0: 1600x1200x32@60 14:19:56 INFO - 2016-05-05 14:19:56.321 screenresolution[1639:7344] starting screenresolution argv=screenresolution list 14:19:56 INFO - Available Modes on Display 0 14:19:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:56 INFO - Intel Iris: 14:19:56 INFO - Chipset Model: Intel Iris 14:19:56 INFO - Type: GPU 14:19:56 INFO - Bus: Built-In 14:19:56 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:56 INFO - Vendor: Intel (0x8086) 14:19:56 INFO - Device ID: 0x0a2e 14:19:56 INFO - Revision ID: 0x0009 14:19:56 INFO - Displays: 14:19:56 INFO - Display: 14:19:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:56 INFO - Display Serial Number: 1600x1200 60 14:19:56 INFO - Main Display: Yes 14:19:56 INFO - Mirror: Off 14:19:56 INFO - Online: Yes 14:19:56 INFO - Rotation: Supported 14:19:56 INFO - Return code: 0 14:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:56 INFO - Python 2.7.3 14:19:56 INFO - Return code: 0 14:19:56 INFO - grabbing minidump binary from tooltool 14:19: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')]} 14:19:56 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 0x10b269e00>, '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 0x10b0d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b264b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:56 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 14:19:56 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 14:19:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:56 INFO - Return code: 0 14:19:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:19:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:19:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:19:57 INFO - Return code: 0 14:19:57 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-0196', '--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 14:19:57 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-0196 --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 14:19:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b8LayTPv7q/Render', 14:19:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:57 INFO - 'HOME': '/Users/cltbld', 14:19:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:57 INFO - 'LOGNAME': 'cltbld', 14:19:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:57 INFO - 'MOZ_NO_REMOTE': '1', 14:19:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:57 INFO - 'NO_EM_RESTART': '1', 14:19:57 INFO - 'PAGER': '/bin/cat', 14:19:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:57 INFO - 'PWD': '/builds/slave/test', 14:19:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:57 INFO - 'SHELL': '/bin/bash', 14:19:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0Hxnf4MtdV/Listeners', 14:19:57 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 14:19:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:57 INFO - 'USER': 'cltbld', 14:19:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:57 INFO - 'XPC_FLAGS': '0x0', 14:19:57 INFO - 'XPC_SERVICE_NAME': '0', 14:19:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:57 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-0196', '--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 14:19:57 INFO - mozversion application_buildid: 20160505123841 14:19:57 INFO - mozversion application_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 14:19:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:19:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:57 INFO - mozversion application_name: Firefox 14:19:57 INFO - mozversion application_remotingname: firefox-dev 14:19:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:19:57 INFO - mozversion application_vendor: Mozilla 14:19:57 INFO - mozversion application_version: 48.0a2 14:19:57 INFO - mozversion platform_buildid: 20160505123841 14:19:57 INFO - mozversion platform_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 14:19:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:19:57 INFO - mozversion platform_version: 48.0a2 14:19:57 INFO - using testdate: 1462483197 14:19:57 INFO - actual date: 1462483197 14:19:57 INFO - starting webserver on 'localhost:49270' 14:19:57 INFO - SUITE-START | Running 2 tests 14:19:57 INFO - TEST-START | dromaeo_css 14:19:57 INFO - operating with platform_type : mac_ 14:19:57 INFO - Initialising browser for dromaeo_css test... 14:19:57 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpN71cQ9/profile http://localhost:49270/getInfo.html) 14:20:00 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 14:20:00 INFO - PROCESS | 1657 | colorDepth:24 14:20:00 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 14:20:00 INFO - PROCESS | 1657 | __metrics 14:20:00 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" 14:20:00 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:20:01 INFO - TEST-INFO | 1657: exit 0 14:20:01 INFO - Browser initialized. 14:20:01 INFO - Running cycle 1/1 for dromaeo_css test... 14:20:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpN71cQ9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:12 INFO - PROCESS | 1659 | RSS: Main: 211173376 14:20:12 INFO - PROCESS | 1659 | 14:21:04 INFO - PROCESS | 1659 | 1462483264667 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] 14:23:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 14:23:16 INFO - PROCESS | 1659 | RSS: Main: 329555968 14:23:16 INFO - PROCESS | 1659 | 14:26:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 14:26:20 INFO - PROCESS | 1659 | RSS: Main: 352485376 14:26:20 INFO - PROCESS | 1659 | 14:29:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 14:29:23 INFO - PROCESS | 1659 | RSS: Main: 356761600 14:29:23 INFO - PROCESS | 1659 | 14:32:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 14:32:25 INFO - PROCESS | 1659 | RSS: Main: 358719488 14:32:25 INFO - PROCESS | 1659 | 14:35:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 14:35:29 INFO - PROCESS | 1659 | RSS: Main: 330518528 14:35:29 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 14:38:32 INFO - PROCESS | 1659 | RSS: Main: 363925504 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | __start_tp_report 14:38:32 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 14:38:32 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 14:38:32 INFO - PROCESS | 1659 | |i|pagename|runs| 14:38:32 INFO - PROCESS | 1659 | |0;dojo.html;2883.942321153577;3453.86184552618;3459.705924996375;3465.1510379956912;3523.718102551796;5670.688112153835;5685.374608793029;5704.54363650908;5710.514606258463;5712.028955077636;4765.8808529786775;5733.827985160452;5740.138979153129;5755.496219399592;5821.476067153955;5082.186850103984;5131.204663277198;5217.113090774568;5219.42586315505;5281.735913204344;5247.553957913582;5268.31511903452;5269.1832765921345;5271;5274.235235890794;5168.819091331804;5209.479052094798;5247.317848679666;5255.842324730245;5274.81538146165;5200.349956255487;5201.661891977009;5228.980348831979;5236.476352364771;5267.762950667229;5088.23676448532;5092.770825312869;5129.0767661820855;5160;5166.328377310963;5498.780048798043;5527.922055199238;5542.473465020817;5559.861003474945;5590.888182236373;5235.895282094337;6236.594621349598;6254.499640028786;6270.905936410957;6274.937250627481;5654.773809047633;6236.656983865885;6259.499240060784;6282.96858515709;6288.088227207029;5795.623284486495;5822.650640961556;5823.068309070529;5870.941290587082;5871.412858714094;6265.780697675559;6284.402981716729;6292.182016337847;6296.874062518816;6304.243490781044;5089.491050894955;6014;6067.302260240041;6165.383461653889;6166.260048794084;6256.468200202943;6296.401841825019;6296.653684047421;6310.4636105930595;6318.210223722035;6162.815115546541;6209.9068513972325;6219.533534984895;6224.972879474836;6225.28409232944;6067.635941843503;6130.153553065376;6154.076888466766;6219.47134493564;6261.592996455307;4848.10310092634;4850.151223535867;4877.512248775094;4884.902301953941;4890.510948905152;4393.582609652078;4403.647708183337;4417.646588272931;4423.336499525097;4453.777311134495;5679.346875109415;5728.570357223225;5729.25519682447;5732.942670573324;5734.455226753452;6130.184124957599;6229.2524897012645;6281.403266689657;6322.9051564226575;6340.207474065742;5621.044422448112;5685.516731077904;5697.886042279131;5703.287089113861;5716.1997320375185;5374.543163831118;5375.946240537544;5380.2736630555155;5383.515483606494;5387.542058924957;6212.689365531795;6247.9062814056915;6299.055141728777;6301.653409062362;6323.52573556985;6215.316315205414;6294.748210071729;6301.558890877778;6315.989441689308;6320.810375688737;6313.715882785377;6317.241930968313;6334.619922804646;6344.365563443619;6352.460040896575;6005.309389420097;6179.598326108789;6180.103884936749;6194;6199.969000155151;6187.721552530057;6223.097650840693;6226.937730622636;6295.150154729053;6316.11574379578;6012.218411606463;6053.818385448268;6054.091886217103;6060.575759696955;6095.75616975316;5958.880822383615;6011.068284415923;6039.5470339723815;6051.213342265477;6054.1826853376;5727.284089488814;5842.3281322646735;5885.117232415172;5898.616589921813;5932.406759324033;6055.969720151371;6060.424259695322;6075.301340345917;6079.878402432015;6095.847603809941;5676.631018983753;5777.133429985536;5850.707464626837;5910.497607703393;5978.940210597838 14:38:32 INFO - PROCESS | 1659 | |1;ext.html;506.6782593053412;535.3709391465028;585.7393459910342;595.6158277910749;618.4526693875919;30080.699193008062;31204.843975780117;38870.41694374583;39733.40399893997;39843.402348964715;44114;44808.775956120255;44820.775896120474;44846;44848;9499.050094990498;9733;9736.707898763048;9737.805243895118;9739.41563506192;8401.83196336073;8621.95689021554;11142.108631309477;11370.31778093317;11379.26034807739;10517.895620959804;10672.733181670443;11324.547018119307;11480.368579728154;11502.482388292547;8385.287250583708;11175.552977880876;11303.208775385727;11497.48261328238;11516.539338426453;11291.548338066468;11454.94272528632;11454.969052785289;11457.942710286396;11460.770784584345;38848.80575597132;39177;39230.21539569221;39290.80354598209;39340.80329598362;3321.4353559894876;3328.1679580104974;3657.5062366580437;3722.7021838252876;3840.2127563849344;26876.462470750477;28939.987100451595;29160.56259156114;29294.75226092226;29576;2734.3847634282324;2734.398432344901;2785.8049936504453;2802.173358859121;2821.1254511101624;499816;500362;501256.49371752905;504665;505575;34593.654063459544;63282.68358658177;63748.36251637517;63838.36161638417;63947;30024.54963175554;35807.28385432329;35836.82081589576;35921.64078359235;35993.820030899675;15297.770533442008;15733;15735.527934161993;15757.290921908541;15762.76355854663;11778.764424711459;12003.699907502383;12837.972962163005;13124.409401576951;13141;8171.0194776625995;8206.753797386087;8217.917820821835;8282.627281772335;8303.750887473385;3430.7255419566377;3464.566929133858;3471.809050502196;3481.7040551552896;3513.8243087845503;966.415318732157;989.5744829723144;1020.3724709303873;1033.4729288063143;1057.4554104636118;26141.346466338113;29159;29742.702572973994;29887.402251954838;30571.541426878615;764.3923081150499;1120.2270433400927;1123.1436104449267;1147.1167201254987;1208.8368070310396;15062.096274223582;15300.540983770505;15608;15956.2819673115;15957.760633590506;661439;667924;673972;675974;680536;62052.68973655193;62744;62917.37082629207;62929;63008;1384.4116250593459;1433.1472773699631;1535.9692806143814;1561.0399604243535;1561.93752249911;29888.551671725378;30805.537916931713;31504.212394690316;31937.68062319347;32064.83967580147;54712.452875470735;55778.72110639421;56306.71846640741;56426;56503.434965649816;451883;452103;459681;460826;461163;32031.519527206645;33057.66942330546;33833.95494405417;34139.82930085334;41461.75614731563;34450.48324275188;34993;45905.85235369009;46133.76933115313;46300.7684961573;35866.64133358737;36755.63244367522;37629.62370376261;37793.62206377901;39076.02309942274;36929.815350924146;37300.81349593234;37483.93838338918;38019.23961520809;38074.80962595169;37909.86270411892;38591.228175436896;39233;39814;39911.60088399195;32617.836910815295;33961.320773584885;34687.82656086703;34716.652833471344;34746.13134671653;22691.907428514533;25575.104871329415;25744.742552574233;25996.480070398866;26258.86870565635 14:38:32 INFO - PROCESS | 1659 | |2;jquery.html;404.31266846361183;409.3287009304739;411.30900087852416;411.9794010299485;417.9644730197933;19717.901410492945;20290.59418811623;20628.896855515737;20644.17423303068;21295;10100.898991010088;11977.28136311822;12436.316002619853;12668.239905605647;12722.300273484954;5444.1561557958385;5541.861453463675;5616.691081990489;5621.241132447129;5648.830535083934;5834.445727655887;6163.414475624831;6165.845853853644;6175.691215439232;6179.134921111048;6089.269287685462;6147.446729794317;6159.38406159385;6173.938260617404;6180.660063696518;5972.522198224154;6125.754969801203;6126.509879209652;6161.445469907708;6180.042093475496;5781.8725348557045;5966;5979.880402391971;6002.879942401171;6008.6094403864045;13708.65728356789;14017.719645607132;14180.22008789516;14247.572572822832;14283.428662853474;1850.6298740251868;2207.404000919759;2297.4715815362392;2311.31816114245;2337.614293641547;9964.20286377088;10054.497275136215;10192.745181370525;10509.106725928305;10522.736931576695;1693.7713408689915;1718.19244954871;1758.909476124798;1780.7061834797244;1794.6590147871993;122126;127719;127970.51860987826;128066;131212;11749.706257343634;11809.822852657217;11846.822297665542;11849.822252666218;11861.5848445304;15722.13528255957;15764.132972686612;15820.60448488797;15832;15835.366585336458;9041;9096.1813436791;9112.952010518742;9138.268938484905;9243.121903419164;9042.367034307666;9042.773930651654;9051;9171.724848254564;9173.312001599907;4721.551452611996;4749.738764367993;4757.502311286533;4770.427548694179;4811.893264549173;1668.5828542864283;1925.3550060729533;1983.8313743331978;1985.1463870535808;1985.6525108106023;398.2473125792255;712.4611530803318;740.3410964241829;952.3476418653245;1060.671191930504;11681.007114395201;12034.277943323428;12250.509979600894;12410.5656302029;12489.875101249054;445.54999450554527;463.55498721227326;463.84461205496535;468.8171613070905;471.44134200971274;6246.125542424064;6259.4679452246155;6328.493720502349;6350.174477317919;6430.485561155097;486414;512369;518535;647431;651637;128911.06633400507;134864;138521;139336.6066339344;140227;1013.7566983923805;1024.6208902705898;1058.872935247775;1059.7085801404676;1062.3466568060587;18530.44408667742;18811;19186.904065479583;19226.230950762372;19400.417987460398;43475.26098956006;51287.23069153891;53314.73342633262;53440.46559534355;53658;73267.26732732604;74504;74617.6269118651;75528.62235688786;78657.60671196607;8032.558209298545;8431.620577073923;8496.362772791941;8729.51987640686;8911.50986695738;12268.44791984345;12390;12787.232766034067;13068.8039679403;13074.019448541207;10973.561057557929;11165.497552610315;11499.597514086967;11506.884931150917;11610.6516804496;8096.919030809617;10862.131029517777;11387.373694446886;11432;11439.714007149887;8935.910640893508;10758.300710453796;10926.289791163867;11674.416279185836;11971.042316614476;8430.45202061864;8543.530105844238;8577.142285771373;8730.91269087301;8860.291176705978;8042.064040314518;8072.717454889051;8077.87883181741;8258.710945116893;8277.917220827714 14:38:32 INFO - PROCESS | 1659 | |3;mootools.html;1379.7654398752213;1397.8112954751105;1563.405905755813;1777.2091419318401;1986.0069965017492;7425.257474252572;7876.527408355496;8601.827963440728;8820.382573219877;8828.646854125844;7487.876818477231;8268.710595129172;8644.827103457927;8663.090375510576;8670.306375489961;2427.4295540548;2458.791002764767;2460.3972026853444;2461.1878080233478;2461.4092617771694;2394.6048901931163;2460.052879641342;2461.1386014894733;2465.2111324376206;2466.05057053035;2400.7839294463583;2424.4787370715294;2438.195395519483;2440.9755902441016;2442.572549803786;2368.017272831773;2413.54142712884;2423.430493833948;2438.5488684593406;2441.6581678564926;1960.3824795189469;2417.7703118203744;2420.1287536486852;2424.5878200705924;2425.2360506440414;8631.050584435707;8693.043765185825;8777.29781617469;8793.384463087586;8806.515641639708;5211.973940130275;5413.377461591928;5493.752781124859;5495.890082198333;5515.255440515519;3270.4930735735998;3365.1755319946524;3433.8454769535433;3579.176789338441;3602.5496812898386;1044.9059584637419;1053.6944286157004;1062.8193207154725;1067.4822710985165;1081.767420004695;11539.307641541534;11638;11789.58736444229;11793.469293881795;11819.704507387298;4584.243599806061;4649.256119020941;4733;4757.809687612456;4767.880802979884;9274.582643780912;9298.442093474414;9311.627534898527;9316.953415233016;9324.440533568008;5252.501012403815;5335.279737235423;5365.141577347606;5371.301730775052;5376.892462150735;4432;4610.377599024156;4800.039992001613;4962.702237865739;4998.5751211146735;3229.8856894371406;3844.0966372902344;3888.9499835044944;3927.548331941863;3968.3650615901315;1222.9816552751715;1232;1235.8516977962522;1250.3748125937032;1251.8247445357658;675.6925598852608;823.6664151018847;929.6839074714494;957.9760505987267;963.7831487915163;5776.335721392094;5787.524181333817;5913.994620914455;5947.256592925884;6028.095785632103;489.9461059283476;493.5410068636131;504.93183420237796;520.3859445853853;646.3956200952125;3852.152526444178;3864.8260828262796;4022.0749227677798;4048.4129801178665;4074.4499492568716;10387.740306492247;10863.728406789736;11027.61403350879;11030.724231894266;11045.33727976324;10816.891831081588;10830.512626931806;10851.511681974172;11054.447277636247;11056.336619802838;8198.959005204935;8201.015878094548;8288.958555207186;8322.583870806435;8346.081930987586;5613.46672066153;5831.446012628793;5841.702296907559;5955.434233747787;6090.665013424305;9469.242460602998;9948.552315145691;10240.334378265388;10271.229657775846;10299.176065914859;10260.948695256477;10326.967303269614;10377.896221037994;10482.63310784119;10484.947575262075;6882.621455819778;6928.376446119873;7009.808332583371;7018.368346848808;7038.788836334918;6848.3494068063455;8303.1281715419;8310.70912518059;8325.583720813815;8349.415540912078;6276.466500347522;7325.230850760593;7562.092548893947;7593.430492712958;7688.308052275098;7135.357817796424;7340.412766978522;7413.481056326221;7422.406207503494;7495.62521873915;6826.351496607815;6828.385445309946;6895;6916.066331045245;6916.792496225121;4807.855764327076;4871.244957031666;5030.0945829749535;5058.570021548209;5073.873153171053;5030.9245361320345;5077.949220507748;5097.209932460474;5128.897422051613;5277.525022747971 14:38:32 INFO - PROCESS | 1659 | |4;prototype.html;313.07018156076447;317.0393707067585;326.05769326646;326.34404846259014;328.6975982096471;5112.463191364905;5248.291480650111;5259.711370714175;5267.47325267473;5273.2617433559335;3867.7099217558657;4581.40441742573;4929.507049295069;5164.096283150452;5176.2235664650325;3857.9228415431744;3884.7662594008416;3946.6842652587866;4071.5570657605494;4079.51045874494;3382.6279109297957;3755.286495565834;3812.7902965336893;4036;4047.4738284023038;3847.365184744514;4015.7791321477307;4022.376531637586;4026.228459477835;4029.0459127323224;3869.742333741531;3929.2337994091163;3949.8025098744947;3988.504310883419;4068.0236743181717;3116.735077518391;3425.640307767673;3576.4635304704243;3586.7446393762234;3590.35373632746;5067.6452648314635;5071.2139618359215;5095.949040509585;5106.821261255838;5122.948770512322;4058.736182148151;4514.9774251128865;4579.656525760548;4666.626669866402;4667.393238878924;5412.621116521845;5432.538234250093;5482.314710661168;5487.862803429906;5527.2538207342295;3881.6506514413836;3892.4939757831585;3905.37513997759;3919.7844118573466;3923.3526468132723;5139.614528910347;5222.4255331913455;5518.668879867221;5725.856853578653;5839.357670656224;3962.6829853611644;3978.661813745864;3983.661388781928;3985.1631157456677;3995.6603688686787;3575.9450961965763;3711.4432835074954;4069.898252543651;4074.409210664496;4087.7751723654887;4495;4629.40285601467;4668.4631267404;4674.322223277606;4684.789184486706;4747.667663263538;5514.448555144453;5535.557155427556;5540.722963851791;5570.944290557042;1464.1507925403241;1479.8309335624735;1482.2885015192553;1485.264793927013;1487.018567745283;948.3456415073715;966.2415004221652;998.3810037776625;1009.7172791618359;1016.4968340671415;4379.978100109479;4921.630877684188;4926.556609905126;5019;5036.823711170092;4660.533946605312;4882.6826256293225;5621.90372877285;5630.352509461464;5633.802816901389;4341.869743907688;4413;5132.923006154911;5139.280500729901;5145.048166089286;2906.89825856094;3038.2404398900276;3261.706446419834;3277.4428347181038;3297.1921879139763;5381.560212824435;5770.798022069207;6066.484348830399;6485.675716214265;6547.508936829757;5690.772369105272;6106.72519736604;6171.228596425447;6434.227892652912;6446.452051575575;6444.838879027968;6445.097686323825;6467.870642587122;6475.449586785176;6499.7400103995315;4985.5762260208285;5116.6418350716585;5754.56840736938;6234.251889773256;6246.968765156145;4697.976510117427;4804.711717296974;5588.133839254922;5730.312362516524;5748.5688573358;5092.923606145837;5136.481510165549;5913.438223368773;5968.194293770459;6005.189299444519;3727.6645101940953;3783.2244389899456;3869.477620521306;3873.3802591586336;3909.4135879618284;5242.902838864485;5414.404415514212;5565.443455654402;5682.176084467812;5731.369549349485;4881.071976569307;5508.504234618904;5538.723063846711;5568.97215513936;5570;4835.395575553056;5050.787810925278;5308.575313974796;5481.698506582176;5565.30433695788;4582.358469814162;5148.510891465305;5379.058664733734;5425.891482170256;5442.782288708408;5043.5712964398435;5318.468153184651;5395.356275424935;5551.583631227593;5552.250446189874;5409.053415652166;5426.755795989269;5432.320959880015;5442.6462279951675;5468.28912241406 14:38:32 INFO - PROCESS | 1659 | |5;yui.html;665.7802925034739;714.2821464428247;726.6148941061233;776.4409625069951;786.6460092958166;10957.232993690444;11211.775764484726;11976.580819671313;12211.08416868735;12229.877701222986;14009.509667161652;14027.579172624837;14064.507742229023;14067.718645627081;14073.929630351833;733.7505248215606;747.4879707400421;902.5351943748974;932.6036434515338;934.2899396458706;907.133687328603;921.1893533690377;923.6813299411726;924.3252425729222;924.8057907839362;992.8014397120569;995.6515219673129;995.6714284286204;996.217968894418;1000.3747657713928;786.6813940354137;968.4770224078989;994.5027486256871;1001.884783249921;1005.8088963097061;739.5303981971433;745.8135466133467;747.5813544415145;750.1972889008763;750.6772088002157;14645.48740794067;14713;14736.631584210481;14745.92627036858;14762.63093422673;12345.13584049117;12439.626811195678;12704.745905081847;12740.745185096246;12749.087636854454;9863.358881672668;10062.597496100147;10208.081272685495;10373.636922707668;10418.739531511774;2835.432913417325;3529.876454324112;3553.4847447120023;3555.3244883472057;3689.7786132832116;70462;70790;71975.20061998439;71994.64002680006;72129.27870721278;8261.256486916207;8715.215630593277;9448.48033358172;9484.478353690476;10125.038121378457;21207;21439.249626263318;21494.570108598055;21507.892460537598;21545.353639390843;6570.901436478457;6767.390934815791;6824;6892.241853396223;6914.827129321807;4164.077993210632;4172.207280616673;4186.769727664947;4202.978985105116;4229.386738922839;3468.3063387322636;3514.929701406009;3537.0273174877116;3553.466979953028;3570.6786389225094;559.6502186133666;619.1610367951452;634.3846468925135;652.7193306878088;654.5221987948729;590.9734061967222;595.4640823259101;598.7485256192409;618.6659204029773;635.1076737184263;8124.918750812534;8291.461055031403;8389.790255243546;8438.662453501915;8487.912876974618;494.38449130832345;527.7994362142362;585.5783835638333;613.3590398034065;624.5440828195352;3842.634949679776;3916.3929590912885;4055.1889622075705;4083;4231.661467082626;90283.54858225667;91068.54465727761;93049.53475232581;94269;97435.51282243685;82967.17032829595;83522;83646;83901.70737194734;84576;61585.46036349127;74756.62621686856;75038;76210.61894690602;76493.6175319131;8110.959445202736;8276.875846862182;8400.705975290835;8419.284360829399;8464.44981076228;45212.32181517214;45585.77207113943;45625.859353516425;45766.77116614395;47235;61173.08240376309;61304;62597.68701156465;63012.054819178666;63089.84550772351;11674.278379749872;11740.647780566596;11797.29216247028;11952.402379880798;11996;12036.699082523008;12290.139690221598;12412.193207441487;12782.48870045225;13074.084814062924;9141;10890.782184356427;11188.72028199269;11616.419179041182;11672.299662020307;10056.34633748804;11470.885291147315;11613.709657258636;11687.88312116868;12281.570145044881;11252.83120753173;11480.483378248166;11581.305121692725;11724.413779311171;11900.583479578174;9003.324750643596;9007.549622518978;9442.575084121368;9709.9029009709;10010.849837252592;9654.324197306121;9766.121049105615;9947.900520994697;9982.850257246293;10004.749881253027 14:38:32 INFO - PROCESS | 1659 | __end_tp_report 14:38:32 INFO - PROCESS | 1659 | __start_cc_report 14:38:32 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1078 14:38:32 INFO - PROCESS | 1659 | __end_cc_report 14:38:32 INFO - PROCESS | 1659 | __startTimestamp1462484312877__endTimestamp 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | ------- Summary: start ------- 14:38:32 INFO - PROCESS | 1659 | Number of tests: 6 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5714.51 Median:5871.41 stddev:646.83 (11.0%) stddev-sans-first:609.51 14:38:32 INFO - PROCESS | 1659 | Values: 2883.9 3453.9 3459.7 3465.2 3523.7 5670.7 5685.4 5704.5 5710.5 5712.0 4765.9 5733.8 5740.1 5755.5 5821.5 5082.2 5131.2 5217.1 5219.4 5281.7 5247.6 5268.3 5269.2 5271.0 5274.2 5168.8 5209.5 5247.3 5255.8 5274.8 5200.3 5201.7 5229.0 5236.5 5267.8 5088.2 5092.8 5129.1 5160.0 5166.3 5498.8 5527.9 5542.5 5559.9 5590.9 5235.9 6236.6 6254.5 6270.9 6274.9 5654.8 6236.7 6259.5 6283.0 6288.1 5795.6 5822.7 5823.1 5870.9 5871.4 6265.8 6284.4 6292.2 6296.9 6304.2 5089.5 6014.0 6067.3 6165.4 6166.3 6256.5 6296.4 6296.7 6310.5 6318.2 6162.8 6209.9 6219.5 6225.0 6225.3 6067.6 6130.2 6154.1 6219.5 6261.6 4848.1 4850.2 4877.5 4884.9 4890.5 4393.6 4403.6 4417.6 4423.3 4453.8 5679.3 5728.6 5729.3 5732.9 5734.5 6130.2 6229.3 6281.4 6322.9 6340.2 5621.0 5685.5 5697.9 5703.3 5716.2 5374.5 5375.9 5380.3 5383.5 5387.5 6212.7 6247.9 6299.1 6301.7 6323.5 6215.3 6294.7 6301.6 6316.0 6320.8 6313.7 6317.2 6334.6 6344.4 6352.5 6005.3 6179.6 6180.1 6194.0 6200.0 6187.7 6223.1 6226.9 6295.2 6316.1 6012.2 6053.8 6054.1 6060.6 6095.8 5958.9 6011.1 6039.5 6051.2 6054.2 5727.3 5842.3 5885.1 5898.6 5932.4 6056.0 6060.4 6075.3 6079.9 6095.8 5676.6 5777.1 5850.7 5910.5 5978.9 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:67243.38 Median:19660.55 stddev:147593.04 (750.7%) stddev-sans-first:147922.02 14:38:32 INFO - PROCESS | 1659 | Values: 506.7 535.4 585.7 595.6 618.5 30080.7 31204.8 38870.4 39733.4 39843.4 44114.0 44808.8 44820.8 44846.0 44848.0 9499.1 9733.0 9736.7 9737.8 9739.4 8401.8 8622.0 11142.1 11370.3 11379.3 10517.9 10672.7 11324.5 11480.4 11502.5 8385.3 11175.6 11303.2 11497.5 11516.5 11291.5 11454.9 11455.0 11457.9 11460.8 38848.8 39177.0 39230.2 39290.8 39340.8 3321.4 3328.2 3657.5 3722.7 3840.2 26876.5 28940.0 29160.6 29294.8 29576.0 2734.4 2734.4 2785.8 2802.2 2821.1 499816.0 500362.0 501256.5 504665.0 505575.0 34593.7 63282.7 63748.4 63838.4 63947.0 30024.5 35807.3 35836.8 35921.6 35993.8 15297.8 15733.0 15735.5 15757.3 15762.8 11778.8 12003.7 12838.0 13124.4 13141.0 8171.0 8206.8 8217.9 8282.6 8303.8 3430.7 3464.6 3471.8 3481.7 3513.8 966.4 989.6 1020.4 1033.5 1057.5 26141.3 29159.0 29742.7 29887.4 30571.5 764.4 1120.2 1123.1 1147.1 1208.8 15062.1 15300.5 15608.0 15956.3 15957.8 661439.0 667924.0 673972.0 675974.0 680536.0 62052.7 62744.0 62917.4 62929.0 63008.0 1384.4 1433.1 1536.0 1561.0 1561.9 29888.6 30805.5 31504.2 31937.7 32064.8 54712.5 55778.7 56306.7 56426.0 56503.4 451883.0 452103.0 459681.0 460826.0 461163.0 32031.5 33057.7 33834.0 34139.8 41461.8 34450.5 34993.0 45905.9 46133.8 46300.8 35866.6 36755.6 37629.6 37793.6 39076.0 36929.8 37300.8 37483.9 38019.2 38074.8 37909.9 38591.2 39233.0 39814.0 39911.6 32617.8 33961.3 34687.8 34716.7 34746.1 22691.9 25575.1 25744.7 25996.5 26258.9 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33546.69 Median:410.32 stddev:95707.92 (23325.3%) stddev-sans-first:95944.05 14:38:32 INFO - PROCESS | 1659 | Values: 404.3 409.3 411.3 412.0 418.0 19717.9 20290.6 20628.9 20644.2 21295.0 10100.9 11977.3 12436.3 12668.2 12722.3 5444.2 5541.9 5616.7 5621.2 5648.8 5834.4 6163.4 6165.8 6175.7 6179.1 6089.3 6147.4 6159.4 6173.9 6180.7 5972.5 6125.8 6126.5 6161.4 6180.0 5781.9 5966.0 5979.9 6002.9 6008.6 13708.7 14017.7 14180.2 14247.6 14283.4 1850.6 2207.4 2297.5 2311.3 2337.6 9964.2 10054.5 10192.7 10509.1 10522.7 1693.8 1718.2 1758.9 1780.7 1794.7 122126.0 127719.0 127970.5 128066.0 131212.0 11749.7 11809.8 11846.8 11849.8 11861.6 15722.1 15764.1 15820.6 15832.0 15835.4 9041.0 9096.2 9113.0 9138.3 9243.1 9042.4 9042.8 9051.0 9171.7 9173.3 4721.6 4749.7 4757.5 4770.4 4811.9 1668.6 1925.4 1983.8 1985.1 1985.7 398.2 712.5 740.3 952.3 1060.7 11681.0 12034.3 12250.5 12410.6 12489.9 445.5 463.6 463.8 468.8 471.4 6246.1 6259.5 6328.5 6350.2 6430.5 486414.0 512369.0 518535.0 647431.0 651637.0 128911.1 134864.0 138521.0 139336.6 140227.0 1013.8 1024.6 1058.9 1059.7 1062.3 18530.4 18811.0 19186.9 19226.2 19400.4 43475.3 51287.2 53314.7 53440.5 53658.0 73267.3 74504.0 74617.6 75528.6 78657.6 8032.6 8431.6 8496.4 8729.5 8911.5 12268.4 12390.0 12787.2 13068.8 13074.0 10973.6 11165.5 11499.6 11506.9 11610.7 8096.9 10862.1 11387.4 11432.0 11439.7 8935.9 10758.3 10926.3 11674.4 11971.0 8430.5 8543.5 8577.1 8730.9 8860.3 8042.1 8072.7 8077.9 8258.7 8277.9 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5669.89 Median:4980.64 stddev:3197.06 (64.2%) stddev-sans-first:3189.78 14:38:32 INFO - PROCESS | 1659 | Values: 1379.8 1397.8 1563.4 1777.2 1986.0 7425.3 7876.5 8601.8 8820.4 8828.6 7487.9 8268.7 8644.8 8663.1 8670.3 2427.4 2458.8 2460.4 2461.2 2461.4 2394.6 2460.1 2461.1 2465.2 2466.1 2400.8 2424.5 2438.2 2441.0 2442.6 2368.0 2413.5 2423.4 2438.5 2441.7 1960.4 2417.8 2420.1 2424.6 2425.2 8631.1 8693.0 8777.3 8793.4 8806.5 5212.0 5413.4 5493.8 5495.9 5515.3 3270.5 3365.2 3433.8 3579.2 3602.5 1044.9 1053.7 1062.8 1067.5 1081.8 11539.3 11638.0 11789.6 11793.5 11819.7 4584.2 4649.3 4733.0 4757.8 4767.9 9274.6 9298.4 9311.6 9317.0 9324.4 5252.5 5335.3 5365.1 5371.3 5376.9 4432.0 4610.4 4800.0 4962.7 4998.6 3229.9 3844.1 3888.9 3927.5 3968.4 1223.0 1232.0 1235.9 1250.4 1251.8 675.7 823.7 929.7 958.0 963.8 5776.3 5787.5 5914.0 5947.3 6028.1 489.9 493.5 504.9 520.4 646.4 3852.2 3864.8 4022.1 4048.4 4074.4 10387.7 10863.7 11027.6 11030.7 11045.3 10816.9 10830.5 10851.5 11054.4 11056.3 8199.0 8201.0 8289.0 8322.6 8346.1 5613.5 5831.4 5841.7 5955.4 6090.7 9469.2 9948.6 10240.3 10271.2 10299.2 10260.9 10327.0 10377.9 10482.6 10484.9 6882.6 6928.4 7009.8 7018.4 7038.8 6848.3 8303.1 8310.7 8325.6 8349.4 6276.5 7325.2 7562.1 7593.4 7688.3 7135.4 7340.4 7413.5 7422.4 7495.6 6826.4 6828.4 6895.0 6916.1 6916.8 4807.9 4871.2 5030.1 5058.6 5073.9 5030.9 5077.9 5097.2 5128.9 5277.5 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4548.60 Median:5040.20 stddev:1380.15 (27.4%) stddev-sans-first:1346.91 14:38:32 INFO - PROCESS | 1659 | Values: 313.1 317.0 326.1 326.3 328.7 5112.5 5248.3 5259.7 5267.5 5273.3 3867.7 4581.4 4929.5 5164.1 5176.2 3857.9 3884.8 3946.7 4071.6 4079.5 3382.6 3755.3 3812.8 4036.0 4047.5 3847.4 4015.8 4022.4 4026.2 4029.0 3869.7 3929.2 3949.8 3988.5 4068.0 3116.7 3425.6 3576.5 3586.7 3590.4 5067.6 5071.2 5095.9 5106.8 5122.9 4058.7 4515.0 4579.7 4666.6 4667.4 5412.6 5432.5 5482.3 5487.9 5527.3 3881.7 3892.5 3905.4 3919.8 3923.4 5139.6 5222.4 5518.7 5725.9 5839.4 3962.7 3978.7 3983.7 3985.2 3995.7 3575.9 3711.4 4069.9 4074.4 4087.8 4495.0 4629.4 4668.5 4674.3 4684.8 4747.7 5514.4 5535.6 5540.7 5570.9 1464.2 1479.8 1482.3 1485.3 1487.0 948.3 966.2 998.4 1009.7 1016.5 4380.0 4921.6 4926.6 5019.0 5036.8 4660.5 4882.7 5621.9 5630.4 5633.8 4341.9 4413.0 5132.9 5139.3 5145.0 2906.9 3038.2 3261.7 3277.4 3297.2 5381.6 5770.8 6066.5 6485.7 6547.5 5690.8 6106.7 6171.2 6434.2 6446.5 6444.8 6445.1 6467.9 6475.4 6499.7 4985.6 5116.6 5754.6 6234.3 6247.0 4698.0 4804.7 5588.1 5730.3 5748.6 5092.9 5136.5 5913.4 5968.2 6005.2 3727.7 3783.2 3869.5 3873.4 3909.4 5242.9 5414.4 5565.4 5682.2 5731.4 4881.1 5508.5 5538.7 5569.0 5570.0 4835.4 5050.8 5308.6 5481.7 5565.3 4582.4 5148.5 5379.1 5425.9 5442.8 5043.6 5318.5 5395.4 5551.6 5552.3 5409.1 5426.8 5432.3 5442.6 5468.3 14:38:32 INFO - PROCESS | 1659 | 14:38:32 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17957.98 Median:30885.92 stddev:25344.55 (82.1%) stddev-sans-first:25382.39 14:38:32 INFO - PROCESS | 1659 | Values: 665.8 714.3 726.6 776.4 786.6 10957.2 11211.8 11976.6 12211.1 12229.9 14009.5 14027.6 14064.5 14067.7 14073.9 733.8 747.5 902.5 932.6 934.3 907.1 921.2 923.7 924.3 924.8 992.8 995.7 995.7 996.2 1000.4 786.7 968.5 994.5 1001.9 1005.8 739.5 745.8 747.6 750.2 750.7 14645.5 14713.0 14736.6 14745.9 14762.6 12345.1 12439.6 12704.7 12740.7 12749.1 9863.4 10062.6 10208.1 10373.6 10418.7 2835.4 3529.9 3553.5 3555.3 3689.8 70462.0 70790.0 71975.2 71994.6 72129.3 8261.3 8715.2 9448.5 9484.5 10125.0 21207.0 21439.2 21494.6 21507.9 21545.4 6570.9 6767.4 6824.0 6892.2 6914.8 4164.1 4172.2 4186.8 4203.0 4229.4 3468.3 3514.9 3537.0 3553.5 3570.7 559.7 619.2 634.4 652.7 654.5 591.0 595.5 598.7 618.7 635.1 8124.9 8291.5 8389.8 8438.7 8487.9 494.4 527.8 585.6 613.4 624.5 3842.6 3916.4 4055.2 4083.0 4231.7 90283.5 91068.5 93049.5 94269.0 97435.5 82967.2 83522.0 83646.0 83901.7 84576.0 61585.5 74756.6 75038.0 76210.6 76493.6 8111.0 8276.9 8400.7 8419.3 8464.4 45212.3 45585.8 45625.9 45766.8 47235.0 61173.1 61304.0 62597.7 63012.1 63089.8 11674.3 11740.6 11797.3 11952.4 11996.0 12036.7 12290.1 12412.2 12782.5 13074.1 9141.0 10890.8 11188.7 11616.4 11672.3 10056.3 11470.9 11613.7 11687.9 12281.6 11252.8 11480.5 11581.3 11724.4 11900.6 9003.3 9007.5 9442.6 9709.9 10010.8 9654.3 9766.1 9947.9 9982.9 10004.7 14:38:32 INFO - PROCESS | 1659 | -------- Summary: end -------- 14:38:32 INFO - PROCESS | 1659 | 14:38:33 INFO - TEST-INFO | 1659: exit 0 14:38:33 INFO - TEST-OK | dromaeo_css | took 1115476ms 14:38:33 INFO - TEST-START | kraken 14:38:33 INFO - operating with platform_type : mac_ 14:38:33 INFO - Initialising browser for kraken test... 14:38:33 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpAUdnKn/profile http://localhost:49270/getInfo.html) 14:38:35 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 14:38:35 INFO - PROCESS | 1669 | colorDepth:24 14:38:35 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 14:38:35 INFO - PROCESS | 1669 | __metrics 14:38:36 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:38:36 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:38:36 INFO - TEST-INFO | 1669: exit 0 14:38:36 INFO - Browser initialized. 14:38:36 INFO - Running cycle 1/1 for kraken test... 14:38:36 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpAUdnKn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:48 INFO - PROCESS | 1671 | RSS: Main: 211398656 14:38:48 INFO - PROCESS | 1671 | 14:38:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:52 INFO - PROCESS | 1671 | RSS: Main: 269594624 14:38:52 INFO - PROCESS | 1671 | 14:38:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 14:38:55 INFO - PROCESS | 1671 | RSS: Main: 284250112 14:38:55 INFO - PROCESS | 1671 | 14:38:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 14:38:59 INFO - PROCESS | 1671 | RSS: Main: 371056640 14:38:59 INFO - PROCESS | 1671 | 14:39:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 14:39:02 INFO - PROCESS | 1671 | RSS: Main: 277565440 14:39:02 INFO - PROCESS | 1671 | 14:39:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:04 INFO - PROCESS | 1671 | RSS: Main: 277860352 14:39:04 INFO - PROCESS | 1671 | 14:39:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:08 INFO - PROCESS | 1671 | RSS: Main: 316190720 14:39:08 INFO - PROCESS | 1671 | 14:39:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:11 INFO - PROCESS | 1671 | RSS: Main: 316878848 14:39:11 INFO - PROCESS | 1671 | 14:39:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 14:39:15 INFO - PROCESS | 1671 | RSS: Main: 316719104 14:39:15 INFO - PROCESS | 1671 | 14:39:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:17 INFO - PROCESS | 1671 | RSS: Main: 307204096 14:39:17 INFO - PROCESS | 1671 | 14:39:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:19 INFO - PROCESS | 1671 | RSS: Main: 313528320 14:39:19 INFO - PROCESS | 1671 | 14:39:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:22 INFO - PROCESS | 1671 | RSS: Main: 316604416 14:39:22 INFO - PROCESS | 1671 | 14:39:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:26 INFO - PROCESS | 1671 | RSS: Main: 322129920 14:39:26 INFO - PROCESS | 1671 | 14:39:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:29 INFO - PROCESS | 1671 | RSS: Main: 320180224 14:39:29 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 14:39:31 INFO - PROCESS | 1671 | RSS: Main: 384696320 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | __start_tp_report 14:39:31 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 14:39:31 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 14:39:31 INFO - PROCESS | 1671 | |i|pagename|runs| 14:39:31 INFO - PROCESS | 1671 | |0;ai-astar;114;115;108;115;112;117;115;113;104;114 14:39:31 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;133;191;190;123;139;120;153;158;142 14:39:31 INFO - PROCESS | 1671 | |2;audio-dft;185;183;189;187;169;249;262;201;186;186 14:39:31 INFO - PROCESS | 1671 | |3;audio-fft;74;76;75;153;57;56;57;66;133;55 14:39:31 INFO - PROCESS | 1671 | |4;audio-oscillator;90;92;142;78;85;91;125;79;75;124 14:39:31 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;84;88;83;88;84;86;84;87;84 14:39:31 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;92;92;101;148;133;93;93;92;92 14:39:31 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;89;86;87;85;86;86;85;87;86 14:39:31 INFO - PROCESS | 1671 | |8;json-parse-financial;57;60;63;63;64;64;64;80;59;57 14:39:31 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;63;62;72;73;69;67;67;67;67 14:39:31 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;80;82;81;80;80;80;79;83;85 14:39:31 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;136;138;136;133;143;150;136;135;173;135 14:39:31 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;167;169;168;167;166;167;167;170;167;168 14:39:31 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;68;67;67;68;68;66;69;67;66 14:39:31 INFO - PROCESS | 1671 | __end_tp_report 14:39:31 INFO - PROCESS | 1671 | __start_cc_report 14:39:31 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2022 14:39:31 INFO - PROCESS | 1671 | __end_cc_report 14:39:31 INFO - PROCESS | 1671 | __startTimestamp1462484371510__endTimestamp 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | ------- Summary: start ------- 14:39:31 INFO - PROCESS | 1671 | Number of tests: 14 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.50 stddev:3.89 (3.4%) stddev-sans-first:4.10 14:39:31 INFO - PROCESS | 1671 | Values: 114.0 115.0 108.0 115.0 112.0 117.0 115.0 113.0 104.0 114.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:150.40 Median:154.00 stddev:24.68 (16.0%) stddev-sans-first:26.12 14:39:31 INFO - PROCESS | 1671 | Values: 155.0 133.0 191.0 190.0 123.0 139.0 120.0 153.0 158.0 142.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:199.70 Median:188.00 stddev:30.55 (16.3%) stddev-sans-first:31.94 14:39:31 INFO - PROCESS | 1671 | Values: 185.0 183.0 189.0 187.0 169.0 249.0 262.0 201.0 186.0 186.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:80.20 Median:61.50 stddev:34.43 (56.0%) stddev-sans-first:36.45 14:39:31 INFO - PROCESS | 1671 | Values: 74.0 76.0 75.0 153.0 57.0 56.0 57.0 66.0 133.0 55.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:82.00 stddev:23.45 (28.6%) stddev-sans-first:24.69 14:39:31 INFO - PROCESS | 1671 | Values: 90.0 92.0 142.0 78.0 85.0 91.0 125.0 79.0 75.0 124.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.90 (2.2%) stddev-sans-first:1.94 14:39:31 INFO - PROCESS | 1671 | Values: 87.0 84.0 88.0 83.0 88.0 84.0 86.0 84.0 87.0 84.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:92.00 stddev:20.37 (22.1%) stddev-sans-first:21.22 14:39:31 INFO - PROCESS | 1671 | Values: 92.0 92.0 92.0 101.0 148.0 133.0 93.0 93.0 92.0 92.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.00 stddev:1.23 (1.4%) stddev-sans-first:1.22 14:39:31 INFO - PROCESS | 1671 | Values: 85.0 89.0 86.0 87.0 85.0 86.0 86.0 85.0 87.0 86.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.50 stddev:6.57 (10.4%) stddev-sans-first:6.59 14:39:31 INFO - PROCESS | 1671 | Values: 57.0 60.0 63.0 63.0 64.0 64.0 64.0 80.0 59.0 57.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:3.57 (5.3%) stddev-sans-first:3.61 14:39:31 INFO - PROCESS | 1671 | Values: 64.0 63.0 62.0 72.0 73.0 69.0 67.0 67.0 67.0 67.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.50 stddev:1.81 (2.2%) stddev-sans-first:1.90 14:39:31 INFO - PROCESS | 1671 | Values: 82.0 80.0 82.0 81.0 80.0 80.0 80.0 79.0 83.0 85.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:137.00 stddev:12.14 (8.9%) stddev-sans-first:12.71 14:39:31 INFO - PROCESS | 1671 | Values: 136.0 138.0 136.0 133.0 143.0 150.0 136.0 135.0 173.0 135.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:167.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 14:39:31 INFO - PROCESS | 1671 | Values: 167.0 169.0 168.0 167.0 166.0 167.0 167.0 170.0 167.0 168.0 14:39:31 INFO - PROCESS | 1671 | 14:39:31 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 14:39:31 INFO - PROCESS | 1671 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 66.0 69.0 67.0 66.0 14:39:31 INFO - PROCESS | 1671 | -------- Summary: end -------- 14:39:31 INFO - PROCESS | 1671 | 14:39:32 INFO - TEST-INFO | 1671: exit 0 14:39:32 INFO - TEST-OK | kraken | took 58803ms 14:39:32 INFO - SUITE-END | took 1174s 14:39:32 INFO - Completed test suite (00:19:34) 14:39:32 INFO - javascript benchmark 14:39:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2883.942321153577, 3453.86184552618, 3459.705924996375, 3465.1510379956912, 3523.718102551796, 5670.688112153835, 5685.374608793029, 5704.54363650908, 5710.514606258463, 5712.028955077636, 4765.8808529786775, 5733.827985160452, 5740.138979153129, 5755.496219399592, 5821.476067153955, 5082.186850103984, 5131.204663277198, 5217.113090774568, 5219.42586315505, 5281.735913204344, 5247.553957913582, 5268.31511903452, 5269.1832765921345, 5271.0, 5274.235235890794, 5168.819091331804, 5209.479052094798, 5247.317848679666, 5255.842324730245, 5274.81538146165, 5200.349956255487, 5201.661891977009, 5228.980348831979, 5236.476352364771, 5267.762950667229, 5088.23676448532, 5092.770825312869, 5129.0767661820855, 5160.0, 5166.328377310963, 5498.780048798043, 5527.922055199238, 5542.473465020817, 5559.861003474945, 5590.888182236373, 5235.895282094337, 6236.594621349598, 6254.499640028786, 6270.905936410957, 6274.937250627481, 5654.773809047633, 6236.656983865885, 6259.499240060784, 6282.96858515709, 6288.088227207029, 5795.623284486495, 5822.650640961556, 5823.068309070529, 5870.941290587082, 5871.412858714094, 6265.780697675559, 6284.402981716729, 6292.182016337847, 6296.874062518816, 6304.243490781044, 5089.491050894955, 6014.0, 6067.302260240041, 6165.383461653889, 6166.260048794084, 6256.468200202943, 6296.401841825019, 6296.653684047421, 6310.4636105930595, 6318.210223722035, 6162.815115546541, 6209.9068513972325, 6219.533534984895, 6224.972879474836, 6225.28409232944, 6067.635941843503, 6130.153553065376, 6154.076888466766, 6219.47134493564, 6261.592996455307, 4848.10310092634, 4850.151223535867, 4877.512248775094, 4884.902301953941, 4890.510948905152, 4393.582609652078, 4403.647708183337, 4417.646588272931, 4423.336499525097, 4453.777311134495, 5679.346875109415, 5728.570357223225, 5729.25519682447, 5732.942670573324, 5734.455226753452, 6130.184124957599, 6229.2524897012645, 6281.403266689657, 6322.9051564226575, 6340.207474065742, 5621.044422448112, 5685.516731077904, 5697.886042279131, 5703.287089113861, 5716.1997320375185, 5374.543163831118, 5375.946240537544, 5380.2736630555155, 5383.515483606494, 5387.542058924957, 6212.689365531795, 6247.9062814056915, 6299.055141728777, 6301.653409062362, 6323.52573556985, 6215.316315205414, 6294.748210071729, 6301.558890877778, 6315.989441689308, 6320.810375688737, 6313.715882785377, 6317.241930968313, 6334.619922804646, 6344.365563443619, 6352.460040896575, 6005.309389420097, 6179.598326108789, 6180.103884936749, 6194.0, 6199.969000155151, 6187.721552530057, 6223.097650840693, 6226.937730622636, 6295.150154729053, 6316.11574379578, 6012.218411606463, 6053.818385448268, 6054.091886217103, 6060.575759696955, 6095.75616975316, 5958.880822383615, 6011.068284415923, 6039.5470339723815, 6051.213342265477, 6054.1826853376, 5727.284089488814, 5842.3281322646735, 5885.117232415172, 5898.616589921813, 5932.406759324033, 6055.969720151371, 6060.424259695322, 6075.301340345917, 6079.878402432015, 6095.847603809941, 5676.631018983753, 5777.133429985536, 5850.707464626837, 5910.497607703393, 5978.940210597838], "value": 5673.380952463571, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.6782593053412, 535.3709391465028, 585.7393459910342, 595.6158277910749, 618.4526693875919, 30080.699193008062, 31204.843975780117, 38870.41694374583, 39733.40399893997, 39843.402348964715, 44114.0, 44808.775956120255, 44820.775896120474, 44846.0, 44848.0, 9499.050094990498, 9733.0, 9736.707898763048, 9737.805243895118, 9739.41563506192, 8401.83196336073, 8621.95689021554, 11142.108631309477, 11370.31778093317, 11379.26034807739, 10517.895620959804, 10672.733181670443, 11324.547018119307, 11480.368579728154, 11502.482388292547, 8385.287250583708, 11175.552977880876, 11303.208775385727, 11497.48261328238, 11516.539338426453, 11291.548338066468, 11454.94272528632, 11454.969052785289, 11457.942710286396, 11460.770784584345, 38848.80575597132, 39177.0, 39230.21539569221, 39290.80354598209, 39340.80329598362, 3321.4353559894876, 3328.1679580104974, 3657.5062366580437, 3722.7021838252876, 3840.2127563849344, 26876.462470750477, 28939.987100451595, 29160.56259156114, 29294.75226092226, 29576.0, 2734.3847634282324, 2734.398432344901, 2785.8049936504453, 2802.173358859121, 2821.1254511101624, 499816.0, 500362.0, 501256.49371752905, 504665.0, 505575.0, 34593.654063459544, 63282.68358658177, 63748.36251637517, 63838.36161638417, 63947.0, 30024.54963175554, 35807.28385432329, 35836.82081589576, 35921.64078359235, 35993.820030899675, 15297.770533442008, 15733.0, 15735.527934161993, 15757.290921908541, 15762.76355854663, 11778.764424711459, 12003.699907502383, 12837.972962163005, 13124.409401576951, 13141.0, 8171.0194776625995, 8206.753797386087, 8217.917820821835, 8282.627281772335, 8303.750887473385, 3430.7255419566377, 3464.566929133858, 3471.809050502196, 3481.7040551552896, 3513.8243087845503, 966.415318732157, 989.5744829723144, 1020.3724709303873, 1033.4729288063143, 1057.4554104636118, 26141.346466338113, 29159.0, 29742.702572973994, 29887.402251954838, 30571.541426878615, 764.3923081150499, 1120.2270433400927, 1123.1436104449267, 1147.1167201254987, 1208.8368070310396, 15062.096274223582, 15300.540983770505, 15608.0, 15956.2819673115, 15957.760633590506, 661439.0, 667924.0, 673972.0, 675974.0, 680536.0, 62052.68973655193, 62744.0, 62917.37082629207, 62929.0, 63008.0, 1384.4116250593459, 1433.1472773699631, 1535.9692806143814, 1561.0399604243535, 1561.93752249911, 29888.551671725378, 30805.537916931713, 31504.212394690316, 31937.68062319347, 32064.83967580147, 54712.452875470735, 55778.72110639421, 56306.71846640741, 56426.0, 56503.434965649816, 451883.0, 452103.0, 459681.0, 460826.0, 461163.0, 32031.519527206645, 33057.66942330546, 33833.95494405417, 34139.82930085334, 41461.75614731563, 34450.48324275188, 34993.0, 45905.85235369009, 46133.76933115313, 46300.7684961573, 35866.64133358737, 36755.63244367522, 37629.62370376261, 37793.62206377901, 39076.02309942274, 36929.815350924146, 37300.81349593234, 37483.93838338918, 38019.23961520809, 38074.80962595169, 37909.86270411892, 38591.228175436896, 39233.0, 39814.0, 39911.60088399195, 32617.836910815295, 33961.320773584885, 34687.82656086703, 34716.652833471344, 34746.13134671653, 22691.907428514533, 25575.104871329415, 25744.742552574233, 25996.480070398866, 26258.86870565635], "value": 19112.465914916324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.31266846361183, 409.3287009304739, 411.30900087852416, 411.9794010299485, 417.9644730197933, 19717.901410492945, 20290.59418811623, 20628.896855515737, 20644.17423303068, 21295.0, 10100.898991010088, 11977.28136311822, 12436.316002619853, 12668.239905605647, 12722.300273484954, 5444.1561557958385, 5541.861453463675, 5616.691081990489, 5621.241132447129, 5648.830535083934, 5834.445727655887, 6163.414475624831, 6165.845853853644, 6175.691215439232, 6179.134921111048, 6089.269287685462, 6147.446729794317, 6159.38406159385, 6173.938260617404, 6180.660063696518, 5972.522198224154, 6125.754969801203, 6126.509879209652, 6161.445469907708, 6180.042093475496, 5781.8725348557045, 5966.0, 5979.880402391971, 6002.879942401171, 6008.6094403864045, 13708.65728356789, 14017.719645607132, 14180.22008789516, 14247.572572822832, 14283.428662853474, 1850.6298740251868, 2207.404000919759, 2297.4715815362392, 2311.31816114245, 2337.614293641547, 9964.20286377088, 10054.497275136215, 10192.745181370525, 10509.106725928305, 10522.736931576695, 1693.7713408689915, 1718.19244954871, 1758.909476124798, 1780.7061834797244, 1794.6590147871993, 122126.0, 127719.0, 127970.51860987826, 128066.0, 131212.0, 11749.706257343634, 11809.822852657217, 11846.822297665542, 11849.822252666218, 11861.5848445304, 15722.13528255957, 15764.132972686612, 15820.60448488797, 15832.0, 15835.366585336458, 9041.0, 9096.1813436791, 9112.952010518742, 9138.268938484905, 9243.121903419164, 9042.367034307666, 9042.773930651654, 9051.0, 9171.724848254564, 9173.312001599907, 4721.551452611996, 4749.738764367993, 4757.502311286533, 4770.427548694179, 4811.893264549173, 1668.5828542864283, 1925.3550060729533, 1983.8313743331978, 1985.1463870535808, 1985.6525108106023, 398.2473125792255, 712.4611530803318, 740.3410964241829, 952.3476418653245, 1060.671191930504, 11681.007114395201, 12034.277943323428, 12250.509979600894, 12410.5656302029, 12489.875101249054, 445.54999450554527, 463.55498721227326, 463.84461205496535, 468.8171613070905, 471.44134200971274, 6246.125542424064, 6259.4679452246155, 6328.493720502349, 6350.174477317919, 6430.485561155097, 486414.0, 512369.0, 518535.0, 647431.0, 651637.0, 128911.06633400507, 134864.0, 138521.0, 139336.6066339344, 140227.0, 1013.7566983923805, 1024.6208902705898, 1058.872935247775, 1059.7085801404676, 1062.3466568060587, 18530.44408667742, 18811.0, 19186.904065479583, 19226.230950762372, 19400.417987460398, 43475.26098956006, 51287.23069153891, 53314.73342633262, 53440.46559534355, 53658.0, 73267.26732732604, 74504.0, 74617.6269118651, 75528.62235688786, 78657.60671196607, 8032.558209298545, 8431.620577073923, 8496.362772791941, 8729.51987640686, 8911.50986695738, 12268.44791984345, 12390.0, 12787.232766034067, 13068.8039679403, 13074.019448541207, 10973.561057557929, 11165.497552610315, 11499.597514086967, 11506.884931150917, 11610.6516804496, 8096.919030809617, 10862.131029517777, 11387.373694446886, 11432.0, 11439.714007149887, 8935.910640893508, 10758.300710453796, 10926.289791163867, 11674.416279185836, 11971.042316614476, 8430.45202061864, 8543.530105844238, 8577.142285771373, 8730.91269087301, 8860.291176705978, 8042.064040314518, 8072.717454889051, 8077.87883181741, 8258.710945116893, 8277.917220827714], "value": 8853.16409910358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.7654398752213, 1397.8112954751105, 1563.405905755813, 1777.2091419318401, 1986.0069965017492, 7425.257474252572, 7876.527408355496, 8601.827963440728, 8820.382573219877, 8828.646854125844, 7487.876818477231, 8268.710595129172, 8644.827103457927, 8663.090375510576, 8670.306375489961, 2427.4295540548, 2458.791002764767, 2460.3972026853444, 2461.1878080233478, 2461.4092617771694, 2394.6048901931163, 2460.052879641342, 2461.1386014894733, 2465.2111324376206, 2466.05057053035, 2400.7839294463583, 2424.4787370715294, 2438.195395519483, 2440.9755902441016, 2442.572549803786, 2368.017272831773, 2413.54142712884, 2423.430493833948, 2438.5488684593406, 2441.6581678564926, 1960.3824795189469, 2417.7703118203744, 2420.1287536486852, 2424.5878200705924, 2425.2360506440414, 8631.050584435707, 8693.043765185825, 8777.29781617469, 8793.384463087586, 8806.515641639708, 5211.973940130275, 5413.377461591928, 5493.752781124859, 5495.890082198333, 5515.255440515519, 3270.4930735735998, 3365.1755319946524, 3433.8454769535433, 3579.176789338441, 3602.5496812898386, 1044.9059584637419, 1053.6944286157004, 1062.8193207154725, 1067.4822710985165, 1081.767420004695, 11539.307641541534, 11638.0, 11789.58736444229, 11793.469293881795, 11819.704507387298, 4584.243599806061, 4649.256119020941, 4733.0, 4757.809687612456, 4767.880802979884, 9274.582643780912, 9298.442093474414, 9311.627534898527, 9316.953415233016, 9324.440533568008, 5252.501012403815, 5335.279737235423, 5365.141577347606, 5371.301730775052, 5376.892462150735, 4432.0, 4610.377599024156, 4800.039992001613, 4962.702237865739, 4998.5751211146735, 3229.8856894371406, 3844.0966372902344, 3888.9499835044944, 3927.548331941863, 3968.3650615901315, 1222.9816552751715, 1232.0, 1235.8516977962522, 1250.3748125937032, 1251.8247445357658, 675.6925598852608, 823.6664151018847, 929.6839074714494, 957.9760505987267, 963.7831487915163, 5776.335721392094, 5787.524181333817, 5913.994620914455, 5947.256592925884, 6028.095785632103, 489.9461059283476, 493.5410068636131, 504.93183420237796, 520.3859445853853, 646.3956200952125, 3852.152526444178, 3864.8260828262796, 4022.0749227677798, 4048.4129801178665, 4074.4499492568716, 10387.740306492247, 10863.728406789736, 11027.61403350879, 11030.724231894266, 11045.33727976324, 10816.891831081588, 10830.512626931806, 10851.511681974172, 11054.447277636247, 11056.336619802838, 8198.959005204935, 8201.015878094548, 8288.958555207186, 8322.583870806435, 8346.081930987586, 5613.46672066153, 5831.446012628793, 5841.702296907559, 5955.434233747787, 6090.665013424305, 9469.242460602998, 9948.552315145691, 10240.334378265388, 10271.229657775846, 10299.176065914859, 10260.948695256477, 10326.967303269614, 10377.896221037994, 10482.63310784119, 10484.947575262075, 6882.621455819778, 6928.376446119873, 7009.808332583371, 7018.368346848808, 7038.788836334918, 6848.3494068063455, 8303.1281715419, 8310.70912518059, 8325.583720813815, 8349.415540912078, 6276.466500347522, 7325.230850760593, 7562.092548893947, 7593.430492712958, 7688.308052275098, 7135.357817796424, 7340.412766978522, 7413.481056326221, 7422.406207503494, 7495.62521873915, 6826.351496607815, 6828.385445309946, 6895.0, 6916.066331045245, 6916.792496225121, 4807.855764327076, 4871.244957031666, 5030.0945829749535, 5058.570021548209, 5073.873153171053, 5030.9245361320345, 5077.949220507748, 5097.209932460474, 5128.897422051613, 5277.525022747971], "value": 4502.811119840258, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.07018156076447, 317.0393707067585, 326.05769326646, 326.34404846259014, 328.6975982096471, 5112.463191364905, 5248.291480650111, 5259.711370714175, 5267.47325267473, 5273.2617433559335, 3867.7099217558657, 4581.40441742573, 4929.507049295069, 5164.096283150452, 5176.2235664650325, 3857.9228415431744, 3884.7662594008416, 3946.6842652587866, 4071.5570657605494, 4079.51045874494, 3382.6279109297957, 3755.286495565834, 3812.7902965336893, 4036.0, 4047.4738284023038, 3847.365184744514, 4015.7791321477307, 4022.376531637586, 4026.228459477835, 4029.0459127323224, 3869.742333741531, 3929.2337994091163, 3949.8025098744947, 3988.504310883419, 4068.0236743181717, 3116.735077518391, 3425.640307767673, 3576.4635304704243, 3586.7446393762234, 3590.35373632746, 5067.6452648314635, 5071.2139618359215, 5095.949040509585, 5106.821261255838, 5122.948770512322, 4058.736182148151, 4514.9774251128865, 4579.656525760548, 4666.626669866402, 4667.393238878924, 5412.621116521845, 5432.538234250093, 5482.314710661168, 5487.862803429906, 5527.2538207342295, 3881.6506514413836, 3892.4939757831585, 3905.37513997759, 3919.7844118573466, 3923.3526468132723, 5139.614528910347, 5222.4255331913455, 5518.668879867221, 5725.856853578653, 5839.357670656224, 3962.6829853611644, 3978.661813745864, 3983.661388781928, 3985.1631157456677, 3995.6603688686787, 3575.9450961965763, 3711.4432835074954, 4069.898252543651, 4074.409210664496, 4087.7751723654887, 4495.0, 4629.40285601467, 4668.4631267404, 4674.322223277606, 4684.789184486706, 4747.667663263538, 5514.448555144453, 5535.557155427556, 5540.722963851791, 5570.944290557042, 1464.1507925403241, 1479.8309335624735, 1482.2885015192553, 1485.264793927013, 1487.018567745283, 948.3456415073715, 966.2415004221652, 998.3810037776625, 1009.7172791618359, 1016.4968340671415, 4379.978100109479, 4921.630877684188, 4926.556609905126, 5019.0, 5036.823711170092, 4660.533946605312, 4882.6826256293225, 5621.90372877285, 5630.352509461464, 5633.802816901389, 4341.869743907688, 4413.0, 5132.923006154911, 5139.280500729901, 5145.048166089286, 2906.89825856094, 3038.2404398900276, 3261.706446419834, 3277.4428347181038, 3297.1921879139763, 5381.560212824435, 5770.798022069207, 6066.484348830399, 6485.675716214265, 6547.508936829757, 5690.772369105272, 6106.72519736604, 6171.228596425447, 6434.227892652912, 6446.452051575575, 6444.838879027968, 6445.097686323825, 6467.870642587122, 6475.449586785176, 6499.7400103995315, 4985.5762260208285, 5116.6418350716585, 5754.56840736938, 6234.251889773256, 6246.968765156145, 4697.976510117427, 4804.711717296974, 5588.133839254922, 5730.312362516524, 5748.5688573358, 5092.923606145837, 5136.481510165549, 5913.438223368773, 5968.194293770459, 6005.189299444519, 3727.6645101940953, 3783.2244389899456, 3869.477620521306, 3873.3802591586336, 3909.4135879618284, 5242.902838864485, 5414.404415514212, 5565.443455654402, 5682.176084467812, 5731.369549349485, 4881.071976569307, 5508.504234618904, 5538.723063846711, 5568.97215513936, 5570.0, 4835.395575553056, 5050.787810925278, 5308.575313974796, 5481.698506582176, 5565.30433695788, 4582.358469814162, 5148.510891465305, 5379.058664733734, 5425.891482170256, 5442.782288708408, 5043.5712964398435, 5318.468153184651, 5395.356275424935, 5551.583631227593, 5552.250446189874, 5409.053415652166, 5426.755795989269, 5432.320959880015, 5442.6462279951675, 5468.28912241406], "value": 4128.341847345903, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.7802925034739, 714.2821464428247, 726.6148941061233, 776.4409625069951, 786.6460092958166, 10957.232993690444, 11211.775764484726, 11976.580819671313, 12211.08416868735, 12229.877701222986, 14009.509667161652, 14027.579172624837, 14064.507742229023, 14067.718645627081, 14073.929630351833, 733.7505248215606, 747.4879707400421, 902.5351943748974, 932.6036434515338, 934.2899396458706, 907.133687328603, 921.1893533690377, 923.6813299411726, 924.3252425729222, 924.8057907839362, 992.8014397120569, 995.6515219673129, 995.6714284286204, 996.217968894418, 1000.3747657713928, 786.6813940354137, 968.4770224078989, 994.5027486256871, 1001.884783249921, 1005.8088963097061, 739.5303981971433, 745.8135466133467, 747.5813544415145, 750.1972889008763, 750.6772088002157, 14645.48740794067, 14713.0, 14736.631584210481, 14745.92627036858, 14762.63093422673, 12345.13584049117, 12439.626811195678, 12704.745905081847, 12740.745185096246, 12749.087636854454, 9863.358881672668, 10062.597496100147, 10208.081272685495, 10373.636922707668, 10418.739531511774, 2835.432913417325, 3529.876454324112, 3553.4847447120023, 3555.3244883472057, 3689.7786132832116, 70462.0, 70790.0, 71975.20061998439, 71994.64002680006, 72129.27870721278, 8261.256486916207, 8715.215630593277, 9448.48033358172, 9484.478353690476, 10125.038121378457, 21207.0, 21439.249626263318, 21494.570108598055, 21507.892460537598, 21545.353639390843, 6570.901436478457, 6767.390934815791, 6824.0, 6892.241853396223, 6914.827129321807, 4164.077993210632, 4172.207280616673, 4186.769727664947, 4202.978985105116, 4229.386738922839, 3468.3063387322636, 3514.929701406009, 3537.0273174877116, 3553.466979953028, 3570.6786389225094, 559.6502186133666, 619.1610367951452, 634.3846468925135, 652.7193306878088, 654.5221987948729, 590.9734061967222, 595.4640823259101, 598.7485256192409, 618.6659204029773, 635.1076737184263, 8124.918750812534, 8291.461055031403, 8389.790255243546, 8438.662453501915, 8487.912876974618, 494.38449130832345, 527.7994362142362, 585.5783835638333, 613.3590398034065, 624.5440828195352, 3842.634949679776, 3916.3929590912885, 4055.1889622075705, 4083.0, 4231.661467082626, 90283.54858225667, 91068.54465727761, 93049.53475232581, 94269.0, 97435.51282243685, 82967.17032829595, 83522.0, 83646.0, 83901.70737194734, 84576.0, 61585.46036349127, 74756.62621686856, 75038.0, 76210.61894690602, 76493.6175319131, 8110.959445202736, 8276.875846862182, 8400.705975290835, 8419.284360829399, 8464.44981076228, 45212.32181517214, 45585.77207113943, 45625.859353516425, 45766.77116614395, 47235.0, 61173.08240376309, 61304.0, 62597.68701156465, 63012.054819178666, 63089.84550772351, 11674.278379749872, 11740.647780566596, 11797.29216247028, 11952.402379880798, 11996.0, 12036.699082523008, 12290.139690221598, 12412.193207441487, 12782.48870045225, 13074.084814062924, 9141.0, 10890.782184356427, 11188.72028199269, 11616.419179041182, 11672.299662020307, 10056.34633748804, 11470.885291147315, 11613.709657258636, 11687.88312116868, 12281.570145044881, 11252.83120753173, 11480.483378248166, 11581.305121692725, 11724.413779311171, 11900.583479578174, 9003.324750643596, 9007.549622518978, 9442.575084121368, 9709.9029009709, 10010.849837252592, 9654.324197306121, 9766.121049105615, 9947.900520994697, 9982.850257246293, 10004.749881253027], "value": 6849.433549755735, "unit": "score"}], "name": "dromaeo_css", "value": 7044.8072973354965, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 108.0, 115.0, 112.0, 117.0, 115.0, 113.0, 104.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 133.0, 191.0, 190.0, 123.0, 139.0, 120.0, 153.0, 158.0, 142.0], "value": 150.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 189.0, 187.0, 169.0, 249.0, 262.0, 201.0, 186.0, 186.0], "value": 199.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 153.0, 57.0, 56.0, 57.0, 66.0, 133.0, 55.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 142.0, 78.0, 85.0, 91.0, 125.0, 79.0, 75.0, 124.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 83.0, 88.0, 84.0, 86.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 101.0, 148.0, 133.0, 93.0, 93.0, 92.0, 92.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 86.0, 87.0, 85.0, 86.0, 86.0, 85.0, 87.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 63.0, 63.0, 64.0, 64.0, 64.0, 80.0, 59.0, 57.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 72.0, 73.0, 69.0, 67.0, 67.0, 67.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 82.0, 81.0, 80.0, 80.0, 80.0, 79.0, 83.0, 85.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 136.0, 133.0, 143.0, 150.0, 136.0, 135.0, 173.0, 135.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 168.0, 167.0, 166.0, 167.0, 167.0, 170.0, 167.0, 168.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 66.0, 69.0, 67.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1503.3999999999999, "alertThreshold": 2.0}]} 14:39:32 INFO - Return code: 0 14:39:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:39:32 ERROR - # TBPL SUCCESS # 14:39:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:39:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:39:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:39:32 INFO - 2016-05-05 14:39:32.843 screenresolution[1675:9520] starting screenresolution argv=screenresolution get 14:39:32 INFO - 2016-05-05 14:39:32.846 screenresolution[1675:9520] Display 0: 1600x1200x32@60 14:39:32 INFO - 2016-05-05 14:39:32.852 screenresolution[1676:9523] starting screenresolution argv=screenresolution list 14:39:32 INFO - Available Modes on Display 0 14:39:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:39:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:33 INFO - Intel Iris: 14:39:33 INFO - Chipset Model: Intel Iris 14:39:33 INFO - Type: GPU 14:39:33 INFO - Bus: Built-In 14:39:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:33 INFO - Vendor: Intel (0x8086) 14:39:33 INFO - Device ID: 0x0a2e 14:39:33 INFO - Revision ID: 0x0009 14:39:33 INFO - Displays: 14:39:33 INFO - Display: 14:39:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:33 INFO - Display Serial Number: 1600x1200 60 14:39:33 INFO - Main Display: Yes 14:39:33 INFO - Mirror: Off 14:39:33 INFO - Online: Yes 14:39:33 INFO - Rotation: Supported 14:39:33 INFO - Return code: 0 14:39:33 INFO - Running post-action listener: _resource_record_post_action 14:39:33 INFO - Running post-run listener: _resource_record_post_run 14:39:34 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 37537280; Write bytes: 651791360; Read time: 393; Write time: 1851 14:39:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346882560; Write bytes: 346211840; Read time: 26497; Write time: 673 14:39:34 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35739136; Write bytes: 294790656; Read time: 372; Write time: 1149 14:39:34 INFO - Running post-run listener: _upload_blobber_files 14:39:34 INFO - Blob upload gear active. 14:39:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:34 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 14:39:34 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'] 14:39:34 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 14:39:34 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:39:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:35 INFO - (blobuploader) - INFO - Done attempting. 14:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:39:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:39:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:36 INFO - (blobuploader) - INFO - Done attempting. 14:39:36 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:36 INFO - Return code: 0 14:39:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff267a1c5f0ff1f656c9217ef4752b74af5698c49bb9a5eecc8e71c44ff0751c6c77c3ca91b04ff05f2e90cf9e9a40354faaf3a4e422061a6cddcc0133648cb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ee7c5c1319ad07033640aa1beeee5d0ab9d15c09f4032ec5189cc6d858632b9c673df4eb69400c1187a80e61f72583aec1c57ff40a4c0807537d41ed0a73569"} 14:39:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:36 INFO - Contents: 14:39:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff267a1c5f0ff1f656c9217ef4752b74af5698c49bb9a5eecc8e71c44ff0751c6c77c3ca91b04ff05f2e90cf9e9a40354faaf3a4e422061a6cddcc0133648cb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ee7c5c1319ad07033640aa1beeee5d0ab9d15c09f4032ec5189cc6d858632b9c673df4eb69400c1187a80e61f72583aec1c57ff40a4c0807537d41ed0a73569"} 14:39:36 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:36 INFO - Copying logs to upload dir... 14:39:36 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.742778 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-05 14:39:36.825960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 14:39:36.829618) ========= 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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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/ff267a1c5f0ff1f656c9217ef4752b74af5698c49bb9a5eecc8e71c44ff0751c6c77c3ca91b04ff05f2e90cf9e9a40354faaf3a4e422061a6cddcc0133648cb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ee7c5c1319ad07033640aa1beeee5d0ab9d15c09f4032ec5189cc6d858632b9c673df4eb69400c1187a80e61f72583aec1c57ff40a4c0807537d41ed0a73569"} 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.010705 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/ff267a1c5f0ff1f656c9217ef4752b74af5698c49bb9a5eecc8e71c44ff0751c6c77c3ca91b04ff05f2e90cf9e9a40354faaf3a4e422061a6cddcc0133648cb4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ee7c5c1319ad07033640aa1beeee5d0ab9d15c09f4032ec5189cc6d858632b9c673df4eb69400c1187a80e61f72583aec1c57ff40a4c0807537d41ed0a73569"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 14:39:36.857002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:39:36.857355) ========= 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.b8LayTPv7q/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.0Hxnf4MtdV/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.005030 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:39:36.909021) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 14:39:36.909741) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 14:39:36.910439) ========= ========= Total master_lag: 0.15 =========