builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0130 starttime: 1463759001.62 results: success (0) buildid: 20160520074013 builduid: 0fe72d3c11114744b43110916d4809f9 revision: d54effc885ef80a247708f9794bb0091375b309c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:21.616801) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:21.617265) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:21.617563) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:21.678043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:21.678381) ========= 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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-20 08:43:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-05-20 08:43:22 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.513799 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:22.216168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:22.216481) ========= 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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.020980 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:22.265333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:22.265653) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d54effc885ef80a247708f9794bb0091375b309c --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 d54effc885ef80a247708f9794bb0091375b309c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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-20 08:43:22,343 truncating revision to first 12 chars 2016-05-20 08:43:22,343 Setting DEBUG logging. 2016-05-20 08:43:22,343 attempt 1/10 2016-05-20 08:43:22,343 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d54effc885ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 08:43:22,956 unpacking tar archive at: mozilla-aurora-d54effc885ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.858529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:23.145461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:23.145817) ========= script_repo_revision: d54effc885ef80a247708f9794bb0091375b309c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:23.146187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:23.146456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 08:43:23.161480) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-20 08:43:23.161845) ========= /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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:43:23 INFO - MultiFileLogger online at 20160520 08:43:23 in /builds/slave/test 08:43:23 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 08:43:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:23 INFO - {'append_to_log': False, 08:43:23 INFO - 'base_work_dir': '/builds/slave/test', 08:43:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:43:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:23 INFO - 'branch': 'Mozilla-Aurora', 08:43:23 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:23 INFO - 'config_files': ('talos/mac_config.py',), 08:43:23 INFO - 'default_actions': ('clobber', 08:43:23 INFO - 'read-buildbot-config', 08:43:23 INFO - 'download-and-extract', 08:43:23 INFO - 'populate-webroot', 08:43:23 INFO - 'create-virtualenv', 08:43:23 INFO - 'install', 08:43:23 INFO - 'run-tests'), 08:43:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:23 INFO - 'download_minidump_stackwalk': True, 08:43:23 INFO - 'download_symbols': 'ondemand', 08:43:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:23 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:23 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:23 INFO - 'installer_path': 'installer.exe', 08:43:23 INFO - 'log_level': 'info', 08:43:23 INFO - 'log_name': 'talos', 08:43:23 INFO - 'log_to_console': True, 08:43:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:23 INFO - 'opt_config_files': (), 08:43:23 INFO - 'pip_index': False, 08:43:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:23 INFO - 'cmd': ('bash', 08:43:23 INFO - '-c', 08:43:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:23 INFO - 'enabled': True, 08:43:23 INFO - 'halt_on_failure': False, 08:43:23 INFO - 'name': 'check_screen_resolution'},), 08:43:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:23 INFO - 'cmd': ('bash', 08:43:23 INFO - '-c', 08:43:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:23 INFO - 'enabled': True, 08:43:23 INFO - 'halt_on_failure': False, 08:43:23 INFO - 'name': 'check_screen_resolution'},), 08:43:23 INFO - 'run_cmd_checks_enabled': True, 08:43:23 INFO - 'sps_profile': False, 08:43:23 INFO - 'sps_profile_interval': 0, 08:43:23 INFO - 'suite': 'dromaeojs', 08:43:23 INFO - 'system_bits': '32', 08:43:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:23 INFO - 'title': 't-yosemite-r7-0130', 08:43:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:23 INFO - 'use_talos_json': True, 08:43:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:23 INFO - 'work_dir': 'build'} 08:43:23 INFO - ##### 08:43:23 INFO - ##### Running clobber step. 08:43:23 INFO - ##### 08:43:23 INFO - Running pre-action listener: _resource_record_pre_action 08:43:23 INFO - Running main action method: clobber 08:43:23 INFO - rmtree: /builds/slave/test/build 08:43:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:24 INFO - Running post-action listener: _resource_record_post_action 08:43:24 INFO - ##### 08:43:24 INFO - ##### Running read-buildbot-config step. 08:43:24 INFO - ##### 08:43:24 INFO - Running pre-action listener: _resource_record_pre_action 08:43:24 INFO - Running main action method: read_buildbot_config 08:43:24 INFO - Using buildbot properties: 08:43:24 INFO - { 08:43:24 INFO - "project": "", 08:43:24 INFO - "product": "firefox", 08:43:24 INFO - "script_repo_revision": "production", 08:43:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:43:24 INFO - "repository": "", 08:43:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:43:24 INFO - "buildid": "20160520074013", 08:43:24 INFO - "pgo_build": "False", 08:43:24 INFO - "basedir": "/builds/slave/test", 08:43:24 INFO - "buildnumber": 12, 08:43:24 INFO - "slavename": "t-yosemite-r7-0130", 08:43:24 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:43:24 INFO - "platform": "yosemite_r7", 08:43:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:43:24 INFO - "branch": "mozilla-aurora", 08:43:24 INFO - "repo_path": "releases/mozilla-aurora", 08:43:24 INFO - "revision": "d54effc885ef80a247708f9794bb0091375b309c", 08:43:24 INFO - "stage_platform": "macosx64", 08:43:24 INFO - "builduid": "0fe72d3c11114744b43110916d4809f9", 08:43:24 INFO - "slavebuilddir": "test" 08:43:24 INFO - } 08:43:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 08:43:24 INFO - Running post-action listener: _resource_record_post_action 08:43:24 INFO - ##### 08:43:24 INFO - ##### Running download-and-extract step. 08:43:24 INFO - ##### 08:43:24 INFO - Running pre-action listener: _resource_record_pre_action 08:43:24 INFO - Running main action method: download_and_extract 08:43:24 INFO - mkdir: /builds/slave/test/build/tests 08:43:24 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')]} 08:43:24 INFO - https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:43:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:43:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:43:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:30 INFO - Downloaded 1199 bytes. 08:43:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:43:30 INFO - Using the following test package requirements: 08:43:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:43:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 08:43:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 08:43:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'jsshell-mac.zip'], 08:43:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 08:43:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:43:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 08:43:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 08:43:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 08:43:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:43:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 08:43:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:43:30 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')]} 08:43:30 INFO - https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:43:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:43:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:43:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:33 INFO - Downloaded 18102379 bytes. 08:43:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:43:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:43:36 INFO - Return code: 0 08:43:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:36 INFO - https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:43:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:43:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:43:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:38 INFO - Downloaded 18102379 bytes. 08:43:38 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'] 08:43:38 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 08:43:42 INFO - Return code: 0 08:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:42 INFO - https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:43:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:43:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:43:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:43 INFO - Downloaded 10840145 bytes. 08:43:43 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'] 08:43:43 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 08:43:43 INFO - Return code: 0 08:43:43 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')]} 08:43:43 INFO - https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:43:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:43:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:43:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 08:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aRm8sitoTo2MWNO6xDgxjA/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 08:43:47 INFO - Downloaded 114096575 bytes. 08:43:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:43:47 INFO - mkdir: /builds/slave/test/properties 08:43:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:47 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:47 INFO - Contents: 08:43:47 INFO - build_url:https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:43:48 INFO - Running post-action listener: _resource_record_post_action 08:43:48 INFO - Running post-action listener: set_extra_try_arguments 08:43:48 INFO - ##### 08:43:48 INFO - ##### Running populate-webroot step. 08:43:48 INFO - ##### 08:43:48 INFO - Running pre-action listener: _resource_record_pre_action 08:43:48 INFO - Running main action method: populate_webroot 08:43:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:43:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:43:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:43:48 INFO - '--tppagecycles', 08:43:48 INFO - '7'], 08:43:48 INFO - 'tests': ['tsvgm']}}, 08:43:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:43:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:43:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:43:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:43:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['damp', 'tps']}, 08:43:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'tests': ['damp', 'tps']}, 08:43:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['dromaeo_dom']}, 08:43:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:43:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'other-e10s': {'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['a11yr', 08:43:48 INFO - 'ts_paint', 08:43:48 INFO - 'tpaint', 08:43:48 INFO - 'sessionrestore', 08:43:48 INFO - 'sessionrestore_no_auto_restore', 08:43:48 INFO - 'tabpaint']}, 08:43:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['tsvgx', 08:43:48 INFO - 'tsvgr_opacity', 08:43:48 INFO - 'tart', 08:43:48 INFO - 'tscrollx', 08:43:48 INFO - 'cart']}, 08:43:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:43:48 INFO - 'tsvgr_opacity', 08:43:48 INFO - 'tart', 08:43:48 INFO - 'tscrollx', 08:43:48 INFO - 'cart']}, 08:43:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'talos_options': ['--disable-e10s'], 08:43:48 INFO - 'tests': ['tp5o']}, 08:43:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'tests': ['tp5o']}, 08:43:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'talos_options': ['--disable-e10s', 08:43:48 INFO - '--xperf_path', 08:43:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:48 INFO - 'tests': ['tp5n']}, 08:43:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:48 INFO - 'talos_options': ['--xperf_path', 08:43:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:48 INFO - 'tests': ['tp5n']}}, 08:43:48 INFO - 'talos.zip': {'path': '', 08:43:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:43:48 INFO - Running post-action listener: _resource_record_post_action 08:43:48 INFO - ##### 08:43:48 INFO - ##### Running create-virtualenv step. 08:43:48 INFO - ##### 08:43:48 INFO - Running pre-action listener: _resource_record_pre_action 08:43:48 INFO - Running main action method: create_virtualenv 08:43:48 INFO - Creating virtualenv /builds/slave/test/build/venv 08:43:48 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 08:43:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:43:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:43:48 INFO - Using real prefix '/tools/python27' 08:43:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:43:49 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:52 INFO - Installing pip.................done. 08:43:52 INFO - Return code: 0 08:43:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:52 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')]} 08:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 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')]} 08:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:43:52 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 08:43:52 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 08:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:52 INFO - 'HOME': '/Users/cltbld', 08:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:52 INFO - 'LOGNAME': 'cltbld', 08:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:52 INFO - 'MOZ_NO_REMOTE': '1', 08:43:52 INFO - 'NO_EM_RESTART': '1', 08:43:52 INFO - 'PAGER': '/bin/cat', 08:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:52 INFO - 'PWD': '/builds/slave/test', 08:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:52 INFO - 'SHELL': '/bin/bash', 08:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:43:52 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:52 INFO - 'USER': 'cltbld', 08:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:52 INFO - 'XPC_FLAGS': '0x0', 08:43:52 INFO - 'XPC_SERVICE_NAME': '0', 08:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:52 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 08:43:52 INFO - Cleaning up... 08:43:52 INFO - Return code: 0 08:43:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:43:52 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')]} 08:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 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')]} 08:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:43:52 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 08:43:52 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 08:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:52 INFO - 'HOME': '/Users/cltbld', 08:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:52 INFO - 'LOGNAME': 'cltbld', 08:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:52 INFO - 'MOZ_NO_REMOTE': '1', 08:43:52 INFO - 'NO_EM_RESTART': '1', 08:43:52 INFO - 'PAGER': '/bin/cat', 08:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:52 INFO - 'PWD': '/builds/slave/test', 08:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:52 INFO - 'SHELL': '/bin/bash', 08:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:43:52 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:52 INFO - 'USER': 'cltbld', 08:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:52 INFO - 'XPC_FLAGS': '0x0', 08:43:52 INFO - 'XPC_SERVICE_NAME': '0', 08:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:52 INFO - Downloading/unpacking psutil>=0.7.1 08:43:52 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 08:43:52 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 08:43:52 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 08:43:52 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 08:43:52 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 08:43:52 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 08:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:56 INFO - Installing collected packages: psutil 08:43:56 INFO - Running setup.py install for psutil 08:43:56 INFO - building 'psutil._psutil_osx' extension 08:43:56 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 08:43:56 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 08:43:56 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 08:43:56 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 08:43:56 INFO - building 'psutil._psutil_posix' extension 08:43:56 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 08:43:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:43:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:43:56 INFO - ^ 08:43:57 INFO - 1 warning generated. 08:43:57 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 08:43:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:57 INFO - Successfully installed psutil 08:43:57 INFO - Cleaning up... 08:43:57 INFO - Return code: 0 08:43:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:57 INFO - 'HOME': '/Users/cltbld', 08:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:57 INFO - 'LOGNAME': 'cltbld', 08:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:57 INFO - 'MOZ_NO_REMOTE': '1', 08:43:57 INFO - 'NO_EM_RESTART': '1', 08:43:57 INFO - 'PAGER': '/bin/cat', 08:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:57 INFO - 'PWD': '/builds/slave/test', 08:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:57 INFO - 'SHELL': '/bin/bash', 08:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:43:57 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:57 INFO - 'USER': 'cltbld', 08:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:57 INFO - 'XPC_FLAGS': '0x0', 08:43:57 INFO - 'XPC_SERVICE_NAME': '0', 08:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:44:00 INFO - Installing collected packages: mozsystemmonitor 08:44:00 INFO - Running setup.py install for mozsystemmonitor 08:44:00 INFO - Successfully installed mozsystemmonitor 08:44:00 INFO - Cleaning up... 08:44:00 INFO - Return code: 0 08:44:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:44:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:00 INFO - 'HOME': '/Users/cltbld', 08:44:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:00 INFO - 'LOGNAME': 'cltbld', 08:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:00 INFO - 'MOZ_NO_REMOTE': '1', 08:44:00 INFO - 'NO_EM_RESTART': '1', 08:44:00 INFO - 'PAGER': '/bin/cat', 08:44:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:00 INFO - 'PWD': '/builds/slave/test', 08:44:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:00 INFO - 'SHELL': '/bin/bash', 08:44:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:00 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:00 INFO - 'USER': 'cltbld', 08:44:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:00 INFO - 'XPC_FLAGS': '0x0', 08:44:00 INFO - 'XPC_SERVICE_NAME': '0', 08:44:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:02 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:04 INFO - Downloading docopt-0.6.1.tar.gz 08:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:04 INFO - Running setup.py install for blobuploader 08:44:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:04 INFO - Running setup.py install for requests 08:44:04 INFO - Running setup.py install for docopt 08:44:05 INFO - Successfully installed blobuploader requests docopt 08:44:05 INFO - Cleaning up... 08:44:05 INFO - Return code: 0 08:44:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:05 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')]} 08:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:05 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')]} 08:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:05 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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:05 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 08:44:05 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 08:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:05 INFO - 'HOME': '/Users/cltbld', 08:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:05 INFO - 'LOGNAME': 'cltbld', 08:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:05 INFO - 'MOZ_NO_REMOTE': '1', 08:44:05 INFO - 'NO_EM_RESTART': '1', 08:44:05 INFO - 'PAGER': '/bin/cat', 08:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:05 INFO - 'PWD': '/builds/slave/test', 08:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:05 INFO - 'SHELL': '/bin/bash', 08:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:05 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:05 INFO - 'USER': 'cltbld', 08:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:05 INFO - 'XPC_FLAGS': '0x0', 08:44:05 INFO - 'XPC_SERVICE_NAME': '0', 08:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-0KA1CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-HWzKWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-qn0p74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-E0mA96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-ZKmgu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:05 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-ddiHgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-2ukvN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-5re_aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-TaErQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-JQmyS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-9J7bKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-bLQgj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-FZ6Odt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-80Vha5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:06 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-8jErth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:07 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-SxP7BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:07 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-U8ven1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:07 INFO - Running setup.py install for manifestparser 08:44:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:07 INFO - Running setup.py install for mozcrash 08:44:07 INFO - Running setup.py install for mozdebug 08:44:07 INFO - Running setup.py install for mozdevice 08:44:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:44:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:07 INFO - Running setup.py install for mozfile 08:44:07 INFO - Running setup.py install for mozhttpd 08:44:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Running setup.py install for mozinfo 08:44:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Running setup.py install for mozInstall 08:44:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Running setup.py install for mozleak 08:44:08 INFO - Running setup.py install for mozlog 08:44:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Running setup.py install for moznetwork 08:44:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:08 INFO - Running setup.py install for mozprocess 08:44:09 INFO - Running setup.py install for mozprofile 08:44:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:09 INFO - Running setup.py install for mozrunner 08:44:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:09 INFO - Running setup.py install for mozscreenshot 08:44:09 INFO - Running setup.py install for moztest 08:44:09 INFO - Running setup.py install for mozversion 08:44:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:09 INFO - Cleaning up... 08:44:09 INFO - Return code: 0 08:44:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:09 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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:09 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 08:44:09 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 08:44:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:09 INFO - 'HOME': '/Users/cltbld', 08:44:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:09 INFO - 'LOGNAME': 'cltbld', 08:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:09 INFO - 'MOZ_NO_REMOTE': '1', 08:44:09 INFO - 'NO_EM_RESTART': '1', 08:44:09 INFO - 'PAGER': '/bin/cat', 08:44:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:09 INFO - 'PWD': '/builds/slave/test', 08:44:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:09 INFO - 'SHELL': '/bin/bash', 08:44:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:09 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:09 INFO - 'USER': 'cltbld', 08:44:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:09 INFO - 'XPC_FLAGS': '0x0', 08:44:09 INFO - 'XPC_SERVICE_NAME': '0', 08:44:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-Vg5CIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-j74kF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-33BW_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-GBw2sY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-dEcB1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-rre5Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-V5ot5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-naI3ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:10 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)) 08:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:10 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-cnCOuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-DTpv40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-UA6zcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-JLhdbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-S6ud_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-czaAYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-I82CMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-jrtTQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:11 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)) 08:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:11 INFO - Running setup.py (path:/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/pip-3siPnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:11 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)) 08:44:11 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)) 08:44:11 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)) 08:44:11 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)) 08:44:11 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)) 08:44:11 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)) 08:44:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:14 INFO - Downloading blessings-1.6.tar.gz 08:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:14 INFO - Installing collected packages: blessings 08:44:14 INFO - Running setup.py install for blessings 08:44:14 INFO - Successfully installed blessings 08:44:14 INFO - Cleaning up... 08:44:14 INFO - Return code: 0 08:44:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:15 INFO - Reading from file tmpfile_stdout 08:44:15 INFO - Current package versions: 08:44:15 INFO - blessings == 1.6 08:44:15 INFO - blobuploader == 1.2.4 08:44:15 INFO - docopt == 0.6.1 08:44:15 INFO - manifestparser == 1.1 08:44:15 INFO - mozInstall == 1.12 08:44:15 INFO - mozcrash == 0.17 08:44:15 INFO - mozdebug == 0.1 08:44:15 INFO - mozdevice == 0.48 08:44:15 INFO - mozfile == 1.2 08:44:15 INFO - mozhttpd == 0.7 08:44:15 INFO - mozinfo == 0.9 08:44:15 INFO - mozleak == 0.1 08:44:15 INFO - mozlog == 3.1 08:44:15 INFO - moznetwork == 0.27 08:44:15 INFO - mozprocess == 0.22 08:44:15 INFO - mozprofile == 0.28 08:44:15 INFO - mozrunner == 6.11 08:44:15 INFO - mozscreenshot == 0.1 08:44:15 INFO - mozsystemmonitor == 0.0 08:44:15 INFO - moztest == 0.7 08:44:15 INFO - mozversion == 1.4 08:44:15 INFO - psutil == 3.1.1 08:44:15 INFO - requests == 1.2.3 08:44:15 INFO - wsgiref == 0.1.2 08:44:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:15 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 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:15 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 08:44:15 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 08:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:15 INFO - 'HOME': '/Users/cltbld', 08:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:15 INFO - 'LOGNAME': 'cltbld', 08:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:15 INFO - 'MOZ_NO_REMOTE': '1', 08:44:15 INFO - 'NO_EM_RESTART': '1', 08:44:15 INFO - 'PAGER': '/bin/cat', 08:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:15 INFO - 'PWD': '/builds/slave/test', 08:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:15 INFO - 'SHELL': '/bin/bash', 08:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:15 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:15 INFO - 'USER': 'cltbld', 08:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:15 INFO - 'XPC_FLAGS': '0x0', 08:44:15 INFO - 'XPC_SERVICE_NAME': '0', 08:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 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)) 08:44:15 INFO - Cleaning up... 08:44:15 INFO - Return code: 0 08:44:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:15 INFO - 'HOME': '/Users/cltbld', 08:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:15 INFO - 'LOGNAME': 'cltbld', 08:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:15 INFO - 'MOZ_NO_REMOTE': '1', 08:44:15 INFO - 'NO_EM_RESTART': '1', 08:44:15 INFO - 'PAGER': '/bin/cat', 08:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:15 INFO - 'PWD': '/builds/slave/test', 08:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:15 INFO - 'SHELL': '/bin/bash', 08:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:15 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:15 INFO - 'USER': 'cltbld', 08:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:15 INFO - 'XPC_FLAGS': '0x0', 08:44:15 INFO - 'XPC_SERVICE_NAME': '0', 08:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:15 INFO - Downloading/unpacking jsonschema 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:18 INFO - Downloading/unpacking functools32 (from jsonschema) 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:18 INFO - warning: no files found matching '*.txt' 08:44:18 INFO - no previously-included directories found matching 'build' 08:44:18 INFO - no previously-included directories found matching 'dist' 08:44:18 INFO - no previously-included directories found matching '.git*' 08:44:18 INFO - Installing collected packages: jsonschema, functools32 08:44:18 INFO - Running setup.py install for functools32 08:44:18 INFO - warning: no files found matching '*.txt' 08:44:18 INFO - no previously-included directories found matching 'build' 08:44:18 INFO - no previously-included directories found matching 'dist' 08:44:18 INFO - no previously-included directories found matching '.git*' 08:44:18 INFO - Successfully installed jsonschema functools32 08:44:18 INFO - Cleaning up... 08:44:18 INFO - Return code: 0 08:44:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e930a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e940420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70bd47410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, '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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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 08:44:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:18 INFO - 'HOME': '/Users/cltbld', 08:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:18 INFO - 'LOGNAME': 'cltbld', 08:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:18 INFO - 'MOZ_NO_REMOTE': '1', 08:44:18 INFO - 'NO_EM_RESTART': '1', 08:44:18 INFO - 'PAGER': '/bin/cat', 08:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:18 INFO - 'PWD': '/builds/slave/test', 08:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:18 INFO - 'SHELL': '/bin/bash', 08:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:18 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:18 INFO - 'USER': 'cltbld', 08:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:18 INFO - 'XPC_FLAGS': '0x0', 08:44:18 INFO - 'XPC_SERVICE_NAME': '0', 08:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:19 INFO - Downloading/unpacking flake8 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:44:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:44:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:44:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:44:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:44:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:44:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:44:22 INFO - no previously-included directories found matching 'docs/_build' 08:44:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:44:22 INFO - Running setup.py install for pep8 08:44:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:44:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:44:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:44:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:44:22 INFO - no previously-included directories found matching 'docs/_build' 08:44:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:44:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:44:23 INFO - Cleaning up... 08:44:23 INFO - Return code: 0 08:44:23 INFO - Running post-action listener: _resource_record_post_action 08:44:23 INFO - Running post-action listener: _start_resource_monitoring 08:44:23 INFO - Starting resource monitoring. 08:44:23 INFO - ##### 08:44:23 INFO - ##### Running install step. 08:44:23 INFO - ##### 08:44:23 INFO - Running pre-action listener: _resource_record_pre_action 08:44:23 INFO - Running main action method: install 08:44:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:23 INFO - Reading from file tmpfile_stdout 08:44:23 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:23 INFO - Reading from file tmpfile_stdout 08:44:23 INFO - Output received: 08:44:23 INFO - Usage: mozinstall [options] installer 08:44:23 INFO - Options: 08:44:23 INFO - -h, --help show this help message and exit 08:44:23 INFO - -d DEST, --destination=DEST 08:44:23 INFO - Directory to install application into. [default: 08:44:23 INFO - "/builds/slave/test"] 08:44:23 INFO - --app=APP Application being installed. [default: firefox] 08:44:23 INFO - mkdir: /builds/slave/test/build/application 08:44:23 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'] 08:44:23 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 08:44:54 INFO - Reading from file tmpfile_stdout 08:44:54 INFO - Output received: 08:44:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:44:54 INFO - Running post-action listener: _resource_record_post_action 08:44:54 INFO - ##### 08:44:54 INFO - ##### Running run-tests step. 08:44:54 INFO - ##### 08:44:54 INFO - Running pre-action listener: _resource_record_pre_action 08:44:54 INFO - Running main action method: run_tests 08:44:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:54 INFO - 2016-05-20 08:44:54.644 screenresolution[6576:111172] starting screenresolution argv=screenresolution get 08:44:54 INFO - 2016-05-20 08:44:54.647 screenresolution[6576:111172] Display 0: 1600x1200x32@60 08:44:54 INFO - 2016-05-20 08:44:54.653 screenresolution[6577:111174] starting screenresolution argv=screenresolution list 08:44:54 INFO - Available Modes on Display 0 08:44:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:54 INFO - Intel Iris: 08:44:54 INFO - Chipset Model: Intel Iris 08:44:54 INFO - Type: GPU 08:44:54 INFO - Bus: Built-In 08:44:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:54 INFO - Vendor: Intel (0x8086) 08:44:54 INFO - Device ID: 0x0a2e 08:44:54 INFO - Revision ID: 0x0009 08:44:54 INFO - Displays: 08:44:54 INFO - Display: 08:44:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:54 INFO - Display Serial Number: 1600x1200 60 08:44:54 INFO - Main Display: Yes 08:44:54 INFO - Mirror: Off 08:44:54 INFO - Online: Yes 08:44:54 INFO - Rotation: Supported 08:44:54 INFO - Return code: 0 08:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:54 INFO - Python 2.7.3 08:44:54 INFO - Return code: 0 08:44:54 INFO - grabbing minidump binary from tooltool 08:44: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')]} 08:44:54 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 0x10e7cfe00>, '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 0x10e638db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7cab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:54 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 08:44:54 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 08:44:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:54 INFO - Return code: 0 08:44:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:44:56 INFO - Return code: 0 08:44:56 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/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 08:44:56 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/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --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 08:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8TXG2Yacqy/Render', 08:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:56 INFO - 'HOME': '/Users/cltbld', 08:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:56 INFO - 'LOGNAME': 'cltbld', 08:44:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:56 INFO - 'MOZ_NO_REMOTE': '1', 08:44:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:56 INFO - 'NO_EM_RESTART': '1', 08:44:56 INFO - 'PAGER': '/bin/cat', 08:44:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:56 INFO - 'PWD': '/builds/slave/test', 08:44:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:56 INFO - 'SHELL': '/bin/bash', 08:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nKsTnzfvH/Listeners', 08:44:56 INFO - 'TMPDIR': '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/', 08:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:56 INFO - 'USER': 'cltbld', 08:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:56 INFO - 'XPC_FLAGS': '0x0', 08:44:56 INFO - 'XPC_SERVICE_NAME': '0', 08:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:56 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/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--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 08:44:56 INFO - mozversion application_buildid: 20160520074013 08:44:56 INFO - mozversion application_changeset: d54effc885ef80a247708f9794bb0091375b309c 08:44:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:44:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:56 INFO - mozversion application_name: Firefox 08:44:56 INFO - mozversion application_remotingname: firefox-dev 08:44:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:44:56 INFO - mozversion application_vendor: Mozilla 08:44:56 INFO - mozversion application_version: 48.0a2 08:44:56 INFO - mozversion platform_buildid: 20160520074013 08:44:56 INFO - mozversion platform_changeset: d54effc885ef80a247708f9794bb0091375b309c 08:44:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:44:56 INFO - mozversion platform_version: 48.0a2 08:44:56 INFO - using testdate: 1463759096 08:44:56 INFO - actual date: 1463759096 08:44:56 INFO - starting webserver on 'localhost:59409' 08:44:56 INFO - SUITE-START | Running 2 tests 08:44:56 INFO - TEST-START | dromaeo_css 08:44:56 INFO - operating with platform_type : mac_ 08:44:56 INFO - Initialising browser for dromaeo_css test... 08:44:56 INFO - TEST-INFO | started process 6595 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpXCmL6n/profile http://localhost:59409/getInfo.html) 08:44:58 INFO - PROCESS | 6595 | __metrics Screen width/height:1600/1200 08:44:58 INFO - PROCESS | 6595 | colorDepth:24 08:44:58 INFO - PROCESS | 6595 | Browser inner width/height: 1024/689 08:44:58 INFO - PROCESS | 6595 | __metrics 08:44:58 INFO - PROCESS | 6595 | 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" 08:44:58 ERROR - PROCESS | 6595 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:44:59 INFO - TEST-INFO | 6595: exit 0 08:44:59 INFO - Browser initialized. 08:44:59 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:59 INFO - TEST-INFO | started process 6597 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpXCmL6n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:11 INFO - PROCESS | 6597 | RSS: Main: 211902464 08:45:11 INFO - PROCESS | 6597 | 08:46:02 INFO - PROCESS | 6597 | 1463759162712 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] 08:48:13 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59409/tests/dromaeo/cssquery-ext.html) 08:48:14 INFO - PROCESS | 6597 | RSS: Main: 341721088 08:48:14 INFO - PROCESS | 6597 | 08:51:17 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-ext.html (next: http://localhost:59409/tests/dromaeo/cssquery-jquery.html) 08:51:18 INFO - PROCESS | 6597 | RSS: Main: 355106816 08:51:18 INFO - PROCESS | 6597 | 08:54:20 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59409/tests/dromaeo/cssquery-mootools.html) 08:54:20 INFO - PROCESS | 6597 | RSS: Main: 361324544 08:54:20 INFO - PROCESS | 6597 | 08:57:23 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59409/tests/dromaeo/cssquery-prototype.html) 08:57:23 INFO - PROCESS | 6597 | RSS: Main: 361545728 08:57:23 INFO - PROCESS | 6597 | 09:00:27 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59409/tests/dromaeo/cssquery-yui.html) 09:00:27 INFO - PROCESS | 6597 | RSS: Main: 334422016 09:00:27 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:59409/tests/dromaeo/cssquery-yui.html (next: http://localhost:59409/tests/dromaeo/cssquery-dojo.html) 09:03:30 INFO - PROCESS | 6597 | RSS: Main: 368427008 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | __start_tp_report 09:03:30 INFO - PROCESS | 6597 | _x_x_mozilla_page_load 09:03:30 INFO - PROCESS | 6597 | _x_x_mozilla_page_load_details 09:03:30 INFO - PROCESS | 6597 | |i|pagename|runs| 09:03:30 INFO - PROCESS | 6597 | |0;dojo.html;3297.967020329797;3361.26052268501;3372.9881035689286;3381.4420620597602;3428.554287942569;5617.129344951539;5625.099984002555;5632.662040277581;5637.267155269814;5646.18130371096;5605.5515558755305;5656;5656.830295091144;5662.546996240301;5675.290588676416;4999.325091112708;5058.392992840864;5124.92312615311;5155.81954631588;5211.7133557654315;5142.254373115896;5144.279800827887;5147.073526765181;5147.639665223436;5148.124818780816;5065.113605119108;5099.515546023122;5110.770015349318;5115.718635475047;5123.462036486171;5099.005693889693;5148.588112950993;5159.045576568347;5160.974195129001;5197.064528384889;4933.876653083702;4985.476524964899;5056.064628043787;5095.592352611782;5111.7699703513435;5421.376541697677;5433.918491222635;5459.890802183973;5478.698671573062;5542.39033706292;6129.7854575090105;6133.815985520441;6136.140940268366;6150.169727086831;6177.814665559996;6048.8185354439;6060.3939606039485;6144.41628045335;6154.569180157391;6177.073438984144;5694.857628559278;5718.59969802114;5748.367679555246;5809.215755872988;5821.417858214229;6177.505799536027;6183.041628547582;6187.783427580103;6193.783217587363;6194.225721784777;5804.564657650693;6092;6095.756169753249;6109.053096770008;6109.083637454418;6073.848153796102;6126.823043872739;6145.064304743992;6157.692115394302;6158.04550294705;6081.7871374502565;6095.642128744402;6128.509719222451;6129.540284478682;6133.96933015341;5282.286891269706;6016.699165041818;6130.509559235251;6156.661383623854;6158.599691020159;4824.8793780155775;4852.878678033077;4902.3872015998;4914.606831453475;4920.261960705922;4360.869173924787;4363.563643635674;4383.956160438419;4393.319035549495;4413.977930110329;5629.437056294338;5660.811229641739;5672.489475947102;5674.546036317085;5704.286964129484;5806.129080637938;6103.938960610337;6123.663198524035;6175.84560385994;6206.689665516707;5673.319201695823;5683.9715801420725;5686.0333743263745;5701.315842098892;5703.686297253608;5323.653962492425;5334.519893209552;5336.7865285388925;5339.3058902342445;5369.838904832784;6113.419225173601;6193.597416167935;6221.253449586078;6262.12330273762;6290.748370065146;6194.721237544231;6212.223472065992;6219.067139929047;6222.937770622236;6242.937570624417;6243.687815609293;6247.687615619292;6260.154879091265;6267.655278959519;6270.968645156745;5977.581569290282;6023.337432882292;6056.091586262096;6130.846728831815;6131;5170.3278573785165;6074.51403887679;6207.844803879939;6217.564770466024;6223.96888015557;5604.299462567179;5684;6018.518518518596;6083.6349819011;6091.847703807441;5904;6031.969840150771;6080.695965201634;6081.5742897996715;6121.051237058263;5909.85225369352;5925.348211696796;5940.55445841555;5947;5978.163057171912;5709.400512946247;6048.60684055535;6049.274087109575;6095.329513753395;6101.694915254308;4731.858044258678;5849.64902105875;5920.2895652521975;5981.953158197385;5982.102684597346 09:03:30 INFO - PROCESS | 6597 | |1;ext.html;509.14463700982355;534.8154886564135;544.5915563327504;547.1300631995127;547.1491830204034;31122.84438577807;31810.522842157385;31891.840540797264;31931.521027184583;39307;37903.05242368942;39639;41919.79040104803;42521.78739106308;42631.36052959208;9426.387284826464;9662.033796620351;9668.371555848882;9671.129598336149;9688.806223875517;8684.826303473927;9601.855972160423;9775.853362199572;11710.765784684301;11891.940540297286;11506.367149806758;11571.363575003414;11587.768244635103;11589.942050289737;11600.419979001059;11495.02292305155;11561.65315040546;11565.826512602318;11569.479373428216;11578.710532236677;8653.697120600811;11595.130365222642;11778;11788.64634060971;11803.527858885634;39307.01732456712;39707.60292397068;39779.40330895039;39786.801065994485;39833.60166398328;3246.3020450603235;3285.3593549257903;3377.1894745261206;3396.015155604872;3435.0725304167736;27942;28092.01677941262;28178.859105704545;28794.424111517863;29252.707472925213;2765.1566272286946;2784.5127102757137;2785.7632101271415;2804.8176868503483;2816.788740844445;487962;488600;488624;488959;490251;63429.68285158544;63815.36184638187;63851.76518821819;63883;64011;69291;70606.64696676483;70636.64681676558;70637.64681176562;70668.2933170672;17640.55898602545;17718.46844594664;17874.731879021565;17896.194671239566;17928.282868685106;12584.622461326175;13532.458701651824;13572.253526056162;13633.454661813417;13640.931795340959;17347.913260433616;18491.697462919918;19183.52041198953;20190.394288171377;20420.89789551043;7978.800529986681;8118.5128892265475;8131.552764598004;8148.592570371457;8167.753867686315;982.5529384130203;1011.8482227665909;1066.7759770448292;1105.8775343026834;1125.7016890524017;30414.695853041187;30522.54216186759;30812.383752325277;30877.0736877894;31051.223719407197;710.6624353432181;1097.3678069029877;1116.9720756980978;1125.8198688209848;1292.4830067972887;14420.783688244685;14830.258487075604;14884.627884302763;15116;15128.39486420553;634945;645138;649411;650783;651424;61716.074258886154;62376;62377;62489;62563;1364.3587513868465;1400.6568390744128;1402.9579112626639;1430.6351880270465;1581.0987736989805;28860.567091493223;29334.769942935083;29384.412311754073;29491.115266542038;29978.550321745628;47624;48004;48151.518484814704;48189;48195.9033482158;423985;439842;440095;440712.59287408;440920;23956.161534346214;27712.72287277182;45866;45955.77022114868;47849;46870.06259874852;47053.05893882172;49138;49218.75390623024;50468.242976354646;38060.238795223384;38441.80779096087;39471.605283946796;39686;41339.379909300784;39236.21527569376;39277.60722392739;39442.40836387514;40222.1955560893;40367.596324036385;39874.800625996686;39988.60011399849;40145.19709605733;40592.39111413272;40921.59078409178;34619;34945.82527087348;35310.29379412346;36111.45832812457;36210.637893620726;26196;26213.868930655226;26289.342766430993;26377.868110659325;26815.865920670272 09:03:30 INFO - PROCESS | 6597 | |2;jquery.html;342.85600047979847;394.2410859096239;396.93252147134984;399.94000899865034;411.7282593488296;16246.106464144497;16282.51152465425;19399.515012124702;22656.207032753835;22657.433564160903;18901.62196756064;20878;21185.894070529663;21218.787812121875;21273.68089478655;5451.400345961941;5710.600257981943;5759.539236861041;6006.969965150168;6020.879582408349;5890.705464726768;5968.045112781956;5999.220101386813;6007.399260074001;6008.849778755544;5643.6049476536655;5939.168516407706;5954.106883967396;5975.462208401243;5976.820695379124;5853.50245229156;5910.793122240722;5937.435943585396;5938.851528711773;5946.464818166386;5230.058589453897;5380.569554435636;5686.118651609007;5686.3744988051285;5687.061634830248;13365.66585835352;13593.388297526635;17576.56058598545;25659.486810263876;25685.74314256852;2113.5316608232965;2250.4261413339495;2325.279163459333;2505.4863752930787;2679.3435608275904;9283.205022796894;10219.926907674662;10865.619703310345;11191.048760855338;15067.397304107822;1701.5065630967006;1710.90590017549;1750.9299628014865;1752.3866646673562;1805.8194180581968;107568.46215768949;117658;118185;122414.38792806068;123771.38114309461;11581.305121692725;11637.88362116385;11687.590934317259;11720.941395293139;11810;13547.796783048263;14148.363323650461;15126.546203613909;15186.392544298325;15191.848081519265;8460.323174146053;8778.517181555077;8819.603117859711;8944.8658270126;8968.443993461831;8884.955575222082;8889.422187557788;8902.931648202257;8911.821763564822;8990.820183596423;3922.5292964844402;4591.47198072226;4634.768261586907;4642.6536304472;4687.882802929886;1681.90708803701;1868.9345872894382;1958.4320547041484;1965.49879780655;1976.8929399536305;342.0900404922893;402.41448692152846;425.2749062847837;758.9772306830804;970.7913647508897;8997.442049533067;11938.985186259266;12097.637070887888;12117.939410302892;12184.695382615506;457.98168073276696;468.8569986154188;473.4082397003745;862.9352798540135;952.7237101240701;10574.365538067741;10712.46437678113;10785.78428431427;10806.567737290421;10910.399928004035;516506;638739;643641;652240;656992;75153.4969300611;114558;133791;138529;141113;1054.1303424674675;1080.6974047266622;1112.1603189591806;1117.0951529261324;1120.7198200449889;17815.19831607607;17870.374536891148;17965.28138874483;18235.45293641193;18420.815791842448;36938;50903.72740681365;54307.72846135744;54864;55011.44988550063;70427.64786176036;72295.63852180705;75397.24602753902;76188.61905690435;78328.60835695785;8101.392395570474;8228.547429891414;8303.667853286042;8335.916640833513;8626.568671566522;9166.275457714357;12311.630651080482;12389.62831115068;12545.623631291075;12665.683357915757;10713.94643026811;10874.966878146563;10886.891131088587;10908.236423450282;11101.222914396238;10566.8943310569;10752.247342686249;10787.568497260021;11115.999560039636;11138.498767555317;11094.833577496505;11118.555257789922;11242.494087766234;11488.310701357945;11533.135014873751;8106.108328083788;8290.33677305802;8301.335893128657;8313.625886834985;8533.573321333784;9770.902290977283;10980.560777568808;10996.450177490933;11091.33451992883;11197.944010279896 09:03:30 INFO - PROCESS | 6597 | |3;mootools.html;1427.743006258873;1529.7246495630786;1568.4980806142034;1785.723212902;1943.5724140689044;7931;8568.10034946331;8714.346424018207;8728.91271087289;8729.083446238148;8407.453515521487;8726.738197854074;8728.170823771732;8729.694460693878;8733.126687331263;2171.7936796004433;2400.1839374612628;2449.1672831237374;2462.4336402627405;2466.2231397109945;2471.641611966264;2476.4799392127675;2480.268320845346;2481.702195736514;2483.081259933827;2405.9879700601473;2443.596806526921;2459.950800983979;2461.4707837814867;2463.1132792194794;2008.2368699894093;2213.671796921842;2401.6037353836596;2456.803455723538;2460.1635443949144;2434.0385547708734;2448.7796098351055;2475.9876200618883;2478.1326535712537;2484.155387168353;8563.529005904673;8710.869336959951;8712.651493940235;8718.258947989429;8735.475871447734;5374.677519348813;5523.585731070186;5534.667919924817;5582.581306402036;5603.327600687903;3542.0436482149935;3608.8195590220384;3669.2661467706566;3691.3724666806706;3708.295423869456;1036.212478516333;1043.9217058720626;1049.5591851422344;1069.3690722473777;1080.8000519903765;9933.85099223505;11710.297382157098;11903.523859045714;11905.285682859056;11922.10584206188;4448.466184057934;4563.133004729091;4728.668993170445;4876.000419913909;4943.382077240345;10277.7430564235;10287.897121028695;10294.948525257476;10297.845532317022;10301.381917084998;5522.91715624266;5527.032769265443;5527.2538207342295;5571.275734154534;5582.553395728413;4427.667924905645;4528.7509186994375;4535.886602834889;4548.363229147922;4794.5924596408995;3124.468840297155;4019.196160767858;4104.179164167179;4116.485439320085;4139.482564679415;1336.4587342126454;1353.8578449262686;1357.6266526705037;1367.2001878900955;1373.1623709537175;868.6699054359303;884.7168905950034;905.9864102038474;913.712180663089;920.4201353147608;5490.478404551562;5767.8558036049435;5925;5960.76156953717;6110.694465276719;723.1575214874612;972.3290929258789;991.5587563534302;999.0558921818812;1001.2640709078821;3791.2038471920655;3799.164183879542;3811.656950874379;3918.4122381642983;3966.0481484444103;10422.270441069204;10603.310784798963;10912.890871091346;10918.399488028075;10919.727006824734;10637.787244255072;10762.031417172491;10765.677029689123;10768;10792.622258220925;8060.677572897136;8337.708180213664;8378.916210837935;8454.492730436194;8458.873116903129;5775.9133612996675;5789.913151302818;5814.331351894586;5870.090136028923;5936.851578710394;9019.368644194843;9245.491497967676;9400.106989835955;9417.058294170529;9430.858537121812;9264.770067055551;9366.297527685587;9443.763905902408;9523.095305945924;9689.757756056155;6799.558028728315;6988.301169882971;7272.090988626422;7295.161056478573;7651.1201211859125;8228.876566851623;8278;8337.333013359039;8352.29005534512;8370.162983701824;7254.05697259374;7336.339729424378;7355.411567074728;7468.3278504934815;7487.438442116754;6212.937870621236;7198.74804381865;7282.745103921337;7327.1207455103595;7353.632318384166;6774.390304872585;6851.8972215415815;6854.588724676535;6860.0738900247825;6867.828304292432;4820.108279968217;4824.758762061953;4863.927041094315;4960.70235785854;5010.423801262901;4444.222261104358;4882.340883980617;4955.281484184845;4970.9254361183885;5001 09:03:30 INFO - PROCESS | 6597 | |4;prototype.html;269.94870974514845;282.42808313165824;327.04339806067264;329.9026787097806;336.2350652266096;6242.9699099648615;6494.415502604765;6519.771807986722;6563.967180164104;6579.243387010495;5561.916571251434;6514.576552524082;6539.313372095921;6549.672516374174;6550.508711846619;4593.88515287118;4691.225947718623;4722.952770472303;4769.093872164295;4809.302651115586;4730.621550275987;4761.619070474354;4785.545373189541;4792.6405519586;4801.399825021872;4122.546519882825;4744.596709279698;4759.666823322369;4781.3784208053075;4810.037992401516;3661.286049220403;4622.838200662977;4730.503297153784;4742.33607294979;4747.596454301389;4067.3492241241556;4067.9423349928934;4076.8165432555606;4083.285425050634;4089.9386509202386;6239.563230573862;6248.218972628421;6284.622922624657;6298.212723409574;6312.589681670676;5129.384473863161;5491.65454463655;5684.204211410406;5695.629784064023;5703.486686198262;6971.198312194112;6974.0585021022525;6977.7557785477775;6999.895001574981;7008.509404341698;4404.185225733249;4568.634509239286;4570.200214962369;4580.908381832378;4594.977025114887;7228.819279518001;7356.779296621164;7363.190049094595;7363.631818409058;7384.630768461556;4598.954010459919;4615.3999980003055;4619.769011549409;4621.745803980813;4624.953750462452;4640.396748422683;4675.064987002613;4675.696079754805;4682.344471773887;4682.601978831837;5577.414371490935;5579.8884022320135;5584.385717571064;5588.748506317225;5591.608587398843;5446.319210098738;6993.580385176906;6993.89509157363;7053.858922821618;7079.539829911039;1544.3127808125275;1612.234188760353;1631.1599526243995;1634.4769673704538;1638.1236038719228;820.4625969989667;968.0658164870933;1017.5827910556784;1018.9133923616428;1042.1975079188983;6466.159399278064;6661.53446241826;6666.866662666719;6695.799126026227;6701.229358623723;7338.302861228175;7339.192688804227;7377.77866664001;7387.593682347415;7408;6649.867002659919;6692.665366731644;6703.262641109474;6706.597604143767;6710.530262881649;3816.4275358695863;3864.3430616795213;3864.420336949424;3870.4000879863665;3872.0900588361715;7398.926010739931;7639.69441222356;7657.157712651604;7657.387409007266;7659.808504787425;7434.1822399536;7476.102867655807;7492;7506.399488040944;7517.736879209201;7706;7708.460407771402;7718.343940765098;7718.459707820399;7731.5747633879555;7022.964885175746;7300.744473943599;7302;7315.707371705285;7335.413166946738;6518.902216466851;7252.601106939169;7256.600886951268;7258.673359698932;7304.123505179413;6614.735410583523;7638.427117966064;7639.656215470428;7660.5403675779635;7673.194314596887;4589.632829373575;4634.37435946143;4638.652101092364;4644.442666879861;4650.627949764078;6040.486558642565;6686.030525573668;7281.417486600953;7292.854142917218;7300.56196628204;7034.261402552863;7073.292670733092;7076.823079423056;7089.539179953286;7101.183363913216;7012.158540975116;7052.788416347517;7059.61172135517;7081.362677358857;7097.46768992338;6875.724971001104;6877.759278425231;6887.552309099892;6888.793336199922;6889.896651550331;6714.328567143247;7004.824879378057;7050.717971281297;7069;7070.257622949722;6603.834904127435;6767.729290828509;6813.591184528944;6896.2069362022;6934.271901450275 09:03:30 INFO - PROCESS | 6597 | |5;yui.html;691.5539477037311;754.8188434775652;755.5013690963958;766.8351304469542;784.0160598448946;14765.852341476582;14866.851331486696;15023.774643380331;15058.924705376472;15166.469173578913;13706.588802335946;13939.581812545639;13944.790828137586;13955.720885582283;13963.58109256724;757.8711619024779;772.119783446871;918.2837386838244;950.8478643417054;955.69895482923;939.8120375924808;964.4261664309724;969.1181025267008;969.6073090398364;970.2189737261501;811.2658044469764;896.2516298890401;969.6606187834273;999.8750156230471;1002.5488530161421;783.165928286376;952.6046690623383;979.8873129590118;980.7450062983604;983.955721992512;727.7598392530452;735.481485552684;739.3641468337225;741.4809633256742;747.9738209162707;15385.769213461806;15498.61253468661;15518.844811551853;15523;15538.06771593708;12765.23408595478;12855.421506032157;12895.935520322431;12980.961523078131;13144.145630533985;8293.751187464326;9993.900060999369;10081.495925203784;10395.584176632925;10479.318844275098;3435.347284016029;3451.361498122855;3529.452934795085;3529.9647003529894;3542.5217595624517;79919;80091.5995420025;81157;84482.57758711229;85260;9653.517324133905;9668.178204852526;9742.269329800294;9868.161206297402;10194.745131371626;22419.775802242097;22722.318330450114;22725.545489090455;22768.544629107655;22799.772002279766;6275.811725648238;7587.203343648947;7612;7802.726904558313;7947.682092716226;3453.2748122894473;4029.1799128987855;4073.857414990461;4156.646685031807;4278.465191851019;3705.166337574078;3741.139537906297;3772.679322257639;3775.9993601695573;3779.7732136071922;511.6265126457707;623.0871772852793;656.4453037183572;663.3333499832637;671.5198632977445;489.53738716912886;586.1617886422372;590.7223604905687;612.7579606055583;636.8694417644372;7033.683484243222;7855.842883142305;8038.477498962548;8153.1846815317995;8296.128906464855;525.3511912787781;580.8199458167896;587.1310460518457;608.7990962982205;617.7745123030152;3802.258559580855;3823.751456155341;3873.283442563135;3881.437191607258;3980.9402858957137;90621.09378906258;90891;92056.6191507128;92968;94836;59663.806723866146;77057.61471192609;77217;77549;78626;70772.64613676899;70924.64537677278;71223.8632841026;71398.2150446243;72016.63991680113;7774.222577742406;7943.960280198562;7973;8026.518408895485;8079.515229086273;40505.39241911432;46321.84195395142;48418.5158148414;48851.26723099085;49024.754876225394;63157.052644209456;64443.03335449878;68521.65739171472;69147.65426172837;69915;8127.552984585905;9868.358556693793;10011.599536018477;10393.116585090353;10522.21083418762;10775.838362424727;10889.128869690247;11005.559777609125;11185.832212516656;11717.296962182296;9817.460039697886;10085.596576136872;10133.189344852246;10725.570977161138;10825.404602746925;10190.184785217312;10335.638252661121;10388.948055259676;10482.05661490439;11231.831522527005;10162.847557286499;10259.384436933808;10354.430506322225;10423.478826058818;11081.667549973514;8947.463152210888;9117.589708463003;9191.586378612845;9210;9336.626534938798;8882.555872206492;8949.59726812282;8983.730488085368;9014.323925705467;9126.543672816466 09:03:30 INFO - PROCESS | 6597 | __end_tp_report 09:03:30 INFO - PROCESS | 6597 | __start_cc_report 09:03:30 INFO - PROCESS | 6597 | _x_x_mozilla_cycle_collect,1233 09:03:30 INFO - PROCESS | 6597 | __end_cc_report 09:03:30 INFO - PROCESS | 6597 | __startTimestamp1463760210534__endTimestamp 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | ------- Summary: start ------- 09:03:30 INFO - PROCESS | 6597 | Number of tests: 6 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#0] dojo.html Cycles:165 Average:5650.62 Median:5821.42 stddev:629.85 (10.8%) stddev-sans-first:604.14 09:03:30 INFO - PROCESS | 6597 | Values: 3298.0 3361.3 3373.0 3381.4 3428.6 5617.1 5625.1 5632.7 5637.3 5646.2 5605.6 5656.0 5656.8 5662.5 5675.3 4999.3 5058.4 5124.9 5155.8 5211.7 5142.3 5144.3 5147.1 5147.6 5148.1 5065.1 5099.5 5110.8 5115.7 5123.5 5099.0 5148.6 5159.0 5161.0 5197.1 4933.9 4985.5 5056.1 5095.6 5111.8 5421.4 5433.9 5459.9 5478.7 5542.4 6129.8 6133.8 6136.1 6150.2 6177.8 6048.8 6060.4 6144.4 6154.6 6177.1 5694.9 5718.6 5748.4 5809.2 5821.4 6177.5 6183.0 6187.8 6193.8 6194.2 5804.6 6092.0 6095.8 6109.1 6109.1 6073.8 6126.8 6145.1 6157.7 6158.0 6081.8 6095.6 6128.5 6129.5 6134.0 5282.3 6016.7 6130.5 6156.7 6158.6 4824.9 4852.9 4902.4 4914.6 4920.3 4360.9 4363.6 4384.0 4393.3 4414.0 5629.4 5660.8 5672.5 5674.5 5704.3 5806.1 6103.9 6123.7 6175.8 6206.7 5673.3 5684.0 5686.0 5701.3 5703.7 5323.7 5334.5 5336.8 5339.3 5369.8 6113.4 6193.6 6221.3 6262.1 6290.7 6194.7 6212.2 6219.1 6222.9 6242.9 6243.7 6247.7 6260.2 6267.7 6271.0 5977.6 6023.3 6056.1 6130.8 6131.0 5170.3 6074.5 6207.8 6217.6 6224.0 5604.3 5684.0 6018.5 6083.6 6091.8 5904.0 6032.0 6080.7 6081.6 6121.1 5909.9 5925.3 5940.6 5947.0 5978.2 5709.4 6048.6 6049.3 6095.3 6101.7 4731.9 5849.6 5920.3 5982.0 5982.1 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#1] ext.html Cycles:180 Average:67362.77 Median:37981.65 stddev:141731.84 (373.2%) stddev-sans-first:142040.55 09:03:30 INFO - PROCESS | 6597 | Values: 509.1 534.8 544.6 547.1 547.1 31122.8 31810.5 31891.8 31931.5 39307.0 37903.1 39639.0 41919.8 42521.8 42631.4 9426.4 9662.0 9668.4 9671.1 9688.8 8684.8 9601.9 9775.9 11710.8 11891.9 11506.4 11571.4 11587.8 11589.9 11600.4 11495.0 11561.7 11565.8 11569.5 11578.7 8653.7 11595.1 11778.0 11788.6 11803.5 39307.0 39707.6 39779.4 39786.8 39833.6 3246.3 3285.4 3377.2 3396.0 3435.1 27942.0 28092.0 28178.9 28794.4 29252.7 2765.2 2784.5 2785.8 2804.8 2816.8 487962.0 488600.0 488624.0 488959.0 490251.0 63429.7 63815.4 63851.8 63883.0 64011.0 69291.0 70606.6 70636.6 70637.6 70668.3 17640.6 17718.5 17874.7 17896.2 17928.3 12584.6 13532.5 13572.3 13633.5 13640.9 17347.9 18491.7 19183.5 20190.4 20420.9 7978.8 8118.5 8131.6 8148.6 8167.8 982.6 1011.8 1066.8 1105.9 1125.7 30414.7 30522.5 30812.4 30877.1 31051.2 710.7 1097.4 1117.0 1125.8 1292.5 14420.8 14830.3 14884.6 15116.0 15128.4 634945.0 645138.0 649411.0 650783.0 651424.0 61716.1 62376.0 62377.0 62489.0 62563.0 1364.4 1400.7 1403.0 1430.6 1581.1 28860.6 29334.8 29384.4 29491.1 29978.6 47624.0 48004.0 48151.5 48189.0 48195.9 423985.0 439842.0 440095.0 440712.6 440920.0 23956.2 27712.7 45866.0 45955.8 47849.0 46870.1 47053.1 49138.0 49218.8 50468.2 38060.2 38441.8 39471.6 39686.0 41339.4 39236.2 39277.6 39442.4 40222.2 40367.6 39874.8 39988.6 40145.2 40592.4 40921.6 34619.0 34945.8 35310.3 36111.5 36210.6 26196.0 26213.9 26289.3 26377.9 26815.9 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#2] jquery.html Cycles:180 Average:34853.21 Median:2415.38 stddev:103853.94 (4299.7%) stddev-sans-first:104112.95 09:03:30 INFO - PROCESS | 6597 | Values: 342.9 394.2 396.9 399.9 411.7 16246.1 16282.5 19399.5 22656.2 22657.4 18901.6 20878.0 21185.9 21218.8 21273.7 5451.4 5710.6 5759.5 6007.0 6020.9 5890.7 5968.0 5999.2 6007.4 6008.8 5643.6 5939.2 5954.1 5975.5 5976.8 5853.5 5910.8 5937.4 5938.9 5946.5 5230.1 5380.6 5686.1 5686.4 5687.1 13365.7 13593.4 17576.6 25659.5 25685.7 2113.5 2250.4 2325.3 2505.5 2679.3 9283.2 10219.9 10865.6 11191.0 15067.4 1701.5 1710.9 1750.9 1752.4 1805.8 107568.5 117658.0 118185.0 122414.4 123771.4 11581.3 11637.9 11687.6 11720.9 11810.0 13547.8 14148.4 15126.5 15186.4 15191.8 8460.3 8778.5 8819.6 8944.9 8968.4 8885.0 8889.4 8902.9 8911.8 8990.8 3922.5 4591.5 4634.8 4642.7 4687.9 1681.9 1868.9 1958.4 1965.5 1976.9 342.1 402.4 425.3 759.0 970.8 8997.4 11939.0 12097.6 12117.9 12184.7 458.0 468.9 473.4 862.9 952.7 10574.4 10712.5 10785.8 10806.6 10910.4 516506.0 638739.0 643641.0 652240.0 656992.0 75153.5 114558.0 133791.0 138529.0 141113.0 1054.1 1080.7 1112.2 1117.1 1120.7 17815.2 17870.4 17965.3 18235.5 18420.8 36938.0 50903.7 54307.7 54864.0 55011.4 70427.6 72295.6 75397.2 76188.6 78328.6 8101.4 8228.5 8303.7 8335.9 8626.6 9166.3 12311.6 12389.6 12545.6 12665.7 10713.9 10875.0 10886.9 10908.2 11101.2 10566.9 10752.2 10787.6 11116.0 11138.5 11094.8 11118.6 11242.5 11488.3 11533.1 8106.1 8290.3 8301.3 8313.6 8533.6 9770.9 10980.6 10996.5 11091.3 11197.9 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#3] mootools.html Cycles:180 Average:5673.32 Median:4957.99 stddev:3139.58 (63.3%) stddev-sans-first:3132.17 09:03:30 INFO - PROCESS | 6597 | Values: 1427.7 1529.7 1568.5 1785.7 1943.6 7931.0 8568.1 8714.3 8728.9 8729.1 8407.5 8726.7 8728.2 8729.7 8733.1 2171.8 2400.2 2449.2 2462.4 2466.2 2471.6 2476.5 2480.3 2481.7 2483.1 2406.0 2443.6 2460.0 2461.5 2463.1 2008.2 2213.7 2401.6 2456.8 2460.2 2434.0 2448.8 2476.0 2478.1 2484.2 8563.5 8710.9 8712.7 8718.3 8735.5 5374.7 5523.6 5534.7 5582.6 5603.3 3542.0 3608.8 3669.3 3691.4 3708.3 1036.2 1043.9 1049.6 1069.4 1080.8 9933.9 11710.3 11903.5 11905.3 11922.1 4448.5 4563.1 4728.7 4876.0 4943.4 10277.7 10287.9 10294.9 10297.8 10301.4 5522.9 5527.0 5527.3 5571.3 5582.6 4427.7 4528.8 4535.9 4548.4 4794.6 3124.5 4019.2 4104.2 4116.5 4139.5 1336.5 1353.9 1357.6 1367.2 1373.2 868.7 884.7 906.0 913.7 920.4 5490.5 5767.9 5925.0 5960.8 6110.7 723.2 972.3 991.6 999.1 1001.3 3791.2 3799.2 3811.7 3918.4 3966.0 10422.3 10603.3 10912.9 10918.4 10919.7 10637.8 10762.0 10765.7 10768.0 10792.6 8060.7 8337.7 8378.9 8454.5 8458.9 5775.9 5789.9 5814.3 5870.1 5936.9 9019.4 9245.5 9400.1 9417.1 9430.9 9264.8 9366.3 9443.8 9523.1 9689.8 6799.6 6988.3 7272.1 7295.2 7651.1 8228.9 8278.0 8337.3 8352.3 8370.2 7254.1 7336.3 7355.4 7468.3 7487.4 6212.9 7198.7 7282.7 7327.1 7353.6 6774.4 6851.9 6854.6 6860.1 6867.8 4820.1 4824.8 4863.9 4960.7 5010.4 4444.2 4882.3 4955.3 4970.9 5001.0 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#4] prototype.html Cycles:180 Average:5728.94 Median:6571.61 stddev:1860.57 (28.3%) stddev-sans-first:1820.11 09:03:30 INFO - PROCESS | 6597 | Values: 269.9 282.4 327.0 329.9 336.2 6243.0 6494.4 6519.8 6564.0 6579.2 5561.9 6514.6 6539.3 6549.7 6550.5 4593.9 4691.2 4723.0 4769.1 4809.3 4730.6 4761.6 4785.5 4792.6 4801.4 4122.5 4744.6 4759.7 4781.4 4810.0 3661.3 4622.8 4730.5 4742.3 4747.6 4067.3 4067.9 4076.8 4083.3 4089.9 6239.6 6248.2 6284.6 6298.2 6312.6 5129.4 5491.7 5684.2 5695.6 5703.5 6971.2 6974.1 6977.8 6999.9 7008.5 4404.2 4568.6 4570.2 4580.9 4595.0 7228.8 7356.8 7363.2 7363.6 7384.6 4599.0 4615.4 4619.8 4621.7 4625.0 4640.4 4675.1 4675.7 4682.3 4682.6 5577.4 5579.9 5584.4 5588.7 5591.6 5446.3 6993.6 6993.9 7053.9 7079.5 1544.3 1612.2 1631.2 1634.5 1638.1 820.5 968.1 1017.6 1018.9 1042.2 6466.2 6661.5 6666.9 6695.8 6701.2 7338.3 7339.2 7377.8 7387.6 7408.0 6649.9 6692.7 6703.3 6706.6 6710.5 3816.4 3864.3 3864.4 3870.4 3872.1 7398.9 7639.7 7657.2 7657.4 7659.8 7434.2 7476.1 7492.0 7506.4 7517.7 7706.0 7708.5 7718.3 7718.5 7731.6 7023.0 7300.7 7302.0 7315.7 7335.4 6518.9 7252.6 7256.6 7258.7 7304.1 6614.7 7638.4 7639.7 7660.5 7673.2 4589.6 4634.4 4638.7 4644.4 4650.6 6040.5 6686.0 7281.4 7292.9 7300.6 7034.3 7073.3 7076.8 7089.5 7101.2 7012.2 7052.8 7059.6 7081.4 7097.5 6875.7 6877.8 6887.6 6888.8 6889.9 6714.3 7004.8 7050.7 7069.0 7070.3 6603.8 6767.7 6813.6 6896.2 6934.3 09:03:30 INFO - PROCESS | 6597 | 09:03:30 INFO - PROCESS | 6597 | [#5] yui.html Cycles:180 Average:18022.49 Median:31896.96 stddev:25549.03 (80.1%) stddev-sans-first:25587.56 09:03:30 INFO - PROCESS | 6597 | Values: 691.6 754.8 755.5 766.8 784.0 14765.9 14866.9 15023.8 15058.9 15166.5 13706.6 13939.6 13944.8 13955.7 13963.6 757.9 772.1 918.3 950.8 955.7 939.8 964.4 969.1 969.6 970.2 811.3 896.3 969.7 999.9 1002.5 783.2 952.6 979.9 980.7 984.0 727.8 735.5 739.4 741.5 748.0 15385.8 15498.6 15518.8 15523.0 15538.1 12765.2 12855.4 12895.9 12981.0 13144.1 8293.8 9993.9 10081.5 10395.6 10479.3 3435.3 3451.4 3529.5 3530.0 3542.5 79919.0 80091.6 81157.0 84482.6 85260.0 9653.5 9668.2 9742.3 9868.2 10194.7 22419.8 22722.3 22725.5 22768.5 22799.8 6275.8 7587.2 7612.0 7802.7 7947.7 3453.3 4029.2 4073.9 4156.6 4278.5 3705.2 3741.1 3772.7 3776.0 3779.8 511.6 623.1 656.4 663.3 671.5 489.5 586.2 590.7 612.8 636.9 7033.7 7855.8 8038.5 8153.2 8296.1 525.4 580.8 587.1 608.8 617.8 3802.3 3823.8 3873.3 3881.4 3980.9 90621.1 90891.0 92056.6 92968.0 94836.0 59663.8 77057.6 77217.0 77549.0 78626.0 70772.6 70924.6 71223.9 71398.2 72016.6 7774.2 7944.0 7973.0 8026.5 8079.5 40505.4 46321.8 48418.5 48851.3 49024.8 63157.1 64443.0 68521.7 69147.7 69915.0 8127.6 9868.4 10011.6 10393.1 10522.2 10775.8 10889.1 11005.6 11185.8 11717.3 9817.5 10085.6 10133.2 10725.6 10825.4 10190.2 10335.6 10388.9 10482.1 11231.8 10162.8 10259.4 10354.4 10423.5 11081.7 8947.5 9117.6 9191.6 9210.0 9336.6 8882.6 8949.6 8983.7 9014.3 9126.5 09:03:30 INFO - PROCESS | 6597 | -------- Summary: end -------- 09:03:30 INFO - PROCESS | 6597 | 09:03:31 INFO - TEST-INFO | 6597: exit 0 09:03:31 INFO - TEST-OK | dromaeo_css | took 1114844ms 09:03:31 INFO - TEST-START | kraken 09:03:31 INFO - operating with platform_type : mac_ 09:03:31 INFO - Initialising browser for kraken test... 09:03:31 INFO - TEST-INFO | started process 6599 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp_y9bTP/profile http://localhost:59409/getInfo.html) 09:03:33 INFO - PROCESS | 6599 | __metrics Screen width/height:1600/1200 09:03:33 INFO - PROCESS | 6599 | colorDepth:24 09:03:33 INFO - PROCESS | 6599 | Browser inner width/height: 1024/689 09:03:33 INFO - PROCESS | 6599 | __metrics 09:03:33 INFO - PROCESS | 6599 | 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" 09:03:33 ERROR - PROCESS | 6599 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:03:33 INFO - TEST-INFO | 6599: exit 0 09:03:33 INFO - Browser initialized. 09:03:33 INFO - Running cycle 1/1 for kraken test... 09:03:33 INFO - TEST-INFO | started process 6601 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp_y9bTP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:46 INFO - PROCESS | 6601 | RSS: Main: 211447808 09:03:46 INFO - PROCESS | 6601 | 09:03:48 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59409/tests/kraken/driver.html?testName=audio-beat-detection) 09:03:49 INFO - PROCESS | 6601 | RSS: Main: 272400384 09:03:49 INFO - PROCESS | 6601 | 09:03:52 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59409/tests/kraken/driver.html?testName=audio-dft) 09:03:53 INFO - PROCESS | 6601 | RSS: Main: 287588352 09:03:53 INFO - PROCESS | 6601 | 09:03:56 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59409/tests/kraken/driver.html?testName=audio-fft) 09:03:56 INFO - PROCESS | 6601 | RSS: Main: 374509568 09:03:56 INFO - PROCESS | 6601 | 09:03:58 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59409/tests/kraken/driver.html?testName=audio-oscillator) 09:03:59 INFO - PROCESS | 6601 | RSS: Main: 278183936 09:03:59 INFO - PROCESS | 6601 | 09:04:01 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59409/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:02 INFO - PROCESS | 6601 | RSS: Main: 279113728 09:04:02 INFO - PROCESS | 6601 | 09:04:05 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59409/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:05 INFO - PROCESS | 6601 | RSS: Main: 316383232 09:04:05 INFO - PROCESS | 6601 | 09:04:08 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59409/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:09 INFO - PROCESS | 6601 | RSS: Main: 317644800 09:04:09 INFO - PROCESS | 6601 | 09:04:12 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59409/tests/kraken/driver.html?testName=json-parse-financial) 09:04:12 INFO - PROCESS | 6601 | RSS: Main: 318656512 09:04:12 INFO - PROCESS | 6601 | 09:04:14 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59409/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:14 INFO - PROCESS | 6601 | RSS: Main: 309440512 09:04:14 INFO - PROCESS | 6601 | 09:04:16 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:17 INFO - PROCESS | 6601 | RSS: Main: 315731968 09:04:17 INFO - PROCESS | 6601 | 09:04:19 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:20 INFO - PROCESS | 6601 | RSS: Main: 318783488 09:04:20 INFO - PROCESS | 6601 | 09:04:23 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:23 INFO - PROCESS | 6601 | RSS: Main: 324268032 09:04:23 INFO - PROCESS | 6601 | 09:04:26 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:27 INFO - PROCESS | 6601 | RSS: Main: 322289664 09:04:27 INFO - PROCESS | 6601 | 09:04:28 INFO - PROCESS | 6601 | Cycle 1(1): loaded http://localhost:59409/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59409/tests/kraken/driver.html?testName=ai-astar) 09:04:29 INFO - PROCESS | 6601 | RSS: Main: 386375680 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | __start_tp_report 09:04:29 INFO - PROCESS | 6601 | _x_x_mozilla_page_load 09:04:29 INFO - PROCESS | 6601 | _x_x_mozilla_page_load_details 09:04:29 INFO - PROCESS | 6601 | |i|pagename|runs| 09:04:29 INFO - PROCESS | 6601 | |0;ai-astar;112;114;120;115;112;113;112;113;113;137 09:04:29 INFO - PROCESS | 6601 | |1;audio-beat-detection;160;122;146;181;150;141;146;121;166;137 09:04:29 INFO - PROCESS | 6601 | |2;audio-dft;187;191;187;191;187;185;187;188;188;186 09:04:29 INFO - PROCESS | 6601 | |3;audio-fft;71;80;76;157;56;55;62;66;139;56 09:04:29 INFO - PROCESS | 6601 | |4;audio-oscillator;91;91;145;79;108;123;119;78;107;161 09:04:29 INFO - PROCESS | 6601 | |5;imaging-gaussian-blur;117;130;91;85;87;84;88;86;87;85 09:04:29 INFO - PROCESS | 6601 | |6;imaging-darkroom;92;93;94;93;92;97;93;93;93;93 09:04:29 INFO - PROCESS | 6601 | |7;imaging-desaturate;90;88;86;87;90;87;88;89;88;84 09:04:29 INFO - PROCESS | 6601 | |8;json-parse-financial;61;65;64;63;65;63;64;83;58;58 09:04:29 INFO - PROCESS | 6601 | |9;json-stringify-tinderbox;64;65;67;74;74;71;69;72;70;70 09:04:29 INFO - PROCESS | 6601 | |10;stanford-crypto-aes;82;82;81;82;87;81;81;78;81;86 09:04:29 INFO - PROCESS | 6601 | |11;stanford-crypto-ccm;132;142;147;137;126;126;129;140;170;138 09:04:29 INFO - PROCESS | 6601 | |12;stanford-crypto-pbkdf2;167;167;176;166;169;166;165;166;165;164 09:04:29 INFO - PROCESS | 6601 | |13;stanford-crypto-sha256-iterative;68;67;70;68;67;68;67;68;66;68 09:04:29 INFO - PROCESS | 6601 | __end_tp_report 09:04:29 INFO - PROCESS | 6601 | __start_cc_report 09:04:29 INFO - PROCESS | 6601 | _x_x_mozilla_cycle_collect,2052 09:04:29 INFO - PROCESS | 6601 | __end_cc_report 09:04:29 INFO - PROCESS | 6601 | __startTimestamp1463760269241__endTimestamp 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | ------- Summary: start ------- 09:04:29 INFO - PROCESS | 6601 | Number of tests: 14 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#0] ai-astar Cycles:10 Average:116.10 Median:113.50 stddev:7.72 (6.8%) stddev-sans-first:8.05 09:04:29 INFO - PROCESS | 6601 | Values: 112.0 114.0 120.0 115.0 112.0 113.0 112.0 113.0 113.0 137.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:148.00 stddev:18.66 (12.6%) stddev-sans-first:19.19 09:04:29 INFO - PROCESS | 6601 | Values: 160.0 122.0 146.0 181.0 150.0 141.0 146.0 121.0 166.0 137.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#2] audio-dft Cycles:10 Average:187.70 Median:187.50 stddev:1.95 (1.0%) stddev-sans-first:2.05 09:04:29 INFO - PROCESS | 6601 | Values: 187.0 191.0 187.0 191.0 187.0 185.0 187.0 188.0 188.0 186.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#3] audio-fft Cycles:10 Average:81.80 Median:64.00 stddev:36.17 (56.5%) stddev-sans-first:38.15 09:04:29 INFO - PROCESS | 6601 | Values: 71.0 80.0 76.0 157.0 56.0 55.0 62.0 66.0 139.0 56.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:119.50 stddev:27.48 (23.0%) stddev-sans-first:28.25 09:04:29 INFO - PROCESS | 6601 | Values: 91.0 91.0 145.0 79.0 108.0 123.0 119.0 78.0 107.0 161.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:86.50 stddev:15.97 (18.5%) stddev-sans-first:14.60 09:04:29 INFO - PROCESS | 6601 | Values: 117.0 130.0 91.0 85.0 87.0 84.0 88.0 86.0 87.0 85.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:1.42 (1.5%) stddev-sans-first:1.42 09:04:29 INFO - PROCESS | 6601 | Values: 92.0 93.0 94.0 93.0 92.0 97.0 93.0 93.0 93.0 93.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.74 09:04:29 INFO - PROCESS | 6601 | Values: 90.0 88.0 86.0 87.0 90.0 87.0 88.0 89.0 88.0 84.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:7.03 (11.0%) stddev-sans-first:7.34 09:04:29 INFO - PROCESS | 6601 | Values: 61.0 65.0 64.0 63.0 65.0 63.0 64.0 83.0 58.0 58.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:3.44 (4.9%) stddev-sans-first:2.99 09:04:29 INFO - PROCESS | 6601 | Values: 64.0 65.0 67.0 74.0 74.0 71.0 69.0 72.0 70.0 70.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:2.60 (3.2%) stddev-sans-first:2.76 09:04:29 INFO - PROCESS | 6601 | Values: 82.0 82.0 81.0 82.0 87.0 81.0 81.0 78.0 81.0 86.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:139.00 stddev:13.02 (9.4%) stddev-sans-first:13.58 09:04:29 INFO - PROCESS | 6601 | Values: 132.0 142.0 147.0 137.0 126.0 126.0 129.0 140.0 170.0 138.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:166.50 stddev:3.41 (2.1%) stddev-sans-first:3.62 09:04:29 INFO - PROCESS | 6601 | Values: 167.0 167.0 176.0 166.0 169.0 166.0 165.0 166.0 165.0 164.0 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - PROCESS | 6601 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 09:04:29 INFO - PROCESS | 6601 | Values: 68.0 67.0 70.0 68.0 67.0 68.0 67.0 68.0 66.0 68.0 09:04:29 INFO - PROCESS | 6601 | -------- Summary: end -------- 09:04:29 INFO - PROCESS | 6601 | 09:04:29 INFO - TEST-INFO | 6601: exit 0 09:04:30 INFO - TEST-OK | kraken | took 58886ms 09:04:30 INFO - SUITE-END | took 1173s 09:04:30 INFO - Completed test suite (00:19:34) 09:04:30 INFO - javascript benchmark 09:04:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.967020329797, 3361.26052268501, 3372.9881035689286, 3381.4420620597602, 3428.554287942569, 5617.129344951539, 5625.099984002555, 5632.662040277581, 5637.267155269814, 5646.18130371096, 5605.5515558755305, 5656.0, 5656.830295091144, 5662.546996240301, 5675.290588676416, 4999.325091112708, 5058.392992840864, 5124.92312615311, 5155.81954631588, 5211.7133557654315, 5142.254373115896, 5144.279800827887, 5147.073526765181, 5147.639665223436, 5148.124818780816, 5065.113605119108, 5099.515546023122, 5110.770015349318, 5115.718635475047, 5123.462036486171, 5099.005693889693, 5148.588112950993, 5159.045576568347, 5160.974195129001, 5197.064528384889, 4933.876653083702, 4985.476524964899, 5056.064628043787, 5095.592352611782, 5111.7699703513435, 5421.376541697677, 5433.918491222635, 5459.890802183973, 5478.698671573062, 5542.39033706292, 6129.7854575090105, 6133.815985520441, 6136.140940268366, 6150.169727086831, 6177.814665559996, 6048.8185354439, 6060.3939606039485, 6144.41628045335, 6154.569180157391, 6177.073438984144, 5694.857628559278, 5718.59969802114, 5748.367679555246, 5809.215755872988, 5821.417858214229, 6177.505799536027, 6183.041628547582, 6187.783427580103, 6193.783217587363, 6194.225721784777, 5804.564657650693, 6092.0, 6095.756169753249, 6109.053096770008, 6109.083637454418, 6073.848153796102, 6126.823043872739, 6145.064304743992, 6157.692115394302, 6158.04550294705, 6081.7871374502565, 6095.642128744402, 6128.509719222451, 6129.540284478682, 6133.96933015341, 5282.286891269706, 6016.699165041818, 6130.509559235251, 6156.661383623854, 6158.599691020159, 4824.8793780155775, 4852.878678033077, 4902.3872015998, 4914.606831453475, 4920.261960705922, 4360.869173924787, 4363.563643635674, 4383.956160438419, 4393.319035549495, 4413.977930110329, 5629.437056294338, 5660.811229641739, 5672.489475947102, 5674.546036317085, 5704.286964129484, 5806.129080637938, 6103.938960610337, 6123.663198524035, 6175.84560385994, 6206.689665516707, 5673.319201695823, 5683.9715801420725, 5686.0333743263745, 5701.315842098892, 5703.686297253608, 5323.653962492425, 5334.519893209552, 5336.7865285388925, 5339.3058902342445, 5369.838904832784, 6113.419225173601, 6193.597416167935, 6221.253449586078, 6262.12330273762, 6290.748370065146, 6194.721237544231, 6212.223472065992, 6219.067139929047, 6222.937770622236, 6242.937570624417, 6243.687815609293, 6247.687615619292, 6260.154879091265, 6267.655278959519, 6270.968645156745, 5977.581569290282, 6023.337432882292, 6056.091586262096, 6130.846728831815, 6131.0, 5170.3278573785165, 6074.51403887679, 6207.844803879939, 6217.564770466024, 6223.96888015557, 5604.299462567179, 5684.0, 6018.518518518596, 6083.6349819011, 6091.847703807441, 5904.0, 6031.969840150771, 6080.695965201634, 6081.5742897996715, 6121.051237058263, 5909.85225369352, 5925.348211696796, 5940.55445841555, 5947.0, 5978.163057171912, 5709.400512946247, 6048.60684055535, 6049.274087109575, 6095.329513753395, 6101.694915254308, 4731.858044258678, 5849.64902105875, 5920.2895652521975, 5981.953158197385, 5982.102684597346], "value": 5611.419938704817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.14463700982355, 534.8154886564135, 544.5915563327504, 547.1300631995127, 547.1491830204034, 31122.84438577807, 31810.522842157385, 31891.840540797264, 31931.521027184583, 39307.0, 37903.05242368942, 39639.0, 41919.79040104803, 42521.78739106308, 42631.36052959208, 9426.387284826464, 9662.033796620351, 9668.371555848882, 9671.129598336149, 9688.806223875517, 8684.826303473927, 9601.855972160423, 9775.853362199572, 11710.765784684301, 11891.940540297286, 11506.367149806758, 11571.363575003414, 11587.768244635103, 11589.942050289737, 11600.419979001059, 11495.02292305155, 11561.65315040546, 11565.826512602318, 11569.479373428216, 11578.710532236677, 8653.697120600811, 11595.130365222642, 11778.0, 11788.64634060971, 11803.527858885634, 39307.01732456712, 39707.60292397068, 39779.40330895039, 39786.801065994485, 39833.60166398328, 3246.3020450603235, 3285.3593549257903, 3377.1894745261206, 3396.015155604872, 3435.0725304167736, 27942.0, 28092.01677941262, 28178.859105704545, 28794.424111517863, 29252.707472925213, 2765.1566272286946, 2784.5127102757137, 2785.7632101271415, 2804.8176868503483, 2816.788740844445, 487962.0, 488600.0, 488624.0, 488959.0, 490251.0, 63429.68285158544, 63815.36184638187, 63851.76518821819, 63883.0, 64011.0, 69291.0, 70606.64696676483, 70636.64681676558, 70637.64681176562, 70668.2933170672, 17640.55898602545, 17718.46844594664, 17874.731879021565, 17896.194671239566, 17928.282868685106, 12584.622461326175, 13532.458701651824, 13572.253526056162, 13633.454661813417, 13640.931795340959, 17347.913260433616, 18491.697462919918, 19183.52041198953, 20190.394288171377, 20420.89789551043, 7978.800529986681, 8118.5128892265475, 8131.552764598004, 8148.592570371457, 8167.753867686315, 982.5529384130203, 1011.8482227665909, 1066.7759770448292, 1105.8775343026834, 1125.7016890524017, 30414.695853041187, 30522.54216186759, 30812.383752325277, 30877.0736877894, 31051.223719407197, 710.6624353432181, 1097.3678069029877, 1116.9720756980978, 1125.8198688209848, 1292.4830067972887, 14420.783688244685, 14830.258487075604, 14884.627884302763, 15116.0, 15128.39486420553, 634945.0, 645138.0, 649411.0, 650783.0, 651424.0, 61716.074258886154, 62376.0, 62377.0, 62489.0, 62563.0, 1364.3587513868465, 1400.6568390744128, 1402.9579112626639, 1430.6351880270465, 1581.0987736989805, 28860.567091493223, 29334.769942935083, 29384.412311754073, 29491.115266542038, 29978.550321745628, 47624.0, 48004.0, 48151.518484814704, 48189.0, 48195.9033482158, 423985.0, 439842.0, 440095.0, 440712.59287408, 440920.0, 23956.161534346214, 27712.72287277182, 45866.0, 45955.77022114868, 47849.0, 46870.06259874852, 47053.05893882172, 49138.0, 49218.75390623024, 50468.242976354646, 38060.238795223384, 38441.80779096087, 39471.605283946796, 39686.0, 41339.379909300784, 39236.21527569376, 39277.60722392739, 39442.40836387514, 40222.1955560893, 40367.596324036385, 39874.800625996686, 39988.60011399849, 40145.19709605733, 40592.39111413272, 40921.59078409178, 34619.0, 34945.82527087348, 35310.29379412346, 36111.45832812457, 36210.637893620726, 26196.0, 26213.868930655226, 26289.342766430993, 26377.868110659325, 26815.865920670272], "value": 20566.32265491889, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.85600047979847, 394.2410859096239, 396.93252147134984, 399.94000899865034, 411.7282593488296, 16246.106464144497, 16282.51152465425, 19399.515012124702, 22656.207032753835, 22657.433564160903, 18901.62196756064, 20878.0, 21185.894070529663, 21218.787812121875, 21273.68089478655, 5451.400345961941, 5710.600257981943, 5759.539236861041, 6006.969965150168, 6020.879582408349, 5890.705464726768, 5968.045112781956, 5999.220101386813, 6007.399260074001, 6008.849778755544, 5643.6049476536655, 5939.168516407706, 5954.106883967396, 5975.462208401243, 5976.820695379124, 5853.50245229156, 5910.793122240722, 5937.435943585396, 5938.851528711773, 5946.464818166386, 5230.058589453897, 5380.569554435636, 5686.118651609007, 5686.3744988051285, 5687.061634830248, 13365.66585835352, 13593.388297526635, 17576.56058598545, 25659.486810263876, 25685.74314256852, 2113.5316608232965, 2250.4261413339495, 2325.279163459333, 2505.4863752930787, 2679.3435608275904, 9283.205022796894, 10219.926907674662, 10865.619703310345, 11191.048760855338, 15067.397304107822, 1701.5065630967006, 1710.90590017549, 1750.9299628014865, 1752.3866646673562, 1805.8194180581968, 107568.46215768949, 117658.0, 118185.0, 122414.38792806068, 123771.38114309461, 11581.305121692725, 11637.88362116385, 11687.590934317259, 11720.941395293139, 11810.0, 13547.796783048263, 14148.363323650461, 15126.546203613909, 15186.392544298325, 15191.848081519265, 8460.323174146053, 8778.517181555077, 8819.603117859711, 8944.8658270126, 8968.443993461831, 8884.955575222082, 8889.422187557788, 8902.931648202257, 8911.821763564822, 8990.820183596423, 3922.5292964844402, 4591.47198072226, 4634.768261586907, 4642.6536304472, 4687.882802929886, 1681.90708803701, 1868.9345872894382, 1958.4320547041484, 1965.49879780655, 1976.8929399536305, 342.0900404922893, 402.41448692152846, 425.2749062847837, 758.9772306830804, 970.7913647508897, 8997.442049533067, 11938.985186259266, 12097.637070887888, 12117.939410302892, 12184.695382615506, 457.98168073276696, 468.8569986154188, 473.4082397003745, 862.9352798540135, 952.7237101240701, 10574.365538067741, 10712.46437678113, 10785.78428431427, 10806.567737290421, 10910.399928004035, 516506.0, 638739.0, 643641.0, 652240.0, 656992.0, 75153.4969300611, 114558.0, 133791.0, 138529.0, 141113.0, 1054.1303424674675, 1080.6974047266622, 1112.1603189591806, 1117.0951529261324, 1120.7198200449889, 17815.19831607607, 17870.374536891148, 17965.28138874483, 18235.45293641193, 18420.815791842448, 36938.0, 50903.72740681365, 54307.72846135744, 54864.0, 55011.44988550063, 70427.64786176036, 72295.63852180705, 75397.24602753902, 76188.61905690435, 78328.60835695785, 8101.392395570474, 8228.547429891414, 8303.667853286042, 8335.916640833513, 8626.568671566522, 9166.275457714357, 12311.630651080482, 12389.62831115068, 12545.623631291075, 12665.683357915757, 10713.94643026811, 10874.966878146563, 10886.891131088587, 10908.236423450282, 11101.222914396238, 10566.8943310569, 10752.247342686249, 10787.568497260021, 11115.999560039636, 11138.498767555317, 11094.833577496505, 11118.555257789922, 11242.494087766234, 11488.310701357945, 11533.135014873751, 8106.108328083788, 8290.33677305802, 8301.335893128657, 8313.625886834985, 8533.573321333784, 9770.902290977283, 10980.560777568808, 10996.450177490933, 11091.33451992883, 11197.944010279896], "value": 9136.038973313449, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.743006258873, 1529.7246495630786, 1568.4980806142034, 1785.723212902, 1943.5724140689044, 7931.0, 8568.10034946331, 8714.346424018207, 8728.91271087289, 8729.083446238148, 8407.453515521487, 8726.738197854074, 8728.170823771732, 8729.694460693878, 8733.126687331263, 2171.7936796004433, 2400.1839374612628, 2449.1672831237374, 2462.4336402627405, 2466.2231397109945, 2471.641611966264, 2476.4799392127675, 2480.268320845346, 2481.702195736514, 2483.081259933827, 2405.9879700601473, 2443.596806526921, 2459.950800983979, 2461.4707837814867, 2463.1132792194794, 2008.2368699894093, 2213.671796921842, 2401.6037353836596, 2456.803455723538, 2460.1635443949144, 2434.0385547708734, 2448.7796098351055, 2475.9876200618883, 2478.1326535712537, 2484.155387168353, 8563.529005904673, 8710.869336959951, 8712.651493940235, 8718.258947989429, 8735.475871447734, 5374.677519348813, 5523.585731070186, 5534.667919924817, 5582.581306402036, 5603.327600687903, 3542.0436482149935, 3608.8195590220384, 3669.2661467706566, 3691.3724666806706, 3708.295423869456, 1036.212478516333, 1043.9217058720626, 1049.5591851422344, 1069.3690722473777, 1080.8000519903765, 9933.85099223505, 11710.297382157098, 11903.523859045714, 11905.285682859056, 11922.10584206188, 4448.466184057934, 4563.133004729091, 4728.668993170445, 4876.000419913909, 4943.382077240345, 10277.7430564235, 10287.897121028695, 10294.948525257476, 10297.845532317022, 10301.381917084998, 5522.91715624266, 5527.032769265443, 5527.2538207342295, 5571.275734154534, 5582.553395728413, 4427.667924905645, 4528.7509186994375, 4535.886602834889, 4548.363229147922, 4794.5924596408995, 3124.468840297155, 4019.196160767858, 4104.179164167179, 4116.485439320085, 4139.482564679415, 1336.4587342126454, 1353.8578449262686, 1357.6266526705037, 1367.2001878900955, 1373.1623709537175, 868.6699054359303, 884.7168905950034, 905.9864102038474, 913.712180663089, 920.4201353147608, 5490.478404551562, 5767.8558036049435, 5925.0, 5960.76156953717, 6110.694465276719, 723.1575214874612, 972.3290929258789, 991.5587563534302, 999.0558921818812, 1001.2640709078821, 3791.2038471920655, 3799.164183879542, 3811.656950874379, 3918.4122381642983, 3966.0481484444103, 10422.270441069204, 10603.310784798963, 10912.890871091346, 10918.399488028075, 10919.727006824734, 10637.787244255072, 10762.031417172491, 10765.677029689123, 10768.0, 10792.622258220925, 8060.677572897136, 8337.708180213664, 8378.916210837935, 8454.492730436194, 8458.873116903129, 5775.9133612996675, 5789.913151302818, 5814.331351894586, 5870.090136028923, 5936.851578710394, 9019.368644194843, 9245.491497967676, 9400.106989835955, 9417.058294170529, 9430.858537121812, 9264.770067055551, 9366.297527685587, 9443.763905902408, 9523.095305945924, 9689.757756056155, 6799.558028728315, 6988.301169882971, 7272.090988626422, 7295.161056478573, 7651.1201211859125, 8228.876566851623, 8278.0, 8337.333013359039, 8352.29005534512, 8370.162983701824, 7254.05697259374, 7336.339729424378, 7355.411567074728, 7468.3278504934815, 7487.438442116754, 6212.937870621236, 7198.74804381865, 7282.745103921337, 7327.1207455103595, 7353.632318384166, 6774.390304872585, 6851.8972215415815, 6854.588724676535, 6860.0738900247825, 6867.828304292432, 4820.108279968217, 4824.758762061953, 4863.927041094315, 4960.70235785854, 5010.423801262901, 4444.222261104358, 4882.340883980617, 4955.281484184845, 4970.9254361183885, 5001.0], "value": 4589.292347738438, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.94870974514845, 282.42808313165824, 327.04339806067264, 329.9026787097806, 336.2350652266096, 6242.9699099648615, 6494.415502604765, 6519.771807986722, 6563.967180164104, 6579.243387010495, 5561.916571251434, 6514.576552524082, 6539.313372095921, 6549.672516374174, 6550.508711846619, 4593.88515287118, 4691.225947718623, 4722.952770472303, 4769.093872164295, 4809.302651115586, 4730.621550275987, 4761.619070474354, 4785.545373189541, 4792.6405519586, 4801.399825021872, 4122.546519882825, 4744.596709279698, 4759.666823322369, 4781.3784208053075, 4810.037992401516, 3661.286049220403, 4622.838200662977, 4730.503297153784, 4742.33607294979, 4747.596454301389, 4067.3492241241556, 4067.9423349928934, 4076.8165432555606, 4083.285425050634, 4089.9386509202386, 6239.563230573862, 6248.218972628421, 6284.622922624657, 6298.212723409574, 6312.589681670676, 5129.384473863161, 5491.65454463655, 5684.204211410406, 5695.629784064023, 5703.486686198262, 6971.198312194112, 6974.0585021022525, 6977.7557785477775, 6999.895001574981, 7008.509404341698, 4404.185225733249, 4568.634509239286, 4570.200214962369, 4580.908381832378, 4594.977025114887, 7228.819279518001, 7356.779296621164, 7363.190049094595, 7363.631818409058, 7384.630768461556, 4598.954010459919, 4615.3999980003055, 4619.769011549409, 4621.745803980813, 4624.953750462452, 4640.396748422683, 4675.064987002613, 4675.696079754805, 4682.344471773887, 4682.601978831837, 5577.414371490935, 5579.8884022320135, 5584.385717571064, 5588.748506317225, 5591.608587398843, 5446.319210098738, 6993.580385176906, 6993.89509157363, 7053.858922821618, 7079.539829911039, 1544.3127808125275, 1612.234188760353, 1631.1599526243995, 1634.4769673704538, 1638.1236038719228, 820.4625969989667, 968.0658164870933, 1017.5827910556784, 1018.9133923616428, 1042.1975079188983, 6466.159399278064, 6661.53446241826, 6666.866662666719, 6695.799126026227, 6701.229358623723, 7338.302861228175, 7339.192688804227, 7377.77866664001, 7387.593682347415, 7408.0, 6649.867002659919, 6692.665366731644, 6703.262641109474, 6706.597604143767, 6710.530262881649, 3816.4275358695863, 3864.3430616795213, 3864.420336949424, 3870.4000879863665, 3872.0900588361715, 7398.926010739931, 7639.69441222356, 7657.157712651604, 7657.387409007266, 7659.808504787425, 7434.1822399536, 7476.102867655807, 7492.0, 7506.399488040944, 7517.736879209201, 7706.0, 7708.460407771402, 7718.343940765098, 7718.459707820399, 7731.5747633879555, 7022.964885175746, 7300.744473943599, 7302.0, 7315.707371705285, 7335.413166946738, 6518.902216466851, 7252.601106939169, 7256.600886951268, 7258.673359698932, 7304.123505179413, 6614.735410583523, 7638.427117966064, 7639.656215470428, 7660.5403675779635, 7673.194314596887, 4589.632829373575, 4634.37435946143, 4638.652101092364, 4644.442666879861, 4650.627949764078, 6040.486558642565, 6686.030525573668, 7281.417486600953, 7292.854142917218, 7300.56196628204, 7034.261402552863, 7073.292670733092, 7076.823079423056, 7089.539179953286, 7101.183363913216, 7012.158540975116, 7052.788416347517, 7059.61172135517, 7081.362677358857, 7097.46768992338, 6875.724971001104, 6877.759278425231, 6887.552309099892, 6888.793336199922, 6889.896651550331, 6714.328567143247, 7004.824879378057, 7050.717971281297, 7069.0, 7070.257622949722, 6603.834904127435, 6767.729290828509, 6813.591184528944, 6896.2069362022, 6934.271901450275], "value": 5092.148125272984, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.5539477037311, 754.8188434775652, 755.5013690963958, 766.8351304469542, 784.0160598448946, 14765.852341476582, 14866.851331486696, 15023.774643380331, 15058.924705376472, 15166.469173578913, 13706.588802335946, 13939.581812545639, 13944.790828137586, 13955.720885582283, 13963.58109256724, 757.8711619024779, 772.119783446871, 918.2837386838244, 950.8478643417054, 955.69895482923, 939.8120375924808, 964.4261664309724, 969.1181025267008, 969.6073090398364, 970.2189737261501, 811.2658044469764, 896.2516298890401, 969.6606187834273, 999.8750156230471, 1002.5488530161421, 783.165928286376, 952.6046690623383, 979.8873129590118, 980.7450062983604, 983.955721992512, 727.7598392530452, 735.481485552684, 739.3641468337225, 741.4809633256742, 747.9738209162707, 15385.769213461806, 15498.61253468661, 15518.844811551853, 15523.0, 15538.06771593708, 12765.23408595478, 12855.421506032157, 12895.935520322431, 12980.961523078131, 13144.145630533985, 8293.751187464326, 9993.900060999369, 10081.495925203784, 10395.584176632925, 10479.318844275098, 3435.347284016029, 3451.361498122855, 3529.452934795085, 3529.9647003529894, 3542.5217595624517, 79919.0, 80091.5995420025, 81157.0, 84482.57758711229, 85260.0, 9653.517324133905, 9668.178204852526, 9742.269329800294, 9868.161206297402, 10194.745131371626, 22419.775802242097, 22722.318330450114, 22725.545489090455, 22768.544629107655, 22799.772002279766, 6275.811725648238, 7587.203343648947, 7612.0, 7802.726904558313, 7947.682092716226, 3453.2748122894473, 4029.1799128987855, 4073.857414990461, 4156.646685031807, 4278.465191851019, 3705.166337574078, 3741.139537906297, 3772.679322257639, 3775.9993601695573, 3779.7732136071922, 511.6265126457707, 623.0871772852793, 656.4453037183572, 663.3333499832637, 671.5198632977445, 489.53738716912886, 586.1617886422372, 590.7223604905687, 612.7579606055583, 636.8694417644372, 7033.683484243222, 7855.842883142305, 8038.477498962548, 8153.1846815317995, 8296.128906464855, 525.3511912787781, 580.8199458167896, 587.1310460518457, 608.7990962982205, 617.7745123030152, 3802.258559580855, 3823.751456155341, 3873.283442563135, 3881.437191607258, 3980.9402858957137, 90621.09378906258, 90891.0, 92056.6191507128, 92968.0, 94836.0, 59663.806723866146, 77057.61471192609, 77217.0, 77549.0, 78626.0, 70772.64613676899, 70924.64537677278, 71223.8632841026, 71398.2150446243, 72016.63991680113, 7774.222577742406, 7943.960280198562, 7973.0, 8026.518408895485, 8079.515229086273, 40505.39241911432, 46321.84195395142, 48418.5158148414, 48851.26723099085, 49024.754876225394, 63157.052644209456, 64443.03335449878, 68521.65739171472, 69147.65426172837, 69915.0, 8127.552984585905, 9868.358556693793, 10011.599536018477, 10393.116585090353, 10522.21083418762, 10775.838362424727, 10889.128869690247, 11005.559777609125, 11185.832212516656, 11717.296962182296, 9817.460039697886, 10085.596576136872, 10133.189344852246, 10725.570977161138, 10825.404602746925, 10190.184785217312, 10335.638252661121, 10388.948055259676, 10482.05661490439, 11231.831522527005, 10162.847557286499, 10259.384436933808, 10354.430506322225, 10423.478826058818, 11081.667549973514, 8947.463152210888, 9117.589708463003, 9191.586378612845, 9210.0, 9336.626534938798, 8882.555872206492, 8949.59726812282, 8983.730488085368, 9014.323925705467, 9126.543672816466], "value": 6806.574181652755, "unit": "score"}], "name": "dromaeo_css", "value": 7426.204903102245, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 120.0, 115.0, 112.0, 113.0, 112.0, 113.0, 113.0, 137.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 122.0, 146.0, 181.0, 150.0, 141.0, 146.0, 121.0, 166.0, 137.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 187.0, 191.0, 187.0, 185.0, 187.0, 188.0, 188.0, 186.0], "value": 187.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 76.0, 157.0, 56.0, 55.0, 62.0, 66.0, 139.0, 56.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 145.0, 79.0, 108.0, 123.0, 119.0, 78.0, 107.0, 161.0], "value": 110.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 91.0, 85.0, 87.0, 84.0, 88.0, 86.0, 87.0, 85.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 93.0, 92.0, 97.0, 93.0, 93.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 86.0, 87.0, 90.0, 87.0, 88.0, 89.0, 88.0, 84.0], "value": 87.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 63.0, 65.0, 63.0, 64.0, 83.0, 58.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 74.0, 74.0, 71.0, 69.0, 72.0, 70.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 82.0, 87.0, 81.0, 81.0, 78.0, 81.0, 86.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 142.0, 147.0, 137.0, 126.0, 126.0, 129.0, 140.0, 170.0, 138.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 176.0, 166.0, 169.0, 166.0, 165.0, 166.0, 165.0, 164.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 68.0, 67.0, 68.0, 67.0, 68.0, 66.0, 68.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1507.3999999999999, "alertThreshold": 2.0}]} 09:04:30 INFO - Return code: 0 09:04:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:04:30 ERROR - # TBPL SUCCESS # 09:04:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:30 INFO - 2016-05-20 09:04:30.576 screenresolution[6605:113323] starting screenresolution argv=screenresolution get 09:04:30 INFO - 2016-05-20 09:04:30.579 screenresolution[6605:113323] Display 0: 1600x1200x32@60 09:04:30 INFO - 2016-05-20 09:04:30.585 screenresolution[6606:113326] starting screenresolution argv=screenresolution list 09:04:30 INFO - Available Modes on Display 0 09:04:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:30 INFO - Intel Iris: 09:04:30 INFO - Chipset Model: Intel Iris 09:04:30 INFO - Type: GPU 09:04:30 INFO - Bus: Built-In 09:04:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:30 INFO - Vendor: Intel (0x8086) 09:04:30 INFO - Device ID: 0x0a2e 09:04:30 INFO - Revision ID: 0x0009 09:04:30 INFO - Displays: 09:04:30 INFO - Display: 09:04:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:30 INFO - Display Serial Number: 1600x1200 60 09:04:30 INFO - Main Display: Yes 09:04:30 INFO - Mirror: Off 09:04:30 INFO - Online: Yes 09:04:30 INFO - Rotation: Supported 09:04:30 INFO - Return code: 0 09:04:30 INFO - Running post-action listener: _resource_record_post_action 09:04:30 INFO - Running post-run listener: _resource_record_post_run 09:04:31 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 524288; Write bytes: 653762560; Read time: 7; Write time: 1895 09:04:31 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 348668416; Write bytes: 345863680; Read time: 26489; Write time: 639 09:04:31 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 499712; Write bytes: 297105920; Read time: 6; Write time: 1221 09:04:31 INFO - Running post-run listener: _upload_blobber_files 09:04:31 INFO - Blob upload gear active. 09:04:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:04:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:04:32 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:04:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:04:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:33 INFO - (blobuploader) - INFO - Done attempting. 09:04:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:04:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:04:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:34 INFO - (blobuploader) - INFO - Done attempting. 09:04:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:34 INFO - Return code: 0 09:04:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e17ee8e2c7dbec6b3457c844682172306fffa5f11c5441cf354a1f1667a813c98ffeff023b6fd02a52f6a98143503fa26f4333685f7936c34be38aede6cfe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df02f10313882215e06b43d3487787c9192a983bcc5452b6038b901bbfe16d0a961ba41340e04d92c0506c7e1650c106ad9bbdedf6010f70dfdb0e8a542d6217"} 09:04:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:34 INFO - Contents: 09:04:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e17ee8e2c7dbec6b3457c844682172306fffa5f11c5441cf354a1f1667a813c98ffeff023b6fd02a52f6a98143503fa26f4333685f7936c34be38aede6cfe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df02f10313882215e06b43d3487787c9192a983bcc5452b6038b901bbfe16d0a961ba41340e04d92c0506c7e1650c106ad9bbdedf6010f70dfdb0e8a542d6217"} 09:04:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:34 INFO - Copying logs to upload dir... 09:04:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.621643 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-20 09:04:34.825750) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 09:04:34.829049) ========= 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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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/54e17ee8e2c7dbec6b3457c844682172306fffa5f11c5441cf354a1f1667a813c98ffeff023b6fd02a52f6a98143503fa26f4333685f7936c34be38aede6cfe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df02f10313882215e06b43d3487787c9192a983bcc5452b6038b901bbfe16d0a961ba41340e04d92c0506c7e1650c106ad9bbdedf6010f70dfdb0e8a542d6217"} build_url:https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011861 build_url: 'https://queue.taskcluster.net/v1/task/aRm8sitoTo2MWNO6xDgxjA/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/54e17ee8e2c7dbec6b3457c844682172306fffa5f11c5441cf354a1f1667a813c98ffeff023b6fd02a52f6a98143503fa26f4333685f7936c34be38aede6cfe8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df02f10313882215e06b43d3487787c9192a983bcc5452b6038b901bbfe16d0a961ba41340e04d92c0506c7e1650c106ad9bbdedf6010f70dfdb0e8a542d6217"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 09:04:34.863438) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 09:04:34.864212) ========= 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.8TXG2Yacqy/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.4nKsTnzfvH/Listeners TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/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.005412 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 09:04:34.917614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 09:04:34.918312) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 09:04:34.919124) ========= ========= Total master_lag: 0.19 =========