builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1461861172.04 results: success (0) buildid: 20160428073807 builduid: cf8b813ee5d746f9ac2dd336f9f9ce55 revision: 4292da9df16b220f30921100282264a34cd2ff03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.040408) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.040868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.041175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.116453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.116763) ========= 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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-04-28 09:32:52-- 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% 168M=0s 2016-04-28 09:32:52 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.583873 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.723250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.723567) ========= 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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.027720 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.773474) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:52.773923) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4292da9df16b220f30921100282264a34cd2ff03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4292da9df16b220f30921100282264a34cd2ff03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-04-28 09:32:52,851 truncating revision to first 12 chars 2016-04-28 09:32:52,851 Setting DEBUG logging. 2016-04-28 09:32:52,851 attempt 1/10 2016-04-28 09:32:52,851 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4292da9df16b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 09:32:53,478 unpacking tar archive at: mozilla-central-4292da9df16b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.865903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:53.658030) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:53.658373) ========= script_repo_revision: 4292da9df16b220f30921100282264a34cd2ff03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:53.658804) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:53.659118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:32:53.674774) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-04-28 09:32:53.675160) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:32:53 INFO - MultiFileLogger online at 20160428 09:32:53 in /builds/slave/test 09:32:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:32:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:53 INFO - {'append_to_log': False, 09:32:53 INFO - 'base_work_dir': '/builds/slave/test', 09:32:53 INFO - 'blob_upload_branch': 'Firefox', 09:32:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:53 INFO - 'branch': 'Firefox', 09:32:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:53 INFO - 'config_files': ('talos/mac_config.py',), 09:32:53 INFO - 'default_actions': ('clobber', 09:32:53 INFO - 'read-buildbot-config', 09:32:53 INFO - 'download-and-extract', 09:32:53 INFO - 'populate-webroot', 09:32:53 INFO - 'create-virtualenv', 09:32:53 INFO - 'install', 09:32:53 INFO - 'run-tests'), 09:32:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:53 INFO - 'download_minidump_stackwalk': True, 09:32:53 INFO - 'download_symbols': 'ondemand', 09:32:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:53 INFO - 'installer_path': 'installer.exe', 09:32:53 INFO - 'log_level': 'info', 09:32:53 INFO - 'log_name': 'talos', 09:32:53 INFO - 'log_to_console': True, 09:32:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:53 INFO - 'opt_config_files': (), 09:32:53 INFO - 'pip_index': False, 09:32:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:53 INFO - 'cmd': ('bash', 09:32:53 INFO - '-c', 09:32:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:53 INFO - 'enabled': True, 09:32:53 INFO - 'halt_on_failure': False, 09:32:53 INFO - 'name': 'check_screen_resolution'},), 09:32:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:53 INFO - 'cmd': ('bash', 09:32:53 INFO - '-c', 09:32:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:53 INFO - 'enabled': True, 09:32:53 INFO - 'halt_on_failure': False, 09:32:53 INFO - 'name': 'check_screen_resolution'},), 09:32:53 INFO - 'run_cmd_checks_enabled': True, 09:32:53 INFO - 'sps_profile': False, 09:32:53 INFO - 'sps_profile_interval': 0, 09:32:53 INFO - 'suite': 'dromaeojs-e10s', 09:32:53 INFO - 'system_bits': '32', 09:32:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:53 INFO - 'title': 't-yosemite-r7-0026', 09:32:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:53 INFO - 'use_talos_json': True, 09:32:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:53 INFO - 'work_dir': 'build'} 09:32:53 INFO - ##### 09:32:53 INFO - ##### Running clobber step. 09:32:53 INFO - ##### 09:32:53 INFO - Running pre-action listener: _resource_record_pre_action 09:32:53 INFO - Running main action method: clobber 09:32:53 INFO - rmtree: /builds/slave/test/build 09:32:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:55 INFO - Running post-action listener: _resource_record_post_action 09:32:55 INFO - ##### 09:32:55 INFO - ##### Running read-buildbot-config step. 09:32:55 INFO - ##### 09:32:55 INFO - Running pre-action listener: _resource_record_pre_action 09:32:55 INFO - Running main action method: read_buildbot_config 09:32:55 INFO - Using buildbot properties: 09:32:55 INFO - { 09:32:55 INFO - "project": "", 09:32:55 INFO - "product": "firefox", 09:32:55 INFO - "script_repo_revision": "production", 09:32:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:32:55 INFO - "repository": "", 09:32:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:32:55 INFO - "buildid": "20160428073807", 09:32:55 INFO - "pgo_build": "False", 09:32:55 INFO - "basedir": "/builds/slave/test", 09:32:55 INFO - "buildnumber": 217, 09:32:55 INFO - "slavename": "t-yosemite-r7-0026", 09:32:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:32:55 INFO - "platform": "yosemite_r7", 09:32:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:32:55 INFO - "branch": "mozilla-central", 09:32:55 INFO - "repo_path": "mozilla-central", 09:32:55 INFO - "revision": "4292da9df16b220f30921100282264a34cd2ff03", 09:32:55 INFO - "stage_platform": "macosx64", 09:32:55 INFO - "builduid": "cf8b813ee5d746f9ac2dd336f9f9ce55", 09:32:55 INFO - "slavebuilddir": "test" 09:32:55 INFO - } 09:32:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:32:55 INFO - Running post-action listener: _resource_record_post_action 09:32:55 INFO - ##### 09:32:55 INFO - ##### Running download-and-extract step. 09:32:55 INFO - ##### 09:32:55 INFO - Running pre-action listener: _resource_record_pre_action 09:32:55 INFO - Running main action method: download_and_extract 09:32:55 INFO - mkdir: /builds/slave/test/build/tests 09:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:55 INFO - https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:32:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:32:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:32:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:32:58 INFO - Downloaded 1199 bytes. 09:32:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:32:58 INFO - Using the following test package requirements: 09:32:58 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:32:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:32:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:32:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'jsshell-mac.zip'], 09:32:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:32:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:32:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:32:58 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:32:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:32:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:32:58 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:32:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:58 INFO - https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:32:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:32:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:32:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:32:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:33:05 INFO - Downloaded 18124040 bytes. 09:33:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:33:08 INFO - Return code: 0 09:33:08 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:33: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')]} 09:33:08 INFO - https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:33:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:33:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:33:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:33:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:33:25 INFO - Downloaded 18124040 bytes. 09:33:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:33:28 INFO - Return code: 0 09:33:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:28 INFO - https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:33:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:33:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:33:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:33:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:33:30 INFO - Downloaded 10840145 bytes. 09:33:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:33:30 INFO - Return code: 0 09:33: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')]} 09:33:30 INFO - https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:33:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:33:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:33:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:33:38 INFO - Downloaded 110282734 bytes. 09:33:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:33:38 INFO - mkdir: /builds/slave/test/properties 09:33:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:38 INFO - Contents: 09:33:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:33:39 INFO - Running post-action listener: _resource_record_post_action 09:33:39 INFO - Running post-action listener: set_extra_try_arguments 09:33:39 INFO - ##### 09:33:39 INFO - ##### Running populate-webroot step. 09:33:39 INFO - ##### 09:33:39 INFO - Running pre-action listener: _resource_record_pre_action 09:33:39 INFO - Running main action method: populate_webroot 09:33:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:39 INFO - '--tppagecycles', 09:33:39 INFO - '7'], 09:33:39 INFO - 'tests': ['tsvgm']}}, 09:33:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['damp', 'tps']}, 09:33:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'tests': ['damp', 'tps']}, 09:33:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['dromaeo_dom']}, 09:33:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['a11yr', 09:33:39 INFO - 'ts_paint', 09:33:39 INFO - 'tpaint', 09:33:39 INFO - 'sessionrestore', 09:33:39 INFO - 'sessionrestore_no_auto_restore', 09:33:39 INFO - 'tabpaint']}, 09:33:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['tsvgx', 09:33:39 INFO - 'tsvgr_opacity', 09:33:39 INFO - 'tart', 09:33:39 INFO - 'tscrollx', 09:33:39 INFO - 'cart']}, 09:33:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:39 INFO - 'tsvgr_opacity', 09:33:39 INFO - 'tart', 09:33:39 INFO - 'tscrollx', 09:33:39 INFO - 'cart']}, 09:33:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'talos_options': ['--disable-e10s'], 09:33:39 INFO - 'tests': ['tp5o']}, 09:33:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'tests': ['tp5o']}, 09:33:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'talos_options': ['--disable-e10s', 09:33:39 INFO - '--xperf_path', 09:33:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:39 INFO - 'tests': ['tp5n']}, 09:33:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:39 INFO - 'talos_options': ['--xperf_path', 09:33:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:39 INFO - 'tests': ['tp5n']}}, 09:33:39 INFO - 'talos.zip': {'path': '', 09:33:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:39 INFO - Running post-action listener: _resource_record_post_action 09:33:39 INFO - ##### 09:33:39 INFO - ##### Running create-virtualenv step. 09:33:39 INFO - ##### 09:33:39 INFO - Running pre-action listener: _resource_record_pre_action 09:33:39 INFO - Running main action method: create_virtualenv 09:33:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:39 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 09:33:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:39 INFO - Using real prefix '/tools/python27' 09:33:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:43 INFO - Installing pip.................done. 09:33:43 INFO - Return code: 0 09:33:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:33:43 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 09:33:43 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 09:33:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:33:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:43 INFO - 'HOME': '/Users/cltbld', 09:33:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:43 INFO - 'LOGNAME': 'cltbld', 09:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:43 INFO - 'MOZ_NO_REMOTE': '1', 09:33:43 INFO - 'NO_EM_RESTART': '1', 09:33:43 INFO - 'PAGER': '/bin/cat', 09:33:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:43 INFO - 'PWD': '/builds/slave/test', 09:33:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:43 INFO - 'SHELL': '/bin/bash', 09:33:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:33:43 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:33:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:43 INFO - 'USER': 'cltbld', 09:33:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:43 INFO - 'XPC_FLAGS': '0x0', 09:33:43 INFO - 'XPC_SERVICE_NAME': '0', 09:33:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:43 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 09:33:43 INFO - Cleaning up... 09:33:43 INFO - Return code: 0 09:33:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:33:43 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 09:33:43 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 09:33:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:33:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:43 INFO - 'HOME': '/Users/cltbld', 09:33:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:43 INFO - 'LOGNAME': 'cltbld', 09:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:43 INFO - 'MOZ_NO_REMOTE': '1', 09:33:43 INFO - 'NO_EM_RESTART': '1', 09:33:43 INFO - 'PAGER': '/bin/cat', 09:33:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:43 INFO - 'PWD': '/builds/slave/test', 09:33:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:43 INFO - 'SHELL': '/bin/bash', 09:33:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:33:43 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:33:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:43 INFO - 'USER': 'cltbld', 09:33:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:43 INFO - 'XPC_FLAGS': '0x0', 09:33:43 INFO - 'XPC_SERVICE_NAME': '0', 09:33:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:43 INFO - Downloading/unpacking psutil>=0.7.1 09:33:43 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 09:33:43 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 09:33:43 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 09:33:43 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 09:33:43 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 09:33:43 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 09:33:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:47 INFO - Installing collected packages: psutil 09:33:47 INFO - Running setup.py install for psutil 09:33:47 INFO - building 'psutil._psutil_osx' extension 09:33:47 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 09:33:47 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 09:33:47 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 09:33:48 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 09:33:48 INFO - building 'psutil._psutil_posix' extension 09:33:48 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 09:33:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:33:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:33:48 INFO - ^ 09:33:48 INFO - 1 warning generated. 09:33:48 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 09:33:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:48 INFO - Successfully installed psutil 09:33:48 INFO - Cleaning up... 09:33:48 INFO - Return code: 0 09:33:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:33:48 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')]} 09:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:48 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')]} 09:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:48 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:33:48 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 09:33:48 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 09:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:48 INFO - 'HOME': '/Users/cltbld', 09:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:48 INFO - 'LOGNAME': 'cltbld', 09:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:48 INFO - 'MOZ_NO_REMOTE': '1', 09:33:48 INFO - 'NO_EM_RESTART': '1', 09:33:48 INFO - 'PAGER': '/bin/cat', 09:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:48 INFO - 'PWD': '/builds/slave/test', 09:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:48 INFO - 'SHELL': '/bin/bash', 09:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:33:48 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:48 INFO - 'USER': 'cltbld', 09:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:48 INFO - 'XPC_FLAGS': '0x0', 09:33:48 INFO - 'XPC_SERVICE_NAME': '0', 09:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:33:48 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 09:33:48 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 09:33:48 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 09:33:48 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 09:33:48 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 09:33:48 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 09:33:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:33:51 INFO - Installing collected packages: mozsystemmonitor 09:33:51 INFO - Running setup.py install for mozsystemmonitor 09:33:51 INFO - Successfully installed mozsystemmonitor 09:33:51 INFO - Cleaning up... 09:33:51 INFO - Return code: 0 09:33:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:33:51 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 09:33:51 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 09:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:51 INFO - 'HOME': '/Users/cltbld', 09:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:51 INFO - 'LOGNAME': 'cltbld', 09:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:51 INFO - 'MOZ_NO_REMOTE': '1', 09:33:51 INFO - 'NO_EM_RESTART': '1', 09:33:51 INFO - 'PAGER': '/bin/cat', 09:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:51 INFO - 'PWD': '/builds/slave/test', 09:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:51 INFO - 'SHELL': '/bin/bash', 09:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:33:51 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:51 INFO - 'USER': 'cltbld', 09:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:51 INFO - 'XPC_FLAGS': '0x0', 09:33:51 INFO - 'XPC_SERVICE_NAME': '0', 09:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:51 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 09:33:51 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 09:33:51 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 09:33:51 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 09:33:51 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 09:33:51 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 09:33:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:54 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 09:33:55 INFO - Downloading docopt-0.6.1.tar.gz 09:33:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:55 INFO - Running setup.py install for blobuploader 09:33:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:55 INFO - Running setup.py install for requests 09:33:56 INFO - Running setup.py install for docopt 09:33:56 INFO - Successfully installed blobuploader requests docopt 09:33:56 INFO - Cleaning up... 09:33:56 INFO - Return code: 0 09:33:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:56 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:33:56 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 09:33:56 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 09:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:56 INFO - 'HOME': '/Users/cltbld', 09:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:56 INFO - 'LOGNAME': 'cltbld', 09:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:56 INFO - 'MOZ_NO_REMOTE': '1', 09:33:56 INFO - 'NO_EM_RESTART': '1', 09:33:56 INFO - 'PAGER': '/bin/cat', 09:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:56 INFO - 'PWD': '/builds/slave/test', 09:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:56 INFO - 'SHELL': '/bin/bash', 09:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:33:56 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:56 INFO - 'USER': 'cltbld', 09:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:56 INFO - 'XPC_FLAGS': '0x0', 09:33:56 INFO - 'XPC_SERVICE_NAME': '0', 09:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:56 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-p6WCWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-OvUmXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-6HaAJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-4tjbWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3ziMu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Eg3WmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xM6To8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-H6Mggu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-vtWn6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:57 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qG2j55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-cYczkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-NC63_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-QRSEfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qfeLfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-0uC30J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-N5qaHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:58 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-L1E2ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:58 INFO - Running setup.py install for manifestparser 09:33:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:58 INFO - Running setup.py install for mozcrash 09:33:59 INFO - Running setup.py install for mozdebug 09:33:59 INFO - Running setup.py install for mozdevice 09:33:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozfile 09:33:59 INFO - Running setup.py install for mozhttpd 09:33:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozinfo 09:33:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozInstall 09:33:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozleak 09:34:00 INFO - Running setup.py install for mozlog 09:34:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for moznetwork 09:34:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozprocess 09:34:00 INFO - Running setup.py install for mozprofile 09:34:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozrunner 09:34:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozscreenshot 09:34:01 INFO - Running setup.py install for moztest 09:34:01 INFO - Running setup.py install for mozversion 09:34:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:01 INFO - Cleaning up... 09:34:01 INFO - Return code: 0 09:34:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:01 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')]} 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 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')]} 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:34:01 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 09:34:01 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 09:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:01 INFO - 'HOME': '/Users/cltbld', 09:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:01 INFO - 'LOGNAME': 'cltbld', 09:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:01 INFO - 'MOZ_NO_REMOTE': '1', 09:34:01 INFO - 'NO_EM_RESTART': '1', 09:34:01 INFO - 'PAGER': '/bin/cat', 09:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:01 INFO - 'PWD': '/builds/slave/test', 09:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:01 INFO - 'SHELL': '/bin/bash', 09:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:34:01 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:01 INFO - 'USER': 'cltbld', 09:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:01 INFO - 'XPC_FLAGS': '0x0', 09:34:01 INFO - 'XPC_SERVICE_NAME': '0', 09:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yb0TSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-IEcqaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-FiKA2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:01 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-uCV6Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-hjjo3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ete2lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ZfVcJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-8bgIcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-5ot96p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Hn3ClU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-2XKrHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-cSs1zX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-pq9KHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:03 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-JGwXjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:03 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)) 09:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:03 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oDQX51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:03 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)) 09:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:03 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-We1Tpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:03 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)) 09:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:03 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-WRZR1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:03 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)) 09:34:03 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)) 09:34:03 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)) 09:34:03 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)) 09:34:03 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)) 09:34:03 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)) 09:34:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:05 INFO - Downloading blessings-1.6.tar.gz 09:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:05 INFO - Installing collected packages: blessings 09:34:05 INFO - Running setup.py install for blessings 09:34:06 INFO - Successfully installed blessings 09:34:06 INFO - Cleaning up... 09:34:06 INFO - Return code: 0 09:34:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:06 INFO - Reading from file tmpfile_stdout 09:34:06 INFO - Current package versions: 09:34:06 INFO - blessings == 1.6 09:34:06 INFO - blobuploader == 1.2.4 09:34:06 INFO - docopt == 0.6.1 09:34:06 INFO - manifestparser == 1.1 09:34:06 INFO - mozInstall == 1.12 09:34:06 INFO - mozcrash == 0.17 09:34:06 INFO - mozdebug == 0.1 09:34:06 INFO - mozdevice == 0.48 09:34:06 INFO - mozfile == 1.2 09:34:06 INFO - mozhttpd == 0.7 09:34:06 INFO - mozinfo == 0.9 09:34:06 INFO - mozleak == 0.1 09:34:06 INFO - mozlog == 3.1 09:34:06 INFO - moznetwork == 0.27 09:34:06 INFO - mozprocess == 0.22 09:34:06 INFO - mozprofile == 0.28 09:34:06 INFO - mozrunner == 6.11 09:34:06 INFO - mozscreenshot == 0.1 09:34:06 INFO - mozsystemmonitor == 0.0 09:34:06 INFO - moztest == 0.7 09:34:06 INFO - mozversion == 1.4 09:34:06 INFO - psutil == 3.1.1 09:34:06 INFO - requests == 1.2.3 09:34:06 INFO - wsgiref == 0.1.2 09:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 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 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:34:06 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 09:34:06 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 09:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:06 INFO - 'HOME': '/Users/cltbld', 09:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:06 INFO - 'LOGNAME': 'cltbld', 09:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:06 INFO - 'MOZ_NO_REMOTE': '1', 09:34:06 INFO - 'NO_EM_RESTART': '1', 09:34:06 INFO - 'PAGER': '/bin/cat', 09:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:06 INFO - 'PWD': '/builds/slave/test', 09:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:06 INFO - 'SHELL': '/bin/bash', 09:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:34:06 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:06 INFO - 'USER': 'cltbld', 09:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:06 INFO - 'XPC_FLAGS': '0x0', 09:34:06 INFO - 'XPC_SERVICE_NAME': '0', 09:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 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)) 09:34:06 INFO - Cleaning up... 09:34:06 INFO - Return code: 0 09:34:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:06 INFO - 'HOME': '/Users/cltbld', 09:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:06 INFO - 'LOGNAME': 'cltbld', 09:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:06 INFO - 'MOZ_NO_REMOTE': '1', 09:34:06 INFO - 'NO_EM_RESTART': '1', 09:34:06 INFO - 'PAGER': '/bin/cat', 09:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:06 INFO - 'PWD': '/builds/slave/test', 09:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:06 INFO - 'SHELL': '/bin/bash', 09:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:34:06 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:06 INFO - 'USER': 'cltbld', 09:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:06 INFO - 'XPC_FLAGS': '0x0', 09:34:06 INFO - 'XPC_SERVICE_NAME': '0', 09:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:06 INFO - Downloading/unpacking jsonschema 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:09 INFO - Downloading/unpacking functools32 (from jsonschema) 09:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:09 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:09 INFO - warning: no files found matching '*.txt' 09:34:09 INFO - no previously-included directories found matching 'build' 09:34:09 INFO - no previously-included directories found matching 'dist' 09:34:09 INFO - no previously-included directories found matching '.git*' 09:34:09 INFO - Installing collected packages: jsonschema, functools32 09:34:10 INFO - Running setup.py install for functools32 09:34:10 INFO - warning: no files found matching '*.txt' 09:34:10 INFO - no previously-included directories found matching 'build' 09:34:10 INFO - no previously-included directories found matching 'dist' 09:34:10 INFO - no previously-included directories found matching '.git*' 09:34:10 INFO - Successfully installed jsonschema functools32 09:34:10 INFO - Cleaning up... 09:34:10 INFO - Return code: 0 09:34:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ea8fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea9f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb923c8a240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, '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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 09:34:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:10 INFO - 'HOME': '/Users/cltbld', 09:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:10 INFO - 'LOGNAME': 'cltbld', 09:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:10 INFO - 'MOZ_NO_REMOTE': '1', 09:34:10 INFO - 'NO_EM_RESTART': '1', 09:34:10 INFO - 'PAGER': '/bin/cat', 09:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:10 INFO - 'PWD': '/builds/slave/test', 09:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:10 INFO - 'SHELL': '/bin/bash', 09:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:34:10 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:10 INFO - 'USER': 'cltbld', 09:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:10 INFO - 'XPC_FLAGS': '0x0', 09:34:10 INFO - 'XPC_SERVICE_NAME': '0', 09:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:10 INFO - Downloading/unpacking flake8 09:34: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 09:34: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 09:34: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 09:34: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 09:34: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 09:34: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 09:34:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:34:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:34:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:34:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:34:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:34:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:34:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:34:14 INFO - no previously-included directories found matching 'docs/_build' 09:34:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:34:14 INFO - Running setup.py install for pep8 09:34:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:34:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:34:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:34:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:34:14 INFO - no previously-included directories found matching 'docs/_build' 09:34:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:34:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:34:14 INFO - Cleaning up... 09:34:14 INFO - Return code: 0 09:34:14 INFO - Running post-action listener: _resource_record_post_action 09:34:14 INFO - Running post-action listener: _start_resource_monitoring 09:34:14 INFO - Starting resource monitoring. 09:34:14 INFO - ##### 09:34:14 INFO - ##### Running install step. 09:34:14 INFO - ##### 09:34:14 INFO - Running pre-action listener: _resource_record_pre_action 09:34:14 INFO - Running main action method: install 09:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:14 INFO - Reading from file tmpfile_stdout 09:34:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:15 INFO - Reading from file tmpfile_stdout 09:34:15 INFO - Output received: 09:34:15 INFO - Usage: mozinstall [options] installer 09:34:15 INFO - Options: 09:34:15 INFO - -h, --help show this help message and exit 09:34:15 INFO - -d DEST, --destination=DEST 09:34:15 INFO - Directory to install application into. [default: 09:34:15 INFO - "/builds/slave/test"] 09:34:15 INFO - --app=APP Application being installed. [default: firefox] 09:34:15 INFO - mkdir: /builds/slave/test/build/application 09:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:34:48 INFO - Reading from file tmpfile_stdout 09:34:48 INFO - Output received: 09:34:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:34:48 INFO - Running post-action listener: _resource_record_post_action 09:34:48 INFO - ##### 09:34:48 INFO - ##### Running run-tests step. 09:34:48 INFO - ##### 09:34:48 INFO - Running pre-action listener: _resource_record_pre_action 09:34:48 INFO - Running main action method: run_tests 09:34:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:48 INFO - 2016-04-28 09:34:48.998 screenresolution[1650:7910] starting screenresolution argv=screenresolution get 09:34:49 INFO - 2016-04-28 09:34:49.001 screenresolution[1650:7910] Display 0: 1600x1200x32@60 09:34:49 INFO - 2016-04-28 09:34:49.007 screenresolution[1651:7912] starting screenresolution argv=screenresolution list 09:34:49 INFO - Available Modes on Display 0 09:34:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:49 INFO - Intel Iris: 09:34:49 INFO - Chipset Model: Intel Iris 09:34:49 INFO - Type: GPU 09:34:49 INFO - Bus: Built-In 09:34:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:49 INFO - Vendor: Intel (0x8086) 09:34:49 INFO - Device ID: 0x0a2e 09:34:49 INFO - Revision ID: 0x0009 09:34:49 INFO - Displays: 09:34:49 INFO - Display: 09:34:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:49 INFO - Display Serial Number: 1600x1200 60 09:34:49 INFO - Main Display: Yes 09:34:49 INFO - Mirror: Off 09:34:49 INFO - Online: Yes 09:34:49 INFO - Rotation: Supported 09:34:49 INFO - Return code: 0 09:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:49 INFO - Python 2.7.3 09:34:49 INFO - Return code: 0 09:34:49 INFO - grabbing minidump binary from tooltool 09:34: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')]} 09:34:49 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 0x10e92ee00>, '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 0x10e797db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e929b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:49 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 09:34:49 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 09:34:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:49 INFO - Return code: 0 09:34:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:34:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:34:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:34:50 INFO - Return code: 0 09:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 09:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --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 09:34:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgrhlSXWU1/Render', 09:34:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:50 INFO - 'HOME': '/Users/cltbld', 09:34:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:50 INFO - 'LOGNAME': 'cltbld', 09:34:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:50 INFO - 'MOZ_NO_REMOTE': '1', 09:34:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:50 INFO - 'NO_EM_RESTART': '1', 09:34:50 INFO - 'PAGER': '/bin/cat', 09:34:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:50 INFO - 'PWD': '/builds/slave/test', 09:34:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:50 INFO - 'SHELL': '/bin/bash', 09:34:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJ8xjLhYvN/Listeners', 09:34:50 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 09:34:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:50 INFO - 'USER': 'cltbld', 09:34:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:50 INFO - 'XPC_FLAGS': '0x0', 09:34:50 INFO - 'XPC_SERVICE_NAME': '0', 09:34:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 09:34:50 INFO - mozversion application_buildid: 20160428073807 09:34:50 INFO - mozversion application_changeset: 4292da9df16b220f30921100282264a34cd2ff03 09:34:50 INFO - mozversion application_display_name: Nightly 09:34:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:50 INFO - mozversion application_name: Firefox 09:34:50 INFO - mozversion application_remotingname: firefox 09:34:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:34:50 INFO - mozversion application_vendor: Mozilla 09:34:50 INFO - mozversion application_version: 49.0a1 09:34:50 INFO - mozversion platform_buildid: 20160428073807 09:34:50 INFO - mozversion platform_changeset: 4292da9df16b220f30921100282264a34cd2ff03 09:34:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:34:50 INFO - mozversion platform_version: 49.0a1 09:34:50 INFO - using testdate: 1461861290 09:34:50 INFO - actual date: 1461861290 09:34:50 INFO - starting webserver on 'localhost:49271' 09:34:50 INFO - SUITE-START | Running 2 tests 09:34:50 INFO - TEST-START | dromaeo_css 09:34:50 INFO - operating with platform_type : mac_ 09:34:50 INFO - Initialising browser for dromaeo_css test... 09:34:50 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpqxCvqF/profile http://localhost:49271/getInfo.html) 09:34:53 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:34:53 INFO - PROCESS | 1669 | colorDepth:24 09:34:53 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:34:53 INFO - PROCESS | 1669 | __metrics 09:34:53 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:34:54 INFO - TEST-INFO | 1669: exit 0 09:34:54 INFO - Browser initialized. 09:34:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:54 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpqxCvqF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:06 INFO - PROCESS | 1673 | RSS: Main: 249663488 09:35:06 INFO - PROCESS | 1673 | 09:35:55 INFO - PROCESS | 1673 | 1461861355816 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] 09:38:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 09:38:08 INFO - PROCESS | 1673 | RSS: Main: 261423104 09:38:08 INFO - PROCESS | 1673 | 09:41:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 09:41:11 INFO - PROCESS | 1673 | RSS: Main: 273141760 09:41:11 INFO - PROCESS | 1673 | 09:44:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:44:13 INFO - PROCESS | 1673 | RSS: Main: 275451904 09:44:13 INFO - PROCESS | 1673 | 09:47:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 09:47:15 INFO - PROCESS | 1673 | RSS: Main: 276983808 09:47:15 INFO - PROCESS | 1673 | 09:50:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 09:50:19 INFO - PROCESS | 1673 | RSS: Main: 279859200 09:50:19 INFO - PROCESS | 1673 | 09:53:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 09:53:22 INFO - PROCESS | 1673 | RSS: Main: 281075712 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | __start_tp_report 09:53:22 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:53:22 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:53:22 INFO - PROCESS | 1673 | |i|pagename|runs| 09:53:22 INFO - PROCESS | 1673 | |0;dojo.html;2889.7543708784738;2914.6502419709623;3411.846466908989;3428.70855977242;3439.86240550378;4848.975755121224;5585.2739143911285;5664.065429204176;5705.914411283834;5711.286089238845;5575.080111781562;5647.717614119299;5768.480836724694;5771.624844385112;5778.3643799182055;5164.845054648357;5176.0683077046115;5210.114280572293;5281.9207711884355;5295.443978382272;4256.638185754215;5248.343957005374;5279.86800329991;5297.867553311159;5305.7877684892555;4133.607307305801;5135.922961155586;5193.68837869729;5259;5278.049951008817;5189.1697328427645;5207.4011488678525;5243.292155558989;5243.947560524422;5253.9737301313635;5144.356955380577;5160.587153027749;5188.688678679291;5240.816571419982;5265.526102650741;5478.863028424282;5583.8883222335735;5618.6628802272;5706.629069110494;5724.4561766632105;6125.448709616111;6135.294441139282;6153.569250152491;6155.476784473325;6180.536459765535;6195.7521699131985;6202.937970620281;6224.751009959597;6230.844228894269;6237.750489980395;5764.221830052973;5813.47678708914;5878.441548052928;5908.527317814607;5937.910931336033;6215.016213519581;6266.717997690115;6271.090691849657;6279.96860015697;6319;6025.608335458269;6148.1085242640465;6161.630302181883;6178.969105154445;6184.938150618526;6095.770848210712;6145.095020543646;6162.661053642003;6189.257289125244;6205.410486003822;6077.6353418795015;6102.065131056412;6120.846978825565;6171.537134714914;6179.196704428486;6041.939580604226;6120.632762034292;6149.385061493815;6167.938320616826;6188.690565471708;4894.804207831647;4917.139500587383;4928.6057115431395;4934.037862616755;4973;4320.308750599889;4375.562443755663;4413.558644135561;4416.911661766747;4429.247028005247;5757.65454072757;5835.649861008353;5856.619319744203;5880.882382352329;5894.086416605433;6060.090986352083;6065.727042283108;6087.239095113111;6104.847378815565;6114.144019837227;4974.825881094217;5382.78468861249;5579;5596.916046259309;5615.775368985276;5214.973925130426;5262.868428289246;5264.289320941701;5352.330958630171;5393.191021346829;5387.626155330366;6279.277883043417;6308.27454842699;6332.556721029576;6389.7763578274535;5185.274061631299;6177.320494745664;6219.315875253809;6231.75072997075;6232.501399887908;6168.660723660242;6263.592866463665;6326.778562750282;6327.050942358683;6337.176167098248;6174.969125154345;6186.350433204449;6191.102290167991;6198.969005155126;6200.3489633589625;6172.8765424690355;6200.193974783249;6224.564280500322;6228.190335256388;6235.282942461494;5857.677827719346;5994.160817485468;6057.909131363122;6117.0824376342125;6142.262928448615;6099.420555047263;6173.876522469615;6185.8144255672405;6188.845278868064;6196.225471816023;5943.732532035982;5951.811550878885;6035.39646035393;6038.305594856648;6105.389461053859;4678.321643361762;6055;6127.877442451037;6136.5090792737365;6148.907766383419;5918.082697181943;5959.910601340896;5970.164177015308;5981.581289309706;5982.551308651956 09:53:22 INFO - PROCESS | 1673 | |1;ext.html;495.3238828998417;515.9577653140658;525.3616855520542;530.798296647274;625.6246252248654;29517.11448656541;38401.80799096008;38423.42364864526;38468.61531384685;38523.80738096309;33918.49122263168;34050;37219.81390093052;39102.8044859776;39135;9152.267818574532;9452.43285402878;9475.289353298496;9491.667791627295;9567.282453815957;9544.236461083097;9593.136617704406;9636.95181524095;9711.660091896785;9840;9703.2722545809;9714.174295184917;9727.854082188738;9752.4636145012;9846.655367062118;9665.130138287554;9706.029397060309;9777.608895644165;9809.705708828747;9815.803683926282;7699.653515591755;8302;9525.952370238174;9653.276004299705;9660.033996600354;37985.810070949745;38012.42981355282;38235.42646860299;38595.614043859765;38622.80688596539;2430.0401341470197;2657.41536861892;2735.6717193936656;3224;3398.6431424700545;26234.86882565575;27040.323991900164;27524;27611.171664850088;27638.585421218697;2620.9606855897177;2629.2638061342855;2651.893924243028;2794.5109605819102;2837.6169217155834;363066.18466907763;363477;363668;365493.1725341383;366654;32704;57184;57588.71205643945;57690.711546442;57749.13376299359;30020.84989575038;34515.82742086273;34632.307353853284;34718.13204669853;34969.47545786815;14679.55961321162;14717.55847324582;14773.630659233606;14950.32723527422;14954.850451495564;12532.624021279376;12602.180858244368;12628.558000469939;12647.43086561107;12653.87346126527;7959.522428654299;8103.716369927024;8106.554139522265;8149.877751833727;8178.5092894426525;3395.9320813584086;3401.4557670772556;3449.5170676105367;3457.239407330383;3468.9826550867083;988.9703308900745;1002.4636819301638;1040.0015984654817;1108.645233525264;1135.27909777292;23119.768802312097;23467.061317547108;29245.268868278465;29549;30146.944856929902;1009.1674368645749;1090.939998300101;1104.4974536585835;1105.171121658756;1123.6966019174777;14039.578812635636;14073.296335183404;14083.295835208199;14130.222837744022;14348;566017;569820;579181;580394;583692;54279.18581221285;56514.71742641261;56539.43460565424;56716.43283567194;56863.71568142215;1506.7438535449;1508.2458770614692;1514.0764133878326;1525.9160746159068;1529.648180918395;27643.30891727723;27648.861755691898;27976.741046652543;28219.858900705363;28371.148865534065;42389.36415953701;43403.565964341215;49224.26163607477;49939.75030124826;50260;595161;598191;598677;599672;600050;25237.621435678848;25406.872965635055;25470.617940731274;25547.616785747858;25866.87066564655;40351.39472907967;40707.389389159776;40825.38761918633;42240;43707.78146109249;35073.64926350704;36338.45492317565;36608.633913660524;37288.44067339046;37297.6270237305;35284.94145175649;35332.29335413329;35719.64280357267;35753.82123089368;36166.819165904;28578.571321430612;34244.97265082051;35367.46948795718;35579.64420355763;35862;29188.416231675674;29884.850575746983;29954.400911982073;30041.248968775955;30061.69938300589;19011;23204.53590928138;23451.06195752151;23750.40623984345;23890.163844265368 09:53:22 INFO - PROCESS | 1673 | |2;jquery.html;390.1961958365767;395.1997205658543;398.22545149683856;407.84094203260724;411.0299692725168;14579.343929523171;19919.701204481928;20596.794032059675;20605.27881524147;20684;11837.940810295937;12321.691957701061;12356;19708.50728731779;20270.493237669067;5416.702081385532;5602.215689803431;5617.045102332613;5634.126710359901;5692.402297758737;6042;6054.182685337489;6091.78678746244;6118.479929206001;6124.938750612481;5422.403535611099;5607.887842243153;6086.360932102132;6087.878242435148;6120.357362476942;5482.2873026506695;5485.50630443262;5573.136163894603;5596.916046259309;5602.887942241173;4756.167670657621;5304.416514183436;5336.17289320156;5427.592930530226;5663.745131469093;13529.661758456019;13850.653733656638;13855.722885542333;22694;25671.74328256712;1733.8006129295165;2130.392838041166;2161.827053835689;2169.568085063866;2284.1548627008115;10094.9495252524;10215.489225538768;10232.256455033055;12631.557895473707;14010.668986446271;1660.5682522544105;1686.6289416328389;1712.6317841663977;1779.4483710049926;1818.6635472437508;94128;102688.48655756748;110800;116170.25744613838;117442.99999999914;9781.510924453749;11592.47833847479;11828.52685892572;11968.640940771791;12137.939310303393;14943.476978305707;15432.84567154314;15449.150296733562;15503.14732689713;15566.922165389327;8010.759677209693;8295.714164304542;8701.521416322037;8898;9021.32340074497;8539.139388304864;8617.224449799549;8661.047284798668;8770.780730481789;8786.912130878738;4653.046125544255;4733.597644200281;4744.288356746516;4760.667273036187;4798.520147985243;1698.9915050424668;1959.931402400909;1992.511834600503;2050.261905713942;2068.493219161315;967.2842096848282;997.7505623594102;1023.534291897185;1036.6682661548382;1037.5227395398201;15925.044497330196;20155.596888062155;20256.18975240974;20740.688889666668;20843.270485532932;988.7280997725683;990.6879333010079;991.306085739985;1012.1295685710284;1043.3635482355758;8297.336213102937;10860.348379097279;11058.834117488244;11086.778264434666;11110.333379997226;580397;592714;604315.9784201051;605621;605960;106339;108723.45638271757;117204;127291;130555.69444305435;940.9482478463614;1016.9135623472088;1016.9593216271267;1042.5100202904623;1068.5351871935734;18298.817011830244;18485.53786155357;18522.629547408706;18736.344227951955;18844.811551884304;37776.81111594424;48644.756776215894;50090.49909501004;50383.49616503788;51211.231831521814;65442.34557654362;73280.53438931298;75607.62196189204;75921.24078759142;77765;11002.394868282001;11522;12225.449854756735;12474.875251247371;12586;11629.709257268298;13086;13313.933430332787;13550.390232439368;13598.592042238748;11148.996590306911;11305.26515776472;11486.540538378706;11604.129690273094;11799.587014454453;9659;10063.094321511098;10490.475476226005;10565;11219.382933938712;5476.3702174250475;8117.594120294079;11148.498317575892;12004.899142649827;12022.579209727617;10602.363858168535;11710;11950.462229199533;12307.692307692021;12467.064970127458;11639.53441862316;11709.473073711533;11739.002184814044;11959.641210763692;12011.63965081049 09:53:22 INFO - PROCESS | 1673 | |3;mootools.html;1528.2129703202852;1610.460495733929;1813.6644720726667;1896.914638841252;2011.8591698581106;7775.961120194405;7776.144624091346;8590.74227773167;8941.301152780976;8971.775705607362;8648.308135349173;8685.739427817176;8811.691590794324;8815.51514666693;8865.911340886589;2156.9892150539226;2440.938191886535;2495.862727549984;2497.2633073243437;2497.825152239344;2074.968875466869;2315.3748487908256;2369.969063457393;2475.616279476684;2491.426971796488;2455.2511483997373;2456.987715061422;2471.4315707387314;2474.591692370757;2495.0768215760195;2436.2569416328015;2453.276283496373;2461.5569197544437;2464.494778570389;2470.505898820225;2417.141914620302;2450.9264722058365;2459.483508463225;2470.197185914585;2473.0602371098867;8894.28845692343;8952.55237238142;8961.507117108556;8969.865452018224;8972.10278972104;5263.526282634543;5538.363088244863;5542.972285138589;5553.500184983371;5559.110542313251;3625.202455459821;3626.655467730561;3647.7811331320204;3716.1081340478363;3724.404095344732;877.2937785083005;1047.649037572414;1056.3028401255135;1058.7247315697898;1073.3077165228462;11120.943510366422;11664.475098620582;11742.530298788039;11767.58813441534;11844;4422.823087076546;4931.778069986859;4990.575801056878;5025.673331233458;5058.974705126524;8877.866831997526;10079.294449388619;10102.444365559964;10133.797324053625;10154.593816247416;5484.588655850827;5522.3649280333275;5553.416891226444;5585.636933599303;5595.160725891067;4478.641708663299;4740.478547359788;4884.7313397762755;4888.755562221946;5077.898442031139;4099.61053699898;4106.313742608808;4169.145325208325;4181.937270940938;4201.579842015835;1204.3315959642482;1224.74280401115;1352.817369655084;1368.5004973184914;1396.6578188343922;927.4296307770672;931.8415869302235;956.4548207521646;957.090763774417;983.341161421849;5431;5755.424457554211;5786.797462088806;5862.237909071793;6075.33171351151;409.63542447221994;413.81792011514835;454.0872845580407;462.3942635147967;484.2590836020894;3873.1285460771664;3876.6704830089193;3894.162755007687;3928.9017774555864;3948.072203032285;9176.6788162414;10396.896031039592;10791.46042697862;10946.179036572288;10990.17573681977;10808.675739727822;10819.513121909533;10847.891521084846;10857.782844343068;10880.619178328721;7824.765257042298;8259.876101858477;8285.005799304123;8286.46138001028;8289.0467596226;4994.5754610858485;5982.102684597346;6024.578279520391;6171.47542458896;6231.501479881689;9554.235661146953;9830.361026533252;10045.397276163454;10190.337628054152;10199.54102065394;9976.800463990825;10203.489825508843;10265.435401053013;10273.332233404804;10331.431771252654;7010.404115650227;7454.329110380092;7523.510971786816;7688.077430708351;7747;8377.162283771575;8413.663453462037;8453.577321133796;8465.195806398382;8486.83026339482;6431.742730290736;7418.517796343221;7529.661165247468;7692.307692307719;7770.53376797394;7225.1329840419485;7345.8898116527225;7361.1166660001145;7393.635895667351;7443.441741869273;7103.467239956921;7134.036905017756;7167.713291468492;7175.605341706257;7229.240929702516;4901.9019619608125;4926.7929357307685;5036.420811606712;5036.8992620146655;5214.817481388133;4978;5010.073136469765;5052.8484145475695;5115.079285728604;5239.764210610456 09:53:22 INFO - PROCESS | 1673 | |4;prototype.html;315.569247976512;315.83576540199095;326.3212517962638;327.1902042444949;327.6445057113032;5325.7869685212545;5383.246345511631;5502.835869375111;5619.072852979259;5684.630499017561;5493.917591236124;5495.6153069285065;5525.668459892409;5531.3915469298345;5532.30846144232;4243.745375277494;4245.490541135068;4248.80880360383;4263.445752052245;4317.639943417829;4103.502221689092;4285.400043993844;4287.606527878452;4295.828166873337;4307.375430562566;4168;4171.72883762556;4231.238377092122;4235.322348424253;4240.512341080769;4240.342746874239;4253.957780343804;4271.487421509408;4280.4221430106845;4289.828406863721;3155.952660710091;3543.7519373643854;3697.4084146536425;3710.7959062251566;3737.8504859805576;5243.344581927259;5246.527812496894;5252.264682944391;5259.815906443295;5269.525742683177;4816.638752093607;4837.903241935177;4855.271709243641;4861.489543597907;4921.286413470027;5476.726163691799;5528.198411230389;5593.680695721504;5721.885562288772;5910.734016969247;3515.701165400944;3972.980135099335;4016.5581786003518;4048.7570745755056;4073.083556199861;5294.57643388528;5541.390447050821;5813.767449302023;5896.233489646362;6090.177825993478;3548.28111466202;4034.8184331705143;4102.671786257152;4117.21772863155;4131.595257612426;3746.6253374662315;4025.5974402560096;4210.5157906840495;4236.322188449833;4248.896796085957;4058.492688413948;4065.959340406558;4765.880852978703;4817.084753896818;4862.294967229732;5485.204645326406;5838.649681019152;5840.5035571976005;5865.853353666107;5905.372109411271;1302.583173384508;1524.67981723837;1525.8556082937796;1529.99785140733;1533.9156346401055;937.3110763588757;997.6358629100471;1016.1718199667248;1030.6032177611523;1031.242037102729;5562.554995600342;5788.131780232915;5805.274340707411;5844.240248767633;5917.704114794243;5145.819896303611;5322.38792538863;6277.96861015692;6390.265119511316;6451.741930322734;5584.274044374205;5665.915011274834;5819.272590926134;5836.620619659709;5844.620099693507;3200.455922493182;3368.585194218434;3387.2378714788974;3394.321135772855;3431.725461963037;5373.838784836383;6278.623282602967;6697.832554186087;6700.899486507707;6709.429698475684;4727.905441891143;5926.525877929756;6139.508839292846;6159.538034647329;6181;5288.312519372457;6231.937680623227;6267.339155123896;6284.748610055546;6323.95654716985;5080.593552515864;5307.920381194208;5705.144228365778;6014.2181516404335;6127.988881834447;5736.971315143398;5956.851078722893;5976.103584462366;6001.4298641628975;6018.638881667114;5990.1913241713555;5996.610220335664;6048.185544336593;6214.844628884314;6329.715162817776;3245.6916592923635;3517.856696573675;3590.08452844523;3999.4800675911947;4002.7198096132993;5734.476830194701;5910.852228694317;6029.366916473882;6058.303295121117;6092.238470191226;4908.8772780680765;5539.030669632879;5722.0272553665145;5819.272590926134;5900.616459930091;4908.582770464408;5296.2055691646565;5530.198121272312;5785.334686511105;5874.236349274567;5434.456554344692;5755.625884317506;5776.624519406225;5788.27646544182;5814.186013957965;5877.529797616095;5880.235569375954;5883.73476211653;5911.290645122613;5933.554983376178;5521.5306698929435;5804.65172089676;5807.186993820784;5828.825135245949;5842.795502157404 09:53:22 INFO - PROCESS | 1673 | |5;yui.html;591.0477166553068;621.2110619513221;639.6162302618429;710.9395701365384;733.1056111543918;14165.504207352744;14413;14625.853741462583;14633.487827926037;14645.04807187532;11634.360110193937;13598.796018059737;13829.723405531884;13864.930675346634;13874.861251387483;720.4488566246825;884.1158841158841;905.2260317428603;911.7264820553808;918.1369512658114;914.2548822709471;923.9584218710174;926.157196950775;926.2543652359848;926.5645146781036;933.8412469880103;941.948192849393;959.1559427703646;967.9612815487372;970.1898914406479;952.3714348529971;954.9283803714749;955.2930831184942;958.1807554540907;960.1214888377126;682.4813142012057;686.9141357330334;701.8771714949863;707.3987110955698;712.8681193979079;14760.040597361245;14818.184999825005;14852.331645075998;14890.478833240893;15062.774058389023;11179.888201118047;12523.185992910523;12573.622791316275;12692.80960785589;12778.297193654345;7498.250174982512;8976.775580610538;9651.034896510362;9974.351667141613;10001.34991225568;3488.389531831919;3489.476578513218;3541.628129046439;3591.94612080819;3606.3328284267495;67478;81651.95870103236;83721;84188.57905710432;85209.33116019588;8429.704960326359;9461.858072128924;9480.146786789222;9486.383385079947;9760.853587196198;22086.56826494723;22309.665355019686;22319.218827340967;22341.441463963532;22525.43686407853;7138.607376594233;7363.705451781976;7463.5521868686965;7541.5475071495885;7574.242575742382;3995.4006898965386;4088;4101.958980410158;4104.610062044101;4107.856225032109;3591.4612808078996;3607.783532988029;3634.945475817865;3697.3159965406485;3697.5378077740284;636.0872148466965;645.6803882078308;650.5771248688391;657.8947368421029;658.1969996604136;494.39189796549726;578.2107423367157;593.2673148661399;593.4214141212284;605.288785676828;8134.918650813534;8163.673453061811;8257.628406721711;8321.04308004575;8461;524.8031988004499;561.4161272276787;564.305903738404;603.9335673075958;608.6743592178163;3453.706434953057;3869.5743468218475;3900.8244628991765;3926.7251292409255;3988.6210809973004;77523;99290;99517;100762.4961875186;101894.49052754689;75062.6246868773;77846;81842.59078704569;81991;84120.57939710263;67319.66340168366;68512.65743671349;68921;68927.65536172387;69064;6394.584352017104;7710.92289077102;7833.804154896173;7932.682692692228;7992.080910695345;32718;44534;44843.32735008912;45988.31017534673;46854.828629284544;62541.68729156325;69408.3059169422;70398.64800675964;70414;70621;10074.244431667508;10477.52851121686;10536.473176340998;10651.30766500175;10798.514066866852;11035.282706624044;11115.666530004113;11165.72085697864;11172.776544468903;11352.148588856035;10496.475176241005;10596.735081623021;10765.46172691378;10833.566657333933;10914.56341746353;10366.481675916024;10560.683179504627;10619.681409557727;10636.84044739314;10765.40790256512;10422.583096676048;10532.526036328536;10581.682549523526;10634.14926805869;10721.571137154426;8775.561221939006;8877.156670116328;9137.360384773003;9185.678501252423;9193.632254710004;8315.584220788814;8588.742337729878;8832.602532885907;8835.469871807712;8972.461652300883 09:53:22 INFO - PROCESS | 1673 | __end_tp_report 09:53:22 INFO - PROCESS | 1673 | __start_cc_report 09:53:22 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,421 09:53:22 INFO - PROCESS | 1673 | __end_cc_report 09:53:22 INFO - PROCESS | 1673 | __startTimestamp1461862402110__endTimestamp 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:53:22 INFO - PROCESS | 1673 | Number of tests: 6 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5686.95 Median:5959.91 stddev:677.27 (11.4%) stddev-sans-first:642.81 09:53:22 INFO - PROCESS | 1673 | Values: 2889.8 2914.7 3411.8 3428.7 3439.9 4849.0 5585.3 5664.1 5705.9 5711.3 5575.1 5647.7 5768.5 5771.6 5778.4 5164.8 5176.1 5210.1 5281.9 5295.4 4256.6 5248.3 5279.9 5297.9 5305.8 4133.6 5135.9 5193.7 5259.0 5278.0 5189.2 5207.4 5243.3 5243.9 5254.0 5144.4 5160.6 5188.7 5240.8 5265.5 5478.9 5583.9 5618.7 5706.6 5724.5 6125.4 6135.3 6153.6 6155.5 6180.5 6195.8 6202.9 6224.8 6230.8 6237.8 5764.2 5813.5 5878.4 5908.5 5937.9 6215.0 6266.7 6271.1 6280.0 6319.0 6025.6 6148.1 6161.6 6179.0 6184.9 6095.8 6145.1 6162.7 6189.3 6205.4 6077.6 6102.1 6120.8 6171.5 6179.2 6041.9 6120.6 6149.4 6167.9 6188.7 4894.8 4917.1 4928.6 4934.0 4973.0 4320.3 4375.6 4413.6 4416.9 4429.2 5757.7 5835.6 5856.6 5880.9 5894.1 6060.1 6065.7 6087.2 6104.8 6114.1 4974.8 5382.8 5579.0 5596.9 5615.8 5215.0 5262.9 5264.3 5352.3 5393.2 5387.6 6279.3 6308.3 6332.6 6389.8 5185.3 6177.3 6219.3 6231.8 6232.5 6168.7 6263.6 6326.8 6327.1 6337.2 6175.0 6186.4 6191.1 6199.0 6200.3 6172.9 6200.2 6224.6 6228.2 6235.3 5857.7 5994.2 6057.9 6117.1 6142.3 6099.4 6173.9 6185.8 6188.8 6196.2 5943.7 5951.8 6035.4 6038.3 6105.4 4678.3 6055.0 6127.9 6136.5 6148.9 5918.1 5959.9 5970.2 5981.6 5982.6 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:62729.67 Median:35736.73 stddev:140364.15 (392.8%) stddev-sans-first:140680.13 09:53:22 INFO - PROCESS | 1673 | Values: 495.3 516.0 525.4 530.8 625.6 29517.1 38401.8 38423.4 38468.6 38523.8 33918.5 34050.0 37219.8 39102.8 39135.0 9152.3 9452.4 9475.3 9491.7 9567.3 9544.2 9593.1 9637.0 9711.7 9840.0 9703.3 9714.2 9727.9 9752.5 9846.7 9665.1 9706.0 9777.6 9809.7 9815.8 7699.7 8302.0 9526.0 9653.3 9660.0 37985.8 38012.4 38235.4 38595.6 38622.8 2430.0 2657.4 2735.7 3224.0 3398.6 26234.9 27040.3 27524.0 27611.2 27638.6 2621.0 2629.3 2651.9 2794.5 2837.6 363066.2 363477.0 363668.0 365493.2 366654.0 32704.0 57184.0 57588.7 57690.7 57749.1 30020.8 34515.8 34632.3 34718.1 34969.5 14679.6 14717.6 14773.6 14950.3 14954.9 12532.6 12602.2 12628.6 12647.4 12653.9 7959.5 8103.7 8106.6 8149.9 8178.5 3395.9 3401.5 3449.5 3457.2 3469.0 989.0 1002.5 1040.0 1108.6 1135.3 23119.8 23467.1 29245.3 29549.0 30146.9 1009.2 1090.9 1104.5 1105.2 1123.7 14039.6 14073.3 14083.3 14130.2 14348.0 566017.0 569820.0 579181.0 580394.0 583692.0 54279.2 56514.7 56539.4 56716.4 56863.7 1506.7 1508.2 1514.1 1525.9 1529.6 27643.3 27648.9 27976.7 28219.9 28371.1 42389.4 43403.6 49224.3 49939.8 50260.0 595161.0 598191.0 598677.0 599672.0 600050.0 25237.6 25406.9 25470.6 25547.6 25866.9 40351.4 40707.4 40825.4 42240.0 43707.8 35073.6 36338.5 36608.6 37288.4 37297.6 35284.9 35332.3 35719.6 35753.8 36166.8 28578.6 34245.0 35367.5 35579.6 35862.0 29188.4 29884.9 29954.4 30041.2 30061.7 19011.0 23204.5 23451.1 23750.4 23890.2 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34015.51 Median:10956.11 stddev:99293.65 (906.3%) stddev-sans-first:99540.10 09:53:22 INFO - PROCESS | 1673 | Values: 390.2 395.2 398.2 407.8 411.0 14579.3 19919.7 20596.8 20605.3 20684.0 11837.9 12321.7 12356.0 19708.5 20270.5 5416.7 5602.2 5617.0 5634.1 5692.4 6042.0 6054.2 6091.8 6118.5 6124.9 5422.4 5607.9 6086.4 6087.9 6120.4 5482.3 5485.5 5573.1 5596.9 5602.9 4756.2 5304.4 5336.2 5427.6 5663.7 13529.7 13850.7 13855.7 22694.0 25671.7 1733.8 2130.4 2161.8 2169.6 2284.2 10094.9 10215.5 10232.3 12631.6 14010.7 1660.6 1686.6 1712.6 1779.4 1818.7 94128.0 102688.5 110800.0 116170.3 117443.0 9781.5 11592.5 11828.5 11968.6 12137.9 14943.5 15432.8 15449.2 15503.1 15566.9 8010.8 8295.7 8701.5 8898.0 9021.3 8539.1 8617.2 8661.0 8770.8 8786.9 4653.0 4733.6 4744.3 4760.7 4798.5 1699.0 1959.9 1992.5 2050.3 2068.5 967.3 997.8 1023.5 1036.7 1037.5 15925.0 20155.6 20256.2 20740.7 20843.3 988.7 990.7 991.3 1012.1 1043.4 8297.3 10860.3 11058.8 11086.8 11110.3 580397.0 592714.0 604316.0 605621.0 605960.0 106339.0 108723.5 117204.0 127291.0 130555.7 940.9 1016.9 1017.0 1042.5 1068.5 18298.8 18485.5 18522.6 18736.3 18844.8 37776.8 48644.8 50090.5 50383.5 51211.2 65442.3 73280.5 75607.6 75921.2 77765.0 11002.4 11522.0 12225.4 12474.9 12586.0 11629.7 13086.0 13313.9 13550.4 13598.6 11149.0 11305.3 11486.5 11604.1 11799.6 9659.0 10063.1 10490.5 10565.0 11219.4 5476.4 8117.6 11148.5 12004.9 12022.6 10602.4 11710.0 11950.5 12307.7 12467.1 11639.5 11709.5 11739.0 11959.6 12011.6 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5749.27 Median:4929.29 stddev:3198.63 (64.9%) stddev-sans-first:3191.87 09:53:22 INFO - PROCESS | 1673 | Values: 1528.2 1610.5 1813.7 1896.9 2011.9 7776.0 7776.1 8590.7 8941.3 8971.8 8648.3 8685.7 8811.7 8815.5 8865.9 2157.0 2440.9 2495.9 2497.3 2497.8 2075.0 2315.4 2370.0 2475.6 2491.4 2455.3 2457.0 2471.4 2474.6 2495.1 2436.3 2453.3 2461.6 2464.5 2470.5 2417.1 2450.9 2459.5 2470.2 2473.1 8894.3 8952.6 8961.5 8969.9 8972.1 5263.5 5538.4 5543.0 5553.5 5559.1 3625.2 3626.7 3647.8 3716.1 3724.4 877.3 1047.6 1056.3 1058.7 1073.3 11120.9 11664.5 11742.5 11767.6 11844.0 4422.8 4931.8 4990.6 5025.7 5059.0 8877.9 10079.3 10102.4 10133.8 10154.6 5484.6 5522.4 5553.4 5585.6 5595.2 4478.6 4740.5 4884.7 4888.8 5077.9 4099.6 4106.3 4169.1 4181.9 4201.6 1204.3 1224.7 1352.8 1368.5 1396.7 927.4 931.8 956.5 957.1 983.3 5431.0 5755.4 5786.8 5862.2 6075.3 409.6 413.8 454.1 462.4 484.3 3873.1 3876.7 3894.2 3928.9 3948.1 9176.7 10396.9 10791.5 10946.2 10990.2 10808.7 10819.5 10847.9 10857.8 10880.6 7824.8 8259.9 8285.0 8286.5 8289.0 4994.6 5982.1 6024.6 6171.5 6231.5 9554.2 9830.4 10045.4 10190.3 10199.5 9976.8 10203.5 10265.4 10273.3 10331.4 7010.4 7454.3 7523.5 7688.1 7747.0 8377.2 8413.7 8453.6 8465.2 8486.8 6431.7 7418.5 7529.7 7692.3 7770.5 7225.1 7345.9 7361.1 7393.6 7443.4 7103.5 7134.0 7167.7 7175.6 7229.2 4901.9 4926.8 5036.4 5036.9 5214.8 4978.0 5010.1 5052.8 5115.1 5239.8 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4806.07 Median:5408.85 stddev:1464.81 (27.1%) stddev-sans-first:1429.62 09:53:22 INFO - PROCESS | 1673 | Values: 315.6 315.8 326.3 327.2 327.6 5325.8 5383.2 5502.8 5619.1 5684.6 5493.9 5495.6 5525.7 5531.4 5532.3 4243.7 4245.5 4248.8 4263.4 4317.6 4103.5 4285.4 4287.6 4295.8 4307.4 4168.0 4171.7 4231.2 4235.3 4240.5 4240.3 4254.0 4271.5 4280.4 4289.8 3156.0 3543.8 3697.4 3710.8 3737.9 5243.3 5246.5 5252.3 5259.8 5269.5 4816.6 4837.9 4855.3 4861.5 4921.3 5476.7 5528.2 5593.7 5721.9 5910.7 3515.7 3973.0 4016.6 4048.8 4073.1 5294.6 5541.4 5813.8 5896.2 6090.2 3548.3 4034.8 4102.7 4117.2 4131.6 3746.6 4025.6 4210.5 4236.3 4248.9 4058.5 4066.0 4765.9 4817.1 4862.3 5485.2 5838.6 5840.5 5865.9 5905.4 1302.6 1524.7 1525.9 1530.0 1533.9 937.3 997.6 1016.2 1030.6 1031.2 5562.6 5788.1 5805.3 5844.2 5917.7 5145.8 5322.4 6278.0 6390.3 6451.7 5584.3 5665.9 5819.3 5836.6 5844.6 3200.5 3368.6 3387.2 3394.3 3431.7 5373.8 6278.6 6697.8 6700.9 6709.4 4727.9 5926.5 6139.5 6159.5 6181.0 5288.3 6231.9 6267.3 6284.7 6324.0 5080.6 5307.9 5705.1 6014.2 6128.0 5737.0 5956.9 5976.1 6001.4 6018.6 5990.2 5996.6 6048.2 6214.8 6329.7 3245.7 3517.9 3590.1 3999.5 4002.7 5734.5 5910.9 6029.4 6058.3 6092.2 4908.9 5539.0 5722.0 5819.3 5900.6 4908.6 5296.2 5530.2 5785.3 5874.2 5434.5 5755.6 5776.6 5788.3 5814.2 5877.5 5880.2 5883.7 5911.3 5933.6 5521.5 5804.7 5807.2 5828.8 5842.8 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18030.39 Median:31588.89 stddev:26011.73 (82.3%) stddev-sans-first:26051.73 09:53:22 INFO - PROCESS | 1673 | Values: 591.0 621.2 639.6 710.9 733.1 14165.5 14413.0 14625.9 14633.5 14645.0 11634.4 13598.8 13829.7 13864.9 13874.9 720.4 884.1 905.2 911.7 918.1 914.3 924.0 926.2 926.3 926.6 933.8 941.9 959.2 968.0 970.2 952.4 954.9 955.3 958.2 960.1 682.5 686.9 701.9 707.4 712.9 14760.0 14818.2 14852.3 14890.5 15062.8 11179.9 12523.2 12573.6 12692.8 12778.3 7498.3 8976.8 9651.0 9974.4 10001.3 3488.4 3489.5 3541.6 3591.9 3606.3 67478.0 81652.0 83721.0 84188.6 85209.3 8429.7 9461.9 9480.1 9486.4 9760.9 22086.6 22309.7 22319.2 22341.4 22525.4 7138.6 7363.7 7463.6 7541.5 7574.2 3995.4 4088.0 4102.0 4104.6 4107.9 3591.5 3607.8 3634.9 3697.3 3697.5 636.1 645.7 650.6 657.9 658.2 494.4 578.2 593.3 593.4 605.3 8134.9 8163.7 8257.6 8321.0 8461.0 524.8 561.4 564.3 603.9 608.7 3453.7 3869.6 3900.8 3926.7 3988.6 77523.0 99290.0 99517.0 100762.5 101894.5 75062.6 77846.0 81842.6 81991.0 84120.6 67319.7 68512.7 68921.0 68927.7 69064.0 6394.6 7710.9 7833.8 7932.7 7992.1 32718.0 44534.0 44843.3 45988.3 46854.8 62541.7 69408.3 70398.6 70414.0 70621.0 10074.2 10477.5 10536.5 10651.3 10798.5 11035.3 11115.7 11165.7 11172.8 11352.1 10496.5 10596.7 10765.5 10833.6 10914.6 10366.5 10560.7 10619.7 10636.8 10765.4 10422.6 10532.5 10581.7 10634.1 10721.6 8775.6 8877.2 9137.4 9185.7 9193.6 8315.6 8588.7 8832.6 8835.5 8972.5 09:53:22 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:53:22 INFO - PROCESS | 1673 | 09:53:22 INFO - TEST-INFO | 1673: exit 0 09:53:22 INFO - TEST-OK | dromaeo_css | took 1112010ms 09:53:22 INFO - TEST-START | kraken 09:53:22 INFO - operating with platform_type : mac_ 09:53:22 INFO - Initialising browser for kraken test... 09:53:22 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpw1Spkr/profile http://localhost:49271/getInfo.html) 09:53:25 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 09:53:25 INFO - PROCESS | 1681 | colorDepth:24 09:53:25 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 09:53:25 INFO - PROCESS | 1681 | __metrics 09:53:25 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:53:26 INFO - TEST-INFO | 1681: exit 0 09:53:26 INFO - Browser initialized. 09:53:26 INFO - Running cycle 1/1 for kraken test... 09:53:26 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpw1Spkr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:37 INFO - PROCESS | 1684 | RSS: Main: 249995264 09:53:37 INFO - PROCESS | 1684 | 09:53:40 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:41 INFO - PROCESS | 1684 | RSS: Main: 261922816 09:53:41 INFO - PROCESS | 1684 | 09:53:44 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 09:53:44 INFO - PROCESS | 1684 | RSS: Main: 266551296 09:53:44 INFO - PROCESS | 1684 | 09:53:48 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 09:53:49 INFO - PROCESS | 1684 | RSS: Main: 266924032 09:53:49 INFO - PROCESS | 1684 | 09:53:51 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 09:53:51 INFO - PROCESS | 1684 | RSS: Main: 267563008 09:53:51 INFO - PROCESS | 1684 | 09:53:53 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:54 INFO - PROCESS | 1684 | RSS: Main: 267862016 09:53:54 INFO - PROCESS | 1684 | 09:53:57 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:58 INFO - PROCESS | 1684 | RSS: Main: 268013568 09:53:58 INFO - PROCESS | 1684 | 09:54:01 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 09:54:01 INFO - PROCESS | 1684 | RSS: Main: 268152832 09:54:01 INFO - PROCESS | 1684 | 09:54:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 09:54:05 INFO - PROCESS | 1684 | RSS: Main: 268664832 09:54:05 INFO - PROCESS | 1684 | 09:54:07 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:07 INFO - PROCESS | 1684 | RSS: Main: 268124160 09:54:07 INFO - PROCESS | 1684 | 09:54:09 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:10 INFO - PROCESS | 1684 | RSS: Main: 268115968 09:54:10 INFO - PROCESS | 1684 | 09:54:12 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:13 INFO - PROCESS | 1684 | RSS: Main: 268197888 09:54:13 INFO - PROCESS | 1684 | 09:54:15 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:16 INFO - PROCESS | 1684 | RSS: Main: 268263424 09:54:16 INFO - PROCESS | 1684 | 09:54:19 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:19 INFO - PROCESS | 1684 | RSS: Main: 268275712 09:54:19 INFO - PROCESS | 1684 | 09:54:21 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 09:54:22 INFO - PROCESS | 1684 | RSS: Main: 269926400 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | __start_tp_report 09:54:22 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 09:54:22 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 09:54:22 INFO - PROCESS | 1684 | |i|pagename|runs| 09:54:22 INFO - PROCESS | 1684 | |0;ai-astar;117;110;141;170;116;131;118;117;116;117 09:54:22 INFO - PROCESS | 1684 | |1;audio-beat-detection;155;125;141;161;122;166;170;120;165;122 09:54:22 INFO - PROCESS | 1684 | |2;audio-dft;196;203;239;256;253;241;273;197;279;279 09:54:22 INFO - PROCESS | 1684 | |3;audio-fft;71;77;73;134;69;68;95;81;81;57 09:54:22 INFO - PROCESS | 1684 | |4;audio-oscillator;81;82;123;76;97;98;121;79;83;123 09:54:22 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;110;89;90;87;91;86;90;87;103;131 09:54:22 INFO - PROCESS | 1684 | |6;imaging-darkroom;96;133;114;149;146;98;155;153;97;97 09:54:22 INFO - PROCESS | 1684 | |7;imaging-desaturate;92;77;142;147;96;93;91;90;92;89 09:54:22 INFO - PROCESS | 1684 | |8;json-parse-financial;61;64;62;61;64;62;62;83;68;88 09:54:22 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;67;69;68;67;79;76;68;68;68;68 09:54:22 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;85;85;84;83;85;82;86;83;83;85 09:54:22 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;160;146;138;139;139;139;138;137;222;139 09:54:22 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;170;165;170;168;174;171;185;230;175;169 09:54:22 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;68;69;70;66;99;96;92;98;93;71 09:54:22 INFO - PROCESS | 1684 | __end_tp_report 09:54:22 INFO - PROCESS | 1684 | __start_cc_report 09:54:22 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1083 09:54:22 INFO - PROCESS | 1684 | __end_cc_report 09:54:22 INFO - PROCESS | 1684 | __startTimestamp1461862462074__endTimestamp 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | ------- Summary: start ------- 09:54:22 INFO - PROCESS | 1684 | Number of tests: 14 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:125.30 Median:117.50 stddev:18.09 (15.4%) stddev-sans-first:18.93 09:54:22 INFO - PROCESS | 1684 | Values: 117.0 110.0 141.0 170.0 116.0 131.0 118.0 117.0 116.0 117.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:158.00 stddev:20.87 (13.2%) stddev-sans-first:21.80 09:54:22 INFO - PROCESS | 1684 | Values: 155.0 125.0 141.0 161.0 122.0 166.0 170.0 120.0 165.0 122.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:241.60 Median:254.50 stddev:32.81 (12.9%) stddev-sans-first:30.36 09:54:22 INFO - PROCESS | 1684 | Values: 196.0 203.0 239.0 256.0 253.0 241.0 273.0 197.0 279.0 279.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.00 stddev:21.27 (28.4%) stddev-sans-first:22.28 09:54:22 INFO - PROCESS | 1684 | Values: 71.0 77.0 73.0 134.0 69.0 68.0 95.0 81.0 81.0 57.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:81.50 stddev:19.34 (23.7%) stddev-sans-first:19.70 09:54:22 INFO - PROCESS | 1684 | Values: 81.0 82.0 123.0 76.0 97.0 98.0 121.0 79.0 83.0 123.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:88.00 stddev:14.44 (16.4%) stddev-sans-first:14.45 09:54:22 INFO - PROCESS | 1684 | Values: 110.0 89.0 90.0 87.0 91.0 86.0 90.0 87.0 103.0 131.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:123.80 Median:125.50 stddev:25.84 (20.6%) stddev-sans-first:25.37 09:54:22 INFO - PROCESS | 1684 | Values: 96.0 133.0 114.0 149.0 146.0 98.0 155.0 153.0 97.0 97.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:91.50 stddev:23.54 (25.7%) stddev-sans-first:24.75 09:54:22 INFO - PROCESS | 1684 | Values: 92.0 77.0 142.0 147.0 96.0 93.0 91.0 90.0 92.0 89.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:64.00 stddev:9.78 (15.3%) stddev-sans-first:10.08 09:54:22 INFO - PROCESS | 1684 | Values: 61.0 64.0 62.0 61.0 64.0 62.0 62.0 83.0 68.0 88.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:68.00 stddev:4.16 (6.1%) stddev-sans-first:4.28 09:54:22 INFO - PROCESS | 1684 | Values: 67.0 69.0 68.0 67.0 79.0 76.0 68.0 68.0 68.0 68.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:1.29 (1.5%) stddev-sans-first:1.32 09:54:22 INFO - PROCESS | 1684 | Values: 85.0 85.0 84.0 83.0 85.0 82.0 86.0 83.0 83.0 85.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:139.00 stddev:26.33 (18.9%) stddev-sans-first:27.66 09:54:22 INFO - PROCESS | 1684 | Values: 160.0 146.0 138.0 139.0 139.0 139.0 138.0 137.0 222.0 139.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:172.50 stddev:19.16 (11.1%) stddev-sans-first:20.12 09:54:22 INFO - PROCESS | 1684 | Values: 170.0 165.0 170.0 168.0 174.0 171.0 185.0 230.0 175.0 169.0 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.20 Median:92.50 stddev:14.33 (15.5%) stddev-sans-first:14.25 09:54:22 INFO - PROCESS | 1684 | Values: 68.0 69.0 70.0 66.0 99.0 96.0 92.0 98.0 93.0 71.0 09:54:22 INFO - PROCESS | 1684 | -------- Summary: end -------- 09:54:22 INFO - PROCESS | 1684 | 09:54:22 INFO - TEST-INFO | 1684: exit 0 09:54:22 INFO - TEST-OK | kraken | took 60318ms 09:54:22 INFO - SUITE-END | took 1172s 09:54:23 INFO - Completed test suite (00:19:32) 09:54:23 INFO - javascript benchmark 09:54:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2889.7543708784738, 2914.6502419709623, 3411.846466908989, 3428.70855977242, 3439.86240550378, 4848.975755121224, 5585.2739143911285, 5664.065429204176, 5705.914411283834, 5711.286089238845, 5575.080111781562, 5647.717614119299, 5768.480836724694, 5771.624844385112, 5778.3643799182055, 5164.845054648357, 5176.0683077046115, 5210.114280572293, 5281.9207711884355, 5295.443978382272, 4256.638185754215, 5248.343957005374, 5279.86800329991, 5297.867553311159, 5305.7877684892555, 4133.607307305801, 5135.922961155586, 5193.68837869729, 5259.0, 5278.049951008817, 5189.1697328427645, 5207.4011488678525, 5243.292155558989, 5243.947560524422, 5253.9737301313635, 5144.356955380577, 5160.587153027749, 5188.688678679291, 5240.816571419982, 5265.526102650741, 5478.863028424282, 5583.8883222335735, 5618.6628802272, 5706.629069110494, 5724.4561766632105, 6125.448709616111, 6135.294441139282, 6153.569250152491, 6155.476784473325, 6180.536459765535, 6195.7521699131985, 6202.937970620281, 6224.751009959597, 6230.844228894269, 6237.750489980395, 5764.221830052973, 5813.47678708914, 5878.441548052928, 5908.527317814607, 5937.910931336033, 6215.016213519581, 6266.717997690115, 6271.090691849657, 6279.96860015697, 6319.0, 6025.608335458269, 6148.1085242640465, 6161.630302181883, 6178.969105154445, 6184.938150618526, 6095.770848210712, 6145.095020543646, 6162.661053642003, 6189.257289125244, 6205.410486003822, 6077.6353418795015, 6102.065131056412, 6120.846978825565, 6171.537134714914, 6179.196704428486, 6041.939580604226, 6120.632762034292, 6149.385061493815, 6167.938320616826, 6188.690565471708, 4894.804207831647, 4917.139500587383, 4928.6057115431395, 4934.037862616755, 4973.0, 4320.308750599889, 4375.562443755663, 4413.558644135561, 4416.911661766747, 4429.247028005247, 5757.65454072757, 5835.649861008353, 5856.619319744203, 5880.882382352329, 5894.086416605433, 6060.090986352083, 6065.727042283108, 6087.239095113111, 6104.847378815565, 6114.144019837227, 4974.825881094217, 5382.78468861249, 5579.0, 5596.916046259309, 5615.775368985276, 5214.973925130426, 5262.868428289246, 5264.289320941701, 5352.330958630171, 5393.191021346829, 5387.626155330366, 6279.277883043417, 6308.27454842699, 6332.556721029576, 6389.7763578274535, 5185.274061631299, 6177.320494745664, 6219.315875253809, 6231.75072997075, 6232.501399887908, 6168.660723660242, 6263.592866463665, 6326.778562750282, 6327.050942358683, 6337.176167098248, 6174.969125154345, 6186.350433204449, 6191.102290167991, 6198.969005155126, 6200.3489633589625, 6172.8765424690355, 6200.193974783249, 6224.564280500322, 6228.190335256388, 6235.282942461494, 5857.677827719346, 5994.160817485468, 6057.909131363122, 6117.0824376342125, 6142.262928448615, 6099.420555047263, 6173.876522469615, 6185.8144255672405, 6188.845278868064, 6196.225471816023, 5943.732532035982, 5951.811550878885, 6035.39646035393, 6038.305594856648, 6105.389461053859, 4678.321643361762, 6055.0, 6127.877442451037, 6136.5090792737365, 6148.907766383419, 5918.082697181943, 5959.910601340896, 5970.164177015308, 5981.581289309706, 5982.551308651956], "value": 5642.653145669077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.3238828998417, 515.9577653140658, 525.3616855520542, 530.798296647274, 625.6246252248654, 29517.11448656541, 38401.80799096008, 38423.42364864526, 38468.61531384685, 38523.80738096309, 33918.49122263168, 34050.0, 37219.81390093052, 39102.8044859776, 39135.0, 9152.267818574532, 9452.43285402878, 9475.289353298496, 9491.667791627295, 9567.282453815957, 9544.236461083097, 9593.136617704406, 9636.95181524095, 9711.660091896785, 9840.0, 9703.2722545809, 9714.174295184917, 9727.854082188738, 9752.4636145012, 9846.655367062118, 9665.130138287554, 9706.029397060309, 9777.608895644165, 9809.705708828747, 9815.803683926282, 7699.653515591755, 8302.0, 9525.952370238174, 9653.276004299705, 9660.033996600354, 37985.810070949745, 38012.42981355282, 38235.42646860299, 38595.614043859765, 38622.80688596539, 2430.0401341470197, 2657.41536861892, 2735.6717193936656, 3224.0, 3398.6431424700545, 26234.86882565575, 27040.323991900164, 27524.0, 27611.171664850088, 27638.585421218697, 2620.9606855897177, 2629.2638061342855, 2651.893924243028, 2794.5109605819102, 2837.6169217155834, 363066.18466907763, 363477.0, 363668.0, 365493.1725341383, 366654.0, 32704.0, 57184.0, 57588.71205643945, 57690.711546442, 57749.13376299359, 30020.84989575038, 34515.82742086273, 34632.307353853284, 34718.13204669853, 34969.47545786815, 14679.55961321162, 14717.55847324582, 14773.630659233606, 14950.32723527422, 14954.850451495564, 12532.624021279376, 12602.180858244368, 12628.558000469939, 12647.43086561107, 12653.87346126527, 7959.522428654299, 8103.716369927024, 8106.554139522265, 8149.877751833727, 8178.5092894426525, 3395.9320813584086, 3401.4557670772556, 3449.5170676105367, 3457.239407330383, 3468.9826550867083, 988.9703308900745, 1002.4636819301638, 1040.0015984654817, 1108.645233525264, 1135.27909777292, 23119.768802312097, 23467.061317547108, 29245.268868278465, 29549.0, 30146.944856929902, 1009.1674368645749, 1090.939998300101, 1104.4974536585835, 1105.171121658756, 1123.6966019174777, 14039.578812635636, 14073.296335183404, 14083.295835208199, 14130.222837744022, 14348.0, 566017.0, 569820.0, 579181.0, 580394.0, 583692.0, 54279.18581221285, 56514.71742641261, 56539.43460565424, 56716.43283567194, 56863.71568142215, 1506.7438535449, 1508.2458770614692, 1514.0764133878326, 1525.9160746159068, 1529.648180918395, 27643.30891727723, 27648.861755691898, 27976.741046652543, 28219.858900705363, 28371.148865534065, 42389.36415953701, 43403.565964341215, 49224.26163607477, 49939.75030124826, 50260.0, 595161.0, 598191.0, 598677.0, 599672.0, 600050.0, 25237.621435678848, 25406.872965635055, 25470.617940731274, 25547.616785747858, 25866.87066564655, 40351.39472907967, 40707.389389159776, 40825.38761918633, 42240.0, 43707.78146109249, 35073.64926350704, 36338.45492317565, 36608.633913660524, 37288.44067339046, 37297.6270237305, 35284.94145175649, 35332.29335413329, 35719.64280357267, 35753.82123089368, 36166.819165904, 28578.571321430612, 34244.97265082051, 35367.46948795718, 35579.64420355763, 35862.0, 29188.416231675674, 29884.850575746983, 29954.400911982073, 30041.248968775955, 30061.69938300589, 19011.0, 23204.53590928138, 23451.06195752151, 23750.40623984345, 23890.163844265368], "value": 17692.917112258106, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1961958365767, 395.1997205658543, 398.22545149683856, 407.84094203260724, 411.0299692725168, 14579.343929523171, 19919.701204481928, 20596.794032059675, 20605.27881524147, 20684.0, 11837.940810295937, 12321.691957701061, 12356.0, 19708.50728731779, 20270.493237669067, 5416.702081385532, 5602.215689803431, 5617.045102332613, 5634.126710359901, 5692.402297758737, 6042.0, 6054.182685337489, 6091.78678746244, 6118.479929206001, 6124.938750612481, 5422.403535611099, 5607.887842243153, 6086.360932102132, 6087.878242435148, 6120.357362476942, 5482.2873026506695, 5485.50630443262, 5573.136163894603, 5596.916046259309, 5602.887942241173, 4756.167670657621, 5304.416514183436, 5336.17289320156, 5427.592930530226, 5663.745131469093, 13529.661758456019, 13850.653733656638, 13855.722885542333, 22694.0, 25671.74328256712, 1733.8006129295165, 2130.392838041166, 2161.827053835689, 2169.568085063866, 2284.1548627008115, 10094.9495252524, 10215.489225538768, 10232.256455033055, 12631.557895473707, 14010.668986446271, 1660.5682522544105, 1686.6289416328389, 1712.6317841663977, 1779.4483710049926, 1818.6635472437508, 94128.0, 102688.48655756748, 110800.0, 116170.25744613838, 117442.99999999914, 9781.510924453749, 11592.47833847479, 11828.52685892572, 11968.640940771791, 12137.939310303393, 14943.476978305707, 15432.84567154314, 15449.150296733562, 15503.14732689713, 15566.922165389327, 8010.759677209693, 8295.714164304542, 8701.521416322037, 8898.0, 9021.32340074497, 8539.139388304864, 8617.224449799549, 8661.047284798668, 8770.780730481789, 8786.912130878738, 4653.046125544255, 4733.597644200281, 4744.288356746516, 4760.667273036187, 4798.520147985243, 1698.9915050424668, 1959.931402400909, 1992.511834600503, 2050.261905713942, 2068.493219161315, 967.2842096848282, 997.7505623594102, 1023.534291897185, 1036.6682661548382, 1037.5227395398201, 15925.044497330196, 20155.596888062155, 20256.18975240974, 20740.688889666668, 20843.270485532932, 988.7280997725683, 990.6879333010079, 991.306085739985, 1012.1295685710284, 1043.3635482355758, 8297.336213102937, 10860.348379097279, 11058.834117488244, 11086.778264434666, 11110.333379997226, 580397.0, 592714.0, 604315.9784201051, 605621.0, 605960.0, 106339.0, 108723.45638271757, 117204.0, 127291.0, 130555.69444305435, 940.9482478463614, 1016.9135623472088, 1016.9593216271267, 1042.5100202904623, 1068.5351871935734, 18298.817011830244, 18485.53786155357, 18522.629547408706, 18736.344227951955, 18844.811551884304, 37776.81111594424, 48644.756776215894, 50090.49909501004, 50383.49616503788, 51211.231831521814, 65442.34557654362, 73280.53438931298, 75607.62196189204, 75921.24078759142, 77765.0, 11002.394868282001, 11522.0, 12225.449854756735, 12474.875251247371, 12586.0, 11629.709257268298, 13086.0, 13313.933430332787, 13550.390232439368, 13598.592042238748, 11148.996590306911, 11305.26515776472, 11486.540538378706, 11604.129690273094, 11799.587014454453, 9659.0, 10063.094321511098, 10490.475476226005, 10565.0, 11219.382933938712, 5476.3702174250475, 8117.594120294079, 11148.498317575892, 12004.899142649827, 12022.579209727617, 10602.363858168535, 11710.0, 11950.462229199533, 12307.692307692021, 12467.064970127458, 11639.53441862316, 11709.473073711533, 11739.002184814044, 11959.641210763692, 12011.63965081049], "value": 9517.452940627374, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.2129703202852, 1610.460495733929, 1813.6644720726667, 1896.914638841252, 2011.8591698581106, 7775.961120194405, 7776.144624091346, 8590.74227773167, 8941.301152780976, 8971.775705607362, 8648.308135349173, 8685.739427817176, 8811.691590794324, 8815.51514666693, 8865.911340886589, 2156.9892150539226, 2440.938191886535, 2495.862727549984, 2497.2633073243437, 2497.825152239344, 2074.968875466869, 2315.3748487908256, 2369.969063457393, 2475.616279476684, 2491.426971796488, 2455.2511483997373, 2456.987715061422, 2471.4315707387314, 2474.591692370757, 2495.0768215760195, 2436.2569416328015, 2453.276283496373, 2461.5569197544437, 2464.494778570389, 2470.505898820225, 2417.141914620302, 2450.9264722058365, 2459.483508463225, 2470.197185914585, 2473.0602371098867, 8894.28845692343, 8952.55237238142, 8961.507117108556, 8969.865452018224, 8972.10278972104, 5263.526282634543, 5538.363088244863, 5542.972285138589, 5553.500184983371, 5559.110542313251, 3625.202455459821, 3626.655467730561, 3647.7811331320204, 3716.1081340478363, 3724.404095344732, 877.2937785083005, 1047.649037572414, 1056.3028401255135, 1058.7247315697898, 1073.3077165228462, 11120.943510366422, 11664.475098620582, 11742.530298788039, 11767.58813441534, 11844.0, 4422.823087076546, 4931.778069986859, 4990.575801056878, 5025.673331233458, 5058.974705126524, 8877.866831997526, 10079.294449388619, 10102.444365559964, 10133.797324053625, 10154.593816247416, 5484.588655850827, 5522.3649280333275, 5553.416891226444, 5585.636933599303, 5595.160725891067, 4478.641708663299, 4740.478547359788, 4884.7313397762755, 4888.755562221946, 5077.898442031139, 4099.61053699898, 4106.313742608808, 4169.145325208325, 4181.937270940938, 4201.579842015835, 1204.3315959642482, 1224.74280401115, 1352.817369655084, 1368.5004973184914, 1396.6578188343922, 927.4296307770672, 931.8415869302235, 956.4548207521646, 957.090763774417, 983.341161421849, 5431.0, 5755.424457554211, 5786.797462088806, 5862.237909071793, 6075.33171351151, 409.63542447221994, 413.81792011514835, 454.0872845580407, 462.3942635147967, 484.2590836020894, 3873.1285460771664, 3876.6704830089193, 3894.162755007687, 3928.9017774555864, 3948.072203032285, 9176.6788162414, 10396.896031039592, 10791.46042697862, 10946.179036572288, 10990.17573681977, 10808.675739727822, 10819.513121909533, 10847.891521084846, 10857.782844343068, 10880.619178328721, 7824.765257042298, 8259.876101858477, 8285.005799304123, 8286.46138001028, 8289.0467596226, 4994.5754610858485, 5982.102684597346, 6024.578279520391, 6171.47542458896, 6231.501479881689, 9554.235661146953, 9830.361026533252, 10045.397276163454, 10190.337628054152, 10199.54102065394, 9976.800463990825, 10203.489825508843, 10265.435401053013, 10273.332233404804, 10331.431771252654, 7010.404115650227, 7454.329110380092, 7523.510971786816, 7688.077430708351, 7747.0, 8377.162283771575, 8413.663453462037, 8453.577321133796, 8465.195806398382, 8486.83026339482, 6431.742730290736, 7418.517796343221, 7529.661165247468, 7692.307692307719, 7770.53376797394, 7225.1329840419485, 7345.8898116527225, 7361.1166660001145, 7393.635895667351, 7443.441741869273, 7103.467239956921, 7134.036905017756, 7167.713291468492, 7175.605341706257, 7229.240929702516, 4901.9019619608125, 4926.7929357307685, 5036.420811606712, 5036.8992620146655, 5214.817481388133, 4978.0, 5010.073136469765, 5052.8484145475695, 5115.079285728604, 5239.764210610456], "value": 4565.658343138766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.569247976512, 315.83576540199095, 326.3212517962638, 327.1902042444949, 327.6445057113032, 5325.7869685212545, 5383.246345511631, 5502.835869375111, 5619.072852979259, 5684.630499017561, 5493.917591236124, 5495.6153069285065, 5525.668459892409, 5531.3915469298345, 5532.30846144232, 4243.745375277494, 4245.490541135068, 4248.80880360383, 4263.445752052245, 4317.639943417829, 4103.502221689092, 4285.400043993844, 4287.606527878452, 4295.828166873337, 4307.375430562566, 4168.0, 4171.72883762556, 4231.238377092122, 4235.322348424253, 4240.512341080769, 4240.342746874239, 4253.957780343804, 4271.487421509408, 4280.4221430106845, 4289.828406863721, 3155.952660710091, 3543.7519373643854, 3697.4084146536425, 3710.7959062251566, 3737.8504859805576, 5243.344581927259, 5246.527812496894, 5252.264682944391, 5259.815906443295, 5269.525742683177, 4816.638752093607, 4837.903241935177, 4855.271709243641, 4861.489543597907, 4921.286413470027, 5476.726163691799, 5528.198411230389, 5593.680695721504, 5721.885562288772, 5910.734016969247, 3515.701165400944, 3972.980135099335, 4016.5581786003518, 4048.7570745755056, 4073.083556199861, 5294.57643388528, 5541.390447050821, 5813.767449302023, 5896.233489646362, 6090.177825993478, 3548.28111466202, 4034.8184331705143, 4102.671786257152, 4117.21772863155, 4131.595257612426, 3746.6253374662315, 4025.5974402560096, 4210.5157906840495, 4236.322188449833, 4248.896796085957, 4058.492688413948, 4065.959340406558, 4765.880852978703, 4817.084753896818, 4862.294967229732, 5485.204645326406, 5838.649681019152, 5840.5035571976005, 5865.853353666107, 5905.372109411271, 1302.583173384508, 1524.67981723837, 1525.8556082937796, 1529.99785140733, 1533.9156346401055, 937.3110763588757, 997.6358629100471, 1016.1718199667248, 1030.6032177611523, 1031.242037102729, 5562.554995600342, 5788.131780232915, 5805.274340707411, 5844.240248767633, 5917.704114794243, 5145.819896303611, 5322.38792538863, 6277.96861015692, 6390.265119511316, 6451.741930322734, 5584.274044374205, 5665.915011274834, 5819.272590926134, 5836.620619659709, 5844.620099693507, 3200.455922493182, 3368.585194218434, 3387.2378714788974, 3394.321135772855, 3431.725461963037, 5373.838784836383, 6278.623282602967, 6697.832554186087, 6700.899486507707, 6709.429698475684, 4727.905441891143, 5926.525877929756, 6139.508839292846, 6159.538034647329, 6181.0, 5288.312519372457, 6231.937680623227, 6267.339155123896, 6284.748610055546, 6323.95654716985, 5080.593552515864, 5307.920381194208, 5705.144228365778, 6014.2181516404335, 6127.988881834447, 5736.971315143398, 5956.851078722893, 5976.103584462366, 6001.4298641628975, 6018.638881667114, 5990.1913241713555, 5996.610220335664, 6048.185544336593, 6214.844628884314, 6329.715162817776, 3245.6916592923635, 3517.856696573675, 3590.08452844523, 3999.4800675911947, 4002.7198096132993, 5734.476830194701, 5910.852228694317, 6029.366916473882, 6058.303295121117, 6092.238470191226, 4908.8772780680765, 5539.030669632879, 5722.0272553665145, 5819.272590926134, 5900.616459930091, 4908.582770464408, 5296.2055691646565, 5530.198121272312, 5785.334686511105, 5874.236349274567, 5434.456554344692, 5755.625884317506, 5776.624519406225, 5788.27646544182, 5814.186013957965, 5877.529797616095, 5880.235569375954, 5883.73476211653, 5911.290645122613, 5933.554983376178, 5521.5306698929435, 5804.65172089676, 5807.186993820784, 5828.825135245949, 5842.795502157404], "value": 4345.182294194892, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.0477166553068, 621.2110619513221, 639.6162302618429, 710.9395701365384, 733.1056111543918, 14165.504207352744, 14413.0, 14625.853741462583, 14633.487827926037, 14645.04807187532, 11634.360110193937, 13598.796018059737, 13829.723405531884, 13864.930675346634, 13874.861251387483, 720.4488566246825, 884.1158841158841, 905.2260317428603, 911.7264820553808, 918.1369512658114, 914.2548822709471, 923.9584218710174, 926.157196950775, 926.2543652359848, 926.5645146781036, 933.8412469880103, 941.948192849393, 959.1559427703646, 967.9612815487372, 970.1898914406479, 952.3714348529971, 954.9283803714749, 955.2930831184942, 958.1807554540907, 960.1214888377126, 682.4813142012057, 686.9141357330334, 701.8771714949863, 707.3987110955698, 712.8681193979079, 14760.040597361245, 14818.184999825005, 14852.331645075998, 14890.478833240893, 15062.774058389023, 11179.888201118047, 12523.185992910523, 12573.622791316275, 12692.80960785589, 12778.297193654345, 7498.250174982512, 8976.775580610538, 9651.034896510362, 9974.351667141613, 10001.34991225568, 3488.389531831919, 3489.476578513218, 3541.628129046439, 3591.94612080819, 3606.3328284267495, 67478.0, 81651.95870103236, 83721.0, 84188.57905710432, 85209.33116019588, 8429.704960326359, 9461.858072128924, 9480.146786789222, 9486.383385079947, 9760.853587196198, 22086.56826494723, 22309.665355019686, 22319.218827340967, 22341.441463963532, 22525.43686407853, 7138.607376594233, 7363.705451781976, 7463.5521868686965, 7541.5475071495885, 7574.242575742382, 3995.4006898965386, 4088.0, 4101.958980410158, 4104.610062044101, 4107.856225032109, 3591.4612808078996, 3607.783532988029, 3634.945475817865, 3697.3159965406485, 3697.5378077740284, 636.0872148466965, 645.6803882078308, 650.5771248688391, 657.8947368421029, 658.1969996604136, 494.39189796549726, 578.2107423367157, 593.2673148661399, 593.4214141212284, 605.288785676828, 8134.918650813534, 8163.673453061811, 8257.628406721711, 8321.04308004575, 8461.0, 524.8031988004499, 561.4161272276787, 564.305903738404, 603.9335673075958, 608.6743592178163, 3453.706434953057, 3869.5743468218475, 3900.8244628991765, 3926.7251292409255, 3988.6210809973004, 77523.0, 99290.0, 99517.0, 100762.4961875186, 101894.49052754689, 75062.6246868773, 77846.0, 81842.59078704569, 81991.0, 84120.57939710263, 67319.66340168366, 68512.65743671349, 68921.0, 68927.65536172387, 69064.0, 6394.584352017104, 7710.92289077102, 7833.804154896173, 7932.682692692228, 7992.080910695345, 32718.0, 44534.0, 44843.32735008912, 45988.31017534673, 46854.828629284544, 62541.68729156325, 69408.3059169422, 70398.64800675964, 70414.0, 70621.0, 10074.244431667508, 10477.52851121686, 10536.473176340998, 10651.30766500175, 10798.514066866852, 11035.282706624044, 11115.666530004113, 11165.72085697864, 11172.776544468903, 11352.148588856035, 10496.475176241005, 10596.735081623021, 10765.46172691378, 10833.566657333933, 10914.56341746353, 10366.481675916024, 10560.683179504627, 10619.681409557727, 10636.84044739314, 10765.40790256512, 10422.583096676048, 10532.526036328536, 10581.682549523526, 10634.14926805869, 10721.571137154426, 8775.561221939006, 8877.156670116328, 9137.360384773003, 9185.678501252423, 9193.632254710004, 8315.584220788814, 8588.742337729878, 8832.602532885907, 8835.469871807712, 8972.461652300883], "value": 6729.772878143942, "unit": "score"}], "name": "dromaeo_css", "value": 7088.577333807677, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 110.0, 141.0, 170.0, 116.0, 131.0, 118.0, 117.0, 116.0, 117.0], "value": 125.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 141.0, 161.0, 122.0, 166.0, 170.0, 120.0, 165.0, 122.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 203.0, 239.0, 256.0, 253.0, 241.0, 273.0, 197.0, 279.0, 279.0], "value": 241.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 73.0, 134.0, 69.0, 68.0, 95.0, 81.0, 81.0, 57.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 123.0, 76.0, 97.0, 98.0, 121.0, 79.0, 83.0, 123.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 89.0, 90.0, 87.0, 91.0, 86.0, 90.0, 87.0, 103.0, 131.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 133.0, 114.0, 149.0, 146.0, 98.0, 155.0, 153.0, 97.0, 97.0], "value": 123.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 77.0, 142.0, 147.0, 96.0, 93.0, 91.0, 90.0, 92.0, 89.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 62.0, 61.0, 64.0, 62.0, 62.0, 83.0, 68.0, 88.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 79.0, 76.0, 68.0, 68.0, 68.0, 68.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 83.0, 85.0, 82.0, 86.0, 83.0, 83.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 146.0, 138.0, 139.0, 139.0, 139.0, 138.0, 137.0, 222.0, 139.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 170.0, 168.0, 174.0, 171.0, 185.0, 230.0, 175.0, 169.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 66.0, 99.0, 96.0, 92.0, 98.0, 93.0, 71.0], "value": 82.2, "unit": "score"}], "name": "kraken", "value": 1640.6, "alertThreshold": 2.0}]} 09:54:23 INFO - Return code: 0 09:54:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:54:23 ERROR - # TBPL SUCCESS # 09:54:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:23 INFO - 2016-04-28 09:54:23.413 screenresolution[1689:10850] starting screenresolution argv=screenresolution get 09:54:23 INFO - 2016-04-28 09:54:23.415 screenresolution[1689:10850] Display 0: 1600x1200x32@60 09:54:23 INFO - 2016-04-28 09:54:23.421 screenresolution[1690:10853] starting screenresolution argv=screenresolution list 09:54:23 INFO - Available Modes on Display 0 09:54:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:23 INFO - Intel Iris: 09:54:23 INFO - Chipset Model: Intel Iris 09:54:23 INFO - Type: GPU 09:54:23 INFO - Bus: Built-In 09:54:23 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:23 INFO - Vendor: Intel (0x8086) 09:54:23 INFO - Device ID: 0x0a2e 09:54:23 INFO - Revision ID: 0x0009 09:54:23 INFO - Displays: 09:54:23 INFO - Display: 09:54:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:23 INFO - Display Serial Number: 1600x1200 60 09:54:23 INFO - Main Display: Yes 09:54:23 INFO - Mirror: Off 09:54:23 INFO - Online: Yes 09:54:23 INFO - Rotation: Supported 09:54:23 INFO - Return code: 0 09:54:23 INFO - Running post-action listener: _resource_record_post_action 09:54:23 INFO - Running post-run listener: _resource_record_post_run 09:54:24 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32595456; Write bytes: 698743808; Read time: 315; Write time: 2096 09:54:24 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 391625216; Write bytes: 386082816; Read time: 29438; Write time: 796 09:54:24 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30809600; Write bytes: 299119616; Read time: 296; Write time: 1266 09:54:24 INFO - Running post-run listener: _upload_blobber_files 09:54:24 INFO - Blob upload gear active. 09:54:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:24 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:24 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:26 INFO - (blobuploader) - INFO - Done attempting. 09:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:54:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:27 INFO - (blobuploader) - INFO - Done attempting. 09:54:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:27 INFO - Return code: 0 09:54:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a830da800eb43892e21b42cd99bd43a757b1973936bbf2e8885f220c21ce6be11c28d2b8e04df44cd0abbaebdb2ca941ef05c102a89881efd0ab158a0668a324", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db1901a760021c7eff2de1c1e47cc11a14f4a058087e3bf1c13d392ad44166fe0284c5983c9961c3c65e767503cbdafd825c8e7d206a6a989bb7a5597bcf05ea"} 09:54:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:27 INFO - Contents: 09:54:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a830da800eb43892e21b42cd99bd43a757b1973936bbf2e8885f220c21ce6be11c28d2b8e04df44cd0abbaebdb2ca941ef05c102a89881efd0ab158a0668a324", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db1901a760021c7eff2de1c1e47cc11a14f4a058087e3bf1c13d392ad44166fe0284c5983c9961c3c65e767503cbdafd825c8e7d206a6a989bb7a5597bcf05ea"} 09:54:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:27 INFO - Copying logs to upload dir... 09:54:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.570848 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-04-28 09:54:27.279102) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:54:27.282277) ========= 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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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/Firefox/sha512/a830da800eb43892e21b42cd99bd43a757b1973936bbf2e8885f220c21ce6be11c28d2b8e04df44cd0abbaebdb2ca941ef05c102a89881efd0ab158a0668a324", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db1901a760021c7eff2de1c1e47cc11a14f4a058087e3bf1c13d392ad44166fe0284c5983c9961c3c65e767503cbdafd825c8e7d206a6a989bb7a5597bcf05ea"} build_url:https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/Pq6fkBswSPK5L4cIyDszOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a830da800eb43892e21b42cd99bd43a757b1973936bbf2e8885f220c21ce6be11c28d2b8e04df44cd0abbaebdb2ca941ef05c102a89881efd0ab158a0668a324", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db1901a760021c7eff2de1c1e47cc11a14f4a058087e3bf1c13d392ad44166fe0284c5983c9961c3c65e767503cbdafd825c8e7d206a6a989bb7a5597bcf05ea"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:54:27.310453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:54:27.310753) ========= 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.vgrhlSXWU1/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.XJ8xjLhYvN/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.004686 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:54:27.359258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:54:27.359549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:54:27.405965) ========= ========= Total master_lag: 0.16 =========