builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0370 starttime: 1463682350.26 results: success (0) buildid: 20160519093556 builduid: 8f0a6e4e1d5a45a1bf658c2f5a7a1d89 revision: adb760b8a144c049070c876dccbf228de868a953 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.256026) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.257600) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.257888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.310659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.310953) ========= 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.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-19 11:25:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.56M=0.001s 2016-05-19 11:25:50 (8.56 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.479787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.811029) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.811362) ========= 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.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.019736 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:50.860029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 11:25:50.863406) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev adb760b8a144c049070c876dccbf228de868a953 --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 adb760b8a144c049070c876dccbf228de868a953 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-19 11:25:50,924 truncating revision to first 12 chars 2016-05-19 11:25:50,924 Setting DEBUG logging. 2016-05-19 11:25:50,925 attempt 1/10 2016-05-19 11:25:50,925 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/adb760b8a144?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 11:25:51,890 unpacking tar archive at: mozilla-aurora-adb760b8a144/testing/mozharness/ program finished with exit code 0 elapsedTime=1.252644 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 11:25:52.134022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:52.134324) ========= script_repo_revision: adb760b8a144c049070c876dccbf228de868a953 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:52.134742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:52.135013) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 11:25:52.149267) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 30 secs) (at 2016-05-19 11:25:52.149560) ========= /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.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:25:52 INFO - MultiFileLogger online at 20160519 11:25:52 in /builds/slave/test 11:25:52 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 11:25:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:25:52 INFO - {'append_to_log': False, 11:25:52 INFO - 'base_work_dir': '/builds/slave/test', 11:25:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:25:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:25:52 INFO - 'branch': 'Mozilla-Aurora', 11:25:52 INFO - 'buildbot_json_path': 'buildprops.json', 11:25:52 INFO - 'config_files': ('talos/mac_config.py',), 11:25:52 INFO - 'default_actions': ('clobber', 11:25:52 INFO - 'read-buildbot-config', 11:25:52 INFO - 'download-and-extract', 11:25:52 INFO - 'populate-webroot', 11:25:52 INFO - 'create-virtualenv', 11:25:52 INFO - 'install', 11:25:52 INFO - 'run-tests'), 11:25:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:25:52 INFO - 'download_minidump_stackwalk': True, 11:25:52 INFO - 'download_symbols': 'ondemand', 11:25:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:25:52 INFO - 'tooltool.py': '/tools/tooltool.py', 11:25:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:25:52 INFO - '/tools/misc-python/virtualenv.py')}, 11:25:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:25:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:25:52 INFO - 'installer_path': 'installer.exe', 11:25:52 INFO - 'log_level': 'info', 11:25:52 INFO - 'log_name': 'talos', 11:25:52 INFO - 'log_to_console': True, 11:25:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:25:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:25:52 INFO - 'opt_config_files': (), 11:25:52 INFO - 'pip_index': False, 11:25:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:25:52 INFO - 'cmd': ('bash', 11:25:52 INFO - '-c', 11:25:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:25:52 INFO - 'enabled': True, 11:25:52 INFO - 'halt_on_failure': False, 11:25:52 INFO - 'name': 'check_screen_resolution'},), 11:25:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:25:52 INFO - 'cmd': ('bash', 11:25:52 INFO - '-c', 11:25:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:25:52 INFO - 'enabled': True, 11:25:52 INFO - 'halt_on_failure': False, 11:25:52 INFO - 'name': 'check_screen_resolution'},), 11:25:52 INFO - 'run_cmd_checks_enabled': True, 11:25:52 INFO - 'sps_profile': False, 11:25:52 INFO - 'sps_profile_interval': 0, 11:25:52 INFO - 'suite': 'dromaeojs-e10s', 11:25:52 INFO - 'system_bits': '32', 11:25:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:25:52 INFO - 'title': 't-yosemite-r7-0370', 11:25:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:25:52 INFO - 'use_talos_json': True, 11:25:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:25:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:25:52 INFO - 'work_dir': 'build'} 11:25:52 INFO - ##### 11:25:52 INFO - ##### Running clobber step. 11:25:52 INFO - ##### 11:25:52 INFO - Running pre-action listener: _resource_record_pre_action 11:25:52 INFO - Running main action method: clobber 11:25:52 INFO - rmtree: /builds/slave/test/build 11:25:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:25:53 INFO - Running post-action listener: _resource_record_post_action 11:25:53 INFO - ##### 11:25:53 INFO - ##### Running read-buildbot-config step. 11:25:53 INFO - ##### 11:25:53 INFO - Running pre-action listener: _resource_record_pre_action 11:25:53 INFO - Running main action method: read_buildbot_config 11:25:53 INFO - Using buildbot properties: 11:25:53 INFO - { 11:25:53 INFO - "project": "", 11:25:53 INFO - "product": "firefox", 11:25:53 INFO - "script_repo_revision": "production", 11:25:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:25:53 INFO - "repository": "", 11:25:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:25:53 INFO - "buildid": "20160519093556", 11:25:53 INFO - "pgo_build": "False", 11:25:53 INFO - "basedir": "/builds/slave/test", 11:25:53 INFO - "buildnumber": 294, 11:25:53 INFO - "slavename": "t-yosemite-r7-0370", 11:25:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:25:53 INFO - "platform": "yosemite_r7", 11:25:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:25:53 INFO - "branch": "mozilla-aurora", 11:25:53 INFO - "repo_path": "releases/mozilla-aurora", 11:25:53 INFO - "revision": "adb760b8a144c049070c876dccbf228de868a953", 11:25:53 INFO - "stage_platform": "macosx64", 11:25:53 INFO - "builduid": "8f0a6e4e1d5a45a1bf658c2f5a7a1d89", 11:25:53 INFO - "slavebuilddir": "test" 11:25:53 INFO - } 11:25:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 11:25:53 INFO - Running post-action listener: _resource_record_post_action 11:25:53 INFO - ##### 11:25:53 INFO - ##### Running download-and-extract step. 11:25:53 INFO - ##### 11:25:53 INFO - Running pre-action listener: _resource_record_pre_action 11:25:53 INFO - Running main action method: download_and_extract 11:25:53 INFO - mkdir: /builds/slave/test/build/tests 11:25:53 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')]} 11:25:53 INFO - https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:25:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:25:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:25:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:26:12 INFO - Downloaded 1199 bytes. 11:26:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 11:26:12 INFO - Using the following test package requirements: 11:26:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:26:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 11:26:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 11:26:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'jsshell-mac.zip'], 11:26:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 11:26:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:26:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 11:26:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 11:26:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 11:26:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:26:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 11:26:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:26:12 INFO - https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:26:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:26:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:26:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:14 INFO - Downloaded 18102479 bytes. 11:30:14 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'] 11:30:14 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 11:30:16 INFO - Return code: 0 11:30:16 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 11:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:16 INFO - https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:30:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:30:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:30:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:26 INFO - Downloaded 18102479 bytes. 11:30:26 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'] 11:30:26 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 11:30:30 INFO - Return code: 0 11:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:30 INFO - https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:30:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:30:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:30:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:33 INFO - Downloaded 10840145 bytes. 11:30:33 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'] 11:30:33 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 11:30:33 INFO - Return code: 0 11:30:33 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')]} 11:30:33 INFO - https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 11:30:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:30:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:30:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 11:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JOuFNvVwTWW9SO0leZlkHA/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 11:30:36 INFO - Downloaded 114096389 bytes. 11:30:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:30:36 INFO - mkdir: /builds/slave/test/properties 11:30:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:36 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:36 INFO - Contents: 11:30:36 INFO - build_url:https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:30:38 INFO - Running post-action listener: _resource_record_post_action 11:30:38 INFO - Running post-action listener: set_extra_try_arguments 11:30:38 INFO - ##### 11:30:38 INFO - ##### Running populate-webroot step. 11:30:38 INFO - ##### 11:30:38 INFO - Running pre-action listener: _resource_record_pre_action 11:30:38 INFO - Running main action method: populate_webroot 11:30:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:30:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:30:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:30:38 INFO - '--tppagecycles', 11:30:38 INFO - '7'], 11:30:38 INFO - 'tests': ['tsvgm']}}, 11:30:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:30:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:30:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['damp', 'tps']}, 11:30:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'tests': ['damp', 'tps']}, 11:30:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['dromaeo_dom']}, 11:30:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['a11yr', 11:30:38 INFO - 'ts_paint', 11:30:38 INFO - 'tpaint', 11:30:38 INFO - 'sessionrestore', 11:30:38 INFO - 'sessionrestore_no_auto_restore', 11:30:38 INFO - 'tabpaint']}, 11:30:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['tsvgx', 11:30:38 INFO - 'tsvgr_opacity', 11:30:38 INFO - 'tart', 11:30:38 INFO - 'tscrollx', 11:30:38 INFO - 'cart']}, 11:30:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:38 INFO - 'tsvgr_opacity', 11:30:38 INFO - 'tart', 11:30:38 INFO - 'tscrollx', 11:30:38 INFO - 'cart']}, 11:30:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'talos_options': ['--disable-e10s'], 11:30:38 INFO - 'tests': ['tp5o']}, 11:30:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'tests': ['tp5o']}, 11:30:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'talos_options': ['--disable-e10s', 11:30:38 INFO - '--xperf_path', 11:30:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:38 INFO - 'tests': ['tp5n']}, 11:30:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:30:38 INFO - 'talos_options': ['--xperf_path', 11:30:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:38 INFO - 'tests': ['tp5n']}}, 11:30:38 INFO - 'talos.zip': {'path': '', 11:30:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:30:38 INFO - Running post-action listener: _resource_record_post_action 11:30:38 INFO - ##### 11:30:38 INFO - ##### Running create-virtualenv step. 11:30:38 INFO - ##### 11:30:38 INFO - Running pre-action listener: _resource_record_pre_action 11:30:38 INFO - Running main action method: create_virtualenv 11:30:38 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:38 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 11:30:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:30:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:38 INFO - Using real prefix '/tools/python27' 11:30:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:30:38 INFO - Installing distribute.............................................................................................................................................................................................done. 11:30:41 INFO - Installing pip.................done. 11:30:41 INFO - Return code: 0 11:30:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:30:41 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')]} 11:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:41 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')]} 11:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:41 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:41 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 11:30:41 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 11:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:41 INFO - 'HOME': '/Users/cltbld', 11:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:41 INFO - 'LOGNAME': 'cltbld', 11:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:41 INFO - 'MOZ_NO_REMOTE': '1', 11:30:41 INFO - 'NO_EM_RESTART': '1', 11:30:41 INFO - 'PAGER': '/bin/cat', 11:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:41 INFO - 'PWD': '/builds/slave/test', 11:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:41 INFO - 'SHELL': '/bin/bash', 11:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:41 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:41 INFO - 'USER': 'cltbld', 11:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:41 INFO - 'XPC_FLAGS': '0x0', 11:30:41 INFO - 'XPC_SERVICE_NAME': '0', 11:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:42 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 11:30:42 INFO - Cleaning up... 11:30:42 INFO - Return code: 0 11:30:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:30: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')]} 11:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30: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')]} 11:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:30:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:42 INFO - 'HOME': '/Users/cltbld', 11:30:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:42 INFO - 'LOGNAME': 'cltbld', 11:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:42 INFO - 'MOZ_NO_REMOTE': '1', 11:30:42 INFO - 'NO_EM_RESTART': '1', 11:30:42 INFO - 'PAGER': '/bin/cat', 11:30:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:42 INFO - 'PWD': '/builds/slave/test', 11:30:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:42 INFO - 'SHELL': '/bin/bash', 11:30:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:42 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:42 INFO - 'USER': 'cltbld', 11:30:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:42 INFO - 'XPC_FLAGS': '0x0', 11:30:42 INFO - 'XPC_SERVICE_NAME': '0', 11:30:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:42 INFO - Downloading/unpacking psutil>=0.7.1 11:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:30:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:45 INFO - Installing collected packages: psutil 11:30:45 INFO - Running setup.py install for psutil 11:30:45 INFO - building 'psutil._psutil_osx' extension 11:30:45 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 11:30:46 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 11:30:46 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 11:30:46 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 11:30:46 INFO - building 'psutil._psutil_posix' extension 11:30:46 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 11:30:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:30:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:30:46 INFO - ^ 11:30:46 INFO - 1 warning generated. 11:30:46 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 11:30:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:46 INFO - Successfully installed psutil 11:30:46 INFO - Cleaning up... 11:30:46 INFO - Return code: 0 11:30:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:46 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:46 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 11:30:46 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 11:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:46 INFO - 'HOME': '/Users/cltbld', 11:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:46 INFO - 'LOGNAME': 'cltbld', 11:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:46 INFO - 'MOZ_NO_REMOTE': '1', 11:30:46 INFO - 'NO_EM_RESTART': '1', 11:30:46 INFO - 'PAGER': '/bin/cat', 11:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:46 INFO - 'PWD': '/builds/slave/test', 11:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:46 INFO - 'SHELL': '/bin/bash', 11:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:46 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:46 INFO - 'USER': 'cltbld', 11:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:46 INFO - 'XPC_FLAGS': '0x0', 11:30:46 INFO - 'XPC_SERVICE_NAME': '0', 11:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:30:46 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 11:30:46 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 11:30:46 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 11:30:46 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 11:30:46 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 11:30:46 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 11:30:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:30:49 INFO - Installing collected packages: mozsystemmonitor 11:30:49 INFO - Running setup.py install for mozsystemmonitor 11:30:49 INFO - Successfully installed mozsystemmonitor 11:30:49 INFO - Cleaning up... 11:30:49 INFO - Return code: 0 11:30:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:30:49 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')]} 11:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:49 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')]} 11:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:49 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:49 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 11:30:49 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 11:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:49 INFO - 'HOME': '/Users/cltbld', 11:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:49 INFO - 'LOGNAME': 'cltbld', 11:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:49 INFO - 'MOZ_NO_REMOTE': '1', 11:30:49 INFO - 'NO_EM_RESTART': '1', 11:30:49 INFO - 'PAGER': '/bin/cat', 11:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:49 INFO - 'PWD': '/builds/slave/test', 11:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:49 INFO - 'SHELL': '/bin/bash', 11:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:49 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:49 INFO - 'USER': 'cltbld', 11:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:49 INFO - 'XPC_FLAGS': '0x0', 11:30:49 INFO - 'XPC_SERVICE_NAME': '0', 11:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:50 INFO - Downloading/unpacking blobuploader==1.2.4 11:30:50 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 11:30:50 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 11:30:50 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 11:30:50 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 11:30:50 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 11:30:50 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 11:30:52 INFO - Downloading blobuploader-1.2.4.tar.gz 11:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:30:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:30:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:30:53 INFO - Downloading docopt-0.6.1.tar.gz 11:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:30:53 INFO - Installing collected packages: blobuploader, requests, docopt 11:30:53 INFO - Running setup.py install for blobuploader 11:30:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:30:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:30:53 INFO - Running setup.py install for requests 11:30:54 INFO - Running setup.py install for docopt 11:30:54 INFO - Successfully installed blobuploader requests docopt 11:30:54 INFO - Cleaning up... 11:30:54 INFO - Return code: 0 11:30:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:54 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:54 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 11:30:54 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 11:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:54 INFO - 'HOME': '/Users/cltbld', 11:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:54 INFO - 'LOGNAME': 'cltbld', 11:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:54 INFO - 'MOZ_NO_REMOTE': '1', 11:30:54 INFO - 'NO_EM_RESTART': '1', 11:30:54 INFO - 'PAGER': '/bin/cat', 11:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:54 INFO - 'PWD': '/builds/slave/test', 11:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:54 INFO - 'SHELL': '/bin/bash', 11:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:54 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:54 INFO - 'USER': 'cltbld', 11:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:54 INFO - 'XPC_FLAGS': '0x0', 11:30:54 INFO - 'XPC_SERVICE_NAME': '0', 11:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:30:54 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-k8ukAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:30:54 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-O6bJTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-CV3bCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-RYZM8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Ucq57i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-gwk1Uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Mb5V_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-0oJRE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-kktk4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-7xj5sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:30:55 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-GIqz7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-OR_QCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-5VrQPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-wbMvUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-BoY5Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-O4EZSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:30:56 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-B8Riwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:30:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:30:56 INFO - Running setup.py install for manifestparser 11:30:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:30:56 INFO - Running setup.py install for mozcrash 11:30:56 INFO - Running setup.py install for mozdebug 11:30:57 INFO - Running setup.py install for mozdevice 11:30:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Running setup.py install for mozfile 11:30:57 INFO - Running setup.py install for mozhttpd 11:30:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Running setup.py install for mozinfo 11:30:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Running setup.py install for mozInstall 11:30:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:30:57 INFO - Running setup.py install for mozleak 11:30:57 INFO - Running setup.py install for mozlog 11:30:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Running setup.py install for moznetwork 11:30:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Running setup.py install for mozprocess 11:30:58 INFO - Running setup.py install for mozprofile 11:30:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Running setup.py install for mozrunner 11:30:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:30:58 INFO - Running setup.py install for mozscreenshot 11:30:58 INFO - Running setup.py install for moztest 11:30:59 INFO - Running setup.py install for mozversion 11:30:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:30:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:30:59 INFO - Cleaning up... 11:30:59 INFO - Return code: 0 11:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:30:59 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:30:59 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 11:30:59 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 11:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:30:59 INFO - 'HOME': '/Users/cltbld', 11:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:30:59 INFO - 'LOGNAME': 'cltbld', 11:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:59 INFO - 'MOZ_NO_REMOTE': '1', 11:30:59 INFO - 'NO_EM_RESTART': '1', 11:30:59 INFO - 'PAGER': '/bin/cat', 11:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:59 INFO - 'PWD': '/builds/slave/test', 11:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:30:59 INFO - 'SHELL': '/bin/bash', 11:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:30:59 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:30:59 INFO - 'USER': 'cltbld', 11:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:59 INFO - 'XPC_FLAGS': '0x0', 11:30:59 INFO - 'XPC_SERVICE_NAME': '0', 11:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:30:59 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-90heZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:30:59 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)) 11:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:30:59 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-U3_5yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:30:59 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)) 11:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:30:59 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-V7vdyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:30:59 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)) 11:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:30:59 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-E8JO3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:30:59 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)) 11:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:30:59 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Xg1B7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-8VWBc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-vI7qkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-kLgqAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-QWYghx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-f2XpAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Pd7g00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Sif65K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-AvI4v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:00 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)) 11:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:00 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-3uIZU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:01 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)) 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:01 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-c2NP6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:01 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)) 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:01 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-iyQxPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:01 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)) 11:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:01 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-SA1AeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:01 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)) 11:31:01 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)) 11:31:01 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)) 11:31:01 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)) 11:31:01 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)) 11:31:01 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)) 11:31:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:01 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 11:31:01 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 11:31:01 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 11:31:01 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 11:31:01 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 11:31:01 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 11:31:03 INFO - Downloading blessings-1.6.tar.gz 11:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:03 INFO - Installing collected packages: blessings 11:31:03 INFO - Running setup.py install for blessings 11:31:04 INFO - Successfully installed blessings 11:31:04 INFO - Cleaning up... 11:31:04 INFO - Return code: 0 11:31:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:04 INFO - Reading from file tmpfile_stdout 11:31:04 INFO - Current package versions: 11:31:04 INFO - blessings == 1.6 11:31:04 INFO - blobuploader == 1.2.4 11:31:04 INFO - docopt == 0.6.1 11:31:04 INFO - manifestparser == 1.1 11:31:04 INFO - mozInstall == 1.12 11:31:04 INFO - mozcrash == 0.17 11:31:04 INFO - mozdebug == 0.1 11:31:04 INFO - mozdevice == 0.48 11:31:04 INFO - mozfile == 1.2 11:31:04 INFO - mozhttpd == 0.7 11:31:04 INFO - mozinfo == 0.9 11:31:04 INFO - mozleak == 0.1 11:31:04 INFO - mozlog == 3.1 11:31:04 INFO - moznetwork == 0.27 11:31:04 INFO - mozprocess == 0.22 11:31:04 INFO - mozprofile == 0.28 11:31:04 INFO - mozrunner == 6.11 11:31:04 INFO - mozscreenshot == 0.1 11:31:04 INFO - mozsystemmonitor == 0.0 11:31:04 INFO - moztest == 0.7 11:31:04 INFO - mozversion == 1.4 11:31:04 INFO - psutil == 3.1.1 11:31:04 INFO - requests == 1.2.3 11:31:04 INFO - wsgiref == 0.1.2 11:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:04 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:31:04 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 11:31:04 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 11:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:04 INFO - 'HOME': '/Users/cltbld', 11:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:04 INFO - 'LOGNAME': 'cltbld', 11:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:04 INFO - 'MOZ_NO_REMOTE': '1', 11:31:04 INFO - 'NO_EM_RESTART': '1', 11:31:04 INFO - 'PAGER': '/bin/cat', 11:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:04 INFO - 'PWD': '/builds/slave/test', 11:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:04 INFO - 'SHELL': '/bin/bash', 11:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:31:04 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:04 INFO - 'USER': 'cltbld', 11:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:04 INFO - 'XPC_FLAGS': '0x0', 11:31:04 INFO - 'XPC_SERVICE_NAME': '0', 11:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 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)) 11:31:04 INFO - Cleaning up... 11:31:04 INFO - Return code: 0 11:31:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:31:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:31:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:04 INFO - 'HOME': '/Users/cltbld', 11:31:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:04 INFO - 'LOGNAME': 'cltbld', 11:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:04 INFO - 'MOZ_NO_REMOTE': '1', 11:31:04 INFO - 'NO_EM_RESTART': '1', 11:31:04 INFO - 'PAGER': '/bin/cat', 11:31:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:04 INFO - 'PWD': '/builds/slave/test', 11:31:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:04 INFO - 'SHELL': '/bin/bash', 11:31:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:31:04 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:31:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:04 INFO - 'USER': 'cltbld', 11:31:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:04 INFO - 'XPC_FLAGS': '0x0', 11:31:04 INFO - 'XPC_SERVICE_NAME': '0', 11:31:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:04 INFO - Downloading/unpacking jsonschema 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:31:07 INFO - Downloading/unpacking functools32 (from jsonschema) 11:31:07 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 11:31:07 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 11:31:07 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 11:31:07 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 11:31:07 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 11:31:07 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 11:31:07 INFO - Downloading functools32-3.2.3-2.tar.gz 11:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:31:07 INFO - warning: no files found matching '*.txt' 11:31:07 INFO - no previously-included directories found matching 'build' 11:31:07 INFO - no previously-included directories found matching 'dist' 11:31:07 INFO - no previously-included directories found matching '.git*' 11:31:07 INFO - Installing collected packages: jsonschema, functools32 11:31:07 INFO - Running setup.py install for functools32 11:31:08 INFO - warning: no files found matching '*.txt' 11:31:08 INFO - no previously-included directories found matching 'build' 11:31:08 INFO - no previously-included directories found matching 'dist' 11:31:08 INFO - no previously-included directories found matching '.git*' 11:31:08 INFO - Successfully installed jsonschema functools32 11:31:08 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:08 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 0x110170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110180420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4e9ca91b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, '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.9u4AUewvMr/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.0DpPpPWujz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 11:31:08 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 11:31:08 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 11:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:08 INFO - 'HOME': '/Users/cltbld', 11:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PAGER': '/bin/cat', 11:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:31:08 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - 'XPC_FLAGS': '0x0', 11:31:08 INFO - 'XPC_SERVICE_NAME': '0', 11:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:08 INFO - Downloading/unpacking flake8 11:31:08 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 11:31:08 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 11:31:08 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 11:31:08 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 11:31:08 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 11:31:08 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 11:31:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:31:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31: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 11:31:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:31:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:31:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:31:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:31:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:31:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:31:12 INFO - no previously-included directories found matching 'docs/_build' 11:31:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:31:12 INFO - Running setup.py install for pep8 11:31:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:31:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:31:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:31:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:31:12 INFO - no previously-included directories found matching 'docs/_build' 11:31:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:31:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:31:12 INFO - Cleaning up... 11:31:12 INFO - Return code: 0 11:31:12 INFO - Running post-action listener: _resource_record_post_action 11:31:12 INFO - Running post-action listener: _start_resource_monitoring 11:31:12 INFO - Starting resource monitoring. 11:31:12 INFO - ##### 11:31:12 INFO - ##### Running install step. 11:31:12 INFO - ##### 11:31:12 INFO - Running pre-action listener: _resource_record_pre_action 11:31:12 INFO - Running main action method: install 11:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:12 INFO - Reading from file tmpfile_stdout 11:31:12 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:12 INFO - Reading from file tmpfile_stdout 11:31:12 INFO - Output received: 11:31:12 INFO - Usage: mozinstall [options] installer 11:31:12 INFO - Options: 11:31:12 INFO - -h, --help show this help message and exit 11:31:12 INFO - -d DEST, --destination=DEST 11:31:12 INFO - Directory to install application into. [default: 11:31:12 INFO - "/builds/slave/test"] 11:31:12 INFO - --app=APP Application being installed. [default: firefox] 11:31:12 INFO - mkdir: /builds/slave/test/build/application 11:31:12 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'] 11:31:12 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 11:31:44 INFO - Reading from file tmpfile_stdout 11:31:44 INFO - Output received: 11:31:44 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:31:44 INFO - Running post-action listener: _resource_record_post_action 11:31:44 INFO - ##### 11:31:44 INFO - ##### Running run-tests step. 11:31:44 INFO - ##### 11:31:44 INFO - Running pre-action listener: _resource_record_pre_action 11:31:44 INFO - Running main action method: run_tests 11:31:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:31:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:31:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:31:44 INFO - 2016-05-19 11:31:44.399 screenresolution[2258:19936] starting screenresolution argv=screenresolution get 11:31:44 INFO - 2016-05-19 11:31:44.401 screenresolution[2258:19936] Display 0: 1600x1200x32@60 11:31:44 INFO - 2016-05-19 11:31:44.408 screenresolution[2259:19943] starting screenresolution argv=screenresolution list 11:31:44 INFO - Available Modes on Display 0 11:31:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:31:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:31:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:31:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:31:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:31:44 INFO - Intel Iris: 11:31:44 INFO - Chipset Model: Intel Iris 11:31:44 INFO - Type: GPU 11:31:44 INFO - Bus: Built-In 11:31:44 INFO - VRAM (Dynamic, Max): 1536 MB 11:31:44 INFO - Vendor: Intel (0x8086) 11:31:44 INFO - Device ID: 0x0a2e 11:31:44 INFO - Revision ID: 0x0009 11:31:44 INFO - Displays: 11:31:44 INFO - Display: 11:31:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:31:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:31:44 INFO - Display Serial Number: 1600x1200 60 11:31:44 INFO - Main Display: Yes 11:31:44 INFO - Mirror: Off 11:31:44 INFO - Online: Yes 11:31:44 INFO - Rotation: Supported 11:31:44 INFO - Return code: 0 11:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:44 INFO - Python 2.7.3 11:31:44 INFO - Return code: 0 11:31:44 INFO - grabbing minidump binary from tooltool 11:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:44 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 0x11000fe00>, '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 0x10fe78db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11000ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:44 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 11:31:44 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 11:31:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:44 INFO - Return code: 0 11:31:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:31:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:31:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:31:45 INFO - Return code: 0 11:31:45 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/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 11:31:45 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/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0370 --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 11:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9u4AUewvMr/Render', 11:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:45 INFO - 'HOME': '/Users/cltbld', 11:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:45 INFO - 'LOGNAME': 'cltbld', 11:31:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:45 INFO - 'MOZ_NO_REMOTE': '1', 11:31:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:45 INFO - 'NO_EM_RESTART': '1', 11:31:45 INFO - 'PAGER': '/bin/cat', 11:31:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:45 INFO - 'PWD': '/builds/slave/test', 11:31:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:45 INFO - 'SHELL': '/bin/bash', 11:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0DpPpPWujz/Listeners', 11:31:45 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 11:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:45 INFO - 'USER': 'cltbld', 11:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:45 INFO - 'XPC_FLAGS': '0x0', 11:31:45 INFO - 'XPC_SERVICE_NAME': '0', 11:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:45 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/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 11:31:46 INFO - mozversion application_buildid: 20160519093556 11:31:46 INFO - mozversion application_changeset: adb760b8a144c049070c876dccbf228de868a953 11:31:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:31:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:46 INFO - mozversion application_name: Firefox 11:31:46 INFO - mozversion application_remotingname: firefox-dev 11:31:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:31:46 INFO - mozversion application_vendor: Mozilla 11:31:46 INFO - mozversion application_version: 48.0a2 11:31:46 INFO - mozversion platform_buildid: 20160519093556 11:31:46 INFO - mozversion platform_changeset: adb760b8a144c049070c876dccbf228de868a953 11:31:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:31:46 INFO - mozversion platform_version: 48.0a2 11:31:46 INFO - using testdate: 1463682706 11:31:46 INFO - actual date: 1463682706 11:31:46 INFO - starting webserver on 'localhost:54886' 11:31:46 INFO - SUITE-START | Running 2 tests 11:31:46 INFO - TEST-START | dromaeo_css 11:31:46 INFO - operating with platform_type : mac_ 11:31:46 INFO - Initialising browser for dromaeo_css test... 11:31:46 INFO - TEST-INFO | started process 2277 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpLYKnkA/profile http://localhost:54886/getInfo.html) 11:31:49 INFO - PROCESS | 2277 | __metrics Screen width/height:1600/1200 11:31:49 INFO - PROCESS | 2277 | colorDepth:24 11:31:49 INFO - PROCESS | 2277 | Browser inner width/height: 1024/689 11:31:49 INFO - PROCESS | 2277 | __metrics 11:31:49 ERROR - PROCESS | 2277 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 11:31:49 INFO - TEST-INFO | 2277: exit 0 11:31:49 INFO - Browser initialized. 11:31:49 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:49 INFO - TEST-INFO | started process 2280 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpLYKnkA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:32:01 INFO - PROCESS | 2280 | RSS: Main: 221696000 11:32:01 INFO - PROCESS | 2280 | 11:32:51 INFO - PROCESS | 2280 | 1463682771333 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] 11:35:02 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54886/tests/dromaeo/cssquery-ext.html) 11:35:03 INFO - PROCESS | 2280 | RSS: Main: 233762816 11:35:03 INFO - PROCESS | 2280 | 11:38:05 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-ext.html (next: http://localhost:54886/tests/dromaeo/cssquery-jquery.html) 11:38:06 INFO - PROCESS | 2280 | RSS: Main: 245723136 11:38:06 INFO - PROCESS | 2280 | 11:41:08 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54886/tests/dromaeo/cssquery-mootools.html) 11:41:08 INFO - PROCESS | 2280 | RSS: Main: 247705600 11:41:08 INFO - PROCESS | 2280 | 11:44:10 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54886/tests/dromaeo/cssquery-prototype.html) 11:44:11 INFO - PROCESS | 2280 | RSS: Main: 249389056 11:44:11 INFO - PROCESS | 2280 | 11:47:13 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54886/tests/dromaeo/cssquery-yui.html) 11:47:14 INFO - PROCESS | 2280 | RSS: Main: 252231680 11:47:14 INFO - PROCESS | 2280 | 11:50:16 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54886/tests/dromaeo/cssquery-yui.html (next: http://localhost:54886/tests/dromaeo/cssquery-dojo.html) 11:50:16 INFO - PROCESS | 2280 | RSS: Main: 252903424 11:50:16 INFO - PROCESS | 2280 | 11:50:16 INFO - PROCESS | 2280 | __start_tp_report 11:50:16 INFO - PROCESS | 2280 | _x_x_mozilla_page_load 11:50:16 INFO - PROCESS | 2280 | _x_x_mozilla_page_load_details 11:50:16 INFO - PROCESS | 2280 | |i|pagename|runs| 11:50:16 INFO - PROCESS | 2280 | |0;dojo.html;3350.313185796912;3430.2110514581645;3437.1407148212948;3446.6208717041136;3482.651734826516;5659.915101273484;5674.744636491363;5701.2018317435595;5716.1711551824965;5727.799527016556;5680;5691.686957217351;5723.771049158039;5724.542036637069;5734.369219385864;5156.587473002161;5171.974140129294;5196.220566914974;5229.921551176735;5277.947220527803;4231.4499115114995;5260.210968354739;5296.152615581508;5302.893942121155;5316.388615309231;4330.437043184382;5221.921671174917;5235.476452354753;5245.790168393279;5273.235380869772;4290.892727681801;5233.816816411445;5255.264263003182;5258.290130832327;5266.841994740125;4237.745735255864;4728.550787675165;5132.820351287724;5174.249733788617;5201.037808005493;5648.576356773217;5682.261306030231;5740.225069615591;5760.74076666551;5770.6249093808765;6022.849428764316;6160.0759886017;6160.6611636359985;6170.907436388457;6196.442320191204;6145.832291864532;6224.253089629274;6257.7184026718905;6274.905876411857;6276.152719382871;5840.299164100293;5848.210491583667;5855.092460668603;5858.76564937402;5886.117082437626;6291.245050593867;6293.465055470337;6302.401271879165;6320.241571011416;6331.651759153291;6019.217501724747;6116.701002199504;6116.724747386378;6181.81454556364;6229.283632382243;6254.093156492374;6298.433141017331;6312.433475086561;6319.083732858801;6320.431161195423;6199.628022318675;6245.500359971283;6250.343713910065;6276.121343011982;6280.057991301342;6063.575549711478;6157.907631385533;6181.907271390933;6225.657588832567;6284.874302513924;4897.877553061131;4901.43633482147;4914.827981020647;4915.754212289371;4923.926141107886;4300.956990430056;4454.910901782011;4463.620592249695;4464.888377790581;4479.888002799956;5714.20001199824;5764.855878603068;5767.625104368202;5770.682612456355;5792.1311803229855;6263.937360626426;6295.307516173218;6299.275583307886;6330.651814150266;6336.366363363627;4818.939833236683;5040.924386134211;5510;5776.595638305363;5808.099744539603;5318.680879147263;5389.326334208224;5391;5408.432114627986;5465.453454654582;6272.3100458949475;6324.715387807468;6330.841728956813;6342.841428964312;6348.5238607105575;6154.1999540059505;6180.134781130555;6237.719302631302;6288.65412402304;6327.493800496041;6282.968585157045;6336.398042185985;6368.554201205871;6370.076338930737;6485.383888530582;6199.721012554536;6234.906476402767;6265.498760099273;6275.403836635513;6276.403741644536;6116.396640261158;6185.41238582334;6229.688515574113;6295.244570651551;6310.305866354605;6103.145559621567;6110.7555697771595;6116.694165291807;6116.847078823065;6117.785877494266;5996.760129594942;6092.725827337869;6139.631622102688;6158.199434073542;6165.167702360303;5793.623414478046;5857.1799948008165;5944;5949.791757288474;5961.7615295387695;6114.174586430775;6129.3564175762685;6149.846253843511;6152.692365381802;6155.076738489083;5841.4450627190345;6007.87984240304;6016;6021.518278537794;6026.8794624106395 11:50:16 INFO - PROCESS | 2280 | |1;ext.html;515.2657463115061;526.6339893773828;547.1683041776497;573.8408962152085;614.7264467312045;37594;39202.607973920254;39573.60426395731;39626.60373396272;39775.801120994394;39877.80061099698;40306.798466007625;40415;40428;40547.986300342505;8586.184312490335;9286.721398358026;9459.054094590554;9677.419354838732;9699.854502182472;9504.239660827117;9618;9728.17310528606;9748.902510974905;9752.853707194397;9674.226061915066;9690.903090969106;9695.90304096957;9731.416115033086;9744.853827192563;9665.033496650349;9707;9709.174720148796;9722.611095556169;9729.270304727173;9601;9677.274204434696;9728.270379721549;9729.56216970238;9794.853077203848;36064.81967590145;39868.60131398678;40088.79955600233;40097.59902400968;40293.79853100716;3371.089805752433;3495.196104895863;3516.261585067139;3809.866654667101;3814.5803961564484;28160.718392816016;28172.859135704395;28178.295542611395;28729.856350718113;29174.70825291741;2739.9726002739917;2804.326961529239;2840.6733225679104;2844.3742376677305;2866.870990805419;393353;394457;398132;398650;398730;36611.63388366136;37510.874673759834;37511.43732844009;60620.696896516114;61249.693751530955;35818;35915.461268081;35991.640083599355;36183.09542261412;36279;15768.605784855241;16060.357585696442;16197.919010404872;16277.674446511004;16298.918505407397;13340.399682014333;13346;13423.127496712683;13544.59366219015;13574;7886.960565197137;8317.8336433271;8357.080721120672;8389.45468544555;8426;3070.142563748944;3506.701930335899;3515.3848076586496;3519.3665140274998;3534.310809392195;1025.4821315235758;1031.633770011635;1061.103367654331;1063.547992103353;1090.9945450272858;30774;31036.91370802011;31141.8442907784;31330.21674458111;31360;1053.8103141434615;1089.9019088282093;1124.2804605052609;1142.6800495861173;1145.2613064573304;15529.428092750353;15722.921385393229;15774.605634859221;15806.288717007763;16124.193790310437;633696;635547;636598;644768;648539;50245.9950800982;59743.701281494185;62272.68863655653;62601.686991565664;63706.36293637097;1194.8207768834745;1466.0836976889232;1468.6842328899124;1513.1828812441158;1554.1141549317008;30179;30249.975002499574;30600.11497413153;30679.386412271182;30789.538156927218;55183.17225241705;55184;55692;55710.72144639251;55889.44110558843;553351;555132;559300;559752;559777;27730.5840412398;28026;28050.579241311803;28451.573226402033;28494.57258141171;47282.52717472781;48819;49001.2649810246;49425.27011554578;49488.50511494983;38385.808070959465;39161;39380.80309598434;39584.40623390594;39982.60017399789;30327.848360758057;38971.415428769156;39270.21459570735;39393.60606393899;39594;37765;38474.0381490465;38497.42253866138;38852.80573597227;39197.804010979766;33749;33948.83025584856;34722.70134337234;34883;34931;25748.742512574634;25789.613155802304;25985;25998.8300526473;26526.469470610093 11:50:16 INFO - PROCESS | 2280 | |2;jquery.html;405.11280296151443;406.58122134201744;407.18359688823915;412.558562338298;416.08253800370187;14903.701925961474;19886.303979360724;20288.797112028893;20408.897955510238;20597.38207853765;20038;20188.091535880885;20505.692414613757;20538.69191962122;20830.791692083076;5584.497395234438;5637.915431268534;5858.179854820329;5873.177755114288;6007.609505382158;5837.5913686041995;5913.497352725002;5928.644281343111;5943.227380440559;5945.85135371617;5624.240727501776;5860.033094539417;5863.1791549182935;5893.44012318829;5907.911381329283;5718.056520674084;5759.308882934054;5778.133280007991;5814.651120932736;5818.621789583706;5634.915476267859;5648.858778530528;5660.858478538028;5703.74333155009;5815.79644712433;13033.087683862039;13327.533536326278;20009.499762505908;24326.756732432805;24431.389215269584;2121.8726876387313;2124.7450305963384;2127.8723276603455;2175.7497887742798;2177.2379667116543;9732.367396119229;10124.29129960903;10563.366198028143;13577.59267221985;14695.77956330656;1716.76823628811;1725.3961113610262;1744.3371518822894;1801.63976197971;1811.3931832836015;98568;111224.99999999919;115184.42407787907;121926.78073219243;124859.9999999991;9135.862962055575;9363.271547868282;9518.286128540249;11718.589849355401;12113.576024839089;14801.259937003322;14808.555743327717;14826.481073162387;14845.480408185662;14884;8538.103499132625;8660;8693.913060869438;8721.30229581632;8819.911800882037;8663.826723465621;8668.696595619123;8735.868961965576;8749.56252187388;8817.426867253736;3728.7762734236035;4549.45406551216;4625.560571745679;4637.142128706133;4645.628349732014;1708.6411853510654;1963.9901800490907;1978.4163671716879;1978.6141702368188;2026.9290574830104;912.270183852915;966;971.6162115964155;1004.0862814838462;1056.8837427882927;18506.44480665582;19124.139413726414;19595;19981.60036799285;20109;1005.4118340770585;1009.6819501856892;1020.7295066807302;1024.6157690865925;1027.2193133218807;10186.64346747875;10330.793384132276;10331.896681033093;10343.4311112888;10371.429571373646;578828.105859468;580380;580947;639628;646508;121574;125199.37400313122;126145.36927315305;126713.73286267204;127137;1049.6693541534546;1054.741588310884;1066.1417558865032;1070.7590792071721;1071.1377341240377;16955.66088678195;17102.657946841242;17410.390636328175;17434.651306974043;17748.467545973643;51781.22328165156;54428.72785636205;55126.44873551213;55348;56634;78390.21609783829;79342.6032869832;79697.60151199206;79933.60033199796;83504.16495835206;8041.356691464552;8066.556339401389;8078.394120440873;8094.83810323802;8373.539455329772;11656.766864662828;12050.638480845588;12310.322932238812;12314.876851231373;12470.127091103535;9981.850272246067;10460.581576736846;10564.841527376942;10660.520276587727;10732.087772539422;10258.179345652181;10275.897241027495;10399.428031458343;10587.788244234918;10652.680419587425;10367.118794902517;10911.508982095665;11069.889301106887;11097.944510277397;11163.888361116286;7675.769726908202;8040.5979701015885;8176;8239.835203296021;8288.668453262044;9394.483303418105;9459.581062840629;10588.152947764314;10603.41681207541;10758.46207689628 11:50:16 INFO - PROCESS | 2280 | |3;mootools.html;1592.3391792406148;1605.181357507671;1843.336398896398;1902.5338791995955;1981.8018198180175;7712.15166331703;8546.70086546971;8656.74029779106;8816.250618697402;8869.113088691136;8546.102659220785;8587.91412085879;8727.956360218206;8731.519766412845;8881.023087460375;2126.255810466332;2317.8989979759563;2396.05355884426;2429.2712186344024;2439.3901524618846;2407.807375409972;2426.5510880487077;2447.8898449569724;2453.521563295158;2461.360046387943;2426.4783071639595;2430;2443.6456713776493;2487.9004839806385;2498.725140234573;2448.326710154704;2455.7667021633006;2467.469494058786;2472.24596498068;2482.1808803094937;2441.4140606254373;2462.0398044762555;2478.467129567152;2479.4049428137296;2513.421912960011;8865;8905.777355566099;8909.1536304051;8913.55432228386;9028.368014239006;5337.839864804062;5483.643563168382;5505.229267902497;5509.944900551023;5548;3474.930501389958;3553;3607.4047782115917;3608.332458495187;3626.0934766308424;1024.4826362686795;1046.1212581431635;1046.2257929132463;1047.6804574604741;1048.6067724603274;11771.470283837249;11857.762844743056;11887.405629718565;11914.642560723105;11927;4193.454850869429;4735.976320118446;4750.049990002013;4806.50998190562;4914.508549145057;10176.949115254376;10190;10212.387256764468;10227.43749093792;10242.846357304647;5437.945620543744;5438.91841622376;5447.073997420369;5454.672719636756;5480.479354461319;4400.977995110004;4808.27875818623;4896.608271338285;4928.950710492921;4958.082754690394;4054.8378064877666;4100.712950093524;4103.349432599078;4181.456410666594;4190.8533201337805;1240.299230934519;1342.852286248512;1370.9245991470366;1379.3861731529373;1389.6803735140975;787.7006737439737;911.7674992876774;921.741912264567;927.9164875161133;939.9530023498797;5612.887742245132;5702.800401985911;5835.416458354215;5870.882582348329;5936.762529498858;944.0559440559441;986.9753256168653;995.1988649137369;1017.4251547875426;1042.2599954032721;3352.6312105668358;3621.511096002059;3777.58446570877;3844.7116466265143;3881.8059097045034;10404.271700980858;10684.786304273872;10827.78344433107;10840.78318433627;10842.783144337069;10689.83965240522;10711.892881071246;10811.78376432467;10857.402842843561;10947.890521094847;8304.211099945496;8431;8511.702090426805;8524.95737521321;8587.570621469027;5767.192593037062;5783.8554036149435;5823.79616713413;5922.881542369215;5962.880742385215;9407.43555386679;9411.341206115507;9540.666076687283;9627.807443850943;9786.706398808046;8473.601855693989;8541.359398045295;9078.955920069013;9772.804543909224;9929.553170107218;6617.2721000688925;7158.606276654834;7190.316919892602;7404.666789994355;7462.552246865205;8222.013358397031;8276.668933242603;8307.584620769057;8310.335173186251;8403.243708066304;7231.2768723127265;7291.526050806893;7458.179600244077;7553.055868016498;7579.962100189464;7271.854562908607;7344.339009488958;7346.853062938818;7449.180590135189;7519.210482899217;6957.339052789976;6982;7050.788476345718;7069.646517673993;7107.538010029331;4713.128071306819;4726.125666751662;4818.229083346788;4931.186354251508;4951;4774.40319960005;4801.159797035435;4834.274858771212;4891.584215341736;5137.972405518986 11:50:16 INFO - PROCESS | 2280 | |4;prototype.html;327.91146390474574;328.09282334345534;331.34228556315725;332.7304883044734;335.6140438495728;6840.076589660401;6853.897191542124;6888.931110688892;6893;6897.965510172455;6759.053732477445;6772.593644381328;6773.424258937984;6827.453803695705;6834.282400347966;4749.738764367967;4810.975945120287;4945.7774400152075;4949.480304568023;4966.031623833354;4902.583280421169;4935.111679897617;4957.479464656213;4960.107180707471;4970.130227210242;4885.584725298354;4904.681195722284;4913.238448040559;4936.210206366982;4941.604671626279;4918.950810491885;4923.4091908970795;4925.064237794808;4927.457979622239;4971.900561988776;4251.086016506466;4265.658747300239;4266.274733295316;4277.251480990814;4297.828086876521;6381.872362552769;6533.93466065338;6571.868562628721;6611.404973552403;6623.07276981223;5997.190379298782;5998.7600495980105;6026.156338112668;6040.788572399989;6077.1491991121275;7312.707491700326;7326.230745771723;7336.743213987538;7340.1191856976975;7353.963230183868;4808.903821923542;4812.831550895737;4818.373611430527;4830.2030165022725;4832.589229915461;6485.189351331083;7419.258074192591;7566.43251756115;7711.884321735179;7753.496022758559;4883.6093112551625;4891.853244402673;4892.877678058077;4896.755162241873;4903.04390643821;4934.235193545006;4975.751212439436;4983.07813054586;4986.376702912136;4994.750262486861;5912.906112369225;5918.940810591839;5937.139114828326;5942.910856337158;6055.576109672281;7344.632768361667;7354.70581176747;7354.926450735531;7381.66782494789;7385.076865391826;1721.6470623522148;1736.2620886123348;1740.5909611241348;1743.311392000178;1745.659596378694;847.5380917399908;1065.4086981725218;1069.9144068474504;1072.2922870905165;1072.367303291062;6771;6811.931880681229;6812.420944219698;6812.931870681329;6829.965850170816;6521.934780652227;6568.86862262752;7210.846264597639;7601;7627.466077374531;5555;6306.148669929501;6864.691088900911;6922.619255940871;6963.512554121163;3037.407705497407;3549.254656522108;3974.0462289050997;4088.2436749201493;4095.52901416335;8140.023197216372;8141.918580814235;8152.103268640445;8154.8776768348525;8180.9181908181345;6502;6579.671016449159;7742.961285193651;7942.086660034054;8016.839663206703;8050.959745201237;8088.029436467779;8092.473989190684;8113.229243221885;8131.7560473185895;7707.92292077072;7722;7731.806704832424;7748.496347737604;7751.3411360035025;7594;7602.8099297518;7616.9238307616215;7621.12357078943;7636.732714354971;7809.570473624005;8105.148959359182;8114.553699546582;8119.5534245617055;8128.105908350195;4780.6175505958745;4785;4805.423349198119;4811.807527698853;4812.302216178705;7676.232376762502;7678.577678227528;7683.5005724629955;7695;7700.383969282332;7365.926340736524;7402.518836275625;7408.222136675787;7419.258074192538;7423;6300.401461861115;7181.425485961006;7291.234420385996;7407.925920740724;7413.332800047806;7202.459815513962;7253.347198752138;7253.492255542062;7259.2377800330205;7262.419006479363;5923.378045305353;5947.286325640951;6711.697973591103;7374.2994415532585;7383.741569045058;6956.7217311309005;7241.891371629536;7244.275572442924;7254.238304978113;7255.56466612005 11:50:16 INFO - PROCESS | 2280 | |5;yui.html;576.6626523483765;681.8534015186734;688.4733179117976;691.9515633905627;755.176857225624;12503.312317822527;14799.92600037001;14953.925230373832;14979.101253924759;15003.849961500395;12436.316002619853;13679;13698.589042328722;13703.794443083338;13720.862791372083;896.9820603587924;923.9097864519864;937.5078084005884;939.7180845746283;942.853857652065;906.6373450619772;932.6875496708611;946.1484663802564;947.924166066713;948.3029972969896;960.9807803843919;985.8373368394207;990.8464188050827;991.8165139449189;995.9850602240972;913.6341169950899;919.3197034194715;955.8136163448165;973.9220862331032;978.6036655154674;596.2219303808528;603.610671117127;688.435482904019;711.0542977839513;717.2361435071632;13230.470781168742;14000.789988150185;15053.322600483005;15114.546563603;15172.393104275816;12632.747345053047;12704.237745735285;12793.48826046957;12855.614331570068;12989.61031169057;10303.02121298483;10355.792884142274;10374;10509.474526273656;10535.894641053568;3515.542979412686;3518.9296214075575;3588.2464682416726;3596.1908570571672;3733.831977560989;86992;87999;88529.11470885274;88964.5551772237;89669;9312.813743725088;9393.389429687048;9404.858927116098;9545.809083818285;9578.808423831484;21192.682109768364;21202.469938251357;21228.25701100485;21243.68134477984;21289.467763306042;7196.424286057103;7207.423406127497;7209.315115064061;7249.565026098451;7324.304191101837;4004.519457665099;4008.6392224699916;4063.6952228582973;4065.6747460203114;4208.095259519157;3425.160835595295;3479.0258727556325;3490.47642853574;3495.811424115762;3502.7197824174004;632.9050642403585;656.4485831901226;665.4244078871837;682.0383259603934;695.085961960021;515.7679044429947;520.5679286192451;542.4846395923888;589.7994681808206;595.9204648772554;8062.879056814152;8302.294304984143;8313.376496762767;8447.070822209675;8605.881235439478;504.3216873305398;583.2592607388593;593.7595273914071;602.093848757617;611.7767015039478;3477.791332520057;3801.7718936863876;3867.497225360685;3926.469926559878;4068.7965601719798;97100.51449742707;97626;97773;99036.50481747689;100448;68292.31707682859;75510.62244688741;83433.16566834375;84412.15587844166;84668;71000;72455;73032;73162;73388.99999999894;7796.837375567992;7884.369250460064;7901.841963160819;7933;8241.134680858424;38742.80628596839;49695.00609987853;50481;50487.74756126196;50781.746091269306;46368.536314636425;66032;67656.323436765;71706.28293716996;73438;8573.91426085756;9111.407758495676;9981.201503879527;10132.240081993732;10911.563537458413;11877.524899003944;12038.337891416057;12096.939515302367;12180.147389682637;12450.128491005542;11588.246763960316;11591.88408115908;11634.825477617673;11748.412579371168;11892.108091893315;7873.212678732081;10397.064264216257;10978.945105274423;11413.828792567952;12059.51761929513;10917;11165.553377864795;11176.313154842881;11243.718907027389;11370.431478426211;9728.610855565981;9737.561809718438;9751.756206094904;9811.607535698493;9936.056074672895;7884.72403465876;9414.576344064397;9726.70819875405;9765.2676049298;9765.316427850266 11:50:16 INFO - PROCESS | 2280 | __end_tp_report 11:50:16 INFO - PROCESS | 2280 | __start_cc_report 11:50:16 INFO - PROCESS | 2280 | _x_x_mozilla_cycle_collect,415 11:50:16 INFO - PROCESS | 2280 | __end_cc_report 11:50:16 INFO - PROCESS | 2280 | __startTimestamp1463683816983__endTimestamp 11:50:16 INFO - PROCESS | 2280 | 11:50:16 INFO - PROCESS | 2280 | ------- Summary: start ------- 11:50:16 INFO - PROCESS | 2280 | Number of tests: 6 11:50:16 INFO - PROCESS | 2280 | 11:50:16 INFO - PROCESS | 2280 | [#0] dojo.html Cycles:165 Average:5731.03 Median:6007.88 stddev:679.49 (11.3%) stddev-sans-first:655.40 11:50:16 INFO - PROCESS | 2280 | Values: 3350.3 3430.2 3437.1 3446.6 3482.7 5659.9 5674.7 5701.2 5716.2 5727.8 5680.0 5691.7 5723.8 5724.5 5734.4 5156.6 5172.0 5196.2 5229.9 5277.9 4231.4 5260.2 5296.2 5302.9 5316.4 4330.4 5221.9 5235.5 5245.8 5273.2 4290.9 5233.8 5255.3 5258.3 5266.8 4237.7 4728.6 5132.8 5174.2 5201.0 5648.6 5682.3 5740.2 5760.7 5770.6 6022.8 6160.1 6160.7 6170.9 6196.4 6145.8 6224.3 6257.7 6274.9 6276.2 5840.3 5848.2 5855.1 5858.8 5886.1 6291.2 6293.5 6302.4 6320.2 6331.7 6019.2 6116.7 6116.7 6181.8 6229.3 6254.1 6298.4 6312.4 6319.1 6320.4 6199.6 6245.5 6250.3 6276.1 6280.1 6063.6 6157.9 6181.9 6225.7 6284.9 4897.9 4901.4 4914.8 4915.8 4923.9 4301.0 4454.9 4463.6 4464.9 4479.9 5714.2 5764.9 5767.6 5770.7 5792.1 6263.9 6295.3 6299.3 6330.7 6336.4 4818.9 5040.9 5510.0 5776.6 5808.1 5318.7 5389.3 5391.0 5408.4 5465.5 6272.3 6324.7 6330.8 6342.8 6348.5 6154.2 6180.1 6237.7 6288.7 6327.5 6283.0 6336.4 6368.6 6370.1 6485.4 6199.7 6234.9 6265.5 6275.4 6276.4 6116.4 6185.4 6229.7 6295.2 6310.3 6103.1 6110.8 6116.7 6116.8 6117.8 5996.8 6092.7 6139.6 6158.2 6165.2 5793.6 5857.2 5944.0 5949.8 5961.8 6114.2 6129.4 6149.8 6152.7 6155.1 5841.4 6007.9 6016.0 6021.5 6026.9 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - PROCESS | 2280 | [#1] ext.html Cycles:180 Average:65815.53 Median:38912.11 stddev:144744.83 (372.0%) stddev-sans-first:145067.84 11:50:17 INFO - PROCESS | 2280 | Values: 515.3 526.6 547.2 573.8 614.7 37594.0 39202.6 39573.6 39626.6 39775.8 39877.8 40306.8 40415.0 40428.0 40548.0 8586.2 9286.7 9459.1 9677.4 9699.9 9504.2 9618.0 9728.2 9748.9 9752.9 9674.2 9690.9 9695.9 9731.4 9744.9 9665.0 9707.0 9709.2 9722.6 9729.3 9601.0 9677.3 9728.3 9729.6 9794.9 36064.8 39868.6 40088.8 40097.6 40293.8 3371.1 3495.2 3516.3 3809.9 3814.6 28160.7 28172.9 28178.3 28729.9 29174.7 2740.0 2804.3 2840.7 2844.4 2866.9 393353.0 394457.0 398132.0 398650.0 398730.0 36611.6 37510.9 37511.4 60620.7 61249.7 35818.0 35915.5 35991.6 36183.1 36279.0 15768.6 16060.4 16197.9 16277.7 16298.9 13340.4 13346.0 13423.1 13544.6 13574.0 7887.0 8317.8 8357.1 8389.5 8426.0 3070.1 3506.7 3515.4 3519.4 3534.3 1025.5 1031.6 1061.1 1063.5 1091.0 30774.0 31036.9 31141.8 31330.2 31360.0 1053.8 1089.9 1124.3 1142.7 1145.3 15529.4 15722.9 15774.6 15806.3 16124.2 633696.0 635547.0 636598.0 644768.0 648539.0 50246.0 59743.7 62272.7 62601.7 63706.4 1194.8 1466.1 1468.7 1513.2 1554.1 30179.0 30250.0 30600.1 30679.4 30789.5 55183.2 55184.0 55692.0 55710.7 55889.4 553351.0 555132.0 559300.0 559752.0 559777.0 27730.6 28026.0 28050.6 28451.6 28494.6 47282.5 48819.0 49001.3 49425.3 49488.5 38385.8 39161.0 39380.8 39584.4 39982.6 30327.8 38971.4 39270.2 39393.6 39594.0 37765.0 38474.0 38497.4 38852.8 39197.8 33749.0 33948.8 34722.7 34883.0 34931.0 25748.7 25789.6 25985.0 25998.8 26526.5 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - PROCESS | 2280 | [#2] jquery.html Cycles:180 Average:34807.02 Median:20714.09 stddev:101041.10 (487.8%) stddev-sans-first:101291.53 11:50:17 INFO - PROCESS | 2280 | Values: 405.1 406.6 407.2 412.6 416.1 14903.7 19886.3 20288.8 20408.9 20597.4 20038.0 20188.1 20505.7 20538.7 20830.8 5584.5 5637.9 5858.2 5873.2 6007.6 5837.6 5913.5 5928.6 5943.2 5945.9 5624.2 5860.0 5863.2 5893.4 5907.9 5718.1 5759.3 5778.1 5814.7 5818.6 5634.9 5648.9 5660.9 5703.7 5815.8 13033.1 13327.5 20009.5 24326.8 24431.4 2121.9 2124.7 2127.9 2175.7 2177.2 9732.4 10124.3 10563.4 13577.6 14695.8 1716.8 1725.4 1744.3 1801.6 1811.4 98568.0 111225.0 115184.4 121926.8 124860.0 9135.9 9363.3 9518.3 11718.6 12113.6 14801.3 14808.6 14826.5 14845.5 14884.0 8538.1 8660.0 8693.9 8721.3 8819.9 8663.8 8668.7 8735.9 8749.6 8817.4 3728.8 4549.5 4625.6 4637.1 4645.6 1708.6 1964.0 1978.4 1978.6 2026.9 912.3 966.0 971.6 1004.1 1056.9 18506.4 19124.1 19595.0 19981.6 20109.0 1005.4 1009.7 1020.7 1024.6 1027.2 10186.6 10330.8 10331.9 10343.4 10371.4 578828.1 580380.0 580947.0 639628.0 646508.0 121574.0 125199.4 126145.4 126713.7 127137.0 1049.7 1054.7 1066.1 1070.8 1071.1 16955.7 17102.7 17410.4 17434.7 17748.5 51781.2 54428.7 55126.4 55348.0 56634.0 78390.2 79342.6 79697.6 79933.6 83504.2 8041.4 8066.6 8078.4 8094.8 8373.5 11656.8 12050.6 12310.3 12314.9 12470.1 9981.9 10460.6 10564.8 10660.5 10732.1 10258.2 10275.9 10399.4 10587.8 10652.7 10367.1 10911.5 11069.9 11097.9 11163.9 7675.8 8040.6 8176.0 8239.8 8288.7 9394.5 9459.6 10588.2 10603.4 10758.5 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - PROCESS | 2280 | [#3] mootools.html Cycles:180 Average:5712.58 Median:4905.56 stddev:3161.87 (64.5%) stddev-sans-first:3155.58 11:50:17 INFO - PROCESS | 2280 | Values: 1592.3 1605.2 1843.3 1902.5 1981.8 7712.2 8546.7 8656.7 8816.3 8869.1 8546.1 8587.9 8728.0 8731.5 8881.0 2126.3 2317.9 2396.1 2429.3 2439.4 2407.8 2426.6 2447.9 2453.5 2461.4 2426.5 2430.0 2443.6 2487.9 2498.7 2448.3 2455.8 2467.5 2472.2 2482.2 2441.4 2462.0 2478.5 2479.4 2513.4 8865.0 8905.8 8909.2 8913.6 9028.4 5337.8 5483.6 5505.2 5509.9 5548.0 3474.9 3553.0 3607.4 3608.3 3626.1 1024.5 1046.1 1046.2 1047.7 1048.6 11771.5 11857.8 11887.4 11914.6 11927.0 4193.5 4736.0 4750.0 4806.5 4914.5 10176.9 10190.0 10212.4 10227.4 10242.8 5437.9 5438.9 5447.1 5454.7 5480.5 4401.0 4808.3 4896.6 4929.0 4958.1 4054.8 4100.7 4103.3 4181.5 4190.9 1240.3 1342.9 1370.9 1379.4 1389.7 787.7 911.8 921.7 927.9 940.0 5612.9 5702.8 5835.4 5870.9 5936.8 944.1 987.0 995.2 1017.4 1042.3 3352.6 3621.5 3777.6 3844.7 3881.8 10404.3 10684.8 10827.8 10840.8 10842.8 10689.8 10711.9 10811.8 10857.4 10947.9 8304.2 8431.0 8511.7 8525.0 8587.6 5767.2 5783.9 5823.8 5922.9 5962.9 9407.4 9411.3 9540.7 9627.8 9786.7 8473.6 8541.4 9079.0 9772.8 9929.6 6617.3 7158.6 7190.3 7404.7 7462.6 8222.0 8276.7 8307.6 8310.3 8403.2 7231.3 7291.5 7458.2 7553.1 7580.0 7271.9 7344.3 7346.9 7449.2 7519.2 6957.3 6982.0 7050.8 7069.6 7107.5 4713.1 4726.1 4818.2 4931.2 4951.0 4774.4 4801.2 4834.3 4891.6 5138.0 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - PROCESS | 2280 | [#4] prototype.html Cycles:180 Average:5981.10 Median:6765.03 stddev:1938.13 (28.6%) stddev-sans-first:1896.55 11:50:17 INFO - PROCESS | 2280 | Values: 327.9 328.1 331.3 332.7 335.6 6840.1 6853.9 6888.9 6893.0 6898.0 6759.1 6772.6 6773.4 6827.5 6834.3 4749.7 4811.0 4945.8 4949.5 4966.0 4902.6 4935.1 4957.5 4960.1 4970.1 4885.6 4904.7 4913.2 4936.2 4941.6 4919.0 4923.4 4925.1 4927.5 4971.9 4251.1 4265.7 4266.3 4277.3 4297.8 6381.9 6533.9 6571.9 6611.4 6623.1 5997.2 5998.8 6026.2 6040.8 6077.1 7312.7 7326.2 7336.7 7340.1 7354.0 4808.9 4812.8 4818.4 4830.2 4832.6 6485.2 7419.3 7566.4 7711.9 7753.5 4883.6 4891.9 4892.9 4896.8 4903.0 4934.2 4975.8 4983.1 4986.4 4994.8 5912.9 5918.9 5937.1 5942.9 6055.6 7344.6 7354.7 7354.9 7381.7 7385.1 1721.6 1736.3 1740.6 1743.3 1745.7 847.5 1065.4 1069.9 1072.3 1072.4 6771.0 6811.9 6812.4 6812.9 6830.0 6521.9 6568.9 7210.8 7601.0 7627.5 5555.0 6306.1 6864.7 6922.6 6963.5 3037.4 3549.3 3974.0 4088.2 4095.5 8140.0 8141.9 8152.1 8154.9 8180.9 6502.0 6579.7 7743.0 7942.1 8016.8 8051.0 8088.0 8092.5 8113.2 8131.8 7707.9 7722.0 7731.8 7748.5 7751.3 7594.0 7602.8 7616.9 7621.1 7636.7 7809.6 8105.1 8114.6 8119.6 8128.1 4780.6 4785.0 4805.4 4811.8 4812.3 7676.2 7678.6 7683.5 7695.0 7700.4 7365.9 7402.5 7408.2 7419.3 7423.0 6300.4 7181.4 7291.2 7407.9 7413.3 7202.5 7253.3 7253.5 7259.2 7262.4 5923.4 5947.3 6711.7 7374.3 7383.7 6956.7 7241.9 7244.3 7254.2 7255.6 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - PROCESS | 2280 | [#5] yui.html Cycles:180 Average:18552.84 Median:622.34 stddev:26858.60 (4315.7%) stddev-sans-first:26900.03 11:50:17 INFO - PROCESS | 2280 | Values: 576.7 681.9 688.5 692.0 755.2 12503.3 14799.9 14953.9 14979.1 15003.8 12436.3 13679.0 13698.6 13703.8 13720.9 897.0 923.9 937.5 939.7 942.9 906.6 932.7 946.1 947.9 948.3 961.0 985.8 990.8 991.8 996.0 913.6 919.3 955.8 973.9 978.6 596.2 603.6 688.4 711.1 717.2 13230.5 14000.8 15053.3 15114.5 15172.4 12632.7 12704.2 12793.5 12855.6 12989.6 10303.0 10355.8 10374.0 10509.5 10535.9 3515.5 3518.9 3588.2 3596.2 3733.8 86992.0 87999.0 88529.1 88964.6 89669.0 9312.8 9393.4 9404.9 9545.8 9578.8 21192.7 21202.5 21228.3 21243.7 21289.5 7196.4 7207.4 7209.3 7249.6 7324.3 4004.5 4008.6 4063.7 4065.7 4208.1 3425.2 3479.0 3490.5 3495.8 3502.7 632.9 656.4 665.4 682.0 695.1 515.8 520.6 542.5 589.8 595.9 8062.9 8302.3 8313.4 8447.1 8605.9 504.3 583.3 593.8 602.1 611.8 3477.8 3801.8 3867.5 3926.5 4068.8 97100.5 97626.0 97773.0 99036.5 100448.0 68292.3 75510.6 83433.2 84412.2 84668.0 71000.0 72455.0 73032.0 73162.0 73389.0 7796.8 7884.4 7901.8 7933.0 8241.1 38742.8 49695.0 50481.0 50487.7 50781.7 46368.5 66032.0 67656.3 71706.3 73438.0 8573.9 9111.4 9981.2 10132.2 10911.6 11877.5 12038.3 12096.9 12180.1 12450.1 11588.2 11591.9 11634.8 11748.4 11892.1 7873.2 10397.1 10978.9 11413.8 12059.5 10917.0 11165.6 11176.3 11243.7 11370.4 9728.6 9737.6 9751.8 9811.6 9936.1 7884.7 9414.6 9726.7 9765.3 9765.3 11:50:17 INFO - PROCESS | 2280 | -------- Summary: end -------- 11:50:17 INFO - PROCESS | 2280 | 11:50:17 INFO - TEST-INFO | 2280: exit 0 11:50:17 INFO - TEST-OK | dromaeo_css | took 1111580ms 11:50:17 INFO - TEST-START | kraken 11:50:17 INFO - operating with platform_type : mac_ 11:50:17 INFO - Initialising browser for kraken test... 11:50:17 INFO - TEST-INFO | started process 2286 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpueT7Lj/profile http://localhost:54886/getInfo.html) 11:50:20 INFO - PROCESS | 2286 | __metrics Screen width/height:1600/1200 11:50:20 INFO - PROCESS | 2286 | colorDepth:24 11:50:20 INFO - PROCESS | 2286 | Browser inner width/height: 1024/689 11:50:20 INFO - PROCESS | 2286 | __metrics 11:50:20 ERROR - PROCESS | 2286 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 11:50:21 INFO - TEST-INFO | 2286: exit 0 11:50:21 INFO - Browser initialized. 11:50:21 INFO - Running cycle 1/1 for kraken test... 11:50:21 INFO - TEST-INFO | started process 2289 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpueT7Lj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:33 INFO - PROCESS | 2289 | RSS: Main: 221646848 11:50:33 INFO - PROCESS | 2289 | 11:50:35 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54886/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:36 INFO - PROCESS | 2289 | RSS: Main: 233091072 11:50:36 INFO - PROCESS | 2289 | 11:50:39 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54886/tests/kraken/driver.html?testName=audio-dft) 11:50:40 INFO - PROCESS | 2289 | RSS: Main: 242135040 11:50:40 INFO - PROCESS | 2289 | 11:50:43 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54886/tests/kraken/driver.html?testName=audio-fft) 11:50:44 INFO - PROCESS | 2289 | RSS: Main: 244334592 11:50:44 INFO - PROCESS | 2289 | 11:50:46 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54886/tests/kraken/driver.html?testName=audio-oscillator) 11:50:46 INFO - PROCESS | 2289 | RSS: Main: 245268480 11:50:46 INFO - PROCESS | 2289 | 11:50:49 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54886/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:49 INFO - PROCESS | 2289 | RSS: Main: 247144448 11:50:49 INFO - PROCESS | 2289 | 11:50:52 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54886/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:53 INFO - PROCESS | 2289 | RSS: Main: 247517184 11:50:53 INFO - PROCESS | 2289 | 11:50:56 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54886/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:57 INFO - PROCESS | 2289 | RSS: Main: 247828480 11:50:57 INFO - PROCESS | 2289 | 11:50:59 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54886/tests/kraken/driver.html?testName=json-parse-financial) 11:51:00 INFO - PROCESS | 2289 | RSS: Main: 247832576 11:51:00 INFO - PROCESS | 2289 | 11:51:02 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54886/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:51:02 INFO - PROCESS | 2289 | RSS: Main: 247283712 11:51:02 INFO - PROCESS | 2289 | 11:51:04 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:51:05 INFO - PROCESS | 2289 | RSS: Main: 247259136 11:51:05 INFO - PROCESS | 2289 | 11:51:07 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:51:08 INFO - PROCESS | 2289 | RSS: Main: 247775232 11:51:08 INFO - PROCESS | 2289 | 11:51:10 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:51:11 INFO - PROCESS | 2289 | RSS: Main: 247611392 11:51:11 INFO - PROCESS | 2289 | 11:51:14 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:51:15 INFO - PROCESS | 2289 | RSS: Main: 247685120 11:51:15 INFO - PROCESS | 2289 | 11:51:16 INFO - PROCESS | 2289 | Cycle 1(1): loaded http://localhost:54886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54886/tests/kraken/driver.html?testName=ai-astar) 11:51:17 INFO - PROCESS | 2289 | RSS: Main: 249364480 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | __start_tp_report 11:51:17 INFO - PROCESS | 2289 | _x_x_mozilla_page_load 11:51:17 INFO - PROCESS | 2289 | _x_x_mozilla_page_load_details 11:51:17 INFO - PROCESS | 2289 | |i|pagename|runs| 11:51:17 INFO - PROCESS | 2289 | |0;ai-astar;111;119;103;121;120;117;105;141;170;155 11:51:17 INFO - PROCESS | 2289 | |1;audio-beat-detection;155;119;160;158;127;140;131;190;192;141 11:51:17 INFO - PROCESS | 2289 | |2;audio-dft;199;199;198;204;199;197;228;202;275;285 11:51:17 INFO - PROCESS | 2289 | |3;audio-fft;75;79;83;143;47;71;82;99;131;58 11:51:17 INFO - PROCESS | 2289 | |4;audio-oscillator;80;108;136;82;115;142;133;83;83;123 11:51:17 INFO - PROCESS | 2289 | |5;imaging-gaussian-blur;89;112;135;105;89;85;88;86;87;86 11:51:17 INFO - PROCESS | 2289 | |6;imaging-darkroom;95;94;95;124;146;150;94;125;104;152 11:51:17 INFO - PROCESS | 2289 | |7;imaging-desaturate;99;93;92;91;94;91;91;92;90;87 11:51:17 INFO - PROCESS | 2289 | |8;json-parse-financial;60;63;67;64;67;66;67;80;64;59 11:51:17 INFO - PROCESS | 2289 | |9;json-stringify-tinderbox;64;67;72;76;75;78;75;71;68;70 11:51:17 INFO - PROCESS | 2289 | |10;stanford-crypto-aes;84;83;81;83;82;81;83;83;82;85 11:51:17 INFO - PROCESS | 2289 | |11;stanford-crypto-ccm;135;128;163;146;153;141;133;130;149;144 11:51:17 INFO - PROCESS | 2289 | |12;stanford-crypto-pbkdf2;196;232;191;184;180;167;170;168;166;222 11:51:17 INFO - PROCESS | 2289 | |13;stanford-crypto-sha256-iterative;72;67;70;65;70;70;74;70;67;71 11:51:17 INFO - PROCESS | 2289 | __end_tp_report 11:51:17 INFO - PROCESS | 2289 | __start_cc_report 11:51:17 INFO - PROCESS | 2289 | _x_x_mozilla_cycle_collect,1089 11:51:17 INFO - PROCESS | 2289 | __end_cc_report 11:51:17 INFO - PROCESS | 2289 | __startTimestamp1463683877033__endTimestamp 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | ------- Summary: start ------- 11:51:17 INFO - PROCESS | 2289 | Number of tests: 14 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#0] ai-astar Cycles:10 Average:126.20 Median:120.50 stddev:22.08 (18.3%) stddev-sans-first:22.72 11:51:17 INFO - PROCESS | 2289 | Values: 111.0 119.0 103.0 121.0 120.0 117.0 105.0 141.0 170.0 155.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:156.50 stddev:24.87 (15.9%) stddev-sans-first:26.35 11:51:17 INFO - PROCESS | 2289 | Values: 155.0 119.0 160.0 158.0 127.0 140.0 131.0 190.0 192.0 141.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#2] audio-dft Cycles:10 Average:218.60 Median:203.00 stddev:33.68 (16.6%) stddev-sans-first:34.97 11:51:17 INFO - PROCESS | 2289 | Values: 199.0 199.0 198.0 204.0 199.0 197.0 228.0 202.0 275.0 285.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#3] audio-fft Cycles:10 Average:86.80 Median:77.00 stddev:30.11 (39.1%) stddev-sans-first:31.64 11:51:17 INFO - PROCESS | 2289 | Values: 75.0 79.0 83.0 143.0 47.0 71.0 82.0 99.0 131.0 58.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:111.00 stddev:24.82 (22.4%) stddev-sans-first:24.09 11:51:17 INFO - PROCESS | 2289 | Values: 80.0 108.0 136.0 82.0 115.0 142.0 133.0 83.0 83.0 123.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:86.50 stddev:16.40 (19.0%) stddev-sans-first:17.19 11:51:17 INFO - PROCESS | 2289 | Values: 89.0 112.0 135.0 105.0 89.0 85.0 88.0 86.0 87.0 86.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#6] imaging-darkroom Cycles:10 Average:117.90 Median:123.00 stddev:24.62 (20.0%) stddev-sans-first:24.68 11:51:17 INFO - PROCESS | 2289 | Values: 95.0 94.0 95.0 124.0 146.0 150.0 94.0 125.0 104.0 152.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.00 stddev:3.09 (3.4%) stddev-sans-first:1.99 11:51:17 INFO - PROCESS | 2289 | Values: 99.0 93.0 92.0 91.0 94.0 91.0 91.0 92.0 90.0 87.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:66.50 stddev:5.77 (8.7%) stddev-sans-first:5.74 11:51:17 INFO - PROCESS | 2289 | Values: 60.0 63.0 67.0 64.0 67.0 66.0 67.0 80.0 64.0 59.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:73.50 stddev:4.45 (6.1%) stddev-sans-first:3.78 11:51:17 INFO - PROCESS | 2289 | Values: 64.0 67.0 72.0 76.0 75.0 78.0 75.0 71.0 68.0 70.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.25 (1.5%) stddev-sans-first:1.24 11:51:17 INFO - PROCESS | 2289 | Values: 84.0 83.0 81.0 83.0 82.0 81.0 83.0 83.0 82.0 85.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:145.00 stddev:11.06 (7.6%) stddev-sans-first:11.42 11:51:17 INFO - PROCESS | 2289 | Values: 135.0 128.0 163.0 146.0 153.0 141.0 133.0 130.0 149.0 144.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:187.50 stddev:23.32 (12.4%) stddev-sans-first:24.53 11:51:17 INFO - PROCESS | 2289 | Values: 196.0 232.0 191.0 184.0 180.0 167.0 170.0 168.0 166.0 222.0 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - PROCESS | 2289 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:2.63 (3.8%) stddev-sans-first:2.65 11:51:17 INFO - PROCESS | 2289 | Values: 72.0 67.0 70.0 65.0 70.0 70.0 74.0 70.0 67.0 71.0 11:51:17 INFO - PROCESS | 2289 | -------- Summary: end -------- 11:51:17 INFO - PROCESS | 2289 | 11:51:17 INFO - TEST-INFO | 2289: exit 0 11:51:17 INFO - TEST-OK | kraken | took 60172ms 11:51:17 INFO - SUITE-END | took 1171s 11:51:18 INFO - Completed test suite (00:19:32) 11:51:18 INFO - javascript benchmark 11:51:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.313185796912, 3430.2110514581645, 3437.1407148212948, 3446.6208717041136, 3482.651734826516, 5659.915101273484, 5674.744636491363, 5701.2018317435595, 5716.1711551824965, 5727.799527016556, 5680.0, 5691.686957217351, 5723.771049158039, 5724.542036637069, 5734.369219385864, 5156.587473002161, 5171.974140129294, 5196.220566914974, 5229.921551176735, 5277.947220527803, 4231.4499115114995, 5260.210968354739, 5296.152615581508, 5302.893942121155, 5316.388615309231, 4330.437043184382, 5221.921671174917, 5235.476452354753, 5245.790168393279, 5273.235380869772, 4290.892727681801, 5233.816816411445, 5255.264263003182, 5258.290130832327, 5266.841994740125, 4237.745735255864, 4728.550787675165, 5132.820351287724, 5174.249733788617, 5201.037808005493, 5648.576356773217, 5682.261306030231, 5740.225069615591, 5760.74076666551, 5770.6249093808765, 6022.849428764316, 6160.0759886017, 6160.6611636359985, 6170.907436388457, 6196.442320191204, 6145.832291864532, 6224.253089629274, 6257.7184026718905, 6274.905876411857, 6276.152719382871, 5840.299164100293, 5848.210491583667, 5855.092460668603, 5858.76564937402, 5886.117082437626, 6291.245050593867, 6293.465055470337, 6302.401271879165, 6320.241571011416, 6331.651759153291, 6019.217501724747, 6116.701002199504, 6116.724747386378, 6181.81454556364, 6229.283632382243, 6254.093156492374, 6298.433141017331, 6312.433475086561, 6319.083732858801, 6320.431161195423, 6199.628022318675, 6245.500359971283, 6250.343713910065, 6276.121343011982, 6280.057991301342, 6063.575549711478, 6157.907631385533, 6181.907271390933, 6225.657588832567, 6284.874302513924, 4897.877553061131, 4901.43633482147, 4914.827981020647, 4915.754212289371, 4923.926141107886, 4300.956990430056, 4454.910901782011, 4463.620592249695, 4464.888377790581, 4479.888002799956, 5714.20001199824, 5764.855878603068, 5767.625104368202, 5770.682612456355, 5792.1311803229855, 6263.937360626426, 6295.307516173218, 6299.275583307886, 6330.651814150266, 6336.366363363627, 4818.939833236683, 5040.924386134211, 5510.0, 5776.595638305363, 5808.099744539603, 5318.680879147263, 5389.326334208224, 5391.0, 5408.432114627986, 5465.453454654582, 6272.3100458949475, 6324.715387807468, 6330.841728956813, 6342.841428964312, 6348.5238607105575, 6154.1999540059505, 6180.134781130555, 6237.719302631302, 6288.65412402304, 6327.493800496041, 6282.968585157045, 6336.398042185985, 6368.554201205871, 6370.076338930737, 6485.383888530582, 6199.721012554536, 6234.906476402767, 6265.498760099273, 6275.403836635513, 6276.403741644536, 6116.396640261158, 6185.41238582334, 6229.688515574113, 6295.244570651551, 6310.305866354605, 6103.145559621567, 6110.7555697771595, 6116.694165291807, 6116.847078823065, 6117.785877494266, 5996.760129594942, 6092.725827337869, 6139.631622102688, 6158.199434073542, 6165.167702360303, 5793.623414478046, 5857.1799948008165, 5944.0, 5949.791757288474, 5961.7615295387695, 6114.174586430775, 6129.3564175762685, 6149.846253843511, 6152.692365381802, 6155.076738489083, 5841.4450627190345, 6007.87984240304, 6016.0, 6021.518278537794, 6026.8794624106395], "value": 5686.9528059177555, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.2657463115061, 526.6339893773828, 547.1683041776497, 573.8408962152085, 614.7264467312045, 37594.0, 39202.607973920254, 39573.60426395731, 39626.60373396272, 39775.801120994394, 39877.80061099698, 40306.798466007625, 40415.0, 40428.0, 40547.986300342505, 8586.184312490335, 9286.721398358026, 9459.054094590554, 9677.419354838732, 9699.854502182472, 9504.239660827117, 9618.0, 9728.17310528606, 9748.902510974905, 9752.853707194397, 9674.226061915066, 9690.903090969106, 9695.90304096957, 9731.416115033086, 9744.853827192563, 9665.033496650349, 9707.0, 9709.174720148796, 9722.611095556169, 9729.270304727173, 9601.0, 9677.274204434696, 9728.270379721549, 9729.56216970238, 9794.853077203848, 36064.81967590145, 39868.60131398678, 40088.79955600233, 40097.59902400968, 40293.79853100716, 3371.089805752433, 3495.196104895863, 3516.261585067139, 3809.866654667101, 3814.5803961564484, 28160.718392816016, 28172.859135704395, 28178.295542611395, 28729.856350718113, 29174.70825291741, 2739.9726002739917, 2804.326961529239, 2840.6733225679104, 2844.3742376677305, 2866.870990805419, 393353.0, 394457.0, 398132.0, 398650.0, 398730.0, 36611.63388366136, 37510.874673759834, 37511.43732844009, 60620.696896516114, 61249.693751530955, 35818.0, 35915.461268081, 35991.640083599355, 36183.09542261412, 36279.0, 15768.605784855241, 16060.357585696442, 16197.919010404872, 16277.674446511004, 16298.918505407397, 13340.399682014333, 13346.0, 13423.127496712683, 13544.59366219015, 13574.0, 7886.960565197137, 8317.8336433271, 8357.080721120672, 8389.45468544555, 8426.0, 3070.142563748944, 3506.701930335899, 3515.3848076586496, 3519.3665140274998, 3534.310809392195, 1025.4821315235758, 1031.633770011635, 1061.103367654331, 1063.547992103353, 1090.9945450272858, 30774.0, 31036.91370802011, 31141.8442907784, 31330.21674458111, 31360.0, 1053.8103141434615, 1089.9019088282093, 1124.2804605052609, 1142.6800495861173, 1145.2613064573304, 15529.428092750353, 15722.921385393229, 15774.605634859221, 15806.288717007763, 16124.193790310437, 633696.0, 635547.0, 636598.0, 644768.0, 648539.0, 50245.9950800982, 59743.701281494185, 62272.68863655653, 62601.686991565664, 63706.36293637097, 1194.8207768834745, 1466.0836976889232, 1468.6842328899124, 1513.1828812441158, 1554.1141549317008, 30179.0, 30249.975002499574, 30600.11497413153, 30679.386412271182, 30789.538156927218, 55183.17225241705, 55184.0, 55692.0, 55710.72144639251, 55889.44110558843, 553351.0, 555132.0, 559300.0, 559752.0, 559777.0, 27730.5840412398, 28026.0, 28050.579241311803, 28451.573226402033, 28494.57258141171, 47282.52717472781, 48819.0, 49001.2649810246, 49425.27011554578, 49488.50511494983, 38385.808070959465, 39161.0, 39380.80309598434, 39584.40623390594, 39982.60017399789, 30327.848360758057, 38971.415428769156, 39270.21459570735, 39393.60606393899, 39594.0, 37765.0, 38474.0381490465, 38497.42253866138, 38852.80573597227, 39197.804010979766, 33749.0, 33948.83025584856, 34722.70134337234, 34883.0, 34931.0, 25748.742512574634, 25789.613155802304, 25985.0, 25998.8300526473, 26526.469470610093], "value": 18778.274290232886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.11280296151443, 406.58122134201744, 407.18359688823915, 412.558562338298, 416.08253800370187, 14903.701925961474, 19886.303979360724, 20288.797112028893, 20408.897955510238, 20597.38207853765, 20038.0, 20188.091535880885, 20505.692414613757, 20538.69191962122, 20830.791692083076, 5584.497395234438, 5637.915431268534, 5858.179854820329, 5873.177755114288, 6007.609505382158, 5837.5913686041995, 5913.497352725002, 5928.644281343111, 5943.227380440559, 5945.85135371617, 5624.240727501776, 5860.033094539417, 5863.1791549182935, 5893.44012318829, 5907.911381329283, 5718.056520674084, 5759.308882934054, 5778.133280007991, 5814.651120932736, 5818.621789583706, 5634.915476267859, 5648.858778530528, 5660.858478538028, 5703.74333155009, 5815.79644712433, 13033.087683862039, 13327.533536326278, 20009.499762505908, 24326.756732432805, 24431.389215269584, 2121.8726876387313, 2124.7450305963384, 2127.8723276603455, 2175.7497887742798, 2177.2379667116543, 9732.367396119229, 10124.29129960903, 10563.366198028143, 13577.59267221985, 14695.77956330656, 1716.76823628811, 1725.3961113610262, 1744.3371518822894, 1801.63976197971, 1811.3931832836015, 98568.0, 111224.99999999919, 115184.42407787907, 121926.78073219243, 124859.9999999991, 9135.862962055575, 9363.271547868282, 9518.286128540249, 11718.589849355401, 12113.576024839089, 14801.259937003322, 14808.555743327717, 14826.481073162387, 14845.480408185662, 14884.0, 8538.103499132625, 8660.0, 8693.913060869438, 8721.30229581632, 8819.911800882037, 8663.826723465621, 8668.696595619123, 8735.868961965576, 8749.56252187388, 8817.426867253736, 3728.7762734236035, 4549.45406551216, 4625.560571745679, 4637.142128706133, 4645.628349732014, 1708.6411853510654, 1963.9901800490907, 1978.4163671716879, 1978.6141702368188, 2026.9290574830104, 912.270183852915, 966.0, 971.6162115964155, 1004.0862814838462, 1056.8837427882927, 18506.44480665582, 19124.139413726414, 19595.0, 19981.60036799285, 20109.0, 1005.4118340770585, 1009.6819501856892, 1020.7295066807302, 1024.6157690865925, 1027.2193133218807, 10186.64346747875, 10330.793384132276, 10331.896681033093, 10343.4311112888, 10371.429571373646, 578828.105859468, 580380.0, 580947.0, 639628.0, 646508.0, 121574.0, 125199.37400313122, 126145.36927315305, 126713.73286267204, 127137.0, 1049.6693541534546, 1054.741588310884, 1066.1417558865032, 1070.7590792071721, 1071.1377341240377, 16955.66088678195, 17102.657946841242, 17410.390636328175, 17434.651306974043, 17748.467545973643, 51781.22328165156, 54428.72785636205, 55126.44873551213, 55348.0, 56634.0, 78390.21609783829, 79342.6032869832, 79697.60151199206, 79933.60033199796, 83504.16495835206, 8041.356691464552, 8066.556339401389, 8078.394120440873, 8094.83810323802, 8373.539455329772, 11656.766864662828, 12050.638480845588, 12310.322932238812, 12314.876851231373, 12470.127091103535, 9981.850272246067, 10460.581576736846, 10564.841527376942, 10660.520276587727, 10732.087772539422, 10258.179345652181, 10275.897241027495, 10399.428031458343, 10587.788244234918, 10652.680419587425, 10367.118794902517, 10911.508982095665, 11069.889301106887, 11097.944510277397, 11163.888361116286, 7675.769726908202, 8040.5979701015885, 8176.0, 8239.835203296021, 8288.668453262044, 9394.483303418105, 9459.581062840629, 10588.152947764314, 10603.41681207541, 10758.46207689628], "value": 9432.14635525484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1592.3391792406148, 1605.181357507671, 1843.336398896398, 1902.5338791995955, 1981.8018198180175, 7712.15166331703, 8546.70086546971, 8656.74029779106, 8816.250618697402, 8869.113088691136, 8546.102659220785, 8587.91412085879, 8727.956360218206, 8731.519766412845, 8881.023087460375, 2126.255810466332, 2317.8989979759563, 2396.05355884426, 2429.2712186344024, 2439.3901524618846, 2407.807375409972, 2426.5510880487077, 2447.8898449569724, 2453.521563295158, 2461.360046387943, 2426.4783071639595, 2430.0, 2443.6456713776493, 2487.9004839806385, 2498.725140234573, 2448.326710154704, 2455.7667021633006, 2467.469494058786, 2472.24596498068, 2482.1808803094937, 2441.4140606254373, 2462.0398044762555, 2478.467129567152, 2479.4049428137296, 2513.421912960011, 8865.0, 8905.777355566099, 8909.1536304051, 8913.55432228386, 9028.368014239006, 5337.839864804062, 5483.643563168382, 5505.229267902497, 5509.944900551023, 5548.0, 3474.930501389958, 3553.0, 3607.4047782115917, 3608.332458495187, 3626.0934766308424, 1024.4826362686795, 1046.1212581431635, 1046.2257929132463, 1047.6804574604741, 1048.6067724603274, 11771.470283837249, 11857.762844743056, 11887.405629718565, 11914.642560723105, 11927.0, 4193.454850869429, 4735.976320118446, 4750.049990002013, 4806.50998190562, 4914.508549145057, 10176.949115254376, 10190.0, 10212.387256764468, 10227.43749093792, 10242.846357304647, 5437.945620543744, 5438.91841622376, 5447.073997420369, 5454.672719636756, 5480.479354461319, 4400.977995110004, 4808.27875818623, 4896.608271338285, 4928.950710492921, 4958.082754690394, 4054.8378064877666, 4100.712950093524, 4103.349432599078, 4181.456410666594, 4190.8533201337805, 1240.299230934519, 1342.852286248512, 1370.9245991470366, 1379.3861731529373, 1389.6803735140975, 787.7006737439737, 911.7674992876774, 921.741912264567, 927.9164875161133, 939.9530023498797, 5612.887742245132, 5702.800401985911, 5835.416458354215, 5870.882582348329, 5936.762529498858, 944.0559440559441, 986.9753256168653, 995.1988649137369, 1017.4251547875426, 1042.2599954032721, 3352.6312105668358, 3621.511096002059, 3777.58446570877, 3844.7116466265143, 3881.8059097045034, 10404.271700980858, 10684.786304273872, 10827.78344433107, 10840.78318433627, 10842.783144337069, 10689.83965240522, 10711.892881071246, 10811.78376432467, 10857.402842843561, 10947.890521094847, 8304.211099945496, 8431.0, 8511.702090426805, 8524.95737521321, 8587.570621469027, 5767.192593037062, 5783.8554036149435, 5823.79616713413, 5922.881542369215, 5962.880742385215, 9407.43555386679, 9411.341206115507, 9540.666076687283, 9627.807443850943, 9786.706398808046, 8473.601855693989, 8541.359398045295, 9078.955920069013, 9772.804543909224, 9929.553170107218, 6617.2721000688925, 7158.606276654834, 7190.316919892602, 7404.666789994355, 7462.552246865205, 8222.013358397031, 8276.668933242603, 8307.584620769057, 8310.335173186251, 8403.243708066304, 7231.2768723127265, 7291.526050806893, 7458.179600244077, 7553.055868016498, 7579.962100189464, 7271.854562908607, 7344.339009488958, 7346.853062938818, 7449.180590135189, 7519.210482899217, 6957.339052789976, 6982.0, 7050.788476345718, 7069.646517673993, 7107.538010029331, 4713.128071306819, 4726.125666751662, 4818.229083346788, 4931.186354251508, 4951.0, 4774.40319960005, 4801.159797035435, 4834.274858771212, 4891.584215341736, 5137.972405518986], "value": 4626.325811681546, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.91146390474574, 328.09282334345534, 331.34228556315725, 332.7304883044734, 335.6140438495728, 6840.076589660401, 6853.897191542124, 6888.931110688892, 6893.0, 6897.965510172455, 6759.053732477445, 6772.593644381328, 6773.424258937984, 6827.453803695705, 6834.282400347966, 4749.738764367967, 4810.975945120287, 4945.7774400152075, 4949.480304568023, 4966.031623833354, 4902.583280421169, 4935.111679897617, 4957.479464656213, 4960.107180707471, 4970.130227210242, 4885.584725298354, 4904.681195722284, 4913.238448040559, 4936.210206366982, 4941.604671626279, 4918.950810491885, 4923.4091908970795, 4925.064237794808, 4927.457979622239, 4971.900561988776, 4251.086016506466, 4265.658747300239, 4266.274733295316, 4277.251480990814, 4297.828086876521, 6381.872362552769, 6533.93466065338, 6571.868562628721, 6611.404973552403, 6623.07276981223, 5997.190379298782, 5998.7600495980105, 6026.156338112668, 6040.788572399989, 6077.1491991121275, 7312.707491700326, 7326.230745771723, 7336.743213987538, 7340.1191856976975, 7353.963230183868, 4808.903821923542, 4812.831550895737, 4818.373611430527, 4830.2030165022725, 4832.589229915461, 6485.189351331083, 7419.258074192591, 7566.43251756115, 7711.884321735179, 7753.496022758559, 4883.6093112551625, 4891.853244402673, 4892.877678058077, 4896.755162241873, 4903.04390643821, 4934.235193545006, 4975.751212439436, 4983.07813054586, 4986.376702912136, 4994.750262486861, 5912.906112369225, 5918.940810591839, 5937.139114828326, 5942.910856337158, 6055.576109672281, 7344.632768361667, 7354.70581176747, 7354.926450735531, 7381.66782494789, 7385.076865391826, 1721.6470623522148, 1736.2620886123348, 1740.5909611241348, 1743.311392000178, 1745.659596378694, 847.5380917399908, 1065.4086981725218, 1069.9144068474504, 1072.2922870905165, 1072.367303291062, 6771.0, 6811.931880681229, 6812.420944219698, 6812.931870681329, 6829.965850170816, 6521.934780652227, 6568.86862262752, 7210.846264597639, 7601.0, 7627.466077374531, 5555.0, 6306.148669929501, 6864.691088900911, 6922.619255940871, 6963.512554121163, 3037.407705497407, 3549.254656522108, 3974.0462289050997, 4088.2436749201493, 4095.52901416335, 8140.023197216372, 8141.918580814235, 8152.103268640445, 8154.8776768348525, 8180.9181908181345, 6502.0, 6579.671016449159, 7742.961285193651, 7942.086660034054, 8016.839663206703, 8050.959745201237, 8088.029436467779, 8092.473989190684, 8113.229243221885, 8131.7560473185895, 7707.92292077072, 7722.0, 7731.806704832424, 7748.496347737604, 7751.3411360035025, 7594.0, 7602.8099297518, 7616.9238307616215, 7621.12357078943, 7636.732714354971, 7809.570473624005, 8105.148959359182, 8114.553699546582, 8119.5534245617055, 8128.105908350195, 4780.6175505958745, 4785.0, 4805.423349198119, 4811.807527698853, 4812.302216178705, 7676.232376762502, 7678.577678227528, 7683.5005724629955, 7695.0, 7700.383969282332, 7365.926340736524, 7402.518836275625, 7408.222136675787, 7419.258074192538, 7423.0, 6300.401461861115, 7181.425485961006, 7291.234420385996, 7407.925920740724, 7413.332800047806, 7202.459815513962, 7253.347198752138, 7253.492255542062, 7259.2377800330205, 7262.419006479363, 5923.378045305353, 5947.286325640951, 6711.697973591103, 7374.2994415532585, 7383.741569045058, 6956.7217311309005, 7241.891371629536, 7244.275572442924, 7254.238304978113, 7255.56466612005], "value": 5324.519713448859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.6626523483765, 681.8534015186734, 688.4733179117976, 691.9515633905627, 755.176857225624, 12503.312317822527, 14799.92600037001, 14953.925230373832, 14979.101253924759, 15003.849961500395, 12436.316002619853, 13679.0, 13698.589042328722, 13703.794443083338, 13720.862791372083, 896.9820603587924, 923.9097864519864, 937.5078084005884, 939.7180845746283, 942.853857652065, 906.6373450619772, 932.6875496708611, 946.1484663802564, 947.924166066713, 948.3029972969896, 960.9807803843919, 985.8373368394207, 990.8464188050827, 991.8165139449189, 995.9850602240972, 913.6341169950899, 919.3197034194715, 955.8136163448165, 973.9220862331032, 978.6036655154674, 596.2219303808528, 603.610671117127, 688.435482904019, 711.0542977839513, 717.2361435071632, 13230.470781168742, 14000.789988150185, 15053.322600483005, 15114.546563603, 15172.393104275816, 12632.747345053047, 12704.237745735285, 12793.48826046957, 12855.614331570068, 12989.61031169057, 10303.02121298483, 10355.792884142274, 10374.0, 10509.474526273656, 10535.894641053568, 3515.542979412686, 3518.9296214075575, 3588.2464682416726, 3596.1908570571672, 3733.831977560989, 86992.0, 87999.0, 88529.11470885274, 88964.5551772237, 89669.0, 9312.813743725088, 9393.389429687048, 9404.858927116098, 9545.809083818285, 9578.808423831484, 21192.682109768364, 21202.469938251357, 21228.25701100485, 21243.68134477984, 21289.467763306042, 7196.424286057103, 7207.423406127497, 7209.315115064061, 7249.565026098451, 7324.304191101837, 4004.519457665099, 4008.6392224699916, 4063.6952228582973, 4065.6747460203114, 4208.095259519157, 3425.160835595295, 3479.0258727556325, 3490.47642853574, 3495.811424115762, 3502.7197824174004, 632.9050642403585, 656.4485831901226, 665.4244078871837, 682.0383259603934, 695.085961960021, 515.7679044429947, 520.5679286192451, 542.4846395923888, 589.7994681808206, 595.9204648772554, 8062.879056814152, 8302.294304984143, 8313.376496762767, 8447.070822209675, 8605.881235439478, 504.3216873305398, 583.2592607388593, 593.7595273914071, 602.093848757617, 611.7767015039478, 3477.791332520057, 3801.7718936863876, 3867.497225360685, 3926.469926559878, 4068.7965601719798, 97100.51449742707, 97626.0, 97773.0, 99036.50481747689, 100448.0, 68292.31707682859, 75510.62244688741, 83433.16566834375, 84412.15587844166, 84668.0, 71000.0, 72455.0, 73032.0, 73162.0, 73388.99999999894, 7796.837375567992, 7884.369250460064, 7901.841963160819, 7933.0, 8241.134680858424, 38742.80628596839, 49695.00609987853, 50481.0, 50487.74756126196, 50781.746091269306, 46368.536314636425, 66032.0, 67656.323436765, 71706.28293716996, 73438.0, 8573.91426085756, 9111.407758495676, 9981.201503879527, 10132.240081993732, 10911.563537458413, 11877.524899003944, 12038.337891416057, 12096.939515302367, 12180.147389682637, 12450.128491005542, 11588.246763960316, 11591.88408115908, 11634.825477617673, 11748.412579371168, 11892.108091893315, 7873.212678732081, 10397.064264216257, 10978.945105274423, 11413.828792567952, 12059.51761929513, 10917.0, 11165.553377864795, 11176.313154842881, 11243.718907027389, 11370.431478426211, 9728.610855565981, 9737.561809718438, 9751.756206094904, 9811.607535698493, 9936.056074672895, 7884.72403465876, 9414.576344064397, 9726.70819875405, 9765.2676049298, 9765.316427850266], "value": 6861.091256533663, "unit": "score"}], "name": "dromaeo_css", "value": 7444.719215765539, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 119.0, 103.0, 121.0, 120.0, 117.0, 105.0, 141.0, 170.0, 155.0], "value": 126.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 160.0, 158.0, 127.0, 140.0, 131.0, 190.0, 192.0, 141.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 198.0, 204.0, 199.0, 197.0, 228.0, 202.0, 275.0, 285.0], "value": 218.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 83.0, 143.0, 47.0, 71.0, 82.0, 99.0, 131.0, 58.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 108.0, 136.0, 82.0, 115.0, 142.0, 133.0, 83.0, 83.0, 123.0], "value": 108.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 112.0, 135.0, 105.0, 89.0, 85.0, 88.0, 86.0, 87.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 124.0, 146.0, 150.0, 94.0, 125.0, 104.0, 152.0], "value": 117.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 93.0, 92.0, 91.0, 94.0, 91.0, 91.0, 92.0, 90.0, 87.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 67.0, 64.0, 67.0, 66.0, 67.0, 80.0, 64.0, 59.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 72.0, 76.0, 75.0, 78.0, 75.0, 71.0, 68.0, 70.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 81.0, 83.0, 82.0, 81.0, 83.0, 83.0, 82.0, 85.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 128.0, 163.0, 146.0, 153.0, 141.0, 133.0, 130.0, 149.0, 144.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 232.0, 191.0, 184.0, 180.0, 167.0, 170.0, 168.0, 166.0, 222.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 70.0, 65.0, 70.0, 70.0, 74.0, 70.0, 67.0, 71.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1616.8999999999999, "alertThreshold": 2.0}]} 11:51:18 INFO - Return code: 0 11:51:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:51:18 ERROR - # TBPL SUCCESS # 11:51:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:51:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:51:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:51:18 INFO - 2016-05-19 11:51:18.340 screenresolution[2294:22754] starting screenresolution argv=screenresolution get 11:51:18 INFO - 2016-05-19 11:51:18.343 screenresolution[2294:22754] Display 0: 1600x1200x32@60 11:51:18 INFO - 2016-05-19 11:51:18.349 screenresolution[2295:22757] starting screenresolution argv=screenresolution list 11:51:18 INFO - Available Modes on Display 0 11:51:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:51:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:51:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:51:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:51:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:51:18 INFO - Intel Iris: 11:51:18 INFO - Chipset Model: Intel Iris 11:51:18 INFO - Type: GPU 11:51:18 INFO - Bus: Built-In 11:51:18 INFO - VRAM (Dynamic, Max): 1536 MB 11:51:18 INFO - Vendor: Intel (0x8086) 11:51:18 INFO - Device ID: 0x0a2e 11:51:18 INFO - Revision ID: 0x0009 11:51:18 INFO - Displays: 11:51:18 INFO - Display: 11:51:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:51:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:51:18 INFO - Display Serial Number: 1600x1200 60 11:51:18 INFO - Main Display: Yes 11:51:18 INFO - Mirror: Off 11:51:18 INFO - Online: Yes 11:51:18 INFO - Rotation: Supported 11:51:18 INFO - Return code: 0 11:51:18 INFO - Running post-action listener: _resource_record_post_action 11:51:18 INFO - Running post-run listener: _resource_record_post_run 11:51:19 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 483328; Write bytes: 657846272; Read time: 6; Write time: 1927 11:51:19 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345014784; Write bytes: 346625024; Read time: 26599; Write time: 744 11:51:19 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 479232; Write bytes: 301263872; Read time: 5; Write time: 1148 11:51:19 INFO - Running post-run listener: _upload_blobber_files 11:51:19 INFO - Blob upload gear active. 11:51:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:19 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 11:51:19 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'] 11:51:19 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 11:51:20 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:51:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:21 INFO - (blobuploader) - INFO - Done attempting. 11:51:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:51:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:51:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:51:22 INFO - (blobuploader) - INFO - Done attempting. 11:51:22 INFO - (blobuploader) - INFO - Iteration through files over. 11:51:22 INFO - Return code: 0 11:51:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:51:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:51:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9353c4edcaeabccca996d5ca88b4f80b9c4a4bef0ba5957d7f8557201a629d09aa24ae191977429e0d6df0d959a3ae69d2be6b0117ef2ba8170a77eb07ec1a09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9b4a9275f9d02481b66dc3d3313156fc3e3247dc6643c4117eee1faf3f3aedce5e526c1349582cbd9b70f95ebdbd40227623658ebea769dab4327f070db52a0"} 11:51:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:51:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:51:22 INFO - Contents: 11:51:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9353c4edcaeabccca996d5ca88b4f80b9c4a4bef0ba5957d7f8557201a629d09aa24ae191977429e0d6df0d959a3ae69d2be6b0117ef2ba8170a77eb07ec1a09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9b4a9275f9d02481b66dc3d3313156fc3e3247dc6643c4117eee1faf3f3aedce5e526c1349582cbd9b70f95ebdbd40227623658ebea769dab4327f070db52a0"} 11:51:22 INFO - Running post-run listener: copy_logs_to_upload_dir 11:51:22 INFO - Copying logs to upload dir... 11:51:22 INFO - mkdir: /builds/slave/test/build/upload/logs 11:51:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1530.286505 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 30 secs) (at 2016-05-19 11:51:22.767927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 11:51:22.772911) ========= 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.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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/9353c4edcaeabccca996d5ca88b4f80b9c4a4bef0ba5957d7f8557201a629d09aa24ae191977429e0d6df0d959a3ae69d2be6b0117ef2ba8170a77eb07ec1a09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9b4a9275f9d02481b66dc3d3313156fc3e3247dc6643c4117eee1faf3f3aedce5e526c1349582cbd9b70f95ebdbd40227623658ebea769dab4327f070db52a0"} build_url:https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010931 build_url: 'https://queue.taskcluster.net/v1/task/JOuFNvVwTWW9SO0leZlkHA/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/9353c4edcaeabccca996d5ca88b4f80b9c4a4bef0ba5957d7f8557201a629d09aa24ae191977429e0d6df0d959a3ae69d2be6b0117ef2ba8170a77eb07ec1a09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e9b4a9275f9d02481b66dc3d3313156fc3e3247dc6643c4117eee1faf3f3aedce5e526c1349582cbd9b70f95ebdbd40227623658ebea769dab4327f070db52a0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 11:51:22.802276) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:51:22.802596) ========= 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.9u4AUewvMr/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.0DpPpPWujz/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:51:22.851085) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 11:51:22.851409) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 11:51:22.851730) ========= ========= Total master_lag: 0.46 =========