builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1462400976.35 results: success (0) buildid: 20160504143547 builduid: 52f9b073c31b447bae629043bfb6f83a revision: b6738ca644382710da35245e0c2e01479befeb37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.353566) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.354018) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.354300) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.376536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.377178) ========= 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.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-04 15:29:36-- 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% 15.2M=0.001s 2016-05-04 15:29:36 (15.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133250 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.533231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.534043) ========= 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.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.026459 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:36.586049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-04 15:29:36.586852) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b6738ca644382710da35245e0c2e01479befeb37 --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 b6738ca644382710da35245e0c2e01479befeb37 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-04 15:29:36,661 truncating revision to first 12 chars 2016-05-04 15:29:36,661 Setting DEBUG logging. 2016-05-04 15:29:36,661 attempt 1/10 2016-05-04 15:29:36,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b6738ca64438?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-04 15:29:37,735 unpacking tar archive at: mozilla-aurora-b6738ca64438/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326833 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-04 15:29:37.931170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:37.931642) ========= script_repo_revision: b6738ca644382710da35245e0c2e01479befeb37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:37.932102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:37.932420) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-04 15:29:37.947805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-04 15:29:37.948566) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:29:38 INFO - MultiFileLogger online at 20160504 15:29:38 in /builds/slave/test 15:29:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:29:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:38 INFO - {'append_to_log': False, 15:29:38 INFO - 'base_work_dir': '/builds/slave/test', 15:29:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:29:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:38 INFO - 'branch': 'Mozilla-Aurora', 15:29:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:38 INFO - 'config_files': ('talos/mac_config.py',), 15:29:38 INFO - 'default_actions': ('clobber', 15:29:38 INFO - 'read-buildbot-config', 15:29:38 INFO - 'download-and-extract', 15:29:38 INFO - 'populate-webroot', 15:29:38 INFO - 'create-virtualenv', 15:29:38 INFO - 'install', 15:29:38 INFO - 'run-tests'), 15:29:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:38 INFO - 'download_minidump_stackwalk': True, 15:29:38 INFO - 'download_symbols': 'ondemand', 15:29:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:38 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:38 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:38 INFO - 'installer_path': 'installer.exe', 15:29:38 INFO - 'log_level': 'info', 15:29:38 INFO - 'log_name': 'talos', 15:29:38 INFO - 'log_to_console': True, 15:29:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:29:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:29:38 INFO - 'opt_config_files': (), 15:29:38 INFO - 'pip_index': False, 15:29:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:38 INFO - 'cmd': ('bash', 15:29:38 INFO - '-c', 15:29:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:38 INFO - 'enabled': True, 15:29:38 INFO - 'halt_on_failure': False, 15:29:38 INFO - 'name': 'check_screen_resolution'},), 15:29:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:38 INFO - 'cmd': ('bash', 15:29:38 INFO - '-c', 15:29:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:38 INFO - 'enabled': True, 15:29:38 INFO - 'halt_on_failure': False, 15:29:38 INFO - 'name': 'check_screen_resolution'},), 15:29:38 INFO - 'run_cmd_checks_enabled': True, 15:29:38 INFO - 'sps_profile': False, 15:29:38 INFO - 'sps_profile_interval': 0, 15:29:38 INFO - 'suite': 'dromaeojs-e10s', 15:29:38 INFO - 'system_bits': '32', 15:29:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:38 INFO - 'title': 't-yosemite-r7-0195', 15:29:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:38 INFO - 'use_talos_json': True, 15:29:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:38 INFO - 'work_dir': 'build'} 15:29:38 INFO - ##### 15:29:38 INFO - ##### Running clobber step. 15:29:38 INFO - ##### 15:29:38 INFO - Running pre-action listener: _resource_record_pre_action 15:29:38 INFO - Running main action method: clobber 15:29:38 INFO - rmtree: /builds/slave/test/build 15:29:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:40 INFO - Running post-action listener: _resource_record_post_action 15:29:40 INFO - ##### 15:29:40 INFO - ##### Running read-buildbot-config step. 15:29:40 INFO - ##### 15:29:40 INFO - Running pre-action listener: _resource_record_pre_action 15:29:40 INFO - Running main action method: read_buildbot_config 15:29:40 INFO - Using buildbot properties: 15:29:40 INFO - { 15:29:40 INFO - "project": "", 15:29:40 INFO - "product": "firefox", 15:29:40 INFO - "script_repo_revision": "production", 15:29:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:29:40 INFO - "repository": "", 15:29:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:29:40 INFO - "buildid": "20160504143547", 15:29:40 INFO - "pgo_build": "False", 15:29:40 INFO - "basedir": "/builds/slave/test", 15:29:40 INFO - "buildnumber": 317, 15:29:40 INFO - "slavename": "t-yosemite-r7-0195", 15:29:40 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:29:40 INFO - "platform": "yosemite_r7", 15:29:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:29:40 INFO - "branch": "mozilla-aurora", 15:29:40 INFO - "repo_path": "releases/mozilla-aurora", 15:29:40 INFO - "revision": "b6738ca644382710da35245e0c2e01479befeb37", 15:29:40 INFO - "stage_platform": "macosx64", 15:29:40 INFO - "builduid": "52f9b073c31b447bae629043bfb6f83a", 15:29:40 INFO - "slavebuilddir": "test" 15:29:40 INFO - } 15:29:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:29:40 INFO - Running post-action listener: _resource_record_post_action 15:29:40 INFO - ##### 15:29:40 INFO - ##### Running download-and-extract step. 15:29:40 INFO - ##### 15:29:40 INFO - Running pre-action listener: _resource_record_pre_action 15:29:40 INFO - Running main action method: download_and_extract 15:29:40 INFO - mkdir: /builds/slave/test/build/tests 15:29:40 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')]} 15:29:40 INFO - https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:29:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:29:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:29:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:42 INFO - Downloaded 1199 bytes. 15:29:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:29:42 INFO - Using the following test package requirements: 15:29:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:29:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:29:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:29:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'jsshell-mac.zip'], 15:29:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:29:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:29:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:29:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:29:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:29:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:29:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:29:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:42 INFO - https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:29:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:29:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:48 INFO - Downloaded 18114250 bytes. 15:29:48 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'] 15:29:48 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 15:29:51 INFO - Return code: 0 15:29:51 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 15:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:51 INFO - https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:29:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:29:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:29:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:53 INFO - Downloaded 18114250 bytes. 15:29:53 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'] 15:29:53 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 15:29:57 INFO - Return code: 0 15:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:57 INFO - https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:29:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:29:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:29:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:29:58 INFO - Downloaded 10840145 bytes. 15:29:58 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'] 15:29:58 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 15:29:58 INFO - Return code: 0 15:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:58 INFO - https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:29:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:29:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:29:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:29:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LUt1g0R_SmOV3BkgicpseA/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 15:30:01 INFO - Downloaded 114099301 bytes. 15:30:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:30:01 INFO - mkdir: /builds/slave/test/properties 15:30:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:30:01 INFO - Writing to file /builds/slave/test/properties/build_url 15:30:01 INFO - Contents: 15:30:01 INFO - build_url:https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:30:02 INFO - Running post-action listener: _resource_record_post_action 15:30:02 INFO - Running post-action listener: set_extra_try_arguments 15:30:02 INFO - ##### 15:30:02 INFO - ##### Running populate-webroot step. 15:30:02 INFO - ##### 15:30:02 INFO - Running pre-action listener: _resource_record_pre_action 15:30:02 INFO - Running main action method: populate_webroot 15:30:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:30:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:30:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:30:02 INFO - '--tppagecycles', 15:30:02 INFO - '7'], 15:30:02 INFO - 'tests': ['tsvgm']}}, 15:30:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:30:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:30:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:30:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:30:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['damp', 'tps']}, 15:30:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'tests': ['damp', 'tps']}, 15:30:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['dromaeo_dom']}, 15:30:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:30:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'other-e10s': {'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['a11yr', 15:30:02 INFO - 'ts_paint', 15:30:02 INFO - 'tpaint', 15:30:02 INFO - 'sessionrestore', 15:30:02 INFO - 'sessionrestore_no_auto_restore', 15:30:02 INFO - 'tabpaint']}, 15:30:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['tsvgx', 15:30:02 INFO - 'tsvgr_opacity', 15:30:02 INFO - 'tart', 15:30:02 INFO - 'tscrollx', 15:30:02 INFO - 'cart']}, 15:30:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:30:02 INFO - 'tsvgr_opacity', 15:30:02 INFO - 'tart', 15:30:02 INFO - 'tscrollx', 15:30:02 INFO - 'cart']}, 15:30:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'talos_options': ['--disable-e10s'], 15:30:02 INFO - 'tests': ['tp5o']}, 15:30:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'tests': ['tp5o']}, 15:30:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'talos_options': ['--disable-e10s', 15:30:02 INFO - '--xperf_path', 15:30:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:02 INFO - 'tests': ['tp5n']}, 15:30:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:02 INFO - 'talos_options': ['--xperf_path', 15:30:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:02 INFO - 'tests': ['tp5n']}}, 15:30:02 INFO - 'talos.zip': {'path': '', 15:30:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:30:02 INFO - Running post-action listener: _resource_record_post_action 15:30:02 INFO - ##### 15:30:02 INFO - ##### Running create-virtualenv step. 15:30:02 INFO - ##### 15:30:02 INFO - Running pre-action listener: _resource_record_pre_action 15:30:02 INFO - Running main action method: create_virtualenv 15:30:02 INFO - Creating virtualenv /builds/slave/test/build/venv 15:30:02 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 15:30:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:30:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:30:02 INFO - Using real prefix '/tools/python27' 15:30:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:30:03 INFO - Installing distribute.............................................................................................................................................................................................done. 15:30:06 INFO - Installing pip.................done. 15:30:06 INFO - Return code: 0 15:30:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:30:06 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')]} 15:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:06 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')]} 15:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:06 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:06 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 15:30:06 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 15:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:06 INFO - 'HOME': '/Users/cltbld', 15:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:06 INFO - 'LOGNAME': 'cltbld', 15:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:06 INFO - 'MOZ_NO_REMOTE': '1', 15:30:06 INFO - 'NO_EM_RESTART': '1', 15:30:06 INFO - 'PAGER': '/bin/cat', 15:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:06 INFO - 'PWD': '/builds/slave/test', 15:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:06 INFO - 'SHELL': '/bin/bash', 15:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:06 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:06 INFO - 'USER': 'cltbld', 15:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:06 INFO - 'XPC_FLAGS': '0x0', 15:30:06 INFO - 'XPC_SERVICE_NAME': '0', 15:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:06 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 15:30:06 INFO - Cleaning up... 15:30:06 INFO - Return code: 0 15:30:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:30:06 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')]} 15:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:06 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')]} 15:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:06 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:06 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 15:30:06 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 15:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:06 INFO - 'HOME': '/Users/cltbld', 15:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:06 INFO - 'LOGNAME': 'cltbld', 15:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:06 INFO - 'MOZ_NO_REMOTE': '1', 15:30:06 INFO - 'NO_EM_RESTART': '1', 15:30:06 INFO - 'PAGER': '/bin/cat', 15:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:06 INFO - 'PWD': '/builds/slave/test', 15:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:06 INFO - 'SHELL': '/bin/bash', 15:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:06 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:06 INFO - 'USER': 'cltbld', 15:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:06 INFO - 'XPC_FLAGS': '0x0', 15:30:06 INFO - 'XPC_SERVICE_NAME': '0', 15:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:06 INFO - Downloading/unpacking psutil>=0.7.1 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:30:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:09 INFO - Installing collected packages: psutil 15:30:09 INFO - Running setup.py install for psutil 15:30:09 INFO - building 'psutil._psutil_osx' extension 15:30:09 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 15:30:09 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 15:30:09 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 15:30:10 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 15:30:10 INFO - building 'psutil._psutil_posix' extension 15:30:10 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 15:30:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:30:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:30:10 INFO - ^ 15:30:10 INFO - 1 warning generated. 15:30:10 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 15:30:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:10 INFO - Successfully installed psutil 15:30:10 INFO - Cleaning up... 15:30:10 INFO - Return code: 0 15:30:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:30:10 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')]} 15:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:10 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')]} 15:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:10 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:10 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 15:30:10 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 15:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:10 INFO - 'HOME': '/Users/cltbld', 15:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:10 INFO - 'LOGNAME': 'cltbld', 15:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:10 INFO - 'MOZ_NO_REMOTE': '1', 15:30:10 INFO - 'NO_EM_RESTART': '1', 15:30:10 INFO - 'PAGER': '/bin/cat', 15:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:10 INFO - 'PWD': '/builds/slave/test', 15:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:10 INFO - 'SHELL': '/bin/bash', 15:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:10 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:10 INFO - 'USER': 'cltbld', 15:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:10 INFO - 'XPC_FLAGS': '0x0', 15:30:10 INFO - 'XPC_SERVICE_NAME': '0', 15:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:30:10 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 15:30:10 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 15:30:10 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 15:30:10 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 15:30:10 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 15:30:10 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 15:30:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:30:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:30:13 INFO - Installing collected packages: mozsystemmonitor 15:30:13 INFO - Running setup.py install for mozsystemmonitor 15:30:13 INFO - Successfully installed mozsystemmonitor 15:30:13 INFO - Cleaning up... 15:30:13 INFO - Return code: 0 15:30:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:30:13 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')]} 15:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:13 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')]} 15:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:13 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:13 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 15:30:13 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 15:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:13 INFO - 'HOME': '/Users/cltbld', 15:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:13 INFO - 'LOGNAME': 'cltbld', 15:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:13 INFO - 'MOZ_NO_REMOTE': '1', 15:30:13 INFO - 'NO_EM_RESTART': '1', 15:30:13 INFO - 'PAGER': '/bin/cat', 15:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:13 INFO - 'PWD': '/builds/slave/test', 15:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:13 INFO - 'SHELL': '/bin/bash', 15:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:13 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:13 INFO - 'USER': 'cltbld', 15:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:13 INFO - 'XPC_FLAGS': '0x0', 15:30:13 INFO - 'XPC_SERVICE_NAME': '0', 15:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:13 INFO - Downloading/unpacking blobuploader==1.2.4 15:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:16 INFO - Downloading blobuploader-1.2.4.tar.gz 15:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:17 INFO - Downloading docopt-0.6.1.tar.gz 15:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:17 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:17 INFO - Running setup.py install for blobuploader 15:30:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:17 INFO - Running setup.py install for requests 15:30:18 INFO - Running setup.py install for docopt 15:30:18 INFO - Successfully installed blobuploader requests docopt 15:30:18 INFO - Cleaning up... 15:30:18 INFO - Return code: 0 15:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:18 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:18 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 15:30:18 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 15:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:18 INFO - 'HOME': '/Users/cltbld', 15:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:18 INFO - 'LOGNAME': 'cltbld', 15:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:18 INFO - 'MOZ_NO_REMOTE': '1', 15:30:18 INFO - 'NO_EM_RESTART': '1', 15:30:18 INFO - 'PAGER': '/bin/cat', 15:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:18 INFO - 'PWD': '/builds/slave/test', 15:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:18 INFO - 'SHELL': '/bin/bash', 15:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:18 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:18 INFO - 'USER': 'cltbld', 15:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:18 INFO - 'XPC_FLAGS': '0x0', 15:30:18 INFO - 'XPC_SERVICE_NAME': '0', 15:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PBXLhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-LAlN4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-8s9zMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Bk2d9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-S8mUXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-yuIbME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-mmS7I2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ExfMRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-g06u1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bXwVZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-d09aYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XmSYEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-sdQqcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1EpUhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:20 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-pjKnUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:20 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-AyKj67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:20 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-74SDUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:20 INFO - Running setup.py install for manifestparser 15:30:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Running setup.py install for mozcrash 15:30:20 INFO - Running setup.py install for mozdebug 15:30:20 INFO - Running setup.py install for mozdevice 15:30:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:20 INFO - Running setup.py install for mozfile 15:30:21 INFO - Running setup.py install for mozhttpd 15:30:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Running setup.py install for mozinfo 15:30:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Running setup.py install for mozInstall 15:30:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Running setup.py install for mozleak 15:30:21 INFO - Running setup.py install for mozlog 15:30:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Running setup.py install for moznetwork 15:30:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:21 INFO - Running setup.py install for mozprocess 15:30:22 INFO - Running setup.py install for mozprofile 15:30:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Running setup.py install for mozrunner 15:30:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Running setup.py install for mozscreenshot 15:30:22 INFO - Running setup.py install for moztest 15:30:22 INFO - Running setup.py install for mozversion 15:30:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:22 INFO - Cleaning up... 15:30:22 INFO - Return code: 0 15:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:22 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')]} 15:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:22 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')]} 15:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:22 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:22 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 15:30:22 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 15:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:22 INFO - 'HOME': '/Users/cltbld', 15:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:22 INFO - 'LOGNAME': 'cltbld', 15:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:22 INFO - 'MOZ_NO_REMOTE': '1', 15:30:22 INFO - 'NO_EM_RESTART': '1', 15:30:22 INFO - 'PAGER': '/bin/cat', 15:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:22 INFO - 'PWD': '/builds/slave/test', 15:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:22 INFO - 'SHELL': '/bin/bash', 15:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:22 INFO - 'USER': 'cltbld', 15:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:22 INFO - 'XPC_FLAGS': '0x0', 15:30:22 INFO - 'XPC_SERVICE_NAME': '0', 15:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-efGBTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-MwMUqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-kQpJg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-42qV1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-2WhrEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-8WgrpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-r5t3WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:23 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)) 15:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-oE3SEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PBzt8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-d8xsby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-3SjQOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-XJNvt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-l7g2NB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UtUTvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bkn7tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-zAche9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:24 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)) 15:30:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-RoT_dC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:24 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)) 15:30:24 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)) 15:30:24 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)) 15:30:24 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)) 15:30:24 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)) 15:30:24 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)) 15:30:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30:25 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 15:30:25 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 15:30:25 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 15:30:25 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 15:30:25 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 15:30:25 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 15:30:27 INFO - Downloading blessings-1.6.tar.gz 15:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:27 INFO - Installing collected packages: blessings 15:30:27 INFO - Running setup.py install for blessings 15:30:27 INFO - Successfully installed blessings 15:30:27 INFO - Cleaning up... 15:30:27 INFO - Return code: 0 15:30:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:28 INFO - Reading from file tmpfile_stdout 15:30:28 INFO - Current package versions: 15:30:28 INFO - blessings == 1.6 15:30:28 INFO - blobuploader == 1.2.4 15:30:28 INFO - docopt == 0.6.1 15:30:28 INFO - manifestparser == 1.1 15:30:28 INFO - mozInstall == 1.12 15:30:28 INFO - mozcrash == 0.17 15:30:28 INFO - mozdebug == 0.1 15:30:28 INFO - mozdevice == 0.48 15:30:28 INFO - mozfile == 1.2 15:30:28 INFO - mozhttpd == 0.7 15:30:28 INFO - mozinfo == 0.9 15:30:28 INFO - mozleak == 0.1 15:30:28 INFO - mozlog == 3.1 15:30:28 INFO - moznetwork == 0.27 15:30:28 INFO - mozprocess == 0.22 15:30:28 INFO - mozprofile == 0.28 15:30:28 INFO - mozrunner == 6.11 15:30:28 INFO - mozscreenshot == 0.1 15:30:28 INFO - mozsystemmonitor == 0.0 15:30:28 INFO - moztest == 0.7 15:30:28 INFO - mozversion == 1.4 15:30:28 INFO - psutil == 3.1.1 15:30:28 INFO - requests == 1.2.3 15:30:28 INFO - wsgiref == 0.1.2 15:30:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:28 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:28 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 15:30:28 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 15:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:28 INFO - 'HOME': '/Users/cltbld', 15:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:28 INFO - 'LOGNAME': 'cltbld', 15:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:28 INFO - 'MOZ_NO_REMOTE': '1', 15:30:28 INFO - 'NO_EM_RESTART': '1', 15:30:28 INFO - 'PAGER': '/bin/cat', 15:30:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:28 INFO - 'PWD': '/builds/slave/test', 15:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:28 INFO - 'SHELL': '/bin/bash', 15:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:28 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:28 INFO - 'USER': 'cltbld', 15:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:28 INFO - 'XPC_FLAGS': '0x0', 15:30:28 INFO - 'XPC_SERVICE_NAME': '0', 15:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 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)) 15:30:28 INFO - Cleaning up... 15:30:28 INFO - Return code: 0 15:30:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:28 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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30:28 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 15:30:28 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 15:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:28 INFO - 'HOME': '/Users/cltbld', 15:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:28 INFO - 'LOGNAME': 'cltbld', 15:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:28 INFO - 'MOZ_NO_REMOTE': '1', 15:30:28 INFO - 'NO_EM_RESTART': '1', 15:30:28 INFO - 'PAGER': '/bin/cat', 15:30:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:28 INFO - 'PWD': '/builds/slave/test', 15:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:28 INFO - 'SHELL': '/bin/bash', 15:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:28 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:28 INFO - 'USER': 'cltbld', 15:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:28 INFO - 'XPC_FLAGS': '0x0', 15:30:28 INFO - 'XPC_SERVICE_NAME': '0', 15:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:28 INFO - Downloading/unpacking jsonschema 15:30:28 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 15:30:28 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 15:30:28 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 15:30:28 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 15:30:28 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 15:30:28 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 15:30:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:30:30 INFO - Downloading/unpacking functools32 (from jsonschema) 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:30:31 INFO - warning: no files found matching '*.txt' 15:30:31 INFO - no previously-included directories found matching 'build' 15:30:31 INFO - no previously-included directories found matching 'dist' 15:30:31 INFO - no previously-included directories found matching '.git*' 15:30:31 INFO - Installing collected packages: jsonschema, functools32 15:30:31 INFO - Running setup.py install for functools32 15:30:31 INFO - warning: no files found matching '*.txt' 15:30:31 INFO - no previously-included directories found matching 'build' 15:30:31 INFO - no previously-included directories found matching 'dist' 15:30:31 INFO - no previously-included directories found matching '.git*' 15:30:31 INFO - Successfully installed jsonschema functools32 15:30:31 INFO - Cleaning up... 15:30:31 INFO - Return code: 0 15:30:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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 0x10d876a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d886420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae304916c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, '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.y23a1NaNx9/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.2jPBDZPjvx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 15:30: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 15:30: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 15:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:31 INFO - 'HOME': '/Users/cltbld', 15:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:31 INFO - 'LOGNAME': 'cltbld', 15:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:31 INFO - 'MOZ_NO_REMOTE': '1', 15:30:31 INFO - 'NO_EM_RESTART': '1', 15:30:31 INFO - 'PAGER': '/bin/cat', 15:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:31 INFO - 'PWD': '/builds/slave/test', 15:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:31 INFO - 'SHELL': '/bin/bash', 15:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:30:31 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:31 INFO - 'USER': 'cltbld', 15:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:31 INFO - 'XPC_FLAGS': '0x0', 15:30:31 INFO - 'XPC_SERVICE_NAME': '0', 15:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:32 INFO - Downloading/unpacking flake8 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:32 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 15:30:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:30:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:30:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 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 15:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:30:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:35 INFO - no previously-included directories found matching 'docs/_build' 15:30:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:30:35 INFO - Running setup.py install for pep8 15:30:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:30:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:30:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:30:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:30:35 INFO - no previously-included directories found matching 'docs/_build' 15:30:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:30:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:30:35 INFO - Cleaning up... 15:30:35 INFO - Return code: 0 15:30:35 INFO - Running post-action listener: _resource_record_post_action 15:30:35 INFO - Running post-action listener: _start_resource_monitoring 15:30:36 INFO - Starting resource monitoring. 15:30:36 INFO - ##### 15:30:36 INFO - ##### Running install step. 15:30:36 INFO - ##### 15:30:36 INFO - Running pre-action listener: _resource_record_pre_action 15:30:36 INFO - Running main action method: install 15:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:36 INFO - Reading from file tmpfile_stdout 15:30:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:36 INFO - Reading from file tmpfile_stdout 15:30:36 INFO - Output received: 15:30:36 INFO - Usage: mozinstall [options] installer 15:30:36 INFO - Options: 15:30:36 INFO - -h, --help show this help message and exit 15:30:36 INFO - -d DEST, --destination=DEST 15:30:36 INFO - Directory to install application into. [default: 15:30:36 INFO - "/builds/slave/test"] 15:30:36 INFO - --app=APP Application being installed. [default: firefox] 15:30:36 INFO - mkdir: /builds/slave/test/build/application 15:30: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'] 15:30: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 15:31:08 INFO - Reading from file tmpfile_stdout 15:31:08 INFO - Output received: 15:31:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:31:08 INFO - Running post-action listener: _resource_record_post_action 15:31:08 INFO - ##### 15:31:08 INFO - ##### Running run-tests step. 15:31:08 INFO - ##### 15:31:08 INFO - Running pre-action listener: _resource_record_pre_action 15:31:08 INFO - Running main action method: run_tests 15:31:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:31:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:31:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:31:08 INFO - 2016-05-04 15:31:08.272 screenresolution[1642:7562] starting screenresolution argv=screenresolution get 15:31:08 INFO - 2016-05-04 15:31:08.275 screenresolution[1642:7562] Display 0: 1600x1200x32@60 15:31:08 INFO - 2016-05-04 15:31:08.281 screenresolution[1643:7564] starting screenresolution argv=screenresolution list 15:31:08 INFO - Available Modes on Display 0 15:31:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:31:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:31:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:31:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:31:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:31:08 INFO - Intel Iris: 15:31:08 INFO - Chipset Model: Intel Iris 15:31:08 INFO - Type: GPU 15:31:08 INFO - Bus: Built-In 15:31:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:31:08 INFO - Vendor: Intel (0x8086) 15:31:08 INFO - Device ID: 0x0a2e 15:31:08 INFO - Revision ID: 0x0009 15:31:08 INFO - Displays: 15:31:08 INFO - Display: 15:31:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:31:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:31:08 INFO - Display Serial Number: 1600x1200 60 15:31:08 INFO - Main Display: Yes 15:31:08 INFO - Mirror: Off 15:31:08 INFO - Online: Yes 15:31:08 INFO - Rotation: Supported 15:31:08 INFO - Return code: 0 15:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:31:08 INFO - Python 2.7.3 15:31:08 INFO - Return code: 0 15:31:08 INFO - grabbing minidump binary from tooltool 15:31:08 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')]} 15:31:08 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 0x10d715e00>, '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 0x10d57edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d710b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:31:08 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 15:31:08 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 15:31:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:08 INFO - Return code: 0 15:31:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:31:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:31:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:31:09 INFO - Return code: 0 15:31:09 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:31:09 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y23a1NaNx9/Render', 15:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:09 INFO - 'HOME': '/Users/cltbld', 15:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:09 INFO - 'LOGNAME': 'cltbld', 15:31:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:31:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:09 INFO - 'MOZ_NO_REMOTE': '1', 15:31:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:31:09 INFO - 'NO_EM_RESTART': '1', 15:31:09 INFO - 'PAGER': '/bin/cat', 15:31:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:09 INFO - 'PWD': '/builds/slave/test', 15:31:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:09 INFO - 'SHELL': '/bin/bash', 15:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2jPBDZPjvx/Listeners', 15:31:09 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 15:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:09 INFO - 'USER': 'cltbld', 15:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:09 INFO - 'XPC_FLAGS': '0x0', 15:31:09 INFO - 'XPC_SERVICE_NAME': '0', 15:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:31:09 INFO - mozversion application_buildid: 20160504143547 15:31:09 INFO - mozversion application_changeset: b6738ca644382710da35245e0c2e01479befeb37 15:31:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:31:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:09 INFO - mozversion application_name: Firefox 15:31:09 INFO - mozversion application_remotingname: firefox-dev 15:31:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:31:09 INFO - mozversion application_vendor: Mozilla 15:31:09 INFO - mozversion application_version: 48.0a2 15:31:09 INFO - mozversion platform_buildid: 20160504143547 15:31:09 INFO - mozversion platform_changeset: b6738ca644382710da35245e0c2e01479befeb37 15:31:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:31:09 INFO - mozversion platform_version: 48.0a2 15:31:09 INFO - using testdate: 1462401069 15:31:09 INFO - actual date: 1462401069 15:31:09 INFO - starting webserver on 'localhost:49274' 15:31:09 INFO - SUITE-START | Running 2 tests 15:31:09 INFO - TEST-START | dromaeo_css 15:31:09 INFO - operating with platform_type : mac_ 15:31:09 INFO - Initialising browser for dromaeo_css test... 15:31:09 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpNHVVsc/profile http://localhost:49274/getInfo.html) 15:31:13 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 15:31:13 INFO - PROCESS | 1661 | colorDepth:24 15:31:13 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 15:31:13 INFO - PROCESS | 1661 | __metrics 15:31:13 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:31:13 INFO - TEST-INFO | 1661: exit 0 15:31:13 INFO - Browser initialized. 15:31:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:31:13 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpNHVVsc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:25 INFO - PROCESS | 1664 | RSS: Main: 221581312 15:31:25 INFO - PROCESS | 1664 | 15:32:15 INFO - PROCESS | 1664 | 1462401135451 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] 15:34:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 15:34:27 INFO - PROCESS | 1664 | RSS: Main: 231759872 15:34:27 INFO - PROCESS | 1664 | 15:37:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 15:37:30 INFO - PROCESS | 1664 | RSS: Main: 243732480 15:37:30 INFO - PROCESS | 1664 | 15:40:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 15:40:32 INFO - PROCESS | 1664 | RSS: Main: 245542912 15:40:32 INFO - PROCESS | 1664 | 15:43:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 15:43:35 INFO - PROCESS | 1664 | RSS: Main: 247173120 15:43:35 INFO - PROCESS | 1664 | 15:46:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 15:46:38 INFO - PROCESS | 1664 | RSS: Main: 250589184 15:46:38 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 15:49:40 INFO - PROCESS | 1664 | RSS: Main: 251326464 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | __start_tp_report 15:49:40 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 15:49:40 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 15:49:40 INFO - PROCESS | 1664 | |i|pagename|runs| 15:49:40 INFO - PROCESS | 1664 | |0;dojo.html;3421.4867769834523;3507.0530956641705;3511.7015053720415;3560;3583.7133029357656;5911.142884281782;5934.258217722785;5944.256967879015;5959.761609535619;5993.760249590022;5907.143464197699;5923.111533270012;5941.376155503674;5943.970280148604;5969.761209551623;5294.867628309295;5313.495217954288;5382.703951282678;5411.540019098381;5437.265969094181;5277.340332458442;5345.7594408251725;5419.997300499401;5490.91763623544;5500.779968801243;4170.791460426982;5367.516923476886;5411.134218525037;5472.699001554913;5486.561075113981;4308.224519586473;4441.578050085269;5398.973005134989;5407.729613519308;5410.5401040911565;5172.663776854492;5257.316548848626;5326.733663316857;5337.199420087018;5370.113931201347;4926.581240594518;5724.627899186539;5732.369439361667;5845.860057288831;5908.556858235649;6179.3202747697715;6211.720472578745;6214.937850621481;6215.347388524185;6274.592151510137;6152.784652537184;6208.161898143783;6299.464545513637;6308.558400911938;6321.778737744203;6022.156898034279;6038.516918646497;6041.909371359433;6057.151998720183;6099.573029887865;5340.385855626652;5403.378611459654;6449.129367535323;6461.709223084972;6497.415232629086;6089.117078023662;6138.38839838597;6168.660723660242;6208.4722798561725;6209.192804935329;5688.748475335419;6070.362611925772;6277.52918531112;6294.527910406738;6299.779507717208;6143.232095988002;6199.16311297969;6224.595401298901;6261.154744109578;6286.245650521967;6120.938790612126;6214.40963108504;6273.749050038039;6298.212723409574;6314.589551679126;4969.875753106129;5033.874153146201;5043.773030213649;5051.6716413433005;5051.898962020812;4552.658550608718;4567.2920697291975;4575.22221222393;4578.35902973584;4581.610563102107;5837.941620583825;5871.207387002785;5904.7342869570975;5909.349971503131;5912.379200183918;6306.873862522724;6311.589746666452;6331.620102793847;6336.20797400325;6360.522960777867;5978.1929439525975;6003.879922401527;6004.249468816398;6008.729607167688;6035.275766907911;5417.702026388586;5463.071277882769;5562.9443705563235;5572.498475137176;5596.608237423423;6537.346265373425;6578.407943285128;6593.736250549924;6604.933950660432;6624.503162262849;6322.557420980487;6330.3669633036325;6358.586691865014;6360.236771587439;6368.140301059298;6321.778737744157;6426.29310775805;6528.281888992302;6581.901271480836;6612.603243805194;6199.442050215503;6233.532485063729;6245.156903818108;6248.4376406123665;6251.499880009679;6105.84735381619;6145.709401025834;6319.020551814292;6330.556861019684;6332.145160403286;6146.692665366624;6188.566800323934;6200;6232.626042437287;6232.8130156093575;5252.422233554383;6076;6226.62640241587;6232.5637205397;6251.4061164189325;5528.308961379827;5671.43285671446;6125.663088529996;6166.136740856194;6194.566380353332;6149.2620885494025;6190.907136393048;6213.720382582885;6223.3465486125115;6235.719392627252;6086.60437071589;6144.846378840386;6188.44304012641;6207.317195108625;6238.875222495615 15:49:40 INFO - PROCESS | 1664 | |1;ext.html;537.4491146574758;552.8673118451572;577.5639392258845;588.6750579506396;619.2568917299236;39514.40728389073;40223;40767.59232407675;40815.795921020355;40823;42730.35904461436;44153.55846441535;44250.55749442505;44363.33454998169;44564.331535027;9412.435253884772;9654.469004204766;9681.661141860037;9684.75788105296;9686.854697179548;9511.952440237823;9646.37298575594;9658.517074146264;9665.840099188108;9679.27405444591;8137.633806478722;9603.567839447242;9615.182709469704;9683.322167448281;9709.757256068584;9584.329096963216;9628.759281017996;9656.855147172799;9695.224382049419;9749.658761943298;8785.901762279715;9054.411463254868;9466.29002824791;9519.333646644738;9656.489312778658;33338.499922501185;40447.7977610113;40940;41113.97215069617;41138.9715257118;3223.838808059588;3272.7381809455187;3338.7996720196866;3363.445031569788;3396.915077123067;28728.42543149146;29053.709462905525;29074.982375616753;29325.85337073301;29496.70503294961;2764.405652784641;2798.6221860048836;2813.9859300703365;2825.816321939067;2826.194534557662;378130.10934945423;378979;381856;382838;383907.9999999972;31771.72913083451;34849.47725784115;61998;69250.9612355815;69757.65121174463;32582.348353032805;35514.28971420557;35937;36915.261694766494;37287.81356093202;15195.088294702133;15489.767653485207;15602.219889005504;15837.841621583637;15837.920810395874;13102.868971310356;13162.605121846169;13184.60446186616;13194;13229.3385330735;8431.241188292961;8527.744167674855;8545.957270213608;8552.401331906707;8633.22300992901;3451.551298331251;3499.61504234534;3504.3867323218333;3516.929661406809;3533.1873669056263;1007.4056306779036;1010.6412223660642;1019.4291196929743;1037.6368271105052;1079.8596182496226;29391.706082939323;30894;30979;31257.062288131394;31372.843135784176;1109.8002359575194;1132.937688427128;1135.2961164078324;1150.4880328254014;1155.179822326141;13321.467141314433;15607.921960390126;16060.678786424207;16262.837371626369;16624.085675287733;693885;695571;702741;703082.4845875738;705386;64219.357806421336;64880.67559662172;64893.67553162204;65142;65142.34857651363;1496.865282124614;1503.804505414311;1523.8552337527917;1534.4629379717232;1543.53693891832;30504.389912202074;30804.22989425282;30927.381452370377;31265.8436707815;31329.90345337902;50953.23570146525;51229.74385128199;51306.23040654468;56928.71535642295;57178.14232786595;496267;499062;501941.4902925462;619222.9038854777;621531;28238.85880570584;37265.882023539336;37305.88082357534;37870.81064594659;40434.595654043085;43852.780736096116;44984.550154498036;45157.77421112874;45921.77039114783;46575.76712116418;36486;37529.81235093807;38684.41973370346;39184;39857.20285594214;32948.34103317872;38451.42322865103;38849;39486.40770388389;39523;30444.543331849596;36829;36940.6305936948;37166.25667486689;37496.87509374723;32266.516002260454;32728;33392;33579.49630755492;33663.326733465685;24481.510369792148;24846.627300590866;24986.62520062237;25269.494610108064;25293.87353063223 15:49:40 INFO - PROCESS | 1664 | |2;jquery.html;405.5376870367779;407.9592040795921;408.66693644679583;411.23099803367705;415.6633127166995;19865.20539178433;20544.486387840294;20594.734579196276;20640.48398790031;21733.23933662322;12065.095117866173;12271.63185104446;12583.622491325252;12592.81110783339;12620.305883176421;5262.526372626463;5355.598330125256;5435.42927992561;5450.591205659572;5454.781808727646;5097.3373461449955;5766.711664416784;6092.147099406065;6134.846628834271;6143.662098584576;4411.823527058914;5936.139259807325;6094.299155597097;6105.877882442349;6114.480269177103;5039.823606173766;5244.108501554726;5248.0815857225025;5265.3681558212875;5296.020236256267;5029.949700502984;5184.922226166611;5189.42916279209;5232.607554433433;5430.8913821723345;10199.847002294971;11891.345975971319;14007.64980875476;14126.364313605913;15664.21678916061;2202.977970220293;2291.8166546676225;2352.5294941011866;2381.904723811063;2484.763947424992;10085;10333.896661033368;10486.52810623516;10499.79000419995;10503.474826258656;1531.9617009574738;1664.3758590528553;1763.938262160818;1772.7518147459366;1804.8646351523557;117358.41320793341;119652.40173799162;126846.36576817057;126894.73105268921;127336;11991.64025079249;12029.518819247307;12049.87950120505;12168.69578260533;12233.45229883324;15472.690546189013;15731.370745170065;15741.291641876143;15742.842571574367;15784.973976691708;9076.591553380114;9087.863682044775;9130.908690913006;9138.223251023604;9296.209822165192;9051.547422628842;9074.047225041275;9145.176934075833;9225.630974761069;9243.722688319362;4724.244120940633;4816.518348165156;4826.855194344175;4879.487653796371;4884.096442158141;1760.9471715848545;2027.7465316835396;2035.6742921132548;2047.2220556188554;2050.0672194151625;869.3827382558454;934.4907025671025;952.6662672258755;961.7980224153006;1085.7014321061772;14938.477153299582;18553;20792.48018799512;20997;21406.822624755478;943.429225318686;949.1173208915775;950.9667161649309;965.1940629574244;1033.9844902326472;7490.812729681693;11011.119110471143;11011.83482247767;11013.229073964905;11028.393438360807;639889;642203;642930;652169.7391513012;655228;105228.94771052191;138391;139243;148098;155276.2236188812;1050.0969166516636;1081.5673730507704;1107.5846557540922;1117.8826223246604;1122.6126986189915;18019;18295.26818927228;18318.17568209407;18829.34097306588;19603.31388401399;54189.45810542002;54591.18113228384;54776.452235478726;54953;55376.892462151336;68954;75357.24642753502;79564;79902;82942.17057829344;7994.5602991836;8117.431779775595;8120.02559692817;8410.327173825957;8463.815065890658;9100.31747618918;11798;12413.75172496563;12732.854043136162;12817.487300507875;6459.644719540471;11084.390358530036;11417.600383986854;11527.365994870363;11642;8815.911840881765;9220.677276295297;10897.891021089688;11062.115030797679;11309.038731707897;9093.590788414405;9838.803223935623;11460;11602.941985290357;11932.343721095423;8372.581370931308;8425.620847061775;8446.028706698808;8528.104549022264;8762.868556971524;11148.944255278671;11508.712282192677;11633.767324653629;11821.349825759666;11883.287002779862 15:49:40 INFO - PROCESS | 1664 | |3;mootools.html;1630.5108467459763;1671.2061770658938;1677.8691062350815;1698.3631138323128;1898.392514395394;8188.1402452742495;8751.212390884819;8782.077881822397;9139.99999999999;9159.496227707481;8724.258438032759;8778.736637900858;8855.247303979153;8870.733877983655;8897.91102088979;2445.841020333677;2485.0805202075167;2487.7636624520733;2491.277529516438;2493.987530062347;2437.9756202438016;2487.589547724632;2489.22833921484;2489.601663733803;2500.862452565108;2446.571849926265;2452.141750387368;2458.6477437409444;2465.0263146057296;2491.426971796488;2437.9024839006506;2438;2446.951060978788;2463.7659422354845;2464.963025554618;2433.0875921529428;2437.1348171399077;2448.4735781807;2465.938351541208;2481.1192026830577;8957.283417326598;9008.684696035672;9028.50343231122;9034.09659034098;9043.728688139367;5326.014687282865;5465.07093794052;5530.695811730353;5537.556995440355;5560.36055853578;3589.587197472298;3617.222297206113;3634.818259087035;3706.146926536732;3725.62743725628;1032.7005168501125;1041.2398948767366;1051.8474821150967;1060.8249638809586;1066.6906597086836;11830.526778928832;11926.463309151022;11983.981361584276;12004.759904801855;12013.759724805455;4828.227483602606;4868.41579010521;4888.608911287089;4940.382452193476;4987.077390682735;10122.797544049225;10138.898611013943;10167.4916254187;10175.440350780778;10178.287519873538;5410.8917821643345;5415.566754659618;5482.259894914216;5514.779408823602;5537.916931246034;4492.932606010912;4901.1913034349645;4903.53416425439;5028.069807085701;5033.144365457881;3783.9972407312084;3979.4030895365927;4108.404281379183;4127.772972486542;4184.958150418457;1196.8982636475823;1307.8033599256573;1388.264219963421;1394.0938390046551;1410.6120816775265;846.3736834742247;939.7086903060073;949.8981181829046;963.0995019656643;974.2157563161722;5337.6797392156595;5751.051076572402;5906.852328691817;5942.049272116441;6018.428249316308;944.5182956691999;974.907383798538;1014.2139841622654;1017.6465301149453;1033.9534720937577;3923.941140882889;3963.2667956428154;3989.7606143631474;4025.577314382006;4051.9797401012806;10889.891101089046;11080.778384432266;11181.608643697431;11300.434978251218;11302.434878256054;11108.888911110947;11170.329780213051;11186.384748838893;11190.440477976068;11225.214235003472;7861.803454913673;8256.752297431087;8389.412741108186;8429.747107586782;8450.112738162405;5883.588148829541;6053.576249662481;6057.606255593372;6071.089336599545;6162.291336496181;9906.455144967096;10039.69880903574;10284;10331.638392656221;10340.793184136426;10119.342242754197;10367.429791361548;10418.791624167627;10423.530941107516;10605.681829544817;7012;7497.587632680037;7612.923870761221;7771.1840256774485;7776.300132988058;8495.8725619117;8517.787055323666;8525;8576;8617.784555386166;6442.8389290268115;7404.444666650131;7677.270659287359;7699.88450173259;7750.651220695195;7496.7750967471065;7543.811404714926;7652.234776522303;7662.578558179131;8040.597970101355;4849.878753031202;7044.225135235222;7045.048918395951;7054.470914681317;7078.68145933424;4947.82682606107;4970.161218491172;5051.292819005415;5070.6957582545165;5091.618128640441;4816.446108697544;4945.159322915185;4947.233178857283;5014.623903207347;5037.042961837239 15:49:40 INFO - PROCESS | 1664 | |4;prototype.html;275.401002818869;281.2518700257316;324.31731205811764;332.2408297041261;332.75376221596025;4169.228692691854;5023.799048038079;5313.628046036779;5577.386487486378;5628.746706398211;4808.471068182506;5472.589555783322;5482.424345443731;5488.060745329206;5559.388467268597;4027.8388864445387;4044.4944381952255;4136.66906647469;4249.596288352602;4301.989032577342;4250;4268.658507319407;4282.543935062078;4287.764172970486;4316.007318316789;4224.831006759741;4245.745255284693;4255.893602659928;4262.254105531535;4277.893052673692;3988.5413177484525;4233.449651545295;4236.279832428464;4240.98216428055;4258.169656916903;3688.2807852468777;3691.778493290384;3698.944515832238;3742.9438558421643;3743.082944678571;4616.699914505546;5282.996230716151;5291.100512912814;5291.3650361956425;5311.867203319909;3833.961660383416;4612.492625811158;4803.879903002418;4945.876353091202;4966;5107.744612769384;5546.500814926676;5819.214406055171;5851.531877449794;5859.7694484157955;3496.9825150874085;4097.016715988171;4104.220198162339;4109.239790638741;4162.167566486684;5358.946410535923;5769.740361683735;6019.398060193989;6118.816435506942;6182.783602573933;3744.737868349244;4137.689673274516;4173.99472379886;4201.432806571073;4216.51510076339;4275.358696195533;4278.44380230568;4290.828366865353;4293.914121717548;4303.870883873489;4454.621357184676;4656.743879086615;4750.007495278021;4917.532834380728;4977.203647416396;4518.706284091523;4925.852224433201;5724.225490098083;5892.528597712173;5949.8215053548465;1309.8100775387516;1542.460138951358;1548.9690206196037;1550.1086875046892;1550.6511035017031;971.4705485510414;1023.6621914768109;1033.1682995188944;1053;1055.2402270365324;4755.714657120584;5207.479252074838;5226.797836497627;5835.474807267281;5902.376846367283;5256.237845512379;5963.821085367446;6373.936260637427;6492.870142597121;6509.772157974448;5723.306888351512;5756.5682573807135;5870.090136028923;5872.530197584183;5903.2325797646035;3258.4134855726197;3374.6962773350515;3425.520427140202;3433.639467855889;3445.586529616412;6443.7744678936015;6450.8064758057335;6749.493787965922;6760.391564759097;6762.76330328446;5896.87959287734;6624.8012559623285;6670.232923213893;6696.330366963362;6715.48901497169;6075.908861367083;6135.478484328793;6397;6722.092517510171;6793;6020.217371741645;6515.902261465987;6517.217933847968;6520.641364724986;6535.411812936668;5997.91003134962;6225;6447.4197322241225;6495.188101487314;6504.934950650433;6642.140200743799;6704.491489414842;6716.429103526255;6727.461803055841;6740.764073257413;3931.1744533648252;4438.467383913951;4460.754658493814;4462.7768611568645;4468.508464068885;6166.440927015238;6482.124913136855;6489.707963141765;6507.186601674791;6511.990641450393;4967.453580106113;5121.513456221653;5295.973520132375;6219.61517130431;6281.685915704288;6059;6236.877362074689;6248.4376406123665;6277.780277690259;6335.461485773761;5291.60312976536;5365.731713414236;5693;6107.816765497042;6140.293866205265;5860.739940912724;5891.49922256613;5896.970515147397;5941.940580594139;6017.548683848817;5060.595152387874;5440.945590544202;5735.311762588349;5749.626274292157;5788.652680839163 15:49:40 INFO - PROCESS | 1664 | |5;yui.html;667.8731041102193;687.9896801547976;706.9787906362811;717.7684825384248;781.3670926549494;11848.466818993145;14555.636109097251;14777.817774578034;14879.47921822735;14881.702365952675;13824.93087534561;14048.508302209424;14086.366113524891;14087.436502539911;14095.083819551723;732.904722386089;909.8862642169729;922.5756152169993;937.7983733497297;939.7180845746283;789.6328207383566;945.1729736480579;953.7329547726648;953.9618415263382;958.2717134977432;971.3394891473795;985.7486340983039;997.6807421625083;1001.62439085343;1004.2919741582167;980.4950450517989;982.3810999070611;986.9013098690145;989.7426669066059;991.4893829677721;735.268407934109;737.8376757113426;739.1499775258442;744.8249661329584;747.2415498269206;14819.77770333446;14887.776683349759;14925.179115148663;14946;15005.324760385809;12491.188072775241;12621.810672839914;12664.05019623532;12751.936240318739;12917.935410322982;10005;10036.749081272954;10125.594976200942;10206.132478739317;10270.537825797783;3481.8433170507133;3529.523514325559;3542.326957877995;3587.3542762302777;3742.4947632069593;84991.57504212501;85559.14440855636;87671.56164219201;87951.56024219966;88153.67769483462;9703.369280996712;9720.319577629498;9807.607695692092;9843.704688859203;9985.500724963722;22021.449463763212;22072.558548829256;22113.778862211173;22265.44336391603;22484.98817553214;6303.590266632654;7229.530080544748;7487.101547814297;7518.699252029967;7545.547267163988;3965.9603403966166;3990.1221731219084;4062.370332598393;4080.8571699990357;4124.154548317588;3576.7138628909624;3603.9639603603628;3665.065408320861;3668.5047518585184;3673.338799016149;642.9646369449632;667.1160712056447;668.7191379620571;702.220535205921;713.935745782882;522.9660072095302;540.5513423858188;580.6980370207468;603.1676286724293;614.3978900677362;6808.659567021629;7076.4692648051605;8088.514689118671;8245.299149572353;8357.331413487029;581.8469683101138;596.8895754285471;623.8190924631897;624.7500999600196;635.6313338263795;3811.6378944000276;3957.3866050762176;3987.2224916141654;4004.0790618157985;4117.938230926478;94210;97548.51225743968;103134;105441.41837872438;108609.45695271473;83768;85692;85839.5708021456;86457;86528;76326;77467;78033.60983195048;78543;81580.368392633;7600.46796724224;7632.8855067175145;7731.110922244014;7888.921110789048;8109.797255068671;28393.574096388158;48124.518754813405;48481.27278090902;49722.25972090959;50333.24500132428;60669.69665151646;63090.36909630845;64541;67575.3242467569;68655;11113;11130.554777808797;11212.83180752273;11317.038051765692;11651.88348116508;10489.265751397328;11490.563679540057;12018.158728889244;12072.879271207175;12215.38923053826;8512.787180320294;11213.66359009231;11433.885661143282;11445;11445.141614378788;10971.01260886524;11181.440927953732;11317.981381675689;11407.372594507382;11571.132165087483;11025.283356581796;11061.281016733887;11257.21199516026;11390.088792896715;11452.770944580896;9305.067724545257;9483.336166468282;9612.231021518122;9682.70951871445;9861.211103111877;8678.132186781271;8774.561271936505;9499.905000949902;9641.951790241004;9642.276829237695 15:49:40 INFO - PROCESS | 1664 | __end_tp_report 15:49:40 INFO - PROCESS | 1664 | __start_cc_report 15:49:40 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,415 15:49:40 INFO - PROCESS | 1664 | __end_cc_report 15:49:40 INFO - PROCESS | 1664 | __startTimestamp1462402180702__endTimestamp 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | ------- Summary: start ------- 15:49:40 INFO - PROCESS | 1664 | Number of tests: 6 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5829.34 Median:6086.60 stddev:655.90 (10.8%) stddev-sans-first:630.12 15:49:40 INFO - PROCESS | 1664 | Values: 3421.5 3507.1 3511.7 3560.0 3583.7 5911.1 5934.3 5944.3 5959.8 5993.8 5907.1 5923.1 5941.4 5944.0 5969.8 5294.9 5313.5 5382.7 5411.5 5437.3 5277.3 5345.8 5420.0 5490.9 5500.8 4170.8 5367.5 5411.1 5472.7 5486.6 4308.2 4441.6 5399.0 5407.7 5410.5 5172.7 5257.3 5326.7 5337.2 5370.1 4926.6 5724.6 5732.4 5845.9 5908.6 6179.3 6211.7 6214.9 6215.3 6274.6 6152.8 6208.2 6299.5 6308.6 6321.8 6022.2 6038.5 6041.9 6057.2 6099.6 5340.4 5403.4 6449.1 6461.7 6497.4 6089.1 6138.4 6168.7 6208.5 6209.2 5688.7 6070.4 6277.5 6294.5 6299.8 6143.2 6199.2 6224.6 6261.2 6286.2 6120.9 6214.4 6273.7 6298.2 6314.6 4969.9 5033.9 5043.8 5051.7 5051.9 4552.7 4567.3 4575.2 4578.4 4581.6 5837.9 5871.2 5904.7 5909.3 5912.4 6306.9 6311.6 6331.6 6336.2 6360.5 5978.2 6003.9 6004.2 6008.7 6035.3 5417.7 5463.1 5562.9 5572.5 5596.6 6537.3 6578.4 6593.7 6604.9 6624.5 6322.6 6330.4 6358.6 6360.2 6368.1 6321.8 6426.3 6528.3 6581.9 6612.6 6199.4 6233.5 6245.2 6248.4 6251.5 6105.8 6145.7 6319.0 6330.6 6332.1 6146.7 6188.6 6200.0 6232.6 6232.8 5252.4 6076.0 6226.6 6232.6 6251.4 5528.3 5671.4 6125.7 6166.1 6194.6 6149.3 6190.9 6213.7 6223.3 6235.7 6086.6 6144.8 6188.4 6207.3 6238.9 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67141.13 Median:208424.91 stddev:150094.34 (72.0%) stddev-sans-first:150432.09 15:49:40 INFO - PROCESS | 1664 | Values: 537.4 552.9 577.6 588.7 619.3 39514.4 40223.0 40767.6 40815.8 40823.0 42730.4 44153.6 44250.6 44363.3 44564.3 9412.4 9654.5 9681.7 9684.8 9686.9 9512.0 9646.4 9658.5 9665.8 9679.3 8137.6 9603.6 9615.2 9683.3 9709.8 9584.3 9628.8 9656.9 9695.2 9749.7 8785.9 9054.4 9466.3 9519.3 9656.5 33338.5 40447.8 40940.0 41114.0 41139.0 3223.8 3272.7 3338.8 3363.4 3396.9 28728.4 29053.7 29075.0 29325.9 29496.7 2764.4 2798.6 2814.0 2825.8 2826.2 378130.1 378979.0 381856.0 382838.0 383908.0 31771.7 34849.5 61998.0 69251.0 69757.7 32582.3 35514.3 35937.0 36915.3 37287.8 15195.1 15489.8 15602.2 15837.8 15837.9 13102.9 13162.6 13184.6 13194.0 13229.3 8431.2 8527.7 8546.0 8552.4 8633.2 3451.6 3499.6 3504.4 3516.9 3533.2 1007.4 1010.6 1019.4 1037.6 1079.9 29391.7 30894.0 30979.0 31257.1 31372.8 1109.8 1132.9 1135.3 1150.5 1155.2 13321.5 15607.9 16060.7 16262.8 16624.1 693885.0 695571.0 702741.0 703082.5 705386.0 64219.4 64880.7 64893.7 65142.0 65142.3 1496.9 1503.8 1523.9 1534.5 1543.5 30504.4 30804.2 30927.4 31265.8 31329.9 50953.2 51229.7 51306.2 56928.7 57178.1 496267.0 499062.0 501941.5 619222.9 621531.0 28238.9 37265.9 37305.9 37870.8 40434.6 43852.8 44984.6 45157.8 45921.8 46575.8 36486.0 37529.8 38684.4 39184.0 39857.2 32948.3 38451.4 38849.0 39486.4 39523.0 30444.5 36829.0 36940.6 37166.3 37496.9 32266.5 32728.0 33392.0 33579.5 33663.3 24481.5 24846.6 24986.6 25269.5 25293.9 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:36215.26 Median:2247.40 stddev:107978.32 (4804.6%) stddev-sans-first:108247.75 15:49:40 INFO - PROCESS | 1664 | Values: 405.5 408.0 408.7 411.2 415.7 19865.2 20544.5 20594.7 20640.5 21733.2 12065.1 12271.6 12583.6 12592.8 12620.3 5262.5 5355.6 5435.4 5450.6 5454.8 5097.3 5766.7 6092.1 6134.8 6143.7 4411.8 5936.1 6094.3 6105.9 6114.5 5039.8 5244.1 5248.1 5265.4 5296.0 5029.9 5184.9 5189.4 5232.6 5430.9 10199.8 11891.3 14007.6 14126.4 15664.2 2203.0 2291.8 2352.5 2381.9 2484.8 10085.0 10333.9 10486.5 10499.8 10503.5 1532.0 1664.4 1763.9 1772.8 1804.9 117358.4 119652.4 126846.4 126894.7 127336.0 11991.6 12029.5 12049.9 12168.7 12233.5 15472.7 15731.4 15741.3 15742.8 15785.0 9076.6 9087.9 9130.9 9138.2 9296.2 9051.5 9074.0 9145.2 9225.6 9243.7 4724.2 4816.5 4826.9 4879.5 4884.1 1760.9 2027.7 2035.7 2047.2 2050.1 869.4 934.5 952.7 961.8 1085.7 14938.5 18553.0 20792.5 20997.0 21406.8 943.4 949.1 951.0 965.2 1034.0 7490.8 11011.1 11011.8 11013.2 11028.4 639889.0 642203.0 642930.0 652169.7 655228.0 105228.9 138391.0 139243.0 148098.0 155276.2 1050.1 1081.6 1107.6 1117.9 1122.6 18019.0 18295.3 18318.2 18829.3 19603.3 54189.5 54591.2 54776.5 54953.0 55376.9 68954.0 75357.2 79564.0 79902.0 82942.2 7994.6 8117.4 8120.0 8410.3 8463.8 9100.3 11798.0 12413.8 12732.9 12817.5 6459.6 11084.4 11417.6 11527.4 11642.0 8815.9 9220.7 10897.9 11062.1 11309.0 9093.6 9838.8 11460.0 11602.9 11932.3 8372.6 8425.6 8446.0 8528.1 8762.9 11148.9 11508.7 11633.8 11821.3 11883.3 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5841.32 Median:4859.15 stddev:3274.02 (67.4%) stddev-sans-first:3267.91 15:49:40 INFO - PROCESS | 1664 | Values: 1630.5 1671.2 1677.9 1698.4 1898.4 8188.1 8751.2 8782.1 9140.0 9159.5 8724.3 8778.7 8855.2 8870.7 8897.9 2445.8 2485.1 2487.8 2491.3 2494.0 2438.0 2487.6 2489.2 2489.6 2500.9 2446.6 2452.1 2458.6 2465.0 2491.4 2437.9 2438.0 2447.0 2463.8 2465.0 2433.1 2437.1 2448.5 2465.9 2481.1 8957.3 9008.7 9028.5 9034.1 9043.7 5326.0 5465.1 5530.7 5537.6 5560.4 3589.6 3617.2 3634.8 3706.1 3725.6 1032.7 1041.2 1051.8 1060.8 1066.7 11830.5 11926.5 11984.0 12004.8 12013.8 4828.2 4868.4 4888.6 4940.4 4987.1 10122.8 10138.9 10167.5 10175.4 10178.3 5410.9 5415.6 5482.3 5514.8 5537.9 4492.9 4901.2 4903.5 5028.1 5033.1 3784.0 3979.4 4108.4 4127.8 4185.0 1196.9 1307.8 1388.3 1394.1 1410.6 846.4 939.7 949.9 963.1 974.2 5337.7 5751.1 5906.9 5942.0 6018.4 944.5 974.9 1014.2 1017.6 1034.0 3923.9 3963.3 3989.8 4025.6 4052.0 10889.9 11080.8 11181.6 11300.4 11302.4 11108.9 11170.3 11186.4 11190.4 11225.2 7861.8 8256.8 8389.4 8429.7 8450.1 5883.6 6053.6 6057.6 6071.1 6162.3 9906.5 10039.7 10284.0 10331.6 10340.8 10119.3 10367.4 10418.8 10423.5 10605.7 7012.0 7497.6 7612.9 7771.2 7776.3 8495.9 8517.8 8525.0 8576.0 8617.8 6442.8 7404.4 7677.3 7699.9 7750.7 7496.8 7543.8 7652.2 7662.6 8040.6 4849.9 7044.2 7045.0 7054.5 7078.7 4947.8 4970.2 5051.3 5070.7 5091.6 4816.4 4945.2 4947.2 5014.6 5037.0 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4925.81 Median:5291.48 stddev:1540.73 (29.1%) stddev-sans-first:1505.00 15:49:40 INFO - PROCESS | 1664 | Values: 275.4 281.3 324.3 332.2 332.8 4169.2 5023.8 5313.6 5577.4 5628.7 4808.5 5472.6 5482.4 5488.1 5559.4 4027.8 4044.5 4136.7 4249.6 4302.0 4250.0 4268.7 4282.5 4287.8 4316.0 4224.8 4245.7 4255.9 4262.3 4277.9 3988.5 4233.4 4236.3 4241.0 4258.2 3688.3 3691.8 3698.9 3742.9 3743.1 4616.7 5283.0 5291.1 5291.4 5311.9 3834.0 4612.5 4803.9 4945.9 4966.0 5107.7 5546.5 5819.2 5851.5 5859.8 3497.0 4097.0 4104.2 4109.2 4162.2 5358.9 5769.7 6019.4 6118.8 6182.8 3744.7 4137.7 4174.0 4201.4 4216.5 4275.4 4278.4 4290.8 4293.9 4303.9 4454.6 4656.7 4750.0 4917.5 4977.2 4518.7 4925.9 5724.2 5892.5 5949.8 1309.8 1542.5 1549.0 1550.1 1550.7 971.5 1023.7 1033.2 1053.0 1055.2 4755.7 5207.5 5226.8 5835.5 5902.4 5256.2 5963.8 6373.9 6492.9 6509.8 5723.3 5756.6 5870.1 5872.5 5903.2 3258.4 3374.7 3425.5 3433.6 3445.6 6443.8 6450.8 6749.5 6760.4 6762.8 5896.9 6624.8 6670.2 6696.3 6715.5 6075.9 6135.5 6397.0 6722.1 6793.0 6020.2 6515.9 6517.2 6520.6 6535.4 5997.9 6225.0 6447.4 6495.2 6504.9 6642.1 6704.5 6716.4 6727.5 6740.8 3931.2 4438.5 4460.8 4462.8 4468.5 6166.4 6482.1 6489.7 6507.2 6512.0 4967.5 5121.5 5296.0 6219.6 6281.7 6059.0 6236.9 6248.4 6277.8 6335.5 5291.6 5365.7 5693.0 6107.8 6140.3 5860.7 5891.5 5897.0 5941.9 6017.5 5060.6 5440.9 5735.3 5749.6 5788.7 15:49:40 INFO - PROCESS | 1664 | 15:49:40 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18909.82 Median:30642.05 stddev:27660.56 (90.3%) stddev-sans-first:27704.25 15:49:40 INFO - PROCESS | 1664 | Values: 667.9 688.0 707.0 717.8 781.4 11848.5 14555.6 14777.8 14879.5 14881.7 13824.9 14048.5 14086.4 14087.4 14095.1 732.9 909.9 922.6 937.8 939.7 789.6 945.2 953.7 954.0 958.3 971.3 985.7 997.7 1001.6 1004.3 980.5 982.4 986.9 989.7 991.5 735.3 737.8 739.1 744.8 747.2 14819.8 14887.8 14925.2 14946.0 15005.3 12491.2 12621.8 12664.1 12751.9 12917.9 10005.0 10036.7 10125.6 10206.1 10270.5 3481.8 3529.5 3542.3 3587.4 3742.5 84991.6 85559.1 87671.6 87951.6 88153.7 9703.4 9720.3 9807.6 9843.7 9985.5 22021.4 22072.6 22113.8 22265.4 22485.0 6303.6 7229.5 7487.1 7518.7 7545.5 3966.0 3990.1 4062.4 4080.9 4124.2 3576.7 3604.0 3665.1 3668.5 3673.3 643.0 667.1 668.7 702.2 713.9 523.0 540.6 580.7 603.2 614.4 6808.7 7076.5 8088.5 8245.3 8357.3 581.8 596.9 623.8 624.8 635.6 3811.6 3957.4 3987.2 4004.1 4117.9 94210.0 97548.5 103134.0 105441.4 108609.5 83768.0 85692.0 85839.6 86457.0 86528.0 76326.0 77467.0 78033.6 78543.0 81580.4 7600.5 7632.9 7731.1 7888.9 8109.8 28393.6 48124.5 48481.3 49722.3 50333.2 60669.7 63090.4 64541.0 67575.3 68655.0 11113.0 11130.6 11212.8 11317.0 11651.9 10489.3 11490.6 12018.2 12072.9 12215.4 8512.8 11213.7 11433.9 11445.0 11445.1 10971.0 11181.4 11318.0 11407.4 11571.1 11025.3 11061.3 11257.2 11390.1 11452.8 9305.1 9483.3 9612.2 9682.7 9861.2 8678.1 8774.6 9499.9 9642.0 9642.3 15:49:40 INFO - PROCESS | 1664 | -------- Summary: end -------- 15:49:40 INFO - PROCESS | 1664 | 15:49:41 INFO - TEST-INFO | 1664: exit 0 15:49:41 INFO - TEST-OK | dromaeo_css | took 1111408ms 15:49:41 INFO - TEST-START | kraken 15:49:41 INFO - operating with platform_type : mac_ 15:49:41 INFO - Initialising browser for kraken test... 15:49:41 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp5FhMfo/profile http://localhost:49274/getInfo.html) 15:49:44 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 15:49:44 INFO - PROCESS | 1673 | colorDepth:24 15:49:44 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 15:49:44 INFO - PROCESS | 1673 | __metrics 15:49:44 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:49:44 INFO - TEST-INFO | 1673: exit 0 15:49:44 INFO - Browser initialized. 15:49:44 INFO - Running cycle 1/1 for kraken test... 15:49:44 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmp5FhMfo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:56 INFO - PROCESS | 1676 | RSS: Main: 221700096 15:49:56 INFO - PROCESS | 1676 | 15:49:59 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:00 INFO - PROCESS | 1676 | RSS: Main: 233500672 15:50:00 INFO - PROCESS | 1676 | 15:50:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 15:50:04 INFO - PROCESS | 1676 | RSS: Main: 242479104 15:50:04 INFO - PROCESS | 1676 | 15:50:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 15:50:08 INFO - PROCESS | 1676 | RSS: Main: 246513664 15:50:08 INFO - PROCESS | 1676 | 15:50:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 15:50:10 INFO - PROCESS | 1676 | RSS: Main: 247087104 15:50:10 INFO - PROCESS | 1676 | 15:50:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:13 INFO - PROCESS | 1676 | RSS: Main: 247296000 15:50:13 INFO - PROCESS | 1676 | 15:50:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:17 INFO - PROCESS | 1676 | RSS: Main: 246906880 15:50:17 INFO - PROCESS | 1676 | 15:50:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 15:50:20 INFO - PROCESS | 1676 | RSS: Main: 247123968 15:50:20 INFO - PROCESS | 1676 | 15:50:23 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 15:50:24 INFO - PROCESS | 1676 | RSS: Main: 248512512 15:50:24 INFO - PROCESS | 1676 | 15:50:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:26 INFO - PROCESS | 1676 | RSS: Main: 248147968 15:50:26 INFO - PROCESS | 1676 | 15:50:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:29 INFO - PROCESS | 1676 | RSS: Main: 248152064 15:50:29 INFO - PROCESS | 1676 | 15:50:31 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:32 INFO - PROCESS | 1676 | RSS: Main: 248221696 15:50:32 INFO - PROCESS | 1676 | 15:50:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:50:35 INFO - PROCESS | 1676 | RSS: Main: 248238080 15:50:35 INFO - PROCESS | 1676 | 15:50:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:50:39 INFO - PROCESS | 1676 | RSS: Main: 248209408 15:50:39 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 15:50:41 INFO - PROCESS | 1676 | RSS: Main: 249638912 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | __start_tp_report 15:50:41 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 15:50:41 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 15:50:41 INFO - PROCESS | 1676 | |i|pagename|runs| 15:50:41 INFO - PROCESS | 1676 | |0;ai-astar;119;116;168;175;162;135;117;115;119;95 15:50:41 INFO - PROCESS | 1676 | |1;audio-beat-detection;136;115;177;193;156;135;120;158;156;126 15:50:41 INFO - PROCESS | 1676 | |2;audio-dft;195;200;235;276;200;199;199;198;198;195 15:50:41 INFO - PROCESS | 1676 | |3;audio-fft;74;70;80;149;59;65;80;94;148;55 15:50:41 INFO - PROCESS | 1676 | |4;audio-oscillator;78;83;132;77;80;121;161;118;78;122 15:50:41 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;85;88;85;129;99;88;101;134;105 15:50:41 INFO - PROCESS | 1676 | |6;imaging-darkroom;94;95;94;93;95;94;94;95;103;131 15:50:41 INFO - PROCESS | 1676 | |7;imaging-desaturate;144;144;94;133;147;90;111;123;92;121 15:50:41 INFO - PROCESS | 1676 | |8;json-parse-financial;60;65;65;64;74;98;100;96;61;59 15:50:41 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;67;65;65;72;73;72;66;68;69;72 15:50:41 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;82;90;104;81;84;82;83;79;80;82 15:50:41 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;138;136;166;137;137;137;136;138;193;131 15:50:41 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;168;170;211;176;179;180;233;174;232;192 15:50:41 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;91;79;68;68;69;70;68;71;86 15:50:41 INFO - PROCESS | 1676 | __end_tp_report 15:50:41 INFO - PROCESS | 1676 | __start_cc_report 15:50:41 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1110 15:50:41 INFO - PROCESS | 1676 | __end_cc_report 15:50:41 INFO - PROCESS | 1676 | __startTimestamp1462402241331__endTimestamp 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | ------- Summary: start ------- 15:50:41 INFO - PROCESS | 1676 | Number of tests: 14 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:132.10 Median:148.50 stddev:26.94 (18.1%) stddev-sans-first:28.15 15:50:41 INFO - PROCESS | 1676 | Values: 119.0 116.0 168.0 175.0 162.0 135.0 117.0 115.0 119.0 95.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:156.00 stddev:25.29 (16.2%) stddev-sans-first:26.50 15:50:41 INFO - PROCESS | 1676 | Values: 136.0 115.0 177.0 193.0 156.0 135.0 120.0 158.0 156.0 126.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:209.50 Median:199.50 stddev:26.16 (13.1%) stddev-sans-first:27.21 15:50:41 INFO - PROCESS | 1676 | Values: 195.0 200.0 235.0 276.0 200.0 199.0 199.0 198.0 198.0 195.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:87.40 Median:72.00 stddev:34.09 (47.4%) stddev-sans-first:35.81 15:50:41 INFO - PROCESS | 1676 | Values: 74.0 70.0 80.0 149.0 59.0 65.0 80.0 94.0 148.0 55.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:105.00 Median:77.50 stddev:29.68 (38.3%) stddev-sans-first:29.83 15:50:41 INFO - PROCESS | 1676 | Values: 78.0 83.0 132.0 77.0 80.0 121.0 161.0 118.0 78.0 122.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:100.10 Median:86.00 stddev:18.05 (21.0%) stddev-sans-first:18.51 15:50:41 INFO - PROCESS | 1676 | Values: 87.0 85.0 88.0 85.0 129.0 99.0 88.0 101.0 134.0 105.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:94.00 stddev:11.66 (12.4%) stddev-sans-first:12.24 15:50:41 INFO - PROCESS | 1676 | Values: 94.0 95.0 94.0 93.0 95.0 94.0 94.0 95.0 103.0 131.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:119.90 Median:145.50 stddev:22.36 (15.4%) stddev-sans-first:21.95 15:50:41 INFO - PROCESS | 1676 | Values: 144.0 144.0 94.0 133.0 147.0 90.0 111.0 123.0 92.0 121.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:74.20 Median:65.00 stddev:16.96 (26.1%) stddev-sans-first:17.19 15:50:41 INFO - PROCESS | 1676 | Values: 60.0 65.0 65.0 64.0 74.0 98.0 100.0 96.0 61.0 59.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.50 stddev:3.14 (4.5%) stddev-sans-first:3.26 15:50:41 INFO - PROCESS | 1676 | Values: 67.0 65.0 65.0 72.0 73.0 72.0 66.0 68.0 69.0 72.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:82.00 stddev:7.41 (9.0%) stddev-sans-first:7.79 15:50:41 INFO - PROCESS | 1676 | Values: 82.0 90.0 104.0 81.0 84.0 82.0 83.0 79.0 80.0 82.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:137.50 stddev:19.42 (14.1%) stddev-sans-first:20.43 15:50:41 INFO - PROCESS | 1676 | Values: 138.0 136.0 166.0 137.0 137.0 137.0 136.0 138.0 193.0 131.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.50 Median:186.00 stddev:24.93 (13.4%) stddev-sans-first:24.95 15:50:41 INFO - PROCESS | 1676 | Values: 168.0 170.0 211.0 176.0 179.0 180.0 233.0 174.0 232.0 192.0 15:50:41 INFO - PROCESS | 1676 | 15:50:41 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:70.50 stddev:8.59 (12.2%) stddev-sans-first:8.76 15:50:41 INFO - PROCESS | 1676 | Values: 67.0 91.0 79.0 68.0 68.0 69.0 70.0 68.0 71.0 86.0 15:50:41 INFO - PROCESS | 1676 | -------- Summary: end -------- 15:50:41 INFO - PROCESS | 1676 | 15:50:42 INFO - TEST-INFO | 1676: exit 0 15:50:42 INFO - TEST-OK | kraken | took 60725ms 15:50:42 INFO - SUITE-END | took 1172s 15:50:42 INFO - Completed test suite (00:19:32) 15:50:42 INFO - javascript benchmark 15:50:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.4867769834523, 3507.0530956641705, 3511.7015053720415, 3560.0, 3583.7133029357656, 5911.142884281782, 5934.258217722785, 5944.256967879015, 5959.761609535619, 5993.760249590022, 5907.143464197699, 5923.111533270012, 5941.376155503674, 5943.970280148604, 5969.761209551623, 5294.867628309295, 5313.495217954288, 5382.703951282678, 5411.540019098381, 5437.265969094181, 5277.340332458442, 5345.7594408251725, 5419.997300499401, 5490.91763623544, 5500.779968801243, 4170.791460426982, 5367.516923476886, 5411.134218525037, 5472.699001554913, 5486.561075113981, 4308.224519586473, 4441.578050085269, 5398.973005134989, 5407.729613519308, 5410.5401040911565, 5172.663776854492, 5257.316548848626, 5326.733663316857, 5337.199420087018, 5370.113931201347, 4926.581240594518, 5724.627899186539, 5732.369439361667, 5845.860057288831, 5908.556858235649, 6179.3202747697715, 6211.720472578745, 6214.937850621481, 6215.347388524185, 6274.592151510137, 6152.784652537184, 6208.161898143783, 6299.464545513637, 6308.558400911938, 6321.778737744203, 6022.156898034279, 6038.516918646497, 6041.909371359433, 6057.151998720183, 6099.573029887865, 5340.385855626652, 5403.378611459654, 6449.129367535323, 6461.709223084972, 6497.415232629086, 6089.117078023662, 6138.38839838597, 6168.660723660242, 6208.4722798561725, 6209.192804935329, 5688.748475335419, 6070.362611925772, 6277.52918531112, 6294.527910406738, 6299.779507717208, 6143.232095988002, 6199.16311297969, 6224.595401298901, 6261.154744109578, 6286.245650521967, 6120.938790612126, 6214.40963108504, 6273.749050038039, 6298.212723409574, 6314.589551679126, 4969.875753106129, 5033.874153146201, 5043.773030213649, 5051.6716413433005, 5051.898962020812, 4552.658550608718, 4567.2920697291975, 4575.22221222393, 4578.35902973584, 4581.610563102107, 5837.941620583825, 5871.207387002785, 5904.7342869570975, 5909.349971503131, 5912.379200183918, 6306.873862522724, 6311.589746666452, 6331.620102793847, 6336.20797400325, 6360.522960777867, 5978.1929439525975, 6003.879922401527, 6004.249468816398, 6008.729607167688, 6035.275766907911, 5417.702026388586, 5463.071277882769, 5562.9443705563235, 5572.498475137176, 5596.608237423423, 6537.346265373425, 6578.407943285128, 6593.736250549924, 6604.933950660432, 6624.503162262849, 6322.557420980487, 6330.3669633036325, 6358.586691865014, 6360.236771587439, 6368.140301059298, 6321.778737744157, 6426.29310775805, 6528.281888992302, 6581.901271480836, 6612.603243805194, 6199.442050215503, 6233.532485063729, 6245.156903818108, 6248.4376406123665, 6251.499880009679, 6105.84735381619, 6145.709401025834, 6319.020551814292, 6330.556861019684, 6332.145160403286, 6146.692665366624, 6188.566800323934, 6200.0, 6232.626042437287, 6232.8130156093575, 5252.422233554383, 6076.0, 6226.62640241587, 6232.5637205397, 6251.4061164189325, 5528.308961379827, 5671.43285671446, 6125.663088529996, 6166.136740856194, 6194.566380353332, 6149.2620885494025, 6190.907136393048, 6213.720382582885, 6223.3465486125115, 6235.719392627252, 6086.60437071589, 6144.846378840386, 6188.44304012641, 6207.317195108625, 6238.875222495615], "value": 5790.030766224677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.4491146574758, 552.8673118451572, 577.5639392258845, 588.6750579506396, 619.2568917299236, 39514.40728389073, 40223.0, 40767.59232407675, 40815.795921020355, 40823.0, 42730.35904461436, 44153.55846441535, 44250.55749442505, 44363.33454998169, 44564.331535027, 9412.435253884772, 9654.469004204766, 9681.661141860037, 9684.75788105296, 9686.854697179548, 9511.952440237823, 9646.37298575594, 9658.517074146264, 9665.840099188108, 9679.27405444591, 8137.633806478722, 9603.567839447242, 9615.182709469704, 9683.322167448281, 9709.757256068584, 9584.329096963216, 9628.759281017996, 9656.855147172799, 9695.224382049419, 9749.658761943298, 8785.901762279715, 9054.411463254868, 9466.29002824791, 9519.333646644738, 9656.489312778658, 33338.499922501185, 40447.7977610113, 40940.0, 41113.97215069617, 41138.9715257118, 3223.838808059588, 3272.7381809455187, 3338.7996720196866, 3363.445031569788, 3396.915077123067, 28728.42543149146, 29053.709462905525, 29074.982375616753, 29325.85337073301, 29496.70503294961, 2764.405652784641, 2798.6221860048836, 2813.9859300703365, 2825.816321939067, 2826.194534557662, 378130.10934945423, 378979.0, 381856.0, 382838.0, 383907.9999999972, 31771.72913083451, 34849.47725784115, 61998.0, 69250.9612355815, 69757.65121174463, 32582.348353032805, 35514.28971420557, 35937.0, 36915.261694766494, 37287.81356093202, 15195.088294702133, 15489.767653485207, 15602.219889005504, 15837.841621583637, 15837.920810395874, 13102.868971310356, 13162.605121846169, 13184.60446186616, 13194.0, 13229.3385330735, 8431.241188292961, 8527.744167674855, 8545.957270213608, 8552.401331906707, 8633.22300992901, 3451.551298331251, 3499.61504234534, 3504.3867323218333, 3516.929661406809, 3533.1873669056263, 1007.4056306779036, 1010.6412223660642, 1019.4291196929743, 1037.6368271105052, 1079.8596182496226, 29391.706082939323, 30894.0, 30979.0, 31257.062288131394, 31372.843135784176, 1109.8002359575194, 1132.937688427128, 1135.2961164078324, 1150.4880328254014, 1155.179822326141, 13321.467141314433, 15607.921960390126, 16060.678786424207, 16262.837371626369, 16624.085675287733, 693885.0, 695571.0, 702741.0, 703082.4845875738, 705386.0, 64219.357806421336, 64880.67559662172, 64893.67553162204, 65142.0, 65142.34857651363, 1496.865282124614, 1503.804505414311, 1523.8552337527917, 1534.4629379717232, 1543.53693891832, 30504.389912202074, 30804.22989425282, 30927.381452370377, 31265.8436707815, 31329.90345337902, 50953.23570146525, 51229.74385128199, 51306.23040654468, 56928.71535642295, 57178.14232786595, 496267.0, 499062.0, 501941.4902925462, 619222.9038854777, 621531.0, 28238.85880570584, 37265.882023539336, 37305.88082357534, 37870.81064594659, 40434.595654043085, 43852.780736096116, 44984.550154498036, 45157.77421112874, 45921.77039114783, 46575.76712116418, 36486.0, 37529.81235093807, 38684.41973370346, 39184.0, 39857.20285594214, 32948.34103317872, 38451.42322865103, 38849.0, 39486.40770388389, 39523.0, 30444.543331849596, 36829.0, 36940.6305936948, 37166.25667486689, 37496.87509374723, 32266.516002260454, 32728.0, 33392.0, 33579.49630755492, 33663.326733465685, 24481.510369792148, 24846.627300590866, 24986.62520062237, 25269.494610108064, 25293.87353063223], "value": 18929.499028612532, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.5376870367779, 407.9592040795921, 408.66693644679583, 411.23099803367705, 415.6633127166995, 19865.20539178433, 20544.486387840294, 20594.734579196276, 20640.48398790031, 21733.23933662322, 12065.095117866173, 12271.63185104446, 12583.622491325252, 12592.81110783339, 12620.305883176421, 5262.526372626463, 5355.598330125256, 5435.42927992561, 5450.591205659572, 5454.781808727646, 5097.3373461449955, 5766.711664416784, 6092.147099406065, 6134.846628834271, 6143.662098584576, 4411.823527058914, 5936.139259807325, 6094.299155597097, 6105.877882442349, 6114.480269177103, 5039.823606173766, 5244.108501554726, 5248.0815857225025, 5265.3681558212875, 5296.020236256267, 5029.949700502984, 5184.922226166611, 5189.42916279209, 5232.607554433433, 5430.8913821723345, 10199.847002294971, 11891.345975971319, 14007.64980875476, 14126.364313605913, 15664.21678916061, 2202.977970220293, 2291.8166546676225, 2352.5294941011866, 2381.904723811063, 2484.763947424992, 10085.0, 10333.896661033368, 10486.52810623516, 10499.79000419995, 10503.474826258656, 1531.9617009574738, 1664.3758590528553, 1763.938262160818, 1772.7518147459366, 1804.8646351523557, 117358.41320793341, 119652.40173799162, 126846.36576817057, 126894.73105268921, 127336.0, 11991.64025079249, 12029.518819247307, 12049.87950120505, 12168.69578260533, 12233.45229883324, 15472.690546189013, 15731.370745170065, 15741.291641876143, 15742.842571574367, 15784.973976691708, 9076.591553380114, 9087.863682044775, 9130.908690913006, 9138.223251023604, 9296.209822165192, 9051.547422628842, 9074.047225041275, 9145.176934075833, 9225.630974761069, 9243.722688319362, 4724.244120940633, 4816.518348165156, 4826.855194344175, 4879.487653796371, 4884.096442158141, 1760.9471715848545, 2027.7465316835396, 2035.6742921132548, 2047.2220556188554, 2050.0672194151625, 869.3827382558454, 934.4907025671025, 952.6662672258755, 961.7980224153006, 1085.7014321061772, 14938.477153299582, 18553.0, 20792.48018799512, 20997.0, 21406.822624755478, 943.429225318686, 949.1173208915775, 950.9667161649309, 965.1940629574244, 1033.9844902326472, 7490.812729681693, 11011.119110471143, 11011.83482247767, 11013.229073964905, 11028.393438360807, 639889.0, 642203.0, 642930.0, 652169.7391513012, 655228.0, 105228.94771052191, 138391.0, 139243.0, 148098.0, 155276.2236188812, 1050.0969166516636, 1081.5673730507704, 1107.5846557540922, 1117.8826223246604, 1122.6126986189915, 18019.0, 18295.26818927228, 18318.17568209407, 18829.34097306588, 19603.31388401399, 54189.45810542002, 54591.18113228384, 54776.452235478726, 54953.0, 55376.892462151336, 68954.0, 75357.24642753502, 79564.0, 79902.0, 82942.17057829344, 7994.5602991836, 8117.431779775595, 8120.02559692817, 8410.327173825957, 8463.815065890658, 9100.31747618918, 11798.0, 12413.75172496563, 12732.854043136162, 12817.487300507875, 6459.644719540471, 11084.390358530036, 11417.600383986854, 11527.365994870363, 11642.0, 8815.911840881765, 9220.677276295297, 10897.891021089688, 11062.115030797679, 11309.038731707897, 9093.590788414405, 9838.803223935623, 11460.0, 11602.941985290357, 11932.343721095423, 8372.581370931308, 8425.620847061775, 8446.028706698808, 8528.104549022264, 8762.868556971524, 11148.944255278671, 11508.712282192677, 11633.767324653629, 11821.349825759666, 11883.287002779862], "value": 9335.165222138623, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1630.5108467459763, 1671.2061770658938, 1677.8691062350815, 1698.3631138323128, 1898.392514395394, 8188.1402452742495, 8751.212390884819, 8782.077881822397, 9139.99999999999, 9159.496227707481, 8724.258438032759, 8778.736637900858, 8855.247303979153, 8870.733877983655, 8897.91102088979, 2445.841020333677, 2485.0805202075167, 2487.7636624520733, 2491.277529516438, 2493.987530062347, 2437.9756202438016, 2487.589547724632, 2489.22833921484, 2489.601663733803, 2500.862452565108, 2446.571849926265, 2452.141750387368, 2458.6477437409444, 2465.0263146057296, 2491.426971796488, 2437.9024839006506, 2438.0, 2446.951060978788, 2463.7659422354845, 2464.963025554618, 2433.0875921529428, 2437.1348171399077, 2448.4735781807, 2465.938351541208, 2481.1192026830577, 8957.283417326598, 9008.684696035672, 9028.50343231122, 9034.09659034098, 9043.728688139367, 5326.014687282865, 5465.07093794052, 5530.695811730353, 5537.556995440355, 5560.36055853578, 3589.587197472298, 3617.222297206113, 3634.818259087035, 3706.146926536732, 3725.62743725628, 1032.7005168501125, 1041.2398948767366, 1051.8474821150967, 1060.8249638809586, 1066.6906597086836, 11830.526778928832, 11926.463309151022, 11983.981361584276, 12004.759904801855, 12013.759724805455, 4828.227483602606, 4868.41579010521, 4888.608911287089, 4940.382452193476, 4987.077390682735, 10122.797544049225, 10138.898611013943, 10167.4916254187, 10175.440350780778, 10178.287519873538, 5410.8917821643345, 5415.566754659618, 5482.259894914216, 5514.779408823602, 5537.916931246034, 4492.932606010912, 4901.1913034349645, 4903.53416425439, 5028.069807085701, 5033.144365457881, 3783.9972407312084, 3979.4030895365927, 4108.404281379183, 4127.772972486542, 4184.958150418457, 1196.8982636475823, 1307.8033599256573, 1388.264219963421, 1394.0938390046551, 1410.6120816775265, 846.3736834742247, 939.7086903060073, 949.8981181829046, 963.0995019656643, 974.2157563161722, 5337.6797392156595, 5751.051076572402, 5906.852328691817, 5942.049272116441, 6018.428249316308, 944.5182956691999, 974.907383798538, 1014.2139841622654, 1017.6465301149453, 1033.9534720937577, 3923.941140882889, 3963.2667956428154, 3989.7606143631474, 4025.577314382006, 4051.9797401012806, 10889.891101089046, 11080.778384432266, 11181.608643697431, 11300.434978251218, 11302.434878256054, 11108.888911110947, 11170.329780213051, 11186.384748838893, 11190.440477976068, 11225.214235003472, 7861.803454913673, 8256.752297431087, 8389.412741108186, 8429.747107586782, 8450.112738162405, 5883.588148829541, 6053.576249662481, 6057.606255593372, 6071.089336599545, 6162.291336496181, 9906.455144967096, 10039.69880903574, 10284.0, 10331.638392656221, 10340.793184136426, 10119.342242754197, 10367.429791361548, 10418.791624167627, 10423.530941107516, 10605.681829544817, 7012.0, 7497.587632680037, 7612.923870761221, 7771.1840256774485, 7776.300132988058, 8495.8725619117, 8517.787055323666, 8525.0, 8576.0, 8617.784555386166, 6442.8389290268115, 7404.444666650131, 7677.270659287359, 7699.88450173259, 7750.651220695195, 7496.7750967471065, 7543.811404714926, 7652.234776522303, 7662.578558179131, 8040.597970101355, 4849.878753031202, 7044.225135235222, 7045.048918395951, 7054.470914681317, 7078.68145933424, 4947.82682606107, 4970.161218491172, 5051.292819005415, 5070.6957582545165, 5091.618128640441, 4816.446108697544, 4945.159322915185, 4947.233178857283, 5014.623903207347, 5037.042961837239], "value": 4710.182345557798, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.401002818869, 281.2518700257316, 324.31731205811764, 332.2408297041261, 332.75376221596025, 4169.228692691854, 5023.799048038079, 5313.628046036779, 5577.386487486378, 5628.746706398211, 4808.471068182506, 5472.589555783322, 5482.424345443731, 5488.060745329206, 5559.388467268597, 4027.8388864445387, 4044.4944381952255, 4136.66906647469, 4249.596288352602, 4301.989032577342, 4250.0, 4268.658507319407, 4282.543935062078, 4287.764172970486, 4316.007318316789, 4224.831006759741, 4245.745255284693, 4255.893602659928, 4262.254105531535, 4277.893052673692, 3988.5413177484525, 4233.449651545295, 4236.279832428464, 4240.98216428055, 4258.169656916903, 3688.2807852468777, 3691.778493290384, 3698.944515832238, 3742.9438558421643, 3743.082944678571, 4616.699914505546, 5282.996230716151, 5291.100512912814, 5291.3650361956425, 5311.867203319909, 3833.961660383416, 4612.492625811158, 4803.879903002418, 4945.876353091202, 4966.0, 5107.744612769384, 5546.500814926676, 5819.214406055171, 5851.531877449794, 5859.7694484157955, 3496.9825150874085, 4097.016715988171, 4104.220198162339, 4109.239790638741, 4162.167566486684, 5358.946410535923, 5769.740361683735, 6019.398060193989, 6118.816435506942, 6182.783602573933, 3744.737868349244, 4137.689673274516, 4173.99472379886, 4201.432806571073, 4216.51510076339, 4275.358696195533, 4278.44380230568, 4290.828366865353, 4293.914121717548, 4303.870883873489, 4454.621357184676, 4656.743879086615, 4750.007495278021, 4917.532834380728, 4977.203647416396, 4518.706284091523, 4925.852224433201, 5724.225490098083, 5892.528597712173, 5949.8215053548465, 1309.8100775387516, 1542.460138951358, 1548.9690206196037, 1550.1086875046892, 1550.6511035017031, 971.4705485510414, 1023.6621914768109, 1033.1682995188944, 1053.0, 1055.2402270365324, 4755.714657120584, 5207.479252074838, 5226.797836497627, 5835.474807267281, 5902.376846367283, 5256.237845512379, 5963.821085367446, 6373.936260637427, 6492.870142597121, 6509.772157974448, 5723.306888351512, 5756.5682573807135, 5870.090136028923, 5872.530197584183, 5903.2325797646035, 3258.4134855726197, 3374.6962773350515, 3425.520427140202, 3433.639467855889, 3445.586529616412, 6443.7744678936015, 6450.8064758057335, 6749.493787965922, 6760.391564759097, 6762.76330328446, 5896.87959287734, 6624.8012559623285, 6670.232923213893, 6696.330366963362, 6715.48901497169, 6075.908861367083, 6135.478484328793, 6397.0, 6722.092517510171, 6793.0, 6020.217371741645, 6515.902261465987, 6517.217933847968, 6520.641364724986, 6535.411812936668, 5997.91003134962, 6225.0, 6447.4197322241225, 6495.188101487314, 6504.934950650433, 6642.140200743799, 6704.491489414842, 6716.429103526255, 6727.461803055841, 6740.764073257413, 3931.1744533648252, 4438.467383913951, 4460.754658493814, 4462.7768611568645, 4468.508464068885, 6166.440927015238, 6482.124913136855, 6489.707963141765, 6507.186601674791, 6511.990641450393, 4967.453580106113, 5121.513456221653, 5295.973520132375, 6219.61517130431, 6281.685915704288, 6059.0, 6236.877362074689, 6248.4376406123665, 6277.780277690259, 6335.461485773761, 5291.60312976536, 5365.731713414236, 5693.0, 6107.816765497042, 6140.293866205265, 5860.739940912724, 5891.49922256613, 5896.970515147397, 5941.940580594139, 6017.548683848817, 5060.595152387874, 5440.945590544202, 5735.311762588349, 5749.626274292157, 5788.652680839163], "value": 4438.621689885396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.8731041102193, 687.9896801547976, 706.9787906362811, 717.7684825384248, 781.3670926549494, 11848.466818993145, 14555.636109097251, 14777.817774578034, 14879.47921822735, 14881.702365952675, 13824.93087534561, 14048.508302209424, 14086.366113524891, 14087.436502539911, 14095.083819551723, 732.904722386089, 909.8862642169729, 922.5756152169993, 937.7983733497297, 939.7180845746283, 789.6328207383566, 945.1729736480579, 953.7329547726648, 953.9618415263382, 958.2717134977432, 971.3394891473795, 985.7486340983039, 997.6807421625083, 1001.62439085343, 1004.2919741582167, 980.4950450517989, 982.3810999070611, 986.9013098690145, 989.7426669066059, 991.4893829677721, 735.268407934109, 737.8376757113426, 739.1499775258442, 744.8249661329584, 747.2415498269206, 14819.77770333446, 14887.776683349759, 14925.179115148663, 14946.0, 15005.324760385809, 12491.188072775241, 12621.810672839914, 12664.05019623532, 12751.936240318739, 12917.935410322982, 10005.0, 10036.749081272954, 10125.594976200942, 10206.132478739317, 10270.537825797783, 3481.8433170507133, 3529.523514325559, 3542.326957877995, 3587.3542762302777, 3742.4947632069593, 84991.57504212501, 85559.14440855636, 87671.56164219201, 87951.56024219966, 88153.67769483462, 9703.369280996712, 9720.319577629498, 9807.607695692092, 9843.704688859203, 9985.500724963722, 22021.449463763212, 22072.558548829256, 22113.778862211173, 22265.44336391603, 22484.98817553214, 6303.590266632654, 7229.530080544748, 7487.101547814297, 7518.699252029967, 7545.547267163988, 3965.9603403966166, 3990.1221731219084, 4062.370332598393, 4080.8571699990357, 4124.154548317588, 3576.7138628909624, 3603.9639603603628, 3665.065408320861, 3668.5047518585184, 3673.338799016149, 642.9646369449632, 667.1160712056447, 668.7191379620571, 702.220535205921, 713.935745782882, 522.9660072095302, 540.5513423858188, 580.6980370207468, 603.1676286724293, 614.3978900677362, 6808.659567021629, 7076.4692648051605, 8088.514689118671, 8245.299149572353, 8357.331413487029, 581.8469683101138, 596.8895754285471, 623.8190924631897, 624.7500999600196, 635.6313338263795, 3811.6378944000276, 3957.3866050762176, 3987.2224916141654, 4004.0790618157985, 4117.938230926478, 94210.0, 97548.51225743968, 103134.0, 105441.41837872438, 108609.45695271473, 83768.0, 85692.0, 85839.5708021456, 86457.0, 86528.0, 76326.0, 77467.0, 78033.60983195048, 78543.0, 81580.368392633, 7600.46796724224, 7632.8855067175145, 7731.110922244014, 7888.921110789048, 8109.797255068671, 28393.574096388158, 48124.518754813405, 48481.27278090902, 49722.25972090959, 50333.24500132428, 60669.69665151646, 63090.36909630845, 64541.0, 67575.3242467569, 68655.0, 11113.0, 11130.554777808797, 11212.83180752273, 11317.038051765692, 11651.88348116508, 10489.265751397328, 11490.563679540057, 12018.158728889244, 12072.879271207175, 12215.38923053826, 8512.787180320294, 11213.66359009231, 11433.885661143282, 11445.0, 11445.141614378788, 10971.01260886524, 11181.440927953732, 11317.981381675689, 11407.372594507382, 11571.132165087483, 11025.283356581796, 11061.281016733887, 11257.21199516026, 11390.088792896715, 11452.770944580896, 9305.067724545257, 9483.336166468282, 9612.231021518122, 9682.70951871445, 9861.211103111877, 8678.132186781271, 8774.561271936505, 9499.905000949902, 9641.951790241004, 9642.276829237695], "value": 6956.963670021576, "unit": "score"}], "name": "dromaeo_css", "value": 7279.715871622729, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 168.0, 175.0, 162.0, 135.0, 117.0, 115.0, 119.0, 95.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 115.0, 177.0, 193.0, 156.0, 135.0, 120.0, 158.0, 156.0, 126.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 235.0, 276.0, 200.0, 199.0, 199.0, 198.0, 198.0, 195.0], "value": 209.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 80.0, 149.0, 59.0, 65.0, 80.0, 94.0, 148.0, 55.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 132.0, 77.0, 80.0, 121.0, 161.0, 118.0, 78.0, 122.0], "value": 105.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 129.0, 99.0, 88.0, 101.0, 134.0, 105.0], "value": 100.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 93.0, 95.0, 94.0, 94.0, 95.0, 103.0, 131.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 144.0, 94.0, 133.0, 147.0, 90.0, 111.0, 123.0, 92.0, 121.0], "value": 119.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 65.0, 64.0, 74.0, 98.0, 100.0, 96.0, 61.0, 59.0], "value": 74.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 72.0, 73.0, 72.0, 66.0, 68.0, 69.0, 72.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 104.0, 81.0, 84.0, 82.0, 83.0, 79.0, 80.0, 82.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 166.0, 137.0, 137.0, 137.0, 136.0, 138.0, 193.0, 131.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 211.0, 176.0, 179.0, 180.0, 233.0, 174.0, 232.0, 192.0], "value": 191.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 91.0, 79.0, 68.0, 68.0, 69.0, 70.0, 68.0, 71.0, 86.0], "value": 73.7, "unit": "score"}], "name": "kraken", "value": 1637.9, "alertThreshold": 2.0}]} 15:50:42 INFO - Return code: 0 15:50:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:50:42 ERROR - # TBPL SUCCESS # 15:50:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:50:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:50:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:50:42 INFO - 2016-05-04 15:50:42.627 screenresolution[1681:10392] starting screenresolution argv=screenresolution get 15:50:42 INFO - 2016-05-04 15:50:42.630 screenresolution[1681:10392] Display 0: 1600x1200x32@60 15:50:42 INFO - 2016-05-04 15:50:42.636 screenresolution[1682:10395] starting screenresolution argv=screenresolution list 15:50:42 INFO - Available Modes on Display 0 15:50:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:50:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:50:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:50:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:50:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:50:42 INFO - Intel Iris: 15:50:42 INFO - Chipset Model: Intel Iris 15:50:42 INFO - Type: GPU 15:50:42 INFO - Bus: Built-In 15:50:42 INFO - VRAM (Dynamic, Max): 1536 MB 15:50:42 INFO - Vendor: Intel (0x8086) 15:50:42 INFO - Device ID: 0x0a2e 15:50:42 INFO - Revision ID: 0x0009 15:50:42 INFO - Displays: 15:50:42 INFO - Display: 15:50:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:50:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:50:42 INFO - Display Serial Number: 1600x1200 60 15:50:42 INFO - Main Display: Yes 15:50:42 INFO - Mirror: Off 15:50:42 INFO - Online: Yes 15:50:42 INFO - Rotation: Supported 15:50:42 INFO - Return code: 0 15:50:42 INFO - Running post-action listener: _resource_record_post_action 15:50:42 INFO - Running post-run listener: _resource_record_post_run 15:50:43 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 37561856; Write bytes: 656545792; Read time: 450; Write time: 1877 15:50:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358588928; Write bytes: 346490368; Read time: 27028; Write time: 658 15:50:43 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30342656; Write bytes: 298253312; Read time: 355; Write time: 1175 15:50:43 INFO - Running post-run listener: _upload_blobber_files 15:50:43 INFO - Blob upload gear active. 15:50:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:50:43 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 15:50:43 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'] 15:50:43 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 15:50:44 INFO - (blobuploader) - INFO - Open directory for files ... 15:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:45 INFO - (blobuploader) - INFO - Done attempting. 15:50:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:50:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:50:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:46 INFO - (blobuploader) - INFO - Done attempting. 15:50:46 INFO - (blobuploader) - INFO - Iteration through files over. 15:50:46 INFO - Return code: 0 15:50:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:50:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:50:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0780c9d647bd52d7a437cdc8d679d1a957a5438e69213f4744a42d860da0e460512c3b22caebe2942ba75c9d0a23ab61442164b1711cbef7ad5ca3a09c485170", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/324fe1b9a479664d789348fe3ae0cd9d10500209803fa70f79613e11847bab4ad91e8b4bf9572779216f30179d9b6bc47e6700a8d366df8b88f2bd0c33238794"} 15:50:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:50:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:50:46 INFO - Contents: 15:50:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0780c9d647bd52d7a437cdc8d679d1a957a5438e69213f4744a42d860da0e460512c3b22caebe2942ba75c9d0a23ab61442164b1711cbef7ad5ca3a09c485170", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/324fe1b9a479664d789348fe3ae0cd9d10500209803fa70f79613e11847bab4ad91e8b4bf9572779216f30179d9b6bc47e6700a8d366df8b88f2bd0c33238794"} 15:50:46 INFO - Running post-run listener: copy_logs_to_upload_dir 15:50:46 INFO - Copying logs to upload dir... 15:50:46 INFO - mkdir: /builds/slave/test/build/upload/logs 15:50:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.251432 ========= master_lag: 3.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-04 15:50:49.842930) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-04 15:50:49.846829) ========= 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.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0780c9d647bd52d7a437cdc8d679d1a957a5438e69213f4744a42d860da0e460512c3b22caebe2942ba75c9d0a23ab61442164b1711cbef7ad5ca3a09c485170", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/324fe1b9a479664d789348fe3ae0cd9d10500209803fa70f79613e11847bab4ad91e8b4bf9572779216f30179d9b6bc47e6700a8d366df8b88f2bd0c33238794"} build_url:https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010883 build_url: 'https://queue.taskcluster.net/v1/task/LUt1g0R_SmOV3BkgicpseA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0780c9d647bd52d7a437cdc8d679d1a957a5438e69213f4744a42d860da0e460512c3b22caebe2942ba75c9d0a23ab61442164b1711cbef7ad5ca3a09c485170", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/324fe1b9a479664d789348fe3ae0cd9d10500209803fa70f79613e11847bab4ad91e8b4bf9572779216f30179d9b6bc47e6700a8d366df8b88f2bd0c33238794"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-04 15:50:50.140144) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:50:50.140477) ========= 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.y23a1NaNx9/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.2jPBDZPjvx/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005252 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-04 15:50:50.207558) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-04 15:50:50.207868) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-04 15:50:50.209093) ========= ========= Total master_lag: 4.05 =========