builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1464255084.06 results: success (0) buildid: 20160526004016 builduid: a584d421472e4c1aa23b17ed9087214f revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.057257) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.057682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.057954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.126211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.126518) ========= 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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-26 02:31:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-05-26 02:31:24 (18.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111884 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.913105) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.913428) ========= 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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.026701 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:24.967340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 02:31:24.967687) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --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 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-26 02:31:25,042 truncating revision to first 12 chars 2016-05-26 02:31:25,042 Setting DEBUG logging. 2016-05-26 02:31:25,042 attempt 1/10 2016-05-26 02:31:25,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/33693d8bdec1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 02:31:25,796 unpacking tar archive at: mozilla-aurora-33693d8bdec1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.015485 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 02:31:26.004269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:26.004729) ========= script_repo_revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:26.005163) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:26.005469) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:31:26.022337) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-26 02:31:26.022641) ========= /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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:31:26 INFO - MultiFileLogger online at 20160526 02:31:26 in /builds/slave/test 02:31:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:31:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:31:26 INFO - {'append_to_log': False, 02:31:26 INFO - 'base_work_dir': '/builds/slave/test', 02:31:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:31:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:31:26 INFO - 'branch': 'Mozilla-Aurora', 02:31:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:31:26 INFO - 'config_files': ('talos/mac_config.py',), 02:31:26 INFO - 'default_actions': ('clobber', 02:31:26 INFO - 'read-buildbot-config', 02:31:26 INFO - 'download-and-extract', 02:31:26 INFO - 'populate-webroot', 02:31:26 INFO - 'create-virtualenv', 02:31:26 INFO - 'install', 02:31:26 INFO - 'run-tests'), 02:31:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:31:26 INFO - 'download_minidump_stackwalk': True, 02:31:26 INFO - 'download_symbols': 'ondemand', 02:31:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:31:26 INFO - 'tooltool.py': '/tools/tooltool.py', 02:31:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:31:26 INFO - '/tools/misc-python/virtualenv.py')}, 02:31:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:31:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:31:26 INFO - 'installer_path': 'installer.exe', 02:31:26 INFO - 'log_level': 'info', 02:31:26 INFO - 'log_name': 'talos', 02:31:26 INFO - 'log_to_console': True, 02:31:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:31:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:31:26 INFO - 'opt_config_files': (), 02:31:26 INFO - 'pip_index': False, 02:31:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:31:26 INFO - 'cmd': ('bash', 02:31:26 INFO - '-c', 02:31:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:31:26 INFO - 'enabled': True, 02:31:26 INFO - 'halt_on_failure': False, 02:31:26 INFO - 'name': 'check_screen_resolution'},), 02:31:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:31:26 INFO - 'cmd': ('bash', 02:31:26 INFO - '-c', 02:31:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:31:26 INFO - 'enabled': True, 02:31:26 INFO - 'halt_on_failure': False, 02:31:26 INFO - 'name': 'check_screen_resolution'},), 02:31:26 INFO - 'run_cmd_checks_enabled': True, 02:31:26 INFO - 'sps_profile': False, 02:31:26 INFO - 'sps_profile_interval': 0, 02:31:26 INFO - 'suite': 'dromaeojs', 02:31:26 INFO - 'system_bits': '32', 02:31:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:31:26 INFO - 'title': 't-yosemite-r7-0212', 02:31:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:31:26 INFO - 'use_talos_json': True, 02:31:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:31:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:31:26 INFO - 'work_dir': 'build'} 02:31:26 INFO - ##### 02:31:26 INFO - ##### Running clobber step. 02:31:26 INFO - ##### 02:31:26 INFO - Running pre-action listener: _resource_record_pre_action 02:31:26 INFO - Running main action method: clobber 02:31:26 INFO - rmtree: /builds/slave/test/build 02:31:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:31:28 INFO - Running post-action listener: _resource_record_post_action 02:31:28 INFO - ##### 02:31:28 INFO - ##### Running read-buildbot-config step. 02:31:28 INFO - ##### 02:31:28 INFO - Running pre-action listener: _resource_record_pre_action 02:31:28 INFO - Running main action method: read_buildbot_config 02:31:28 INFO - Using buildbot properties: 02:31:28 INFO - { 02:31:28 INFO - "project": "", 02:31:28 INFO - "product": "firefox", 02:31:28 INFO - "script_repo_revision": "production", 02:31:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:31:28 INFO - "repository": "", 02:31:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:31:28 INFO - "buildid": "20160526004016", 02:31:28 INFO - "stage_platform": "macosx64", 02:31:28 INFO - "basedir": "/builds/slave/test", 02:31:28 INFO - "nightly_build": "True", 02:31:28 INFO - "buildnumber": 324, 02:31:28 INFO - "slavename": "t-yosemite-r7-0212", 02:31:28 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:31:28 INFO - "platform": "yosemite_r7", 02:31:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:31:28 INFO - "branch": "mozilla-aurora", 02:31:28 INFO - "repo_path": "releases/mozilla-aurora", 02:31:28 INFO - "revision": "33693d8bdec17f07fc460ca7cc908ade88f97b86", 02:31:28 INFO - "pgo_build": "False", 02:31:28 INFO - "builduid": "a584d421472e4c1aa23b17ed9087214f", 02:31:28 INFO - "slavebuilddir": "test" 02:31:28 INFO - } 02:31:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:31:28 INFO - Running post-action listener: _resource_record_post_action 02:31:28 INFO - ##### 02:31:28 INFO - ##### Running download-and-extract step. 02:31:28 INFO - ##### 02:31:28 INFO - Running pre-action listener: _resource_record_pre_action 02:31:28 INFO - Running main action method: download_and_extract 02:31:28 INFO - mkdir: /builds/slave/test/build/tests 02:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:28 INFO - https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:31:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:31:38 INFO - Downloaded 1199 bytes. 02:31:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:31:38 INFO - Using the following test package requirements: 02:31:38 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:31:38 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:31:38 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:31:38 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'jsshell-mac.zip'], 02:31:38 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:31:38 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:31:38 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:31:38 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:31:38 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:31:38 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:31:38 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:31:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:38 INFO - https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:31:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:31:44 INFO - Downloaded 18425467 bytes. 02:31:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:31:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:31:47 INFO - Return code: 0 02:31:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:47 INFO - https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:31:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:31:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:31:51 INFO - Downloaded 18425467 bytes. 02:31:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:31:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:31:55 INFO - Return code: 0 02:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:55 INFO - https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:31:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:31:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:31:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:31:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:31:55 INFO - Downloaded 10840145 bytes. 02:31:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:31:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:31:56 INFO - Return code: 0 02:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:56 INFO - https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:31:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:31:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:31:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:31:59 INFO - Downloaded 114226980 bytes. 02:31:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:31:59 INFO - mkdir: /builds/slave/test/properties 02:31:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:59 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:59 INFO - Contents: 02:31:59 INFO - build_url:https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:32:00 INFO - Running post-action listener: _resource_record_post_action 02:32:00 INFO - Running post-action listener: set_extra_try_arguments 02:32:00 INFO - ##### 02:32:00 INFO - ##### Running populate-webroot step. 02:32:00 INFO - ##### 02:32:00 INFO - Running pre-action listener: _resource_record_pre_action 02:32:00 INFO - Running main action method: populate_webroot 02:32:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:32:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:32:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:32:00 INFO - '--tppagecycles', 02:32:00 INFO - '7'], 02:32:00 INFO - 'tests': ['tsvgm']}}, 02:32:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:32:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:32:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:32:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:32:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['damp', 'tps']}, 02:32:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'tests': ['damp', 'tps']}, 02:32:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['dromaeo_dom']}, 02:32:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:32:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['a11yr', 02:32:00 INFO - 'ts_paint', 02:32:00 INFO - 'tpaint', 02:32:00 INFO - 'sessionrestore', 02:32:00 INFO - 'sessionrestore_no_auto_restore', 02:32:00 INFO - 'tabpaint']}, 02:32:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['tsvgx', 02:32:00 INFO - 'tsvgr_opacity', 02:32:00 INFO - 'tart', 02:32:00 INFO - 'tscrollx', 02:32:00 INFO - 'cart']}, 02:32:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:32:00 INFO - 'tsvgr_opacity', 02:32:00 INFO - 'tart', 02:32:00 INFO - 'tscrollx', 02:32:00 INFO - 'cart']}, 02:32:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'talos_options': ['--disable-e10s'], 02:32:00 INFO - 'tests': ['tp5o']}, 02:32:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'tests': ['tp5o']}, 02:32:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'talos_options': ['--disable-e10s', 02:32:00 INFO - '--xperf_path', 02:32:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:00 INFO - 'tests': ['tp5n']}, 02:32:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:00 INFO - 'talos_options': ['--xperf_path', 02:32:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:00 INFO - 'tests': ['tp5n']}}, 02:32:00 INFO - 'talos.zip': {'path': '', 02:32:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:32:00 INFO - Running post-action listener: _resource_record_post_action 02:32:00 INFO - ##### 02:32:00 INFO - ##### Running create-virtualenv step. 02:32:00 INFO - ##### 02:32:00 INFO - Running pre-action listener: _resource_record_pre_action 02:32:00 INFO - Running main action method: create_virtualenv 02:32:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:32:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:32:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:32:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:32:00 INFO - Using real prefix '/tools/python27' 02:32:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:32:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:32:04 INFO - Installing pip.................done. 02:32:04 INFO - Return code: 0 02:32:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:04 INFO - 'HOME': '/Users/cltbld', 02:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:04 INFO - 'LOGNAME': 'cltbld', 02:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:04 INFO - 'MOZ_NO_REMOTE': '1', 02:32:04 INFO - 'NO_EM_RESTART': '1', 02:32:04 INFO - 'PAGER': '/bin/cat', 02:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:04 INFO - 'PWD': '/builds/slave/test', 02:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:04 INFO - 'SHELL': '/bin/bash', 02:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:04 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:04 INFO - 'USER': 'cltbld', 02:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:04 INFO - 'XPC_FLAGS': '0x0', 02:32:04 INFO - 'XPC_SERVICE_NAME': '0', 02:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:32:04 INFO - Cleaning up... 02:32:04 INFO - Return code: 0 02:32:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:04 INFO - 'HOME': '/Users/cltbld', 02:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:04 INFO - 'LOGNAME': 'cltbld', 02:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:04 INFO - 'MOZ_NO_REMOTE': '1', 02:32:04 INFO - 'NO_EM_RESTART': '1', 02:32:04 INFO - 'PAGER': '/bin/cat', 02:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:04 INFO - 'PWD': '/builds/slave/test', 02:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:04 INFO - 'SHELL': '/bin/bash', 02:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:04 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:04 INFO - 'USER': 'cltbld', 02:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:04 INFO - 'XPC_FLAGS': '0x0', 02:32:04 INFO - 'XPC_SERVICE_NAME': '0', 02:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:04 INFO - Downloading/unpacking psutil>=0.7.1 02:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:32:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:08 INFO - Installing collected packages: psutil 02:32:08 INFO - Running setup.py install for psutil 02:32:08 INFO - building 'psutil._psutil_osx' extension 02:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:32:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:32:08 INFO - building 'psutil._psutil_posix' extension 02:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:32:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:32:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:32:09 INFO - ^ 02:32:09 INFO - 1 warning generated. 02:32:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:32:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:09 INFO - Successfully installed psutil 02:32:09 INFO - Cleaning up... 02:32:09 INFO - Return code: 0 02:32:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:09 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:09 INFO - 'HOME': '/Users/cltbld', 02:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:09 INFO - 'LOGNAME': 'cltbld', 02:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:09 INFO - 'MOZ_NO_REMOTE': '1', 02:32:09 INFO - 'NO_EM_RESTART': '1', 02:32:09 INFO - 'PAGER': '/bin/cat', 02:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:09 INFO - 'PWD': '/builds/slave/test', 02:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:09 INFO - 'SHELL': '/bin/bash', 02:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:09 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:09 INFO - 'USER': 'cltbld', 02:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:09 INFO - 'XPC_FLAGS': '0x0', 02:32:09 INFO - 'XPC_SERVICE_NAME': '0', 02:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:32:12 INFO - Installing collected packages: mozsystemmonitor 02:32:12 INFO - Running setup.py install for mozsystemmonitor 02:32:12 INFO - Successfully installed mozsystemmonitor 02:32:12 INFO - Cleaning up... 02:32:12 INFO - Return code: 0 02:32:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:12 INFO - 'HOME': '/Users/cltbld', 02:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:12 INFO - 'LOGNAME': 'cltbld', 02:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:12 INFO - 'MOZ_NO_REMOTE': '1', 02:32:12 INFO - 'NO_EM_RESTART': '1', 02:32:12 INFO - 'PAGER': '/bin/cat', 02:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:12 INFO - 'PWD': '/builds/slave/test', 02:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:12 INFO - 'SHELL': '/bin/bash', 02:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:12 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:12 INFO - 'USER': 'cltbld', 02:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:12 INFO - 'XPC_FLAGS': '0x0', 02:32:12 INFO - 'XPC_SERVICE_NAME': '0', 02:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:12 INFO - Downloading/unpacking blobuploader==1.2.4 02:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:14 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:32:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:16 INFO - Downloading docopt-0.6.1.tar.gz 02:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:16 INFO - Running setup.py install for blobuploader 02:32:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:32:16 INFO - Running setup.py install for requests 02:32:16 INFO - Running setup.py install for docopt 02:32:17 INFO - Successfully installed blobuploader requests docopt 02:32:17 INFO - Cleaning up... 02:32:17 INFO - Return code: 0 02:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:17 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:17 INFO - 'HOME': '/Users/cltbld', 02:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:17 INFO - 'LOGNAME': 'cltbld', 02:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:17 INFO - 'MOZ_NO_REMOTE': '1', 02:32:17 INFO - 'NO_EM_RESTART': '1', 02:32:17 INFO - 'PAGER': '/bin/cat', 02:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:17 INFO - 'PWD': '/builds/slave/test', 02:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:17 INFO - 'SHELL': '/bin/bash', 02:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:17 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:17 INFO - 'USER': 'cltbld', 02:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:17 INFO - 'XPC_FLAGS': '0x0', 02:32:17 INFO - 'XPC_SERVICE_NAME': '0', 02:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-bfg51K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pwNVwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fmDeSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-jGyg7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-RYY9Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:17 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-d8X7Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-eEdIC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ckAL28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ykyFxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-U5dQu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-FSNhLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-krNKEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-8vepJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-bCABqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-eiKdjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:18 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-gbaM2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:19 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-2WhA8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:19 INFO - Running setup.py install for manifestparser 02:32:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:19 INFO - Running setup.py install for mozcrash 02:32:19 INFO - Running setup.py install for mozdebug 02:32:19 INFO - Running setup.py install for mozdevice 02:32:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:32:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:19 INFO - Running setup.py install for mozfile 02:32:19 INFO - Running setup.py install for mozhttpd 02:32:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Running setup.py install for mozinfo 02:32:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Running setup.py install for mozInstall 02:32:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Running setup.py install for mozleak 02:32:20 INFO - Running setup.py install for mozlog 02:32:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Running setup.py install for moznetwork 02:32:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:20 INFO - Running setup.py install for mozprocess 02:32:20 INFO - Running setup.py install for mozprofile 02:32:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:21 INFO - Running setup.py install for mozrunner 02:32:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:21 INFO - Running setup.py install for mozscreenshot 02:32:21 INFO - Running setup.py install for moztest 02:32:21 INFO - Running setup.py install for mozversion 02:32:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:21 INFO - Cleaning up... 02:32:21 INFO - Return code: 0 02:32:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:21 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:21 INFO - 'HOME': '/Users/cltbld', 02:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:21 INFO - 'LOGNAME': 'cltbld', 02:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:21 INFO - 'MOZ_NO_REMOTE': '1', 02:32:21 INFO - 'NO_EM_RESTART': '1', 02:32:21 INFO - 'PAGER': '/bin/cat', 02:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:21 INFO - 'PWD': '/builds/slave/test', 02:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:21 INFO - 'SHELL': '/bin/bash', 02:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:21 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:21 INFO - 'USER': 'cltbld', 02:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:21 INFO - 'XPC_FLAGS': '0x0', 02:32:21 INFO - 'XPC_SERVICE_NAME': '0', 02:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-NUmc_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fkHZ0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-3m0ZyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-mXq6_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-D9Aw0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-_xzG_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-FAiPJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1AZEv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-lDPMc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:22 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-8LfY7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-lA7e5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-DMIVP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-NdPWL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-FXCoyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-48t5yK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-aAqbuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:23 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-PPKaFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:26 INFO - Downloading blessings-1.6.tar.gz 02:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:26 INFO - Installing collected packages: blessings 02:32:26 INFO - Running setup.py install for blessings 02:32:26 INFO - Successfully installed blessings 02:32:26 INFO - Cleaning up... 02:32:26 INFO - Return code: 0 02:32:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:26 INFO - Reading from file tmpfile_stdout 02:32:26 INFO - Current package versions: 02:32:26 INFO - blessings == 1.6 02:32:26 INFO - blobuploader == 1.2.4 02:32:26 INFO - docopt == 0.6.1 02:32:26 INFO - manifestparser == 1.1 02:32:26 INFO - mozInstall == 1.12 02:32:26 INFO - mozcrash == 0.17 02:32:26 INFO - mozdebug == 0.1 02:32:26 INFO - mozdevice == 0.48 02:32:26 INFO - mozfile == 1.2 02:32:26 INFO - mozhttpd == 0.7 02:32:26 INFO - mozinfo == 0.9 02:32:26 INFO - mozleak == 0.1 02:32:26 INFO - mozlog == 3.1 02:32:26 INFO - moznetwork == 0.27 02:32:26 INFO - mozprocess == 0.22 02:32:26 INFO - mozprofile == 0.28 02:32:26 INFO - mozrunner == 6.11 02:32:26 INFO - mozscreenshot == 0.1 02:32:26 INFO - mozsystemmonitor == 0.0 02:32:26 INFO - moztest == 0.7 02:32:26 INFO - mozversion == 1.4 02:32:26 INFO - psutil == 3.1.1 02:32:26 INFO - requests == 1.2.3 02:32:26 INFO - wsgiref == 0.1.2 02:32:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:26 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:26 INFO - 'HOME': '/Users/cltbld', 02:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:26 INFO - 'LOGNAME': 'cltbld', 02:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:26 INFO - 'MOZ_NO_REMOTE': '1', 02:32:26 INFO - 'NO_EM_RESTART': '1', 02:32:26 INFO - 'PAGER': '/bin/cat', 02:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:26 INFO - 'PWD': '/builds/slave/test', 02:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:26 INFO - 'SHELL': '/bin/bash', 02:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:26 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:26 INFO - 'USER': 'cltbld', 02:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:26 INFO - 'XPC_FLAGS': '0x0', 02:32:26 INFO - 'XPC_SERVICE_NAME': '0', 02:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:32:27 INFO - Cleaning up... 02:32:27 INFO - Return code: 0 02:32:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:27 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:32:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:27 INFO - 'HOME': '/Users/cltbld', 02:32:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:27 INFO - 'LOGNAME': 'cltbld', 02:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:27 INFO - 'MOZ_NO_REMOTE': '1', 02:32:27 INFO - 'NO_EM_RESTART': '1', 02:32:27 INFO - 'PAGER': '/bin/cat', 02:32:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:27 INFO - 'PWD': '/builds/slave/test', 02:32:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:27 INFO - 'SHELL': '/bin/bash', 02:32:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:27 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:27 INFO - 'USER': 'cltbld', 02:32:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:27 INFO - 'XPC_FLAGS': '0x0', 02:32:27 INFO - 'XPC_SERVICE_NAME': '0', 02:32:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:27 INFO - Downloading/unpacking jsonschema 02:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:32:30 INFO - Downloading/unpacking functools32 (from jsonschema) 02:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:30 INFO - Downloading functools32-3.2.3-2.tar.gz 02:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:32:30 INFO - warning: no files found matching '*.txt' 02:32:30 INFO - no previously-included directories found matching 'build' 02:32:30 INFO - no previously-included directories found matching 'dist' 02:32:30 INFO - no previously-included directories found matching '.git*' 02:32:30 INFO - Installing collected packages: jsonschema, functools32 02:32:30 INFO - Running setup.py install for functools32 02:32:30 INFO - warning: no files found matching '*.txt' 02:32:30 INFO - no previously-included directories found matching 'build' 02:32:30 INFO - no previously-included directories found matching 'dist' 02:32:30 INFO - no previously-included directories found matching '.git*' 02:32:30 INFO - Successfully installed jsonschema functools32 02:32:30 INFO - Cleaning up... 02:32:30 INFO - Return code: 0 02:32:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 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 0x10dc88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f20465780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, '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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:32:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:31 INFO - 'HOME': '/Users/cltbld', 02:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:31 INFO - 'LOGNAME': 'cltbld', 02:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:31 INFO - 'MOZ_NO_REMOTE': '1', 02:32:31 INFO - 'NO_EM_RESTART': '1', 02:32:31 INFO - 'PAGER': '/bin/cat', 02:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:31 INFO - 'PWD': '/builds/slave/test', 02:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:31 INFO - 'SHELL': '/bin/bash', 02:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:32:31 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:31 INFO - 'USER': 'cltbld', 02:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:31 INFO - 'XPC_FLAGS': '0x0', 02:32:31 INFO - 'XPC_SERVICE_NAME': '0', 02:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:31 INFO - Downloading/unpacking flake8 02:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:32:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:32:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:32:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:32:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:32:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:32:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:32:35 INFO - no previously-included directories found matching 'docs/_build' 02:32:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:32:35 INFO - Running setup.py install for pep8 02:32:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:32:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:32:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:32:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:32:35 INFO - no previously-included directories found matching 'docs/_build' 02:32:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:32:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:32:35 INFO - Cleaning up... 02:32:35 INFO - Return code: 0 02:32:35 INFO - Running post-action listener: _resource_record_post_action 02:32:35 INFO - Running post-action listener: _start_resource_monitoring 02:32:35 INFO - Starting resource monitoring. 02:32:35 INFO - ##### 02:32:35 INFO - ##### Running install step. 02:32:35 INFO - ##### 02:32:35 INFO - Running pre-action listener: _resource_record_pre_action 02:32:35 INFO - Running main action method: install 02:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:35 INFO - Reading from file tmpfile_stdout 02:32:35 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:36 INFO - Reading from file tmpfile_stdout 02:32:36 INFO - Output received: 02:32:36 INFO - Usage: mozinstall [options] installer 02:32:36 INFO - Options: 02:32:36 INFO - -h, --help show this help message and exit 02:32:36 INFO - -d DEST, --destination=DEST 02:32:36 INFO - Directory to install application into. [default: 02:32:36 INFO - "/builds/slave/test"] 02:32:36 INFO - --app=APP Application being installed. [default: firefox] 02:32:36 INFO - mkdir: /builds/slave/test/build/application 02:32:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:33:07 INFO - Reading from file tmpfile_stdout 02:33:07 INFO - Output received: 02:33:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:33:07 INFO - Running post-action listener: _resource_record_post_action 02:33:07 INFO - ##### 02:33:07 INFO - ##### Running run-tests step. 02:33:07 INFO - ##### 02:33:07 INFO - Running pre-action listener: _resource_record_pre_action 02:33:07 INFO - Running main action method: run_tests 02:33:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:33:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:33:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:33:07 INFO - 2016-05-26 02:33:07.452 screenresolution[1639:7765] starting screenresolution argv=screenresolution get 02:33:07 INFO - 2016-05-26 02:33:07.455 screenresolution[1639:7765] Display 0: 1600x1200x32@60 02:33:07 INFO - 2016-05-26 02:33:07.460 screenresolution[1640:7767] starting screenresolution argv=screenresolution list 02:33:07 INFO - Available Modes on Display 0 02:33:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:33:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:33:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:33:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:33:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:33:07 INFO - Intel Iris: 02:33:07 INFO - Chipset Model: Intel Iris 02:33:07 INFO - Type: GPU 02:33:07 INFO - Bus: Built-In 02:33:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:33:07 INFO - Vendor: Intel (0x8086) 02:33:07 INFO - Device ID: 0x0a2e 02:33:07 INFO - Revision ID: 0x0009 02:33:07 INFO - Displays: 02:33:07 INFO - Display: 02:33:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:33:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:33:07 INFO - Display Serial Number: 1600x1200 60 02:33:07 INFO - Main Display: Yes 02:33:07 INFO - Mirror: Off 02:33:07 INFO - Online: Yes 02:33:07 INFO - Rotation: Supported 02:33:07 INFO - Return code: 0 02:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:33:07 INFO - Python 2.7.3 02:33:07 INFO - Return code: 0 02:33:07 INFO - grabbing minidump binary from tooltool 02:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:07 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 0x10db27e00>, '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 0x10d990db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db22b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:33:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:33:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:33:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:33:07 INFO - Return code: 0 02:33:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:33:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:33:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:33:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:33:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:33:08 INFO - Return code: 0 02:33:08 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/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:33:08 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/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RAkbWw0jGj/Render', 02:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:08 INFO - 'HOME': '/Users/cltbld', 02:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:08 INFO - 'LOGNAME': 'cltbld', 02:33:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:33:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:08 INFO - 'MOZ_NO_REMOTE': '1', 02:33:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:33:08 INFO - 'NO_EM_RESTART': '1', 02:33:08 INFO - 'PAGER': '/bin/cat', 02:33:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:08 INFO - 'PWD': '/builds/slave/test', 02:33:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:08 INFO - 'SHELL': '/bin/bash', 02:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3x0Evr8y4i/Listeners', 02:33:08 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 02:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:08 INFO - 'USER': 'cltbld', 02:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:08 INFO - 'XPC_FLAGS': '0x0', 02:33:08 INFO - 'XPC_SERVICE_NAME': '0', 02:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:08 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/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:33:09 INFO - mozversion application_buildid: 20160526004016 02:33:09 INFO - mozversion application_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 02:33:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:33:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:33:09 INFO - mozversion application_name: Firefox 02:33:09 INFO - mozversion application_remotingname: firefox-dev 02:33:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:33:09 INFO - mozversion application_vendor: Mozilla 02:33:09 INFO - mozversion application_version: 48.0a2 02:33:09 INFO - mozversion platform_buildid: 20160526004016 02:33:09 INFO - mozversion platform_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 02:33:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:33:09 INFO - mozversion platform_version: 48.0a2 02:33:09 INFO - using testdate: 1464255189 02:33:09 INFO - actual date: 1464255189 02:33:09 INFO - starting webserver on 'localhost:49273' 02:33:09 INFO - SUITE-START | Running 2 tests 02:33:09 INFO - TEST-START | dromaeo_css 02:33:09 INFO - operating with platform_type : mac_ 02:33:09 INFO - Initialising browser for dromaeo_css test... 02:33:09 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmplf5uBu/profile http://localhost:49273/getInfo.html) 02:33:11 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 02:33:11 INFO - PROCESS | 1658 | colorDepth:24 02:33:11 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 02:33:11 INFO - PROCESS | 1658 | __metrics 02:33:11 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:33:11 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:33:12 INFO - TEST-INFO | 1658: exit 0 02:33:12 INFO - Browser initialized. 02:33:12 INFO - Running cycle 1/1 for dromaeo_css test... 02:33:12 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmplf5uBu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:24 INFO - PROCESS | 1662 | RSS: Main: 210845696 02:33:24 INFO - PROCESS | 1662 | 02:34:15 INFO - PROCESS | 1662 | 1464255255730 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:36:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 02:36:27 INFO - PROCESS | 1662 | RSS: Main: 351240192 02:36:27 INFO - PROCESS | 1662 | 02:39:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 02:39:31 INFO - PROCESS | 1662 | RSS: Main: 354656256 02:39:31 INFO - PROCESS | 1662 | 02:42:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 02:42:34 INFO - PROCESS | 1662 | RSS: Main: 358449152 02:42:34 INFO - PROCESS | 1662 | 02:45:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 02:45:36 INFO - PROCESS | 1662 | RSS: Main: 357785600 02:45:36 INFO - PROCESS | 1662 | 02:48:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 02:48:40 INFO - PROCESS | 1662 | RSS: Main: 327680000 02:48:40 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 02:51:43 INFO - PROCESS | 1662 | RSS: Main: 390639616 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | __start_tp_report 02:51:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 02:51:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 02:51:43 INFO - PROCESS | 1662 | |i|pagename|runs| 02:51:43 INFO - PROCESS | 1662 | |0;dojo.html;3376.2741010682707;3457.4813777933323;3460.567429071366;3468.202313467904;3516.5252690886737;5653.660780353177;5680.545556355492;5683.658980461175;5703.144528320744;5706.828795136147;5672.631278966875;5732.79935202268;5736.397678243787;5737.5696822738355;5784.68184249866;5150.995555866608;5182.119039763231;5207.244949482323;5312.123499622558;5333.706646134461;5263.6841789492755;5280.630355875091;5294.1529355303155;5296.126139187029;5300.681959082448;5203.505666961632;5259.6055295852775;5265.921011184835;5270.288511050997;5278.419373868871;5249.947500524984;5305.442928492491;5314.9468505314835;5318.574514038867;5341.65279256847;5127.948720512822;5145.536901678829;5160.483951604847;5228.451012643655;5247.790088396459;5577.944220557823;5586.664800112007;5614.578906582023;5622.465865742748;5633;6266.937330626681;6270.6864656766975;6281.309056003836;6291.993281075006;6302.968485157545;5237.214417837316;6077.1491991121275;6162.1681073055015;6354.142190804228;6360.936390636127;5782.392848750905;5797.768089276382;5802.738876750556;5810.854728631776;5821.039528477796;6322.051692246107;6326;6326.304106548276;6327.335629758901;6339.809805705836;6017.849553761103;6087.908681369783;6130.111133885651;6169.8457538561;6209.285932117774;6288;6301.212348456443;6316.747330106836;6328.6202827830475;6335.208098987627;6212.161358216583;6240.344763799826;6262.937370626327;6267.62394256346;6268.686565671789;6126.816195514141;6197;6202.038684003896;6206.906896396557;6281;4879.804807807719;4893.339399181136;4911.45973942866;4937.432195297587;4976.402831660152;4399.912001759947;4418.050119224382;4426.557344265548;4452;4479.328100784908;5691.117876729114;5709.9714501427225;5715.371309155989;5724.914126288109;5731.512821410227;6134.16106913652;6202.2867370253;6315;6327.746890124436;6330.651814150266;5652.208690783294;5687.971560142173;5694.288213973254;5701.771929122871;5725.8568535786935;5388.56891448691;5409.459054094559;5409.810656626999;5413.566914646819;5425.13197888336;6238.469730072995;6273.12176295319;6352.745890164341;6360.522960777867;6365.363463653783;6276.246850377801;6290;6299.779507717208;6315.273743519554;6318.810435686937;6250.781222657367;6270;6350.079238510482;6359.523035772243;6363.61818290904;6079.209702738615;6149.139120523219;6152.692365381624;6172.135900973957;6183.505319574333;6232.906506402317;6270.49836013127;6292.182016337847;6298.0867774171575;6315.55791094619;5721.971390143022;6019.036954087325;6123.908141377794;6125.234345706786;6132.356102609162;6097.817065488042;6113.111460343324;6117.15760190703;6128.747575574743;6142.345304543789;5950;5964.9105263420215;5970.373110823301;5977.521798256216;5981.641101533748;5904.763809447574;6055.1522786810765;6082.665453399928;6123.83172914054;6137.539684523589;5792.391798861059;5878.500327472213;5891.1752354671235;5895.322037965518;5903.085021821625 02:51:43 INFO - PROCESS | 1662 | |1;ext.html;523.4843678976206;535.4217445159228;546.0253447596042;568.6303902463399;570.8801151758131;24760;31789.364212715733;38175.809120954356;39037.21925561487;39079.41380879289;34806.65193348066;41408.75773726786;41462.75611731645;41963.58036419635;42032;9325.160735533818;9520.666776662818;9530.618775248982;9533.904660953405;9578.56896439662;8532.61603227853;8749.56252187388;8751.737447876541;11271.605493807718;11571.13216508761;11252.43737813106;11326.433678316092;11327.830082548768;11332.376719280477;11337.489812958396;11229.55081796727;11327.263727857662;11338.886611133865;11359.261647992937;11366.886331136708;8506.489610623321;8725.083866194022;8730.083341249141;11123.610673626385;11387.601433949854;38508.61491385107;38946.831595052194;39078.21843563141;39095.609043909484;39126.60873391258;3207.1982004498877;3230.709236168733;3427.4002049641213;3805.06775839921;3851.6533511983775;26461.206163815117;26624.801883915272;26648.333791655168;26680;26738.73261267382;2728.1679087878388;2754.9586756198673;2756.4073724149202;2774.6392968914115;2797.468480988626;373344;375001.1249943733;375683;375880;376497;35683.4647480288;36415.817920910755;36416.453753193724;36514.452283215775;36539.63460365416;35684.464733029025;35815.10462238465;36007;36086.27827443489;36133;15429.30568124437;15572.377104915677;15600.453984110729;15603.985740926804;15650.843491565167;12267.69330766738;13101.475940962446;13141.868581314257;13182.472701092041;13664.726705466033;7544;8158.143394943446;8167.550784706779;8299.917000829915;8337.624806883705;3357.1942733744213;3430.8627654893526;3434.0899661589697;3435.124043368925;3462.082548124749;938.408802454463;999.4209149543703;1007.1288335589809;1032.1794173631981;1043.9739006524746;28411.85794071058;28758;29464;29545.704542954725;29623.703762962527;1099.0438318662814;1103.3159441146381;1151.9517239312186;1157.3287493253893;1184.7512022475205;15224.923875380553;15280.388784448498;15316.770248446282;15430.768538471932;15512.082625075715;620083;626355;626590;628692;629059;58706;60111.398886011455;60176.09735853966;60299.39700603025;60374;1327.1439921250933;1329.6609364611961;1354.1807206640037;1492.2165862921836;1602.7515735061083;23534.646980294965;25759.227223183334;29190.27024324409;29626.407471850012;29833.955811546493;44296.33555496734;57521.42478575328;58507.70746146242;59878;59971.700141499015;390672;441699;448984;449723;450246;30868.845655771576;34257.486137707456;39751.60248397479;39947.201055978134;40488.79755601203;37418.625813742605;41976.74069777912;42022.57977420187;42065.369019464124;42842.357364638934;33896.66103339034;34337.62649493992;34707.65292347044;34715.826420867736;35266;35769.642303576635;35790;36192.27615447729;36459;36460;36322.91031269066;36633.26733465369;36783.89648310555;37049;37755;32484;32892.67107328896;36812.26375472529;41109;41496.79251603843;23153.88423057874;28933;29508.704912950594;29680.703192967794;29879.701202988563 02:51:43 INFO - PROCESS | 1662 | |2;jquery.html;394.85193052605274;396.78970145822717;396.8293633737492;400.8857475619447;411.9794010299484;20026.998650067493;20773.896130519344;20778.48053798652;20834.583308333844;21022.894885525588;17895.463136105904;20718.896405517953;20898.850563219017;21311.25410610629;21660.56678866422;5726.513246374063;5886.823395298137;6054.666993315366;6066.969665151668;6113.724882380304;5981.162637230791;6002.549808764338;6019.398060193989;6022.397760223965;6033.7888173913925;6010.038393856984;6013.488853447462;6024.48791852693;6636.966815165917;6645.202575690924;6580.045893345462;6607.702653380586;6612.471002319803;6617.106690596804;6618.966905165492;5492.615516913817;5793.710314484259;5796.652200867961;5802;5837.124431335291;13270.800937985938;13428.79856802139;13487.797683034663;18355.541111472186;25162;2101.7057611934342;2188.1446501801915;2207.569523942832;2298.5977453945493;2501.574732295514;9716.708498745049;9856.802863942681;9948.104670579683;10036.447995360253;10331.32846364984;1714.3399791080524;1718.9140542972802;1723.8448539631368;1728.6369862328927;1776.7512548243255;66544;87509;102056.95886082311;104591.4770426143;124009.75990240162;11784.523565548185;11790.997765189883;11826.349550774617;11871.0503159747;11885.940570297093;15357.385704571692;15416;15416.075035497906;15462.845371546366;15463.845361546466;8712.564371781385;8733.519656418957;8763.298936085097;8891.288696904232;8971.865422018675;8928.553572321358;8939.776505587412;8943.508107054175;8983.595738191798;8991.190792828545;3941.428492868518;4587.197240482902;4660.650451216173;4681.461631912373;4705.623550115983;1613.790207273047;1958.3439547751377;1977.1300627723756;1989.4143998084553;2018.475196448934;847.8050048488882;895.2793001633747;958.3339578992533;1041.3075304922293;1061.5965932945585;20228.393148205578;20453.590928181355;20510.692339614918;21159.78840211578;21169.78830211678;887.7913690282778;931.3573634192386;940.5062342270363;961.7932144588947;981.7349315684868;11190.888091119148;11323.37721425313;11329.150313726503;11336.886631133748;11338.092952563775;574835.2516474782;586799;591552;599468;599898;77225;98560.52159217617;120082;122828;123353;865.8917635295588;963.0513943765526;973.7030205787303;975.4634950777186;978.5596481583316;13077;14000.859991399955;17679.38122165718;17775;18180.81819181791;46903.76548117238;48709.025819482704;49853.50146498489;52780;53168.734156330516;68699.65650171918;71331.93002105076;72509;72738.63630681812;73684.63157684177;8015.198480151938;8025.197480251928;8035;8126.984126984127;8193.59032048407;11112.555497780322;12356.25862448256;12460.563880264148;12646.683832903884;12748.87251127514;10578.418186999788;10618.787624247318;10782.406967616542;10820.5130769114;10954.835677465004;10612.681619551426;10724.89275107239;10773.138148947908;10911.345319280868;10981.670549883516;10695.572177112828;10897.820535072025;10901.641392060486;11115.72210694739;11184.496697648789;8142.51144931306;8154.959225203836;8198.83602327962;8268.586570671321;8306.54314012735;10060.446675432922;10911.781764364827;11188.83216751733;11211;11222.887771122185 02:51:43 INFO - PROCESS | 1662 | |3;mootools.html;1280.4366078925273;1409.0488919979011;1560.056166019558;1723.310675729708;1921.6829223178167;7807.687692492301;7998.880156778056;8437;8590.226879580836;8602.698905538307;6554.672266386686;7777.766666999985;8585.742427727162;8598.699045533393;8992.190702836744;1874.409560988291;2163.8377121715935;2230.7323121225477;2432.5378178146184;2467.8519288842726;2423.309356833303;2486.8632225227607;2497.1509686706518;2497.5504409206337;2498.0507407185246;2390.725066617335;2427.5509030829267;2430.829841911067;2444.498877730061;2450.7304196538366;2128.372130221588;2371;2421.3462365161486;2422.467057247403;2432.3189506938083;2405.446747248125;2439.3901524618846;2445.3030886197525;2445.792107670832;2446.6330050492393;8500.74497765068;8567.186117318844;8571.614277357472;8588.656453741844;8590.828183436359;5102.056119617845;5239.607029472767;5246.737663116829;5287.894242115175;5289.576833853283;3409.590849098124;3508.0528257370365;3592.209713862946;3610.6569875862015;3611.9097022574383;949.8670186173941;1048.5334026358273;1057.2282233969245;1062.1025233677608;1066.4720963123227;9825.361351512129;11793.823092653616;11822.940885295604;11850.940745296304;11859.881401185963;4706.694064885772;4761.262004389325;4835.443923948791;4844.297576851407;4898.436679781871;9938.751531211778;9948.651797187209;9950.452725100044;9977.700668979942;10000.149987251165;5400.729963501809;5402.6758394496455;5406.78372865081;5408.972295263888;5459;4361.3676016978;4525.886852828639;4696.365990591295;4874.975625121851;4933.925991110136;3276.8689252429635;3956.5449973252867;4025.1144748155357;4061.8578349757618;4069.7354671946227;1312.9868701312864;1318.1893135721655;1318.3803612302202;1326.7678156322797;1335.9599212023654;845.2603971524916;895.8029233568604;923.385948344357;926.6525053105086;926.7914719188263;5796.681182534917;5811.82564523065;5930.318013428425;5968.22413086296;6096.603720758137;452.74872445792244;455.8860284928768;475.79303003193723;475.8365795629652;476.16195495927417;3276.2137087098927;3871.109644781716;3973.543042550086;3993.1414745829784;4013.13717550728;10456.68629941103;10726.249162558703;10779.353238805697;10859.565617375374;10861.1854110942;10423.947880260703;10577.153827693766;10616.628418005334;10717.83923241152;10722.195835312383;8267.669293228204;8302.70940517079;8322.66709331632;8360.665573376997;8361.289290410261;5400.702961337164;5670.461306175906;5731.770729170787;5772.509336706259;5912.822615321547;9206.263498920205;9345.205657519187;9467.242620590474;9482.38364506305;9486.146246837818;9137.908620913706;9461.621535138518;9588.856167157359;9596.23230141573;9601;6722.529422940347;6911.343422374866;7254.16577093641;7349.55902645843;7475.850482990201;8120.14738452454;8146.348292136733;8195.549244791593;8200.302974247255;8233.79415514617;7149.499535032498;7229.277072292729;7307.926920730724;7362.558246505227;7375.004374409601;7027.964860175666;7073.964630176816;7140.607266600179;7170;7195.964020179866;6773.762918298033;6986.510944233854;6987.475939304674;7008.439324853897;7013.333733295329;4772.976135119441;4793.5685788279225;4795.424549054136;4849.878753031202;4954.504549545017;4882.902341953212;4929.950700493093;4938.135826230324;4976.0794253063295;5124.590032797441 02:51:43 INFO - PROCESS | 1662 | |4;prototype.html;313.3154058382434;317.7775557110024;322.7788964559276;325.8843110695703;330.44155377412187;6563.638999855006;6590.154756668135;6683.832904177398;6688.832779180523;6695.430888374494;5741.167530708045;6219.533534984872;6597.670116494168;6603.570767900083;6606.900896486544;4683.625309975194;4783.736894470802;4796.664233503656;4882.048000639877;4891.461939186686;4824.107540105074;4878.609711223111;4891.1195984722735;4896.1186986342445;4899.167141585939;4131.834726610932;4833.7099774013495;4847.248676455138;4847.806087756486;4861.8784530386665;4655.976720116411;4728.763561821912;4828;4839.44346400165;4866.002469493746;3432.8626854925774;3650.7809531427934;3963.207358528306;4148.128892932487;4154.356074808409;6275.987525987506;6373.298937116913;6381.744730210786;6383.265924418705;6407.967960160216;5684.062129748587;5707.343655479632;5718.857028574277;5720.399358067385;5721.513671337942;6781;7020.5787652741;7029.156501219886;7033.085698859167;7058.153021637385;4663.836765713218;4680.297955306697;4689.859304220845;4692.671512994092;4697.929531057037;7357.963210183915;7442.03253577037;7445.8883116753295;7447.44144189188;7456;3878.3600705883778;4733.313669517901;4739.454962679336;4743.57307842289;4757.286407038971;4816.4220293564995;4821.059893320768;4825.734584597881;4827.179379505422;4833.226683730657;4923.975380123148;5444.91832622511;5689.203511508392;5695.601307908407;5696;5676.403977582377;6761.661916904135;7080.079589653312;7085.43316534676;7093.645317734093;1649.0517952177545;1662.883598148118;1672.5818545363659;1675.8407951244612;1677.2620047179203;910.4673765847053;920.8802855628726;983.5573991703762;1066.6373433032807;1081.4214395298477;6423.710933008026;6539.50953678476;6620.3710647488415;6644.534882558174;6659.833504162338;7322.487425880137;7329.633518324063;7341.449391295674;7342.596157211297;7367.852642947111;6569.868602627921;6572.572782769104;6585.5719378240265;6595.406413422815;6612.966935165294;3742.9064273393383;3824.330742120118;3881.6118388160958;3891.0466935600393;3893.9610603894166;7627.809304767426;7654.8086297843;7659.540427574252;7661.61691915402;7675.808104797425;7524.811879703051;7551.73568925085;7569.205233450519;7585.089789225328;7593.354564862048;7679.424043196895;7692.884606730791;7695.92304076952;7700.383969282444;7704.499207551491;6966.268541803038;7311.853762924606;7321.963390183015;7322.52403593786;7326.523775954546;7147.85704285901;7197.064381630355;7210.459215558959;7218.639068046682;7227.313405226495;7679.193684663253;7686.07767067933;7686.692532298645;7698.769036928901;7713.807154821175;4770.833020844254;4771.093492236464;4773;4780.880477988078;4781.665283430266;6819.5908245505425;7051.964740176266;7310.853782924418;7314.049173607397;7320.414366850533;7003.299670032956;7045.471589630901;7060.188078371053;7067.434605231466;7070.434365250665;7035.929640703732;7039.824004399931;7044;7049.577025378494;7053.4004609608755;6046.969765151146;6569.211694596679;6922.411595014481;6926.480513961373;6931.2967942501455;6784.830379240559;7067.787966361017;7075.646217688992;7084.964575177091;7101.786946391617;5808.535317174531;6566.8358291043105;6613.140291762233;6790.932090679227;6946.895796563157 02:51:43 INFO - PROCESS | 1662 | |5;yui.html;670.497127154634;687.3573209049539;742.3726950726638;764.4609709544388;802.1978021978024;14772.113673179616;14990.175540345277;15024.924875375622;15047.849521504782;15062.6987460251;13544.93227533861;13633.182009079463;13663.521776737816;13664.316784160801;13668.111572747765;723.4754802768003;742.1724776873774;901.621319045999;921.5115988526092;927.5594092805896;916.6562539047857;918.0910898210757;918.531548910054;918.591226904028;919.8206349761799;952;972.0668158567784;974.2400927276735;974.9561269742878;975.3562648651891;767.15995984397;951.1724799424476;965.8454647256441;967.1295833749612;968.0706521739104;714.1858281559026;714.4534431160166;715.9677814498308;716.1763971432894;716.3087620446295;14399.496017639438;14450.855491445056;14470;14482.49311274111;14496.927515362355;12267.631971040882;12420.81368779469;12467.438965246585;12601.810972835416;12610.369481525888;9962.651307204285;9975.201983841276;10059.245556583286;10400.115990140772;10453.216008799294;3449.292894956528;3513.437849944022;3515.8317273607754;3575.803330816804;3722.8883133506038;78260;78902;79381;81174.59412702899;81951.590242049;9076.954615226881;9430.3398762086;9458;9649.276304277208;9714.757131071638;20856.270030548858;21328.893355533124;22234.221802236843;22248.555028899656;22259.220927267466;7220.0613920190035;7351.22812104732;7440.107187137578;7481.812954676177;7553.8866916996285;3971.932471193098;3988.8603898863403;4033.7176397651883;4034.152827906114;4238.0888109056705;3676.0993556578273;3728.5339332583426;3735.9439608405896;3741.9812900935867;3745.1386181177936;646.4537465841356;659.7196191618543;666.3203532396928;673.1316601583902;689.2142957028991;510.50226029621564;540.7620646915344;585.844751140948;593.8337265565649;602.9547783916224;7162.247963963813;7920.279023418037;7954.935975017565;8016.879746803803;8224.794380140425;586.2466730251606;592.863641362489;599.5623195067537;606.1513880567242;617.2931992868141;3761.586225515191;3776.9244615108096;3824.980875095662;3865.594112618191;3880.1463677990796;78863.21136788673;80401;80501.59749201217;80664;90596;70754.64622676854;73613;76063.85904211442;76148;78402.60798696084;68133.9779903292;68877;69460;69664.6516767423;70278.64860675766;8207.79480512968;8281.37889658266;8344.040435350013;8439.451435656663;8456.027556831043;45098.54901450944;47051.764741177445;48766.53700388994;49609.75195124001;49621;60974.69512652586;65548.36129096811;66079.66960165168;67642;68448;10535.262531622713;10767.892321077003;10778.89221107779;11062.62589910085;11482.138839586898;11633.185677002866;11677.474513646737;11684.766304674029;11809.7217157616;12280.877191227974;11262.310653402055;11411.543538258376;11621.36082515436;11632.476538555617;11795.646130616095;10166.74583135398;10554.247934875664;10915.345079295268;11631.243969141979;11632.651020469399;10759.990851869761;11188.160887933274;11271.830922536332;11334.546618135511;11597.710057248636;9352.43885366853;9437.669681561112;9505.619775208914;9520.619175232912;9795.461249631338;8052.476589021695;9454.43273403598;9575.569099390683;9598.9040109598;9616.663416780659 02:51:43 INFO - PROCESS | 1662 | __end_tp_report 02:51:43 INFO - PROCESS | 1662 | __start_cc_report 02:51:43 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1407 02:51:43 INFO - PROCESS | 1662 | __end_cc_report 02:51:43 INFO - PROCESS | 1662 | __startTimestamp1464256303629__endTimestamp 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | ------- Summary: start ------- 02:51:43 INFO - PROCESS | 1662 | Number of tests: 6 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5755.66 Median:5904.76 stddev:628.38 (10.6%) stddev-sans-first:601.94 02:51:43 INFO - PROCESS | 1662 | Values: 3376.3 3457.5 3460.6 3468.2 3516.5 5653.7 5680.5 5683.7 5703.1 5706.8 5672.6 5732.8 5736.4 5737.6 5784.7 5151.0 5182.1 5207.2 5312.1 5333.7 5263.7 5280.6 5294.2 5296.1 5300.7 5203.5 5259.6 5265.9 5270.3 5278.4 5249.9 5305.4 5314.9 5318.6 5341.7 5127.9 5145.5 5160.5 5228.5 5247.8 5577.9 5586.7 5614.6 5622.5 5633.0 6266.9 6270.7 6281.3 6292.0 6303.0 5237.2 6077.1 6162.2 6354.1 6360.9 5782.4 5797.8 5802.7 5810.9 5821.0 6322.1 6326.0 6326.3 6327.3 6339.8 6017.8 6087.9 6130.1 6169.8 6209.3 6288.0 6301.2 6316.7 6328.6 6335.2 6212.2 6240.3 6262.9 6267.6 6268.7 6126.8 6197.0 6202.0 6206.9 6281.0 4879.8 4893.3 4911.5 4937.4 4976.4 4399.9 4418.1 4426.6 4452.0 4479.3 5691.1 5710.0 5715.4 5724.9 5731.5 6134.2 6202.3 6315.0 6327.7 6330.7 5652.2 5688.0 5694.3 5701.8 5725.9 5388.6 5409.5 5409.8 5413.6 5425.1 6238.5 6273.1 6352.7 6360.5 6365.4 6276.2 6290.0 6299.8 6315.3 6318.8 6250.8 6270.0 6350.1 6359.5 6363.6 6079.2 6149.1 6152.7 6172.1 6183.5 6232.9 6270.5 6292.2 6298.1 6315.6 5722.0 6019.0 6123.9 6125.2 6132.4 6097.8 6113.1 6117.2 6128.7 6142.3 5950.0 5964.9 5970.4 5977.5 5981.6 5904.8 6055.2 6082.7 6123.8 6137.5 5792.4 5878.5 5891.2 5895.3 5903.1 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:60883.85 Median:35802.55 stddev:131264.50 (366.6%) stddev-sans-first:131554.50 02:51:43 INFO - PROCESS | 1662 | Values: 523.5 535.4 546.0 568.6 570.9 24760.0 31789.4 38175.8 39037.2 39079.4 34806.7 41408.8 41462.8 41963.6 42032.0 9325.2 9520.7 9530.6 9533.9 9578.6 8532.6 8749.6 8751.7 11271.6 11571.1 11252.4 11326.4 11327.8 11332.4 11337.5 11229.6 11327.3 11338.9 11359.3 11366.9 8506.5 8725.1 8730.1 11123.6 11387.6 38508.6 38946.8 39078.2 39095.6 39126.6 3207.2 3230.7 3427.4 3805.1 3851.7 26461.2 26624.8 26648.3 26680.0 26738.7 2728.2 2755.0 2756.4 2774.6 2797.5 373344.0 375001.1 375683.0 375880.0 376497.0 35683.5 36415.8 36416.5 36514.5 36539.6 35684.5 35815.1 36007.0 36086.3 36133.0 15429.3 15572.4 15600.5 15604.0 15650.8 12267.7 13101.5 13141.9 13182.5 13664.7 7544.0 8158.1 8167.6 8299.9 8337.6 3357.2 3430.9 3434.1 3435.1 3462.1 938.4 999.4 1007.1 1032.2 1044.0 28411.9 28758.0 29464.0 29545.7 29623.7 1099.0 1103.3 1152.0 1157.3 1184.8 15224.9 15280.4 15316.8 15430.8 15512.1 620083.0 626355.0 626590.0 628692.0 629059.0 58706.0 60111.4 60176.1 60299.4 60374.0 1327.1 1329.7 1354.2 1492.2 1602.8 23534.6 25759.2 29190.3 29626.4 29834.0 44296.3 57521.4 58507.7 59878.0 59971.7 390672.0 441699.0 448984.0 449723.0 450246.0 30868.8 34257.5 39751.6 39947.2 40488.8 37418.6 41976.7 42022.6 42065.4 42842.4 33896.7 34337.6 34707.7 34715.8 35266.0 35769.6 35790.0 36192.3 36459.0 36460.0 36322.9 36633.3 36783.9 37049.0 37755.0 32484.0 32892.7 36812.3 41109.0 41496.8 23153.9 28933.0 29508.7 29680.7 29879.7 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33202.18 Median:2168.14 stddev:97771.97 (4509.5%) stddev-sans-first:98015.21 02:51:43 INFO - PROCESS | 1662 | Values: 394.9 396.8 396.8 400.9 412.0 20027.0 20773.9 20778.5 20834.6 21022.9 17895.5 20718.9 20898.9 21311.3 21660.6 5726.5 5886.8 6054.7 6067.0 6113.7 5981.2 6002.5 6019.4 6022.4 6033.8 6010.0 6013.5 6024.5 6637.0 6645.2 6580.0 6607.7 6612.5 6617.1 6619.0 5492.6 5793.7 5796.7 5802.0 5837.1 13270.8 13428.8 13487.8 18355.5 25162.0 2101.7 2188.1 2207.6 2298.6 2501.6 9716.7 9856.8 9948.1 10036.4 10331.3 1714.3 1718.9 1723.8 1728.6 1776.8 66544.0 87509.0 102057.0 104591.5 124009.8 11784.5 11791.0 11826.3 11871.1 11885.9 15357.4 15416.0 15416.1 15462.8 15463.8 8712.6 8733.5 8763.3 8891.3 8971.9 8928.6 8939.8 8943.5 8983.6 8991.2 3941.4 4587.2 4660.7 4681.5 4705.6 1613.8 1958.3 1977.1 1989.4 2018.5 847.8 895.3 958.3 1041.3 1061.6 20228.4 20453.6 20510.7 21159.8 21169.8 887.8 931.4 940.5 961.8 981.7 11190.9 11323.4 11329.2 11336.9 11338.1 574835.3 586799.0 591552.0 599468.0 599898.0 77225.0 98560.5 120082.0 122828.0 123353.0 865.9 963.1 973.7 975.5 978.6 13077.0 14000.9 17679.4 17775.0 18180.8 46903.8 48709.0 49853.5 52780.0 53168.7 68699.7 71331.9 72509.0 72738.6 73684.6 8015.2 8025.2 8035.0 8127.0 8193.6 11112.6 12356.3 12460.6 12646.7 12748.9 10578.4 10618.8 10782.4 10820.5 10954.8 10612.7 10724.9 10773.1 10911.3 10981.7 10695.6 10897.8 10901.6 11115.7 11184.5 8142.5 8155.0 8198.8 8268.6 8306.5 10060.4 10911.8 11188.8 11211.0 11222.9 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5600.76 Median:4946.32 stddev:3127.57 (63.2%) stddev-sans-first:3119.48 02:51:43 INFO - PROCESS | 1662 | Values: 1280.4 1409.0 1560.1 1723.3 1921.7 7807.7 7998.9 8437.0 8590.2 8602.7 6554.7 7777.8 8585.7 8598.7 8992.2 1874.4 2163.8 2230.7 2432.5 2467.9 2423.3 2486.9 2497.2 2497.6 2498.1 2390.7 2427.6 2430.8 2444.5 2450.7 2128.4 2371.0 2421.3 2422.5 2432.3 2405.4 2439.4 2445.3 2445.8 2446.6 8500.7 8567.2 8571.6 8588.7 8590.8 5102.1 5239.6 5246.7 5287.9 5289.6 3409.6 3508.1 3592.2 3610.7 3611.9 949.9 1048.5 1057.2 1062.1 1066.5 9825.4 11793.8 11822.9 11850.9 11859.9 4706.7 4761.3 4835.4 4844.3 4898.4 9938.8 9948.7 9950.5 9977.7 10000.1 5400.7 5402.7 5406.8 5409.0 5459.0 4361.4 4525.9 4696.4 4875.0 4933.9 3276.9 3956.5 4025.1 4061.9 4069.7 1313.0 1318.2 1318.4 1326.8 1336.0 845.3 895.8 923.4 926.7 926.8 5796.7 5811.8 5930.3 5968.2 6096.6 452.7 455.9 475.8 475.8 476.2 3276.2 3871.1 3973.5 3993.1 4013.1 10456.7 10726.2 10779.4 10859.6 10861.2 10423.9 10577.2 10616.6 10717.8 10722.2 8267.7 8302.7 8322.7 8360.7 8361.3 5400.7 5670.5 5731.8 5772.5 5912.8 9206.3 9345.2 9467.2 9482.4 9486.1 9137.9 9461.6 9588.9 9596.2 9601.0 6722.5 6911.3 7254.2 7349.6 7475.9 8120.1 8146.3 8195.5 8200.3 8233.8 7149.5 7229.3 7307.9 7362.6 7375.0 7028.0 7074.0 7140.6 7170.0 7196.0 6773.8 6986.5 6987.5 7008.4 7013.3 4773.0 4793.6 4795.4 4849.9 4954.5 4882.9 4930.0 4938.1 4976.1 5124.6 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5764.26 Median:6592.78 stddev:1855.61 (28.1%) stddev-sans-first:1815.15 02:51:43 INFO - PROCESS | 1662 | Values: 313.3 317.8 322.8 325.9 330.4 6563.6 6590.2 6683.8 6688.8 6695.4 5741.2 6219.5 6597.7 6603.6 6606.9 4683.6 4783.7 4796.7 4882.0 4891.5 4824.1 4878.6 4891.1 4896.1 4899.2 4131.8 4833.7 4847.2 4847.8 4861.9 4656.0 4728.8 4828.0 4839.4 4866.0 3432.9 3650.8 3963.2 4148.1 4154.4 6276.0 6373.3 6381.7 6383.3 6408.0 5684.1 5707.3 5718.9 5720.4 5721.5 6781.0 7020.6 7029.2 7033.1 7058.2 4663.8 4680.3 4689.9 4692.7 4697.9 7358.0 7442.0 7445.9 7447.4 7456.0 3878.4 4733.3 4739.5 4743.6 4757.3 4816.4 4821.1 4825.7 4827.2 4833.2 4924.0 5444.9 5689.2 5695.6 5696.0 5676.4 6761.7 7080.1 7085.4 7093.6 1649.1 1662.9 1672.6 1675.8 1677.3 910.5 920.9 983.6 1066.6 1081.4 6423.7 6539.5 6620.4 6644.5 6659.8 7322.5 7329.6 7341.4 7342.6 7367.9 6569.9 6572.6 6585.6 6595.4 6613.0 3742.9 3824.3 3881.6 3891.0 3894.0 7627.8 7654.8 7659.5 7661.6 7675.8 7524.8 7551.7 7569.2 7585.1 7593.4 7679.4 7692.9 7695.9 7700.4 7704.5 6966.3 7311.9 7322.0 7322.5 7326.5 7147.9 7197.1 7210.5 7218.6 7227.3 7679.2 7686.1 7686.7 7698.8 7713.8 4770.8 4771.1 4773.0 4780.9 4781.7 6819.6 7052.0 7310.9 7314.0 7320.4 7003.3 7045.5 7060.2 7067.4 7070.4 7035.9 7039.8 7044.0 7049.6 7053.4 6047.0 6569.2 6922.4 6926.5 6931.3 6784.8 7067.8 7075.6 7085.0 7101.8 5808.5 6566.8 6613.1 6790.9 6946.9 02:51:43 INFO - PROCESS | 1662 | 02:51:43 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17726.13 Median:33104.04 stddev:24468.89 (73.9%) stddev-sans-first:24504.02 02:51:43 INFO - PROCESS | 1662 | Values: 670.5 687.4 742.4 764.5 802.2 14772.1 14990.2 15024.9 15047.8 15062.7 13544.9 13633.2 13663.5 13664.3 13668.1 723.5 742.2 901.6 921.5 927.6 916.7 918.1 918.5 918.6 919.8 952.0 972.1 974.2 975.0 975.4 767.2 951.2 965.8 967.1 968.1 714.2 714.5 716.0 716.2 716.3 14399.5 14450.9 14470.0 14482.5 14496.9 12267.6 12420.8 12467.4 12601.8 12610.4 9962.7 9975.2 10059.2 10400.1 10453.2 3449.3 3513.4 3515.8 3575.8 3722.9 78260.0 78902.0 79381.0 81174.6 81951.6 9077.0 9430.3 9458.0 9649.3 9714.8 20856.3 21328.9 22234.2 22248.6 22259.2 7220.1 7351.2 7440.1 7481.8 7553.9 3971.9 3988.9 4033.7 4034.2 4238.1 3676.1 3728.5 3735.9 3742.0 3745.1 646.5 659.7 666.3 673.1 689.2 510.5 540.8 585.8 593.8 603.0 7162.2 7920.3 7954.9 8016.9 8224.8 586.2 592.9 599.6 606.2 617.3 3761.6 3776.9 3825.0 3865.6 3880.1 78863.2 80401.0 80501.6 80664.0 90596.0 70754.6 73613.0 76063.9 76148.0 78402.6 68134.0 68877.0 69460.0 69664.7 70278.6 8207.8 8281.4 8344.0 8439.5 8456.0 45098.5 47051.8 48766.5 49609.8 49621.0 60974.7 65548.4 66079.7 67642.0 68448.0 10535.3 10767.9 10778.9 11062.6 11482.1 11633.2 11677.5 11684.8 11809.7 12280.9 11262.3 11411.5 11621.4 11632.5 11795.6 10166.7 10554.2 10915.3 11631.2 11632.7 10760.0 11188.2 11271.8 11334.5 11597.7 9352.4 9437.7 9505.6 9520.6 9795.5 8052.5 9454.4 9575.6 9598.9 9616.7 02:51:43 INFO - PROCESS | 1662 | -------- Summary: end -------- 02:51:43 INFO - PROCESS | 1662 | 02:51:44 INFO - TEST-INFO | 1662: exit 0 02:51:44 INFO - TEST-OK | dromaeo_css | took 1115131ms 02:51:44 INFO - TEST-START | kraken 02:51:44 INFO - operating with platform_type : mac_ 02:51:44 INFO - Initialising browser for kraken test... 02:51:44 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmptx4RGi/profile http://localhost:49273/getInfo.html) 02:51:46 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 02:51:46 INFO - PROCESS | 1668 | colorDepth:24 02:51:46 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 02:51:46 INFO - PROCESS | 1668 | __metrics 02:51:46 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:51:46 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:51:47 INFO - TEST-INFO | 1668: exit 0 02:51:47 INFO - Browser initialized. 02:51:47 INFO - Running cycle 1/1 for kraken test... 02:51:47 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmptx4RGi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:59 INFO - PROCESS | 1671 | RSS: Main: 211054592 02:51:59 INFO - PROCESS | 1671 | 02:52:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 02:52:02 INFO - PROCESS | 1671 | RSS: Main: 271831040 02:52:02 INFO - PROCESS | 1671 | 02:52:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 02:52:05 INFO - PROCESS | 1671 | RSS: Main: 285884416 02:52:05 INFO - PROCESS | 1671 | 02:52:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 02:52:09 INFO - PROCESS | 1671 | RSS: Main: 373387264 02:52:09 INFO - PROCESS | 1671 | 02:52:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 02:52:12 INFO - PROCESS | 1671 | RSS: Main: 279777280 02:52:12 INFO - PROCESS | 1671 | 02:52:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:52:15 INFO - PROCESS | 1671 | RSS: Main: 279834624 02:52:15 INFO - PROCESS | 1671 | 02:52:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 02:52:18 INFO - PROCESS | 1671 | RSS: Main: 319545344 02:52:18 INFO - PROCESS | 1671 | 02:52:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 02:52:21 INFO - PROCESS | 1671 | RSS: Main: 318763008 02:52:21 INFO - PROCESS | 1671 | 02:52:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 02:52:25 INFO - PROCESS | 1671 | RSS: Main: 317743104 02:52:25 INFO - PROCESS | 1671 | 02:52:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:52:27 INFO - PROCESS | 1671 | RSS: Main: 309678080 02:52:27 INFO - PROCESS | 1671 | 02:52:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:52:30 INFO - PROCESS | 1671 | RSS: Main: 316329984 02:52:30 INFO - PROCESS | 1671 | 02:52:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:52:32 INFO - PROCESS | 1671 | RSS: Main: 319279104 02:52:32 INFO - PROCESS | 1671 | 02:52:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:52:36 INFO - PROCESS | 1671 | RSS: Main: 324030464 02:52:36 INFO - PROCESS | 1671 | 02:52:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:52:39 INFO - PROCESS | 1671 | RSS: Main: 321789952 02:52:39 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 02:52:41 INFO - PROCESS | 1671 | RSS: Main: 386232320 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | __start_tp_report 02:52:41 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 02:52:41 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 02:52:41 INFO - PROCESS | 1671 | |i|pagename|runs| 02:52:41 INFO - PROCESS | 1671 | |0;ai-astar;115;114;119;115;113;113;114;115;114;135 02:52:41 INFO - PROCESS | 1671 | |1;audio-beat-detection;160;115;159;157;119;148;147;120;167;117 02:52:41 INFO - PROCESS | 1671 | |2;audio-dft;183;187;187;184;184;186;183;186;180;185 02:52:41 INFO - PROCESS | 1671 | |3;audio-fft;70;76;77;150;46;54;85;96;166;86 02:52:41 INFO - PROCESS | 1671 | |4;audio-oscillator;95;97;140;76;78;84;124;79;79;125 02:52:41 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;86;88;83;88;84;88;86;87;83 02:52:41 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;93;96;95;93;93;93;93;94 02:52:41 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;87;85;101;137;86;84;85;86;85 02:52:41 INFO - PROCESS | 1671 | |8;json-parse-financial;61;64;65;63;63;65;64;79;61;58 02:52:41 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;67;69;69;77;72;67;69;69;69 02:52:41 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;80;80;81;80;80;79;81;81;86 02:52:41 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;129;128;128;127;126;125;124;116;168;163 02:52:41 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;209;167;166;163;168;170;171;166;166;161 02:52:41 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;70;69;67;67;67;71;69;92;83;66 02:52:41 INFO - PROCESS | 1671 | __end_tp_report 02:52:41 INFO - PROCESS | 1671 | __start_cc_report 02:52:41 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1972 02:52:41 INFO - PROCESS | 1671 | __end_cc_report 02:52:41 INFO - PROCESS | 1671 | __startTimestamp1464256361555__endTimestamp 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | ------- Summary: start ------- 02:52:41 INFO - PROCESS | 1671 | Number of tests: 14 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:116.70 Median:115.00 stddev:6.65 (5.8%) stddev-sans-first:7.03 02:52:41 INFO - PROCESS | 1671 | Values: 115.0 114.0 119.0 115.0 113.0 113.0 114.0 115.0 114.0 135.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:152.50 stddev:20.76 (13.6%) stddev-sans-first:20.84 02:52:41 INFO - PROCESS | 1671 | Values: 160.0 115.0 159.0 157.0 119.0 148.0 147.0 120.0 167.0 117.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.50 stddev:2.17 (1.2%) stddev-sans-first:2.24 02:52:41 INFO - PROCESS | 1671 | Values: 183.0 187.0 187.0 184.0 184.0 186.0 183.0 186.0 180.0 185.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:90.60 Median:76.50 stddev:38.65 (50.5%) stddev-sans-first:40.27 02:52:41 INFO - PROCESS | 1671 | Values: 70.0 76.0 77.0 150.0 46.0 54.0 85.0 96.0 166.0 86.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:79.00 stddev:23.52 (29.8%) stddev-sans-first:24.93 02:52:41 INFO - PROCESS | 1671 | Values: 95.0 97.0 140.0 76.0 78.0 84.0 124.0 79.0 79.0 125.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:2.00 (2.3%) stddev-sans-first:2.09 02:52:41 INFO - PROCESS | 1671 | Values: 87.0 86.0 88.0 83.0 88.0 84.0 88.0 86.0 87.0 83.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.00 stddev:1.07 (1.2%) stddev-sans-first:1.12 02:52:41 INFO - PROCESS | 1671 | Values: 93.0 93.0 93.0 96.0 95.0 93.0 93.0 93.0 93.0 94.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:85.00 stddev:16.54 (19.5%) stddev-sans-first:17.35 02:52:41 INFO - PROCESS | 1671 | Values: 85.0 87.0 85.0 101.0 137.0 86.0 84.0 85.0 86.0 85.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:5.60 (8.7%) stddev-sans-first:5.81 02:52:41 INFO - PROCESS | 1671 | Values: 61.0 64.0 65.0 63.0 63.0 65.0 64.0 79.0 61.0 58.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.00 stddev:3.27 (4.7%) stddev-sans-first:3.07 02:52:41 INFO - PROCESS | 1671 | Values: 65.0 67.0 69.0 69.0 77.0 72.0 67.0 69.0 69.0 69.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:1.94 (2.4%) stddev-sans-first:2.03 02:52:41 INFO - PROCESS | 1671 | Values: 82.0 80.0 80.0 81.0 80.0 80.0 79.0 81.0 81.0 86.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:133.40 Median:128.00 stddev:17.35 (13.6%) stddev-sans-first:18.33 02:52:41 INFO - PROCESS | 1671 | Values: 129.0 128.0 128.0 127.0 126.0 125.0 124.0 116.0 168.0 163.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:167.50 stddev:13.78 (8.2%) stddev-sans-first:3.13 02:52:41 INFO - PROCESS | 1671 | Values: 209.0 167.0 166.0 163.0 168.0 170.0 171.0 166.0 166.0 161.0 02:52:41 INFO - PROCESS | 1671 | 02:52:41 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:69.50 stddev:8.53 (12.3%) stddev-sans-first:9.01 02:52:41 INFO - PROCESS | 1671 | Values: 70.0 69.0 67.0 67.0 67.0 71.0 69.0 92.0 83.0 66.0 02:52:41 INFO - PROCESS | 1671 | -------- Summary: end -------- 02:52:41 INFO - PROCESS | 1671 | 02:52:42 INFO - TEST-INFO | 1671: exit 0 02:52:42 INFO - TEST-OK | kraken | took 58077ms 02:52:42 INFO - SUITE-END | took 1173s 02:52:42 INFO - Completed test suite (00:19:33) 02:52:42 INFO - javascript benchmark 02:52:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.2741010682707, 3457.4813777933323, 3460.567429071366, 3468.202313467904, 3516.5252690886737, 5653.660780353177, 5680.545556355492, 5683.658980461175, 5703.144528320744, 5706.828795136147, 5672.631278966875, 5732.79935202268, 5736.397678243787, 5737.5696822738355, 5784.68184249866, 5150.995555866608, 5182.119039763231, 5207.244949482323, 5312.123499622558, 5333.706646134461, 5263.6841789492755, 5280.630355875091, 5294.1529355303155, 5296.126139187029, 5300.681959082448, 5203.505666961632, 5259.6055295852775, 5265.921011184835, 5270.288511050997, 5278.419373868871, 5249.947500524984, 5305.442928492491, 5314.9468505314835, 5318.574514038867, 5341.65279256847, 5127.948720512822, 5145.536901678829, 5160.483951604847, 5228.451012643655, 5247.790088396459, 5577.944220557823, 5586.664800112007, 5614.578906582023, 5622.465865742748, 5633.0, 6266.937330626681, 6270.6864656766975, 6281.309056003836, 6291.993281075006, 6302.968485157545, 5237.214417837316, 6077.1491991121275, 6162.1681073055015, 6354.142190804228, 6360.936390636127, 5782.392848750905, 5797.768089276382, 5802.738876750556, 5810.854728631776, 5821.039528477796, 6322.051692246107, 6326.0, 6326.304106548276, 6327.335629758901, 6339.809805705836, 6017.849553761103, 6087.908681369783, 6130.111133885651, 6169.8457538561, 6209.285932117774, 6288.0, 6301.212348456443, 6316.747330106836, 6328.6202827830475, 6335.208098987627, 6212.161358216583, 6240.344763799826, 6262.937370626327, 6267.62394256346, 6268.686565671789, 6126.816195514141, 6197.0, 6202.038684003896, 6206.906896396557, 6281.0, 4879.804807807719, 4893.339399181136, 4911.45973942866, 4937.432195297587, 4976.402831660152, 4399.912001759947, 4418.050119224382, 4426.557344265548, 4452.0, 4479.328100784908, 5691.117876729114, 5709.9714501427225, 5715.371309155989, 5724.914126288109, 5731.512821410227, 6134.16106913652, 6202.2867370253, 6315.0, 6327.746890124436, 6330.651814150266, 5652.208690783294, 5687.971560142173, 5694.288213973254, 5701.771929122871, 5725.8568535786935, 5388.56891448691, 5409.459054094559, 5409.810656626999, 5413.566914646819, 5425.13197888336, 6238.469730072995, 6273.12176295319, 6352.745890164341, 6360.522960777867, 6365.363463653783, 6276.246850377801, 6290.0, 6299.779507717208, 6315.273743519554, 6318.810435686937, 6250.781222657367, 6270.0, 6350.079238510482, 6359.523035772243, 6363.61818290904, 6079.209702738615, 6149.139120523219, 6152.692365381624, 6172.135900973957, 6183.505319574333, 6232.906506402317, 6270.49836013127, 6292.182016337847, 6298.0867774171575, 6315.55791094619, 5721.971390143022, 6019.036954087325, 6123.908141377794, 6125.234345706786, 6132.356102609162, 6097.817065488042, 6113.111460343324, 6117.15760190703, 6128.747575574743, 6142.345304543789, 5950.0, 5964.9105263420215, 5970.373110823301, 5977.521798256216, 5981.641101533748, 5904.763809447574, 6055.1522786810765, 6082.665453399928, 6123.83172914054, 6137.539684523589, 5792.391798861059, 5878.500327472213, 5891.1752354671235, 5895.322037965518, 5903.085021821625], "value": 5716.222286720412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.4843678976206, 535.4217445159228, 546.0253447596042, 568.6303902463399, 570.8801151758131, 24760.0, 31789.364212715733, 38175.809120954356, 39037.21925561487, 39079.41380879289, 34806.65193348066, 41408.75773726786, 41462.75611731645, 41963.58036419635, 42032.0, 9325.160735533818, 9520.666776662818, 9530.618775248982, 9533.904660953405, 9578.56896439662, 8532.61603227853, 8749.56252187388, 8751.737447876541, 11271.605493807718, 11571.13216508761, 11252.43737813106, 11326.433678316092, 11327.830082548768, 11332.376719280477, 11337.489812958396, 11229.55081796727, 11327.263727857662, 11338.886611133865, 11359.261647992937, 11366.886331136708, 8506.489610623321, 8725.083866194022, 8730.083341249141, 11123.610673626385, 11387.601433949854, 38508.61491385107, 38946.831595052194, 39078.21843563141, 39095.609043909484, 39126.60873391258, 3207.1982004498877, 3230.709236168733, 3427.4002049641213, 3805.06775839921, 3851.6533511983775, 26461.206163815117, 26624.801883915272, 26648.333791655168, 26680.0, 26738.73261267382, 2728.1679087878388, 2754.9586756198673, 2756.4073724149202, 2774.6392968914115, 2797.468480988626, 373344.0, 375001.1249943733, 375683.0, 375880.0, 376497.0, 35683.4647480288, 36415.817920910755, 36416.453753193724, 36514.452283215775, 36539.63460365416, 35684.464733029025, 35815.10462238465, 36007.0, 36086.27827443489, 36133.0, 15429.30568124437, 15572.377104915677, 15600.453984110729, 15603.985740926804, 15650.843491565167, 12267.69330766738, 13101.475940962446, 13141.868581314257, 13182.472701092041, 13664.726705466033, 7544.0, 8158.143394943446, 8167.550784706779, 8299.917000829915, 8337.624806883705, 3357.1942733744213, 3430.8627654893526, 3434.0899661589697, 3435.124043368925, 3462.082548124749, 938.408802454463, 999.4209149543703, 1007.1288335589809, 1032.1794173631981, 1043.9739006524746, 28411.85794071058, 28758.0, 29464.0, 29545.704542954725, 29623.703762962527, 1099.0438318662814, 1103.3159441146381, 1151.9517239312186, 1157.3287493253893, 1184.7512022475205, 15224.923875380553, 15280.388784448498, 15316.770248446282, 15430.768538471932, 15512.082625075715, 620083.0, 626355.0, 626590.0, 628692.0, 629059.0, 58706.0, 60111.398886011455, 60176.09735853966, 60299.39700603025, 60374.0, 1327.1439921250933, 1329.6609364611961, 1354.1807206640037, 1492.2165862921836, 1602.7515735061083, 23534.646980294965, 25759.227223183334, 29190.27024324409, 29626.407471850012, 29833.955811546493, 44296.33555496734, 57521.42478575328, 58507.70746146242, 59878.0, 59971.700141499015, 390672.0, 441699.0, 448984.0, 449723.0, 450246.0, 30868.845655771576, 34257.486137707456, 39751.60248397479, 39947.201055978134, 40488.79755601203, 37418.625813742605, 41976.74069777912, 42022.57977420187, 42065.369019464124, 42842.357364638934, 33896.66103339034, 34337.62649493992, 34707.65292347044, 34715.826420867736, 35266.0, 35769.642303576635, 35790.0, 36192.27615447729, 36459.0, 36460.0, 36322.91031269066, 36633.26733465369, 36783.89648310555, 37049.0, 37755.0, 32484.0, 32892.67107328896, 36812.26375472529, 41109.0, 41496.79251603843, 23153.88423057874, 28933.0, 29508.704912950594, 29680.703192967794, 29879.701202988563], "value": 18388.582249296043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.85193052605274, 396.78970145822717, 396.8293633737492, 400.8857475619447, 411.9794010299484, 20026.998650067493, 20773.896130519344, 20778.48053798652, 20834.583308333844, 21022.894885525588, 17895.463136105904, 20718.896405517953, 20898.850563219017, 21311.25410610629, 21660.56678866422, 5726.513246374063, 5886.823395298137, 6054.666993315366, 6066.969665151668, 6113.724882380304, 5981.162637230791, 6002.549808764338, 6019.398060193989, 6022.397760223965, 6033.7888173913925, 6010.038393856984, 6013.488853447462, 6024.48791852693, 6636.966815165917, 6645.202575690924, 6580.045893345462, 6607.702653380586, 6612.471002319803, 6617.106690596804, 6618.966905165492, 5492.615516913817, 5793.710314484259, 5796.652200867961, 5802.0, 5837.124431335291, 13270.800937985938, 13428.79856802139, 13487.797683034663, 18355.541111472186, 25162.0, 2101.7057611934342, 2188.1446501801915, 2207.569523942832, 2298.5977453945493, 2501.574732295514, 9716.708498745049, 9856.802863942681, 9948.104670579683, 10036.447995360253, 10331.32846364984, 1714.3399791080524, 1718.9140542972802, 1723.8448539631368, 1728.6369862328927, 1776.7512548243255, 66544.0, 87509.0, 102056.95886082311, 104591.4770426143, 124009.75990240162, 11784.523565548185, 11790.997765189883, 11826.349550774617, 11871.0503159747, 11885.940570297093, 15357.385704571692, 15416.0, 15416.075035497906, 15462.845371546366, 15463.845361546466, 8712.564371781385, 8733.519656418957, 8763.298936085097, 8891.288696904232, 8971.865422018675, 8928.553572321358, 8939.776505587412, 8943.508107054175, 8983.595738191798, 8991.190792828545, 3941.428492868518, 4587.197240482902, 4660.650451216173, 4681.461631912373, 4705.623550115983, 1613.790207273047, 1958.3439547751377, 1977.1300627723756, 1989.4143998084553, 2018.475196448934, 847.8050048488882, 895.2793001633747, 958.3339578992533, 1041.3075304922293, 1061.5965932945585, 20228.393148205578, 20453.590928181355, 20510.692339614918, 21159.78840211578, 21169.78830211678, 887.7913690282778, 931.3573634192386, 940.5062342270363, 961.7932144588947, 981.7349315684868, 11190.888091119148, 11323.37721425313, 11329.150313726503, 11336.886631133748, 11338.092952563775, 574835.2516474782, 586799.0, 591552.0, 599468.0, 599898.0, 77225.0, 98560.52159217617, 120082.0, 122828.0, 123353.0, 865.8917635295588, 963.0513943765526, 973.7030205787303, 975.4634950777186, 978.5596481583316, 13077.0, 14000.859991399955, 17679.38122165718, 17775.0, 18180.81819181791, 46903.76548117238, 48709.025819482704, 49853.50146498489, 52780.0, 53168.734156330516, 68699.65650171918, 71331.93002105076, 72509.0, 72738.63630681812, 73684.63157684177, 8015.198480151938, 8025.197480251928, 8035.0, 8126.984126984127, 8193.59032048407, 11112.555497780322, 12356.25862448256, 12460.563880264148, 12646.683832903884, 12748.87251127514, 10578.418186999788, 10618.787624247318, 10782.406967616542, 10820.5130769114, 10954.835677465004, 10612.681619551426, 10724.89275107239, 10773.138148947908, 10911.345319280868, 10981.670549883516, 10695.572177112828, 10897.820535072025, 10901.641392060486, 11115.72210694739, 11184.496697648789, 8142.51144931306, 8154.959225203836, 8198.83602327962, 8268.586570671321, 8306.54314012735, 10060.446675432922, 10911.781764364827, 11188.83216751733, 11211.0, 11222.887771122185], "value": 9373.919278869824, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1280.4366078925273, 1409.0488919979011, 1560.056166019558, 1723.310675729708, 1921.6829223178167, 7807.687692492301, 7998.880156778056, 8437.0, 8590.226879580836, 8602.698905538307, 6554.672266386686, 7777.766666999985, 8585.742427727162, 8598.699045533393, 8992.190702836744, 1874.409560988291, 2163.8377121715935, 2230.7323121225477, 2432.5378178146184, 2467.8519288842726, 2423.309356833303, 2486.8632225227607, 2497.1509686706518, 2497.5504409206337, 2498.0507407185246, 2390.725066617335, 2427.5509030829267, 2430.829841911067, 2444.498877730061, 2450.7304196538366, 2128.372130221588, 2371.0, 2421.3462365161486, 2422.467057247403, 2432.3189506938083, 2405.446747248125, 2439.3901524618846, 2445.3030886197525, 2445.792107670832, 2446.6330050492393, 8500.74497765068, 8567.186117318844, 8571.614277357472, 8588.656453741844, 8590.828183436359, 5102.056119617845, 5239.607029472767, 5246.737663116829, 5287.894242115175, 5289.576833853283, 3409.590849098124, 3508.0528257370365, 3592.209713862946, 3610.6569875862015, 3611.9097022574383, 949.8670186173941, 1048.5334026358273, 1057.2282233969245, 1062.1025233677608, 1066.4720963123227, 9825.361351512129, 11793.823092653616, 11822.940885295604, 11850.940745296304, 11859.881401185963, 4706.694064885772, 4761.262004389325, 4835.443923948791, 4844.297576851407, 4898.436679781871, 9938.751531211778, 9948.651797187209, 9950.452725100044, 9977.700668979942, 10000.149987251165, 5400.729963501809, 5402.6758394496455, 5406.78372865081, 5408.972295263888, 5459.0, 4361.3676016978, 4525.886852828639, 4696.365990591295, 4874.975625121851, 4933.925991110136, 3276.8689252429635, 3956.5449973252867, 4025.1144748155357, 4061.8578349757618, 4069.7354671946227, 1312.9868701312864, 1318.1893135721655, 1318.3803612302202, 1326.7678156322797, 1335.9599212023654, 845.2603971524916, 895.8029233568604, 923.385948344357, 926.6525053105086, 926.7914719188263, 5796.681182534917, 5811.82564523065, 5930.318013428425, 5968.22413086296, 6096.603720758137, 452.74872445792244, 455.8860284928768, 475.79303003193723, 475.8365795629652, 476.16195495927417, 3276.2137087098927, 3871.109644781716, 3973.543042550086, 3993.1414745829784, 4013.13717550728, 10456.68629941103, 10726.249162558703, 10779.353238805697, 10859.565617375374, 10861.1854110942, 10423.947880260703, 10577.153827693766, 10616.628418005334, 10717.83923241152, 10722.195835312383, 8267.669293228204, 8302.70940517079, 8322.66709331632, 8360.665573376997, 8361.289290410261, 5400.702961337164, 5670.461306175906, 5731.770729170787, 5772.509336706259, 5912.822615321547, 9206.263498920205, 9345.205657519187, 9467.242620590474, 9482.38364506305, 9486.146246837818, 9137.908620913706, 9461.621535138518, 9588.856167157359, 9596.23230141573, 9601.0, 6722.529422940347, 6911.343422374866, 7254.16577093641, 7349.55902645843, 7475.850482990201, 8120.14738452454, 8146.348292136733, 8195.549244791593, 8200.302974247255, 8233.79415514617, 7149.499535032498, 7229.277072292729, 7307.926920730724, 7362.558246505227, 7375.004374409601, 7027.964860175666, 7073.964630176816, 7140.607266600179, 7170.0, 7195.964020179866, 6773.762918298033, 6986.510944233854, 6987.475939304674, 7008.439324853897, 7013.333733295329, 4772.976135119441, 4793.5685788279225, 4795.424549054136, 4849.878753031202, 4954.504549545017, 4882.902341953212, 4929.950700493093, 4938.135826230324, 4976.0794253063295, 5124.590032797441], "value": 4452.953967521263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.3154058382434, 317.7775557110024, 322.7788964559276, 325.8843110695703, 330.44155377412187, 6563.638999855006, 6590.154756668135, 6683.832904177398, 6688.832779180523, 6695.430888374494, 5741.167530708045, 6219.533534984872, 6597.670116494168, 6603.570767900083, 6606.900896486544, 4683.625309975194, 4783.736894470802, 4796.664233503656, 4882.048000639877, 4891.461939186686, 4824.107540105074, 4878.609711223111, 4891.1195984722735, 4896.1186986342445, 4899.167141585939, 4131.834726610932, 4833.7099774013495, 4847.248676455138, 4847.806087756486, 4861.8784530386665, 4655.976720116411, 4728.763561821912, 4828.0, 4839.44346400165, 4866.002469493746, 3432.8626854925774, 3650.7809531427934, 3963.207358528306, 4148.128892932487, 4154.356074808409, 6275.987525987506, 6373.298937116913, 6381.744730210786, 6383.265924418705, 6407.967960160216, 5684.062129748587, 5707.343655479632, 5718.857028574277, 5720.399358067385, 5721.513671337942, 6781.0, 7020.5787652741, 7029.156501219886, 7033.085698859167, 7058.153021637385, 4663.836765713218, 4680.297955306697, 4689.859304220845, 4692.671512994092, 4697.929531057037, 7357.963210183915, 7442.03253577037, 7445.8883116753295, 7447.44144189188, 7456.0, 3878.3600705883778, 4733.313669517901, 4739.454962679336, 4743.57307842289, 4757.286407038971, 4816.4220293564995, 4821.059893320768, 4825.734584597881, 4827.179379505422, 4833.226683730657, 4923.975380123148, 5444.91832622511, 5689.203511508392, 5695.601307908407, 5696.0, 5676.403977582377, 6761.661916904135, 7080.079589653312, 7085.43316534676, 7093.645317734093, 1649.0517952177545, 1662.883598148118, 1672.5818545363659, 1675.8407951244612, 1677.2620047179203, 910.4673765847053, 920.8802855628726, 983.5573991703762, 1066.6373433032807, 1081.4214395298477, 6423.710933008026, 6539.50953678476, 6620.3710647488415, 6644.534882558174, 6659.833504162338, 7322.487425880137, 7329.633518324063, 7341.449391295674, 7342.596157211297, 7367.852642947111, 6569.868602627921, 6572.572782769104, 6585.5719378240265, 6595.406413422815, 6612.966935165294, 3742.9064273393383, 3824.330742120118, 3881.6118388160958, 3891.0466935600393, 3893.9610603894166, 7627.809304767426, 7654.8086297843, 7659.540427574252, 7661.61691915402, 7675.808104797425, 7524.811879703051, 7551.73568925085, 7569.205233450519, 7585.089789225328, 7593.354564862048, 7679.424043196895, 7692.884606730791, 7695.92304076952, 7700.383969282444, 7704.499207551491, 6966.268541803038, 7311.853762924606, 7321.963390183015, 7322.52403593786, 7326.523775954546, 7147.85704285901, 7197.064381630355, 7210.459215558959, 7218.639068046682, 7227.313405226495, 7679.193684663253, 7686.07767067933, 7686.692532298645, 7698.769036928901, 7713.807154821175, 4770.833020844254, 4771.093492236464, 4773.0, 4780.880477988078, 4781.665283430266, 6819.5908245505425, 7051.964740176266, 7310.853782924418, 7314.049173607397, 7320.414366850533, 7003.299670032956, 7045.471589630901, 7060.188078371053, 7067.434605231466, 7070.434365250665, 7035.929640703732, 7039.824004399931, 7044.0, 7049.577025378494, 7053.4004609608755, 6046.969765151146, 6569.211694596679, 6922.411595014481, 6926.480513961373, 6931.2967942501455, 6784.830379240559, 7067.787966361017, 7075.646217688992, 7084.964575177091, 7101.786946391617, 5808.535317174531, 6566.8358291043105, 6613.140291762233, 6790.932090679227, 6946.895796563157], "value": 5134.951298890898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.497127154634, 687.3573209049539, 742.3726950726638, 764.4609709544388, 802.1978021978024, 14772.113673179616, 14990.175540345277, 15024.924875375622, 15047.849521504782, 15062.6987460251, 13544.93227533861, 13633.182009079463, 13663.521776737816, 13664.316784160801, 13668.111572747765, 723.4754802768003, 742.1724776873774, 901.621319045999, 921.5115988526092, 927.5594092805896, 916.6562539047857, 918.0910898210757, 918.531548910054, 918.591226904028, 919.8206349761799, 952.0, 972.0668158567784, 974.2400927276735, 974.9561269742878, 975.3562648651891, 767.15995984397, 951.1724799424476, 965.8454647256441, 967.1295833749612, 968.0706521739104, 714.1858281559026, 714.4534431160166, 715.9677814498308, 716.1763971432894, 716.3087620446295, 14399.496017639438, 14450.855491445056, 14470.0, 14482.49311274111, 14496.927515362355, 12267.631971040882, 12420.81368779469, 12467.438965246585, 12601.810972835416, 12610.369481525888, 9962.651307204285, 9975.201983841276, 10059.245556583286, 10400.115990140772, 10453.216008799294, 3449.292894956528, 3513.437849944022, 3515.8317273607754, 3575.803330816804, 3722.8883133506038, 78260.0, 78902.0, 79381.0, 81174.59412702899, 81951.590242049, 9076.954615226881, 9430.3398762086, 9458.0, 9649.276304277208, 9714.757131071638, 20856.270030548858, 21328.893355533124, 22234.221802236843, 22248.555028899656, 22259.220927267466, 7220.0613920190035, 7351.22812104732, 7440.107187137578, 7481.812954676177, 7553.8866916996285, 3971.932471193098, 3988.8603898863403, 4033.7176397651883, 4034.152827906114, 4238.0888109056705, 3676.0993556578273, 3728.5339332583426, 3735.9439608405896, 3741.9812900935867, 3745.1386181177936, 646.4537465841356, 659.7196191618543, 666.3203532396928, 673.1316601583902, 689.2142957028991, 510.50226029621564, 540.7620646915344, 585.844751140948, 593.8337265565649, 602.9547783916224, 7162.247963963813, 7920.279023418037, 7954.935975017565, 8016.879746803803, 8224.794380140425, 586.2466730251606, 592.863641362489, 599.5623195067537, 606.1513880567242, 617.2931992868141, 3761.586225515191, 3776.9244615108096, 3824.980875095662, 3865.594112618191, 3880.1463677990796, 78863.21136788673, 80401.0, 80501.59749201217, 80664.0, 90596.0, 70754.64622676854, 73613.0, 76063.85904211442, 76148.0, 78402.60798696084, 68133.9779903292, 68877.0, 69460.0, 69664.6516767423, 70278.64860675766, 8207.79480512968, 8281.37889658266, 8344.040435350013, 8439.451435656663, 8456.027556831043, 45098.54901450944, 47051.764741177445, 48766.53700388994, 49609.75195124001, 49621.0, 60974.69512652586, 65548.36129096811, 66079.66960165168, 67642.0, 68448.0, 10535.262531622713, 10767.892321077003, 10778.89221107779, 11062.62589910085, 11482.138839586898, 11633.185677002866, 11677.474513646737, 11684.766304674029, 11809.7217157616, 12280.877191227974, 11262.310653402055, 11411.543538258376, 11621.36082515436, 11632.476538555617, 11795.646130616095, 10166.74583135398, 10554.247934875664, 10915.345079295268, 11631.243969141979, 11632.651020469399, 10759.990851869761, 11188.160887933274, 11271.830922536332, 11334.546618135511, 11597.710057248636, 9352.43885366853, 9437.669681561112, 9505.619775208914, 9520.619175232912, 9795.461249631338, 8052.476589021695, 9454.43273403598, 9575.569099390683, 9598.9040109598, 9616.663416780659], "value": 6834.786073447181, "unit": "score"}], "name": "dromaeo_css", "value": 7321.284412951368, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 119.0, 115.0, 113.0, 113.0, 114.0, 115.0, 114.0, 135.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 115.0, 159.0, 157.0, 119.0, 148.0, 147.0, 120.0, 167.0, 117.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 187.0, 184.0, 184.0, 186.0, 183.0, 186.0, 180.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 77.0, 150.0, 46.0, 54.0, 85.0, 96.0, 166.0, 86.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 140.0, 76.0, 78.0, 84.0, 124.0, 79.0, 79.0, 125.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 83.0, 88.0, 84.0, 88.0, 86.0, 87.0, 83.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 96.0, 95.0, 93.0, 93.0, 93.0, 93.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 101.0, 137.0, 86.0, 84.0, 85.0, 86.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 63.0, 63.0, 65.0, 64.0, 79.0, 61.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 69.0, 77.0, 72.0, 67.0, 69.0, 69.0, 69.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 80.0, 81.0, 80.0, 80.0, 79.0, 81.0, 81.0, 86.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 128.0, 127.0, 126.0, 125.0, 124.0, 116.0, 168.0, 163.0], "value": 133.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 167.0, 166.0, 163.0, 168.0, 170.0, 171.0, 166.0, 166.0, 161.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 67.0, 67.0, 71.0, 69.0, 92.0, 83.0, 66.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1492.9, "alertThreshold": 2.0}]} 02:52:42 INFO - Return code: 0 02:52:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:52:42 ERROR - # TBPL SUCCESS # 02:52:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:52:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:52:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:52:42 INFO - 2016-05-26 02:52:42.862 screenresolution[1675:10171] starting screenresolution argv=screenresolution get 02:52:42 INFO - 2016-05-26 02:52:42.865 screenresolution[1675:10171] Display 0: 1600x1200x32@60 02:52:42 INFO - 2016-05-26 02:52:42.871 screenresolution[1676:10174] starting screenresolution argv=screenresolution list 02:52:42 INFO - Available Modes on Display 0 02:52:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:52:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:52:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:52:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:52:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:52:43 INFO - Intel Iris: 02:52:43 INFO - Chipset Model: Intel Iris 02:52:43 INFO - Type: GPU 02:52:43 INFO - Bus: Built-In 02:52:43 INFO - VRAM (Dynamic, Max): 1536 MB 02:52:43 INFO - Vendor: Intel (0x8086) 02:52:43 INFO - Device ID: 0x0a2e 02:52:43 INFO - Revision ID: 0x0009 02:52:43 INFO - Displays: 02:52:43 INFO - Display: 02:52:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:52:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:52:43 INFO - Display Serial Number: 1600x1200 60 02:52:43 INFO - Main Display: Yes 02:52:43 INFO - Mirror: Off 02:52:43 INFO - Online: Yes 02:52:43 INFO - Rotation: Supported 02:52:43 INFO - Return code: 0 02:52:43 INFO - Running post-action listener: _resource_record_post_action 02:52:43 INFO - Running post-run listener: _resource_record_post_run 02:52:44 INFO - Total resource usage - Wall time: 1206s; CPU: 52.0%; Read bytes: 31174144; Write bytes: 654954496; Read time: 194; Write time: 1888 02:52:44 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345780736; Write bytes: 347547648; Read time: 26593; Write time: 769 02:52:44 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 30248448; Write bytes: 297449472; Read time: 183; Write time: 1084 02:52:44 INFO - Running post-run listener: _upload_blobber_files 02:52:44 INFO - Blob upload gear active. 02:52:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:52:44 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 02:52:44 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'] 02:52:44 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 02:52:44 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:52:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:45 INFO - (blobuploader) - INFO - Done attempting. 02:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:46 INFO - (blobuploader) - INFO - Done attempting. 02:52:46 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:46 INFO - Return code: 0 02:52:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e7a5d5323f38ecf5e53d84440535358422c415b51ea3bf5d97ef436bd2b192d16f2b7df80414eb60d095dc9bbb4d6c42d0d9b3255cdc22a43dd94ca37e459c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40156c42291a6706b4c17749efe78e5c56ac9887d7ebc20e214d0556cf4da66964eb97511066cce21dfde88fe536d67a42ce50f75689a43207fd29f62a2f6ee7"} 02:52:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:46 INFO - Contents: 02:52:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e7a5d5323f38ecf5e53d84440535358422c415b51ea3bf5d97ef436bd2b192d16f2b7df80414eb60d095dc9bbb4d6c42d0d9b3255cdc22a43dd94ca37e459c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40156c42291a6706b4c17749efe78e5c56ac9887d7ebc20e214d0556cf4da66964eb97511066cce21dfde88fe536d67a42ce50f75689a43207fd29f62a2f6ee7"} 02:52:46 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:46 INFO - Copying logs to upload dir... 02:52:46 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.823692 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-26 02:52:46.895744) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 02:52:46.899704) ========= 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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/e7e7a5d5323f38ecf5e53d84440535358422c415b51ea3bf5d97ef436bd2b192d16f2b7df80414eb60d095dc9bbb4d6c42d0d9b3255cdc22a43dd94ca37e459c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40156c42291a6706b4c17749efe78e5c56ac9887d7ebc20e214d0556cf4da66964eb97511066cce21dfde88fe536d67a42ce50f75689a43207fd29f62a2f6ee7"} build_url:https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/d2DDlnokSuCafyLZgPjWrg/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/e7e7a5d5323f38ecf5e53d84440535358422c415b51ea3bf5d97ef436bd2b192d16f2b7df80414eb60d095dc9bbb4d6c42d0d9b3255cdc22a43dd94ca37e459c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40156c42291a6706b4c17749efe78e5c56ac9887d7ebc20e214d0556cf4da66964eb97511066cce21dfde88fe536d67a42ce50f75689a43207fd29f62a2f6ee7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 02:52:46.930464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:52:46.930760) ========= 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.RAkbWw0jGj/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.3x0Evr8y4i/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.005035 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:52:46.979257) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 02:52:46.979609) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 02:52:46.979944) ========= ========= Total master_lag: 0.84 =========