builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0367 starttime: 1464648644.42 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.424241) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.425033) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.425390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.471676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.472141) ========= 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.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 15:50:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 170M=0s 2016-05-30 15:50:44 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.487846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.984692) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.985142) ========= 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.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.025049 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.036484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.037306) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 15:50:45,115 truncating revision to first 12 chars 2016-05-30 15:50:45,115 Setting DEBUG logging. 2016-05-30 15:50:45,116 attempt 1/10 2016-05-30 15:50:45,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 15:50:45,756 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927602 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.987253) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.987655) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.993169) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.993500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.011794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-30 15:50:46.012158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:50:46 INFO - MultiFileLogger online at 20160530 15:50:46 in /builds/slave/test 15:50:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:50:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:46 INFO - {'append_to_log': False, 15:50:46 INFO - 'base_work_dir': '/builds/slave/test', 15:50:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:50:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:46 INFO - 'branch': 'Mozilla-Aurora', 15:50:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:46 INFO - 'config_files': ('talos/mac_config.py',), 15:50:46 INFO - 'default_actions': ('clobber', 15:50:46 INFO - 'read-buildbot-config', 15:50:46 INFO - 'download-and-extract', 15:50:46 INFO - 'populate-webroot', 15:50:46 INFO - 'create-virtualenv', 15:50:46 INFO - 'install', 15:50:46 INFO - 'run-tests'), 15:50:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:46 INFO - 'download_minidump_stackwalk': True, 15:50:46 INFO - 'download_symbols': 'ondemand', 15:50:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:46 INFO - 'installer_path': 'installer.exe', 15:50:46 INFO - 'log_level': 'info', 15:50:46 INFO - 'log_name': 'talos', 15:50:46 INFO - 'log_to_console': True, 15:50:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:46 INFO - 'opt_config_files': (), 15:50:46 INFO - 'pip_index': False, 15:50:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'run_cmd_checks_enabled': True, 15:50:46 INFO - 'sps_profile': False, 15:50:46 INFO - 'sps_profile_interval': 0, 15:50:46 INFO - 'suite': 'dromaeojs-e10s', 15:50:46 INFO - 'system_bits': '32', 15:50:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:46 INFO - 'title': 't-yosemite-r7-0367', 15:50:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:46 INFO - 'use_talos_json': True, 15:50:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:46 INFO - 'work_dir': 'build'} 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running clobber step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: clobber 15:50:46 INFO - rmtree: /builds/slave/test/build 15:50:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:48 INFO - Running post-action listener: _resource_record_post_action 15:50:48 INFO - ##### 15:50:48 INFO - ##### Running read-buildbot-config step. 15:50:48 INFO - ##### 15:50:48 INFO - Running pre-action listener: _resource_record_pre_action 15:50:48 INFO - Running main action method: read_buildbot_config 15:50:48 INFO - Using buildbot properties: 15:50:48 INFO - { 15:50:48 INFO - "project": "", 15:50:48 INFO - "product": "firefox", 15:50:48 INFO - "script_repo_revision": "production", 15:50:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:50:48 INFO - "repository": "", 15:50:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:50:48 INFO - "buildid": "20160530120549", 15:50:48 INFO - "pgo_build": "False", 15:50:48 INFO - "basedir": "/builds/slave/test", 15:50:48 INFO - "buildnumber": 14, 15:50:48 INFO - "slavename": "t-yosemite-r7-0367", 15:50:48 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:50:48 INFO - "platform": "yosemite_r7", 15:50:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:50:48 INFO - "branch": "mozilla-aurora", 15:50:48 INFO - "repo_path": "releases/mozilla-aurora", 15:50:48 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 15:50:48 INFO - "stage_platform": "macosx64", 15:50:48 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 15:50:48 INFO - "slavebuilddir": "test" 15:50:48 INFO - } 15:50:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:50:48 INFO - Running post-action listener: _resource_record_post_action 15:50:48 INFO - ##### 15:50:48 INFO - ##### Running download-and-extract step. 15:50:48 INFO - ##### 15:50:48 INFO - Running pre-action listener: _resource_record_pre_action 15:50:48 INFO - Running main action method: download_and_extract 15:50:48 INFO - mkdir: /builds/slave/test/build/tests 15:50: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')]} 15:50:48 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:50:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:51:04 INFO - Downloaded 1199 bytes. 15:51:04 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:51:04 INFO - Using the following test package requirements: 15:51:04 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:04 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:51:04 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:51:04 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'jsshell-mac.zip'], 15:51:04 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:51:04 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:04 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:51:04 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:51:04 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:51:04 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:04 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:51:04 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:04 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:09 INFO - Downloaded 18463801 bytes. 15:51:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:12 INFO - Return code: 0 15:51:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:12 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:13 INFO - Downloaded 18463801 bytes. 15:51:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:17 INFO - Return code: 0 15:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:17 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:51:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:51:18 INFO - Downloaded 10840145 bytes. 15:51:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:51:18 INFO - Return code: 0 15:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:18 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:51:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:51:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:51:21 INFO - Downloaded 114159654 bytes. 15:51:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:21 INFO - mkdir: /builds/slave/test/properties 15:51:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:21 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:21 INFO - Contents: 15:51:21 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:22 INFO - Running post-action listener: _resource_record_post_action 15:51:22 INFO - Running post-action listener: set_extra_try_arguments 15:51:22 INFO - ##### 15:51:22 INFO - ##### Running populate-webroot step. 15:51:22 INFO - ##### 15:51:22 INFO - Running pre-action listener: _resource_record_pre_action 15:51:22 INFO - Running main action method: populate_webroot 15:51:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:22 INFO - '--tppagecycles', 15:51:22 INFO - '7'], 15:51:22 INFO - 'tests': ['tsvgm']}}, 15:51:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['damp', 'tps']}, 15:51:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['damp', 'tps']}, 15:51:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['dromaeo_dom']}, 15:51:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['a11yr', 15:51:22 INFO - 'ts_paint', 15:51:22 INFO - 'tpaint', 15:51:22 INFO - 'sessionrestore', 15:51:22 INFO - 'sessionrestore_no_auto_restore', 15:51:22 INFO - 'tabpaint']}, 15:51:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tsvgx', 15:51:22 INFO - 'tsvgr_opacity', 15:51:22 INFO - 'tart', 15:51:22 INFO - 'tscrollx', 15:51:22 INFO - 'cart']}, 15:51:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:22 INFO - 'tsvgr_opacity', 15:51:22 INFO - 'tart', 15:51:22 INFO - 'tscrollx', 15:51:22 INFO - 'cart']}, 15:51:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s'], 15:51:22 INFO - 'tests': ['tp5o']}, 15:51:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'tests': ['tp5o']}, 15:51:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--disable-e10s', 15:51:22 INFO - '--xperf_path', 15:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:22 INFO - 'tests': ['tp5n']}, 15:51:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:22 INFO - 'talos_options': ['--xperf_path', 15:51:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:22 INFO - 'tests': ['tp5n']}}, 15:51:22 INFO - 'talos.zip': {'path': '', 15:51:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:22 INFO - Running post-action listener: _resource_record_post_action 15:51:22 INFO - ##### 15:51:22 INFO - ##### Running create-virtualenv step. 15:51:22 INFO - ##### 15:51:22 INFO - Running pre-action listener: _resource_record_pre_action 15:51:22 INFO - Running main action method: create_virtualenv 15:51:22 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:51:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:22 INFO - Using real prefix '/tools/python27' 15:51:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:23 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:26 INFO - Installing pip.................done. 15:51:26 INFO - Return code: 0 15:51:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:26 INFO - 'HOME': '/Users/cltbld', 15:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PAGER': '/bin/cat', 15:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:26 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - 'XPC_FLAGS': '0x0', 15:51:26 INFO - 'XPC_SERVICE_NAME': '0', 15:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:51:26 INFO - Cleaning up... 15:51:26 INFO - Return code: 0 15:51:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:26 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:26 INFO - 'HOME': '/Users/cltbld', 15:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PAGER': '/bin/cat', 15:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:26 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - 'XPC_FLAGS': '0x0', 15:51:26 INFO - 'XPC_SERVICE_NAME': '0', 15:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:27 INFO - Downloading/unpacking psutil>=0.7.1 15:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:30 INFO - Installing collected packages: psutil 15:51:30 INFO - Running setup.py install for psutil 15:51:30 INFO - building 'psutil._psutil_osx' extension 15:51:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:51:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:51:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:51:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:51:31 INFO - building 'psutil._psutil_posix' extension 15:51:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:51:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:51:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:51:31 INFO - ^ 15:51:31 INFO - 1 warning generated. 15:51:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:51:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:31 INFO - Successfully installed psutil 15:51:31 INFO - Cleaning up... 15:51:31 INFO - Return code: 0 15:51:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:31 INFO - 'HOME': '/Users/cltbld', 15:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:31 INFO - 'LOGNAME': 'cltbld', 15:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:31 INFO - 'MOZ_NO_REMOTE': '1', 15:51:31 INFO - 'NO_EM_RESTART': '1', 15:51:31 INFO - 'PAGER': '/bin/cat', 15:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:31 INFO - 'PWD': '/builds/slave/test', 15:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:31 INFO - 'SHELL': '/bin/bash', 15:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:31 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:31 INFO - 'USER': 'cltbld', 15:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:31 INFO - 'XPC_FLAGS': '0x0', 15:51:31 INFO - 'XPC_SERVICE_NAME': '0', 15:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:51:34 INFO - Installing collected packages: mozsystemmonitor 15:51:34 INFO - Running setup.py install for mozsystemmonitor 15:51:34 INFO - Successfully installed mozsystemmonitor 15:51:34 INFO - Cleaning up... 15:51:34 INFO - Return code: 0 15:51:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:34 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:34 INFO - 'HOME': '/Users/cltbld', 15:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:34 INFO - 'LOGNAME': 'cltbld', 15:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:34 INFO - 'MOZ_NO_REMOTE': '1', 15:51:34 INFO - 'NO_EM_RESTART': '1', 15:51:34 INFO - 'PAGER': '/bin/cat', 15:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:34 INFO - 'PWD': '/builds/slave/test', 15:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:34 INFO - 'SHELL': '/bin/bash', 15:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:34 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:34 INFO - 'USER': 'cltbld', 15:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:34 INFO - 'XPC_FLAGS': '0x0', 15:51:34 INFO - 'XPC_SERVICE_NAME': '0', 15:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:34 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:38 INFO - Downloading docopt-0.6.1.tar.gz 15:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:38 INFO - Running setup.py install for blobuploader 15:51:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:38 INFO - Running setup.py install for requests 15:51:39 INFO - Running setup.py install for docopt 15:51:39 INFO - Successfully installed blobuploader requests docopt 15:51:39 INFO - Cleaning up... 15:51:39 INFO - Return code: 0 15:51:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:39 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:39 INFO - 'HOME': '/Users/cltbld', 15:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:39 INFO - 'LOGNAME': 'cltbld', 15:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:39 INFO - 'MOZ_NO_REMOTE': '1', 15:51:39 INFO - 'NO_EM_RESTART': '1', 15:51:39 INFO - 'PAGER': '/bin/cat', 15:51:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:39 INFO - 'PWD': '/builds/slave/test', 15:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:39 INFO - 'SHELL': '/bin/bash', 15:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:39 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:39 INFO - 'USER': 'cltbld', 15:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:39 INFO - 'XPC_FLAGS': '0x0', 15:51:39 INFO - 'XPC_SERVICE_NAME': '0', 15:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:39 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-5JG7C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:39 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-IaeQWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:39 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-bkxNIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-7meZmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-5D1rFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-uByXMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-BUXtSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-vCKA8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-exnNg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-W5WRCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-cpiQz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:40 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-urjl9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-9md7Is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-IEsSaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-psiwHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-VaGXko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:41 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-fKWCSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:41 INFO - Running setup.py install for manifestparser 15:51:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:41 INFO - Running setup.py install for mozcrash 15:51:41 INFO - Running setup.py install for mozdebug 15:51:41 INFO - Running setup.py install for mozdevice 15:51:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Running setup.py install for mozfile 15:51:42 INFO - Running setup.py install for mozhttpd 15:51:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Running setup.py install for mozinfo 15:51:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Running setup.py install for mozInstall 15:51:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Running setup.py install for mozleak 15:51:42 INFO - Running setup.py install for mozlog 15:51:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:42 INFO - Running setup.py install for moznetwork 15:51:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Running setup.py install for mozprocess 15:51:43 INFO - Running setup.py install for mozprofile 15:51:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Running setup.py install for mozrunner 15:51:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Running setup.py install for mozscreenshot 15:51:43 INFO - Running setup.py install for moztest 15:51:43 INFO - Running setup.py install for mozversion 15:51:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:44 INFO - Cleaning up... 15:51:44 INFO - Return code: 0 15:51:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:44 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:44 INFO - 'HOME': '/Users/cltbld', 15:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:44 INFO - 'LOGNAME': 'cltbld', 15:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:44 INFO - 'MOZ_NO_REMOTE': '1', 15:51:44 INFO - 'NO_EM_RESTART': '1', 15:51:44 INFO - 'PAGER': '/bin/cat', 15:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:44 INFO - 'PWD': '/builds/slave/test', 15:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:44 INFO - 'SHELL': '/bin/bash', 15:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:44 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:44 INFO - 'USER': 'cltbld', 15:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:44 INFO - 'XPC_FLAGS': '0x0', 15:51:44 INFO - 'XPC_SERVICE_NAME': '0', 15:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-bHYy89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-uNoUZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-YCWPfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ZXc0L1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-EuMrh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-zkii55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:44 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-_PDsls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-4h9TTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-KbFokB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-jT_vOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-wrv3Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-icMW0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-elQ82b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-IM2Deb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-lwd3Gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:45 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-vqLP4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:46 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-0fupPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:48 INFO - Downloading blessings-1.6.tar.gz 15:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:48 INFO - Installing collected packages: blessings 15:51:48 INFO - Running setup.py install for blessings 15:51:48 INFO - Successfully installed blessings 15:51:48 INFO - Cleaning up... 15:51:48 INFO - Return code: 0 15:51:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:49 INFO - Reading from file tmpfile_stdout 15:51:49 INFO - Current package versions: 15:51:49 INFO - blessings == 1.6 15:51:49 INFO - blobuploader == 1.2.4 15:51:49 INFO - docopt == 0.6.1 15:51:49 INFO - manifestparser == 1.1 15:51:49 INFO - mozInstall == 1.12 15:51:49 INFO - mozcrash == 0.17 15:51:49 INFO - mozdebug == 0.1 15:51:49 INFO - mozdevice == 0.48 15:51:49 INFO - mozfile == 1.2 15:51:49 INFO - mozhttpd == 0.7 15:51:49 INFO - mozinfo == 0.9 15:51:49 INFO - mozleak == 0.1 15:51:49 INFO - mozlog == 3.1 15:51:49 INFO - moznetwork == 0.27 15:51:49 INFO - mozprocess == 0.22 15:51:49 INFO - mozprofile == 0.28 15:51:49 INFO - mozrunner == 6.11 15:51:49 INFO - mozscreenshot == 0.1 15:51:49 INFO - mozsystemmonitor == 0.0 15:51:49 INFO - moztest == 0.7 15:51:49 INFO - mozversion == 1.4 15:51:49 INFO - psutil == 3.1.1 15:51:49 INFO - requests == 1.2.3 15:51:49 INFO - wsgiref == 0.1.2 15:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:49 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 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:49 INFO - 'HOME': '/Users/cltbld', 15:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:49 INFO - 'LOGNAME': 'cltbld', 15:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:49 INFO - 'MOZ_NO_REMOTE': '1', 15:51:49 INFO - 'NO_EM_RESTART': '1', 15:51:49 INFO - 'PAGER': '/bin/cat', 15:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:49 INFO - 'PWD': '/builds/slave/test', 15:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:49 INFO - 'SHELL': '/bin/bash', 15:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:49 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:49 INFO - 'USER': 'cltbld', 15:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:49 INFO - 'XPC_FLAGS': '0x0', 15:51:49 INFO - 'XPC_SERVICE_NAME': '0', 15:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:49 INFO - Cleaning up... 15:51:49 INFO - Return code: 0 15:51:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:49 INFO - 'HOME': '/Users/cltbld', 15:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:49 INFO - 'LOGNAME': 'cltbld', 15:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:49 INFO - 'MOZ_NO_REMOTE': '1', 15:51:49 INFO - 'NO_EM_RESTART': '1', 15:51:49 INFO - 'PAGER': '/bin/cat', 15:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:49 INFO - 'PWD': '/builds/slave/test', 15:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:49 INFO - 'SHELL': '/bin/bash', 15:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:49 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:49 INFO - 'USER': 'cltbld', 15:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:49 INFO - 'XPC_FLAGS': '0x0', 15:51:49 INFO - 'XPC_SERVICE_NAME': '0', 15:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:49 INFO - Downloading/unpacking jsonschema 15:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:52 INFO - Downloading/unpacking functools32 (from jsonschema) 15:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:52 INFO - warning: no files found matching '*.txt' 15:51:52 INFO - no previously-included directories found matching 'build' 15:51:52 INFO - no previously-included directories found matching 'dist' 15:51:52 INFO - no previously-included directories found matching '.git*' 15:51:52 INFO - Installing collected packages: jsonschema, functools32 15:51:52 INFO - Running setup.py install for functools32 15:51:52 INFO - warning: no files found matching '*.txt' 15:51:52 INFO - no previously-included directories found matching 'build' 15:51:52 INFO - no previously-included directories found matching 'dist' 15:51:52 INFO - no previously-included directories found matching '.git*' 15:51:52 INFO - Successfully installed jsonschema functools32 15:51:52 INFO - Cleaning up... 15:51:52 INFO - Return code: 0 15:51:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2fba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b30b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe73847c520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, '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.4E5yb4odj8/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.ApoP7bCuRe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:51:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:51:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:51:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:52 INFO - 'HOME': '/Users/cltbld', 15:51:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:52 INFO - 'LOGNAME': 'cltbld', 15:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:52 INFO - 'MOZ_NO_REMOTE': '1', 15:51:52 INFO - 'NO_EM_RESTART': '1', 15:51:52 INFO - 'PAGER': '/bin/cat', 15:51:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:52 INFO - 'PWD': '/builds/slave/test', 15:51:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:52 INFO - 'SHELL': '/bin/bash', 15:51:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:51:52 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:51:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:52 INFO - 'USER': 'cltbld', 15:51:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:52 INFO - 'XPC_FLAGS': '0x0', 15:51:52 INFO - 'XPC_SERVICE_NAME': '0', 15:51:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:53 INFO - Downloading/unpacking flake8 15:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:51:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:51:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:56 INFO - no previously-included directories found matching 'docs/_build' 15:51:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:51:56 INFO - Running setup.py install for pep8 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:56 INFO - no previously-included directories found matching 'docs/_build' 15:51:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:51:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:51:57 INFO - Cleaning up... 15:51:57 INFO - Return code: 0 15:51:57 INFO - Running post-action listener: _resource_record_post_action 15:51:57 INFO - Running post-action listener: _start_resource_monitoring 15:51:57 INFO - Starting resource monitoring. 15:51:57 INFO - ##### 15:51:57 INFO - ##### Running install step. 15:51:57 INFO - ##### 15:51:57 INFO - Running pre-action listener: _resource_record_pre_action 15:51:57 INFO - Running main action method: install 15:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:57 INFO - Reading from file tmpfile_stdout 15:51:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:57 INFO - Reading from file tmpfile_stdout 15:51:57 INFO - Output received: 15:51:57 INFO - Usage: mozinstall [options] installer 15:51:57 INFO - Options: 15:51:57 INFO - -h, --help show this help message and exit 15:51:57 INFO - -d DEST, --destination=DEST 15:51:57 INFO - Directory to install application into. [default: 15:51:57 INFO - "/builds/slave/test"] 15:51:57 INFO - --app=APP Application being installed. [default: firefox] 15:51:57 INFO - mkdir: /builds/slave/test/build/application 15:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:52:29 INFO - Reading from file tmpfile_stdout 15:52:29 INFO - Output received: 15:52:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:52:29 INFO - Running post-action listener: _resource_record_post_action 15:52:29 INFO - ##### 15:52:29 INFO - ##### Running run-tests step. 15:52:29 INFO - ##### 15:52:29 INFO - Running pre-action listener: _resource_record_pre_action 15:52:29 INFO - Running main action method: run_tests 15:52:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:29 INFO - 2016-05-30 15:52:29.320 screenresolution[1647:8124] starting screenresolution argv=screenresolution get 15:52:29 INFO - 2016-05-30 15:52:29.322 screenresolution[1647:8124] Display 0: 1600x1200x32@60 15:52:29 INFO - 2016-05-30 15:52:29.328 screenresolution[1648:8127] starting screenresolution argv=screenresolution list 15:52:29 INFO - Available Modes on Display 0 15:52:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:29 INFO - Intel Iris: 15:52:29 INFO - Chipset Model: Intel Iris 15:52:29 INFO - Type: GPU 15:52:29 INFO - Bus: Built-In 15:52:29 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:29 INFO - Vendor: Intel (0x8086) 15:52:29 INFO - Device ID: 0x0a2e 15:52:29 INFO - Revision ID: 0x0009 15:52:29 INFO - Displays: 15:52:29 INFO - Display: 15:52:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:29 INFO - Display Serial Number: 1600x1200 60 15:52:29 INFO - Main Display: Yes 15:52:29 INFO - Mirror: Off 15:52:29 INFO - Online: Yes 15:52:29 INFO - Rotation: Supported 15:52:29 INFO - Return code: 0 15:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:29 INFO - Python 2.7.3 15:52:29 INFO - Return code: 0 15:52:29 INFO - grabbing minidump binary from tooltool 15:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:29 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 0x10b19ae00>, '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 0x10b003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b195b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:52:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:52:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:29 INFO - Return code: 0 15:52:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:52:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:52:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:52:30 INFO - Return code: 0 15:52:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:52:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4E5yb4odj8/Render', 15:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:30 INFO - 'HOME': '/Users/cltbld', 15:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:30 INFO - 'LOGNAME': 'cltbld', 15:52:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:30 INFO - 'MOZ_NO_REMOTE': '1', 15:52:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:30 INFO - 'NO_EM_RESTART': '1', 15:52:30 INFO - 'PAGER': '/bin/cat', 15:52:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:30 INFO - 'PWD': '/builds/slave/test', 15:52:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:30 INFO - 'SHELL': '/bin/bash', 15:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ApoP7bCuRe/Listeners', 15:52:30 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 15:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:30 INFO - 'USER': 'cltbld', 15:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:30 INFO - 'XPC_FLAGS': '0x0', 15:52:30 INFO - 'XPC_SERVICE_NAME': '0', 15:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:52:30 INFO - mozversion application_buildid: 20160530120549 15:52:30 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:52:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:52:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:30 INFO - mozversion application_name: Firefox 15:52:30 INFO - mozversion application_remotingname: firefox-dev 15:52:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:52:30 INFO - mozversion application_vendor: Mozilla 15:52:30 INFO - mozversion application_version: 48.0a2 15:52:30 INFO - mozversion platform_buildid: 20160530120549 15:52:30 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:52:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:52:30 INFO - mozversion platform_version: 48.0a2 15:52:30 INFO - using testdate: 1464648750 15:52:30 INFO - actual date: 1464648750 15:52:30 INFO - starting webserver on 'localhost:49277' 15:52:30 INFO - SUITE-START | Running 2 tests 15:52:30 INFO - TEST-START | dromaeo_css 15:52:30 INFO - operating with platform_type : mac_ 15:52:30 INFO - Initialising browser for dromaeo_css test... 15:52:30 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpr43zQr/profile http://localhost:49277/getInfo.html) 15:52:33 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 15:52:33 INFO - PROCESS | 1666 | colorDepth:24 15:52:33 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 15:52:33 INFO - PROCESS | 1666 | __metrics 15:52:33 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 15:52:34 INFO - TEST-INFO | 1666: exit 0 15:52:34 INFO - Browser initialized. 15:52:34 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:34 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpr43zQr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:46 INFO - PROCESS | 1670 | RSS: Main: 221454336 15:52:46 INFO - PROCESS | 1670 | 15:53:36 INFO - PROCESS | 1670 | 1464648816091 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:55:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 15:55:48 INFO - PROCESS | 1670 | RSS: Main: 232644608 15:55:48 INFO - PROCESS | 1670 | 15:58:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 15:58:51 INFO - PROCESS | 1670 | RSS: Main: 243646464 15:58:51 INFO - PROCESS | 1670 | 16:01:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 16:01:53 INFO - PROCESS | 1670 | RSS: Main: 245497856 16:01:53 INFO - PROCESS | 1670 | 16:04:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 16:04:55 INFO - PROCESS | 1670 | RSS: Main: 247373824 16:04:55 INFO - PROCESS | 1670 | 16:07:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 16:07:59 INFO - PROCESS | 1670 | RSS: Main: 250261504 16:07:59 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 16:11:01 INFO - PROCESS | 1670 | RSS: Main: 250892288 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | __start_tp_report 16:11:01 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 16:11:01 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 16:11:01 INFO - PROCESS | 1670 | |i|pagename|runs| 16:11:01 INFO - PROCESS | 1670 | |0;dojo.html;3420.281740834425;3451.3787518246704;3462.2556150427654;3507.9649203507965;3514.3322768673966;5717.142428635707;5742.741576629051;5743.827685169446;5746.0806270996645;5761.452661997114;5720;5742.770289188438;5762;5799.391063938278;5830.941690583093;5203.141481655532;5240.711760853152;5243.397009343936;5300.363956325227;5378.623496355257;5148.974255128719;5333.333333333333;5342.1452567589195;5370.301860758096;5381.730913454312;5245.213218017289;5262.131748261532;5320.6275560710765;5359.678419294835;5369.5167434930845;4210.305299625574;5130.256112863651;5312.096943519611;5314.681119132864;5359.598030147716;5080;5139.434662187156;5262.55268302191;5277.498637629457;5279.208118782175;5253.922945796103;5512.834614961557;5651.378348381675;5663.886722265532;5759.366469688331;5761.510271626917;6193.94702900508;6219.813405597794;6236.314005459441;6294.464970477515;6166.722497487579;6194.194754681863;6251.187345645082;6270;6281.24625044993;5853.209816674737;5896.911546326808;5907.586468947133;5913.379095195029;5920.881582368372;5162.870928226749;5250.422453530108;6117.908231376533;6297.559170858088;6308.684565771693;6069.726862291208;6156.661383623854;6202.534809889276;6206.286277078194;6214.036824292151;5157.535821776059;6264.561480696308;6274.937250627435;6288.465480434123;6311.905321420182;5940.168376427304;6208.255009398901;6230.906536401958;6256.968715156395;6297.559170858088;5593.161025846074;6111.895622426848;6144.262688477412;6172.290186628596;6191.226096737908;4951.8266860659705;4986.476419975923;4987.675801072991;4989.7006179628615;5001.924971125436;4440.800163992628;4475.015496590745;4475.977620111878;4493.618042466427;4510.639148868148;5588.944110558924;5735.340435849931;5771.8557036074435;5899.557533184942;5922.615030023121;6179.752809887555;6218.5647004710145;6228.937710622927;6247.656378899204;6260.342664020303;5786.855328616818;5837.445442682938;5847.1229315603;5847.3275573308765;5902.76388944446;5348.1442969124755;5419.403865574784;5453.454654534515;5485.533729632946;5493.505584497414;6345.111684364193;6377.585456945284;6381.425671689571;6405.135306733531;6412;6108.615274519966;6214;6230.283517395557;6231.376862313732;6238.656873871981;6304.148939893055;6376.649284289409;6387.297397286202;6390.233172019387;6404.199475065617;6224.190855188977;6268.153799237045;6281.685915704288;6304.747810087545;6316.084167795552;6185.134081228722;6189.597676151216;6250.843728906631;6258.874822503433;6259.906101408392;6074.3014553325165;6109.358517355614;6115.909724157069;6167.84580385494;6181.320054794059;4796.760161991956;6117.571769976059;6130.724117414817;6132.233470816148;6145.508359331332;5001.42483614389;5755.251817263729;5926.348101708722;5984.072468767174;6010.429009244115;6118.80192544137;6120.204373431426;6123.3139694332585;6139.890808645427;6141.802348541992;5207.531322181022;5692.886142277214;5988.790392336247;6005.219321488354;6005.429484198994 16:11:01 INFO - PROCESS | 1670 | |1;ext.html;466.2563211677373;529.5710474515643;576.3372122059634;580.4949693766423;628.271205401734;29524.852375738144;33176.50235246473;35657.28685426293;38232;38233;32077.67922320776;32250.83874580624;39924.80037599815;40110;40141.397879031836;8350.540720260384;9522.428654280731;9787.070228328297;9828.508574571242;9835.70492885211;9671.903280967206;9692.321537492378;9791.167750741195;9794.020597940185;9812.067853553937;8309.501429914224;9735.172510336595;9789;9806.754831129207;9843.753906152368;9783.266255030865;9812.067853553901;9835;9854.260930430246;9864.210863131004;9694.757631059209;9825.4104753715;9833.85249221255;9838.508074596242;9862.50687465631;39549.406758898636;39947.20105597901;39952.20095598072;39980.60019399827;40075;3164.240582260264;3194.34515924235;3325.434676105068;3346.4645656695056;3378.2398960233995;28096;28201;28495.430091398262;28566.714332856613;28732.281692957633;2733.794965377585;2756.8483733394655;2781.513235183855;2794.3153927287944;2797.5803629455745;453382.46617534064;456860;456878;459702;460123;65072.37319066966;65092.67453662701;65093.02360464597;65265.347346526876;65330;64764.676176618814;66983.66508167525;67442.66278668576;69531.65234173898;71541;17588.91205543964;17636.911815441097;17721.822781772018;17785.377511787025;17803.287868485117;13211.669708257179;13300.534481293109;13440.059195856196;13457.798133028013;13506.324683765772;19622.11700473482;19920;19924.103415346344;19951.600967980557;19983.900080499505;7336.156342020629;8160.775883617386;8261.628226729812;8457.915420845837;8467.195616416431;948.5494390164616;962.5716556132454;998.3760149906309;1013.2704452793977;1163.0637336943842;27804.443911122067;28335.57496637552;28608.427831443256;28617.713822861504;28731.425371492453;1114.4594871487323;1128.198978725098;1130.7229728716352;1149.9885001150049;1180.0028975515675;14358.353874075692;14720.779188312184;14857.70284594302;15506.5348039559;16126.75809862853;632923;633967;636902.8154859289;644234;644415;60974.69512652497;61990;62017.75964480685;62118;62139.06791398133;1502.9473968411053;1524.0931645670807;1527.8854085943376;1530.211940850463;1538.2770098053898;30240.33678147723;30369.544456833606;30669.53995690111;31052.689473105882;31180.064598062094;39945.80027099962;42887.71336859899;47793.76103119462;53459.663508411046;55767;423257;504828.4758576184;509743;603514;604356;26282.211533654023;27242.72757272402;27275.72724272732;27661.72338276672;27705.168844934684;45263.32105018493;45463.31805022861;46586.76706616445;47061;47274.52725472839;37759.433608496445;37832.62167378291;37868.431973519866;37985.62014379821;37999;27995;36693.449598255516;36821.52713891414;37363.252734945694;37499;32010.839945801054;35990;37081;37411.43882841705;38285;30471;30722.078337649906;31518;32350.19124521906;32647.347053059282;23957.16149934744;24154.516909661357;24477.38806529851;24519.26442206737;24841 16:11:01 INFO - PROCESS | 1670 | |2;jquery.html;393.9566647668757;394.9861754838581;397.2055888223553;407.1571846278204;408;14839.55481335559;16244.918775406137;19917.900410497947;20628.071736771824;20642.17431302748;19955;20008.399748007545;20167;20321.695174572393;20402.285919992803;5607.2897766181295;5693.402192769761;5915.733791979371;5923.318818335903;5928.347881733006;4410.669199810011;5787.4212578742;5869.002269614176;5904.143899134644;5929.080992446177;5774.989376859033;5823.621464604808;5834.124881267801;5846.649201047951;5859.9414005860035;5516.531094856942;5712.11462223356;5807.070868661015;5815.941840581582;5820.738066787005;5513.145462453326;5588.720563971826;5642.746076426571;5647.632903861277;5650.6892120933335;23509.76490235093;23819.88090059556;24610.769461526852;24692.012319507197;24719;2059.7116403703494;2154.6121698094335;2222.388843068153;2261.434641339665;2279.088364654135;9217.585208665627;9742.902570974271;9798.706038818847;9832.262580306506;10024.94987525065;1709.333359989605;1748.5628592851788;1757.912104394788;1762.2510433065895;1782.6273769197646;84415;103393.4830325851;103535;109017.45491272652;110213.44893275482;11570.884291157148;11719.238249513754;11725.472353744102;11828.053755699524;11849.284802867713;12734.235945843278;14033.298335083206;14816.851831481546;14880.330385132478;14883.553493395215;8940.46357218569;8967.417117887317;8979.910200897908;8992.55037248135;9022.86465703015;7390.445716571278;9092.636294548143;9101.317401194938;9122.726318210463;9186.40288381253;4589;4589.196890544142;4608.377868987711;4662.254039353687;4709.058188362341;1998.7401637786995;2016.1229865008768;2022.646036943529;2029.9695504567148;2082.729245198145;839.9668407858369;846.9836196564148;886.2953951608487;896.4755617963434;977.0669011094387;18975.905120474585;19027.238910443426;19606.509837254183;19953;20029.899850500944;765.9846803063907;809.9797505062421;954.9856752148723;970.9611615535299;983.5033308179467;9208.907910920805;10596.788064238672;10690.572377104829;10716.303440276513;10722.731931701614;573885;577493;581324;600999;601229;112857.87142128639;113394.43302783433;115122;116019;116208.41895790467;1011.8482227665909;1028.3469996552244;1033.9069483746348;1041.8437234414898;1084.8535447714773;16945.06802125895;17596.648067038845;17687.64624707473;17776.733348999518;17851.285948561912;25998.480030398907;33262.835800746856;46957.35649252396;49611;49612.25581616351;65583;72864.54270914658;80159.59920200362;83122.5843870801;84109;7563.659635316534;7667;8111.837763244584;8206.179382061746;8309.95845020771;9011.639534418811;12084.214526055779;12296.75406491883;12430.12989090755;12565;8763.561821908752;9912.513123031604;10263.486825658538;10633.726101600094;10878;5039.798408063783;7502.649523085897;10036.89963100389;10566;10762.246642734934;5696.743646535833;6584.473242140713;10967.725806854893;11027.944860275968;11363.749987501204;8070.515769053875;8150.75547733569;8174.059983102019;8229;8291.212334828182;8130.7154249603345;9883.703488895344;10958.890411096107;11213.775724485628;11271.267367621078 16:11:01 INFO - PROCESS | 1670 | |3;mootools.html;1609.291911558914;1625.9999999999995;1876.5590086329719;1921.5580416504213;2007.4780557055162;7840.960795196016;8537.18896704812;8639.52482613456;8838.823223535534;8853.468791872485;8637;8645.265152462032;8708.521031343274;8771.91228087719;8795.428297160695;2446.7675570820743;2469.6912885889265;2470.888810003545;2477.578811602032;2485.515324511721;2085;2418.093215044358;2472.072972635262;2491.4643351679383;2498.9250322490266;2467.642191882176;2468.7531246875346;2476.715177754554;2511.962320565193;2527.304991127436;2470.234227389506;2494.3514686181634;2496.2386472125995;2514.4342522932375;2516.471540976397;2446.5351583199313;2458.262521243638;2505.4863752930605;2505.536475751992;2506.786923111538;7481.401487880957;8931;8937.50843703596;8955.283577313865;8988.910110898873;4612.423447069116;4639.466461356953;5341.305630268079;5486.670799752028;5506.174073888908;3413.9487907681155;3454.671806178409;3492.4062909305226;3522.0138361258887;3528.823558822074;1059.6026490066224;1077.450500244873;1081.1999120650742;1081.6052140968568;1084.3981590217427;12036.45835937385;12185.32980686062;12194.81707774384;12218.938905305506;12269;4858.58702010333;4898.583620392306;4937.876553086202;4957.950420495821;4980.302757613937;10245.48772561369;10262;10355.689329320132;10392.948035259775;10401.427921464392;5503.954248692736;5509.862253443696;5547.5007249347755;5559.11054231321;5573.526250268692;4426.601605855489;4885.487023862514;4976.751162441863;4990.975045124751;4999.175136102575;4118.320477121301;4137.731047481904;4158.6673066154635;4183.477065366829;4227.894302642397;1183.8753184474783;1262.066071107374;1287.4785711786742;1294.1006000829334;1324.4768316515115;785.4737325991599;893.5487578772678;913.6208473483563;946.8248374050822;955.9113337357895;5004.3494345734825;5761.740721667535;5851.941480585139;5857.70711464425;5890.175375447441;452.81660927323827;466.0818188169301;471.4107365792759;473.46734923211386;478.5979776987347;3467.531883195736;3769.208466222069;3831.6743076838225;3857.691384689218;3929.6856251500385;10478.476076196159;10788.46057697112;10921;10964.451777411097;10972.396518191576;10393.896061039444;10750.78498430027;10810.081143102925;10833.970772776574;10945.945270273756;8015.959920200362;8297.460665056751;8409.20112589303;8459.788505287417;8477.194666506795;5641.774329026957;5724.685142317213;5753;5776;5916.964531206935;9471.763205919908;9477.289203309641;9548.761280967754;9583.424994500352;9695.466749328572;9463.716088517356;9857;9922.206223502248;9966.900330996597;10001.199904007808;6836;7312.5246858954;7533.171351151259;7574.924250757422;7623.618819059136;8319.251267385964;8343.290820280345;8357.289630381485;8387.077421483755;8404.327653787805;7299.489035767658;7434.73978410753;7499.512531685423;7532.472726909064;7571.167171611229;7470.4770666052855;7540.924590754023;7596.81007974805;7628.961855190689;7750.341220996278;6982.511224214256;7020;7021.368076873106;7039.577625342496;7049.929500704928;4941.258811178352;4956.3804524434445;5102.821401250939;5153.201253805666;5200.2199670049795;4305.74165550068;5152.175651895679;5255.106631872514;5286.894262114659;5325.813596524103 16:11:01 INFO - PROCESS | 1670 | |4;prototype.html;300.6605572175956;322.0949132936449;322.72084646780525;326.4906745477056;329.74774297662293;4816.710997340162;6648.634325112117;6657.201135863697;6697.9283314669665;6713.160854893133;5629.35262444819;5657.66054036757;6226.816904788087;6548.345165483461;6561.245456772473;3438.9656103438956;4717.150912835688;4845.975770121145;4854.2718592211095;4871.707697538159;4785.994941062381;4823.842277853325;4833.564979151875;4841;4864.367632207808;4785;4798.976005119969;4807.735574543416;4826.155422801014;4827.63792715546;4816.157172494799;4852.902941941142;4866;4877.195262781637;4893.828715994923;4182.728122672017;4197.412362269285;4201.159768046372;4201.621854033121;4202.621764041221;5193.272941788153;5814.953308404485;6206.968965155191;6231.87536249277;6296.496280297565;5598.860028499279;5631.324241091054;5649.813539156782;5654;5657.868426314712;7078.893816592756;7087;7087.574745515234;7120.715171393138;7162.928370716279;4570.977145114286;4582.381378513891;4585.014221942299;4591.471980722226;4602.930956035662;7339.082614673166;7419.146798118178;7424.257574242587;7454.888176677354;7461.7388391406585;4435.423394958635;4598.701084429501;4628.074385122989;4631.351610774427;4651.883702907387;4674.672772905864;4722.858314250578;4727.361806156125;4730.266808644666;4730.337752714623;5575.358833734092;5605.943940560624;5606.747696353674;5637.43625637433;5657;7069.363757261871;7104.6447677615915;7119.288071192943;7121.537100088478;7129.108861392326;1617.5713435939485;1642.622196894721;1648.0276636784354;1648.5301689018572;1650.1336798180812;865.6234537975874;886.3956239207056;1009.8333774927055;1029.9330543514648;1044.1072882685346;6479.7408103675325;6590.505712071614;6590.9670451647435;6602.768903088465;6619.569727967667;7136.0723105995885;7337.88993165103;7352.558846469229;7420.85158296831;7437.367823734935;5194.68831870089;6463.6768161591735;6517.120188774549;6574;6694.297098804653;3880.165764360676;3902.0635047588376;3924.941125883114;3930.6265904739003;3935;6827.044213810071;7154.785356439315;7542.6605802739;7762.952001479841;7800.882986755203;6379;7291.671874765649;7672;7707.306342429209;7708.730194443167;7722.3049925506975;7737.342325902361;7738.574378409242;7742.458027937991;7762.650680719381;5962.612430192024;7356.264373562601;7376.446766492427;7393.8151546211775;7423.960645509741;7133.464990125865;7183.03029091066;7183.425325974014;7220.0613920190035;7232.819179520554;7381.557106573623;7665.463417560717;7686.154523002577;7711.344535714303;7736.690532378641;4627.444706635225;4656.41794775653;4681;4683.906321873612;4711.434627844544;7381.077365329334;7400.111986561647;7403.629818509161;7418.332350088518;7431.516951398142;7174.282571742784;7183.5330703504105;7186.568805871664;7192.352688258082;7198.748043818441;6487.610743355413;6932.584044957319;7046.26014268515;7055.964720176366;7148.964255178691;6946.79159625212;6949.3745562899585;6963.1644202696;6968.5122041458635;6993.895091573529;7139.286071392819;7154.463415243981;7160.140783106061;7165.892511612435;7213.531120477152;5692.857678558069;5921.289445266596;6709.93290067093;6985.091938048225;6994.356326263222 16:11:01 INFO - PROCESS | 1670 | |5;yui.html;626.0030502696399;668.4218151299126;730.7917243585579;747.9214682458341;762.3177256355565;14757.262136893167;14861.182634955074;14975.700485990288;15023.774643380359;15025.248737563134;13633.182009079437;13659.112157709742;13660.838828699547;13680.179189248653;13693.109947853383;713.2439614009141;897.4166791585473;903.5211337990843;928.4754113925644;932.5943214701626;913.4701872913728;926.3885835348672;928.1275600935134;929.0477260807659;929.5166513413021;963.4893506441597;980.289290264556;984.4880662055726;985.300436689951;988.293370240278;971.6696323250059;976.6386437018349;976.9169620582259;977.2621670638658;979.7158823941085;713.935745782882;723.479095051562;726.0960104669667;727.6689106456549;732.6556518436327;14278.286085695674;14468.927655361762;14518.782218266735;14522.564323070324;14537.927310363486;12646.430910609044;12675.93662031684;12876.420561074774;12911.612651620466;13065.542706005339;9105.124067681474;9469.431834089977;9725.638410622518;10198.388096714221;10254.282200245985;2920.4013177298602;3238.951415728766;3529.3117842020815;3529.400001999666;3621.112827357288;81261;81894.59052704758;81924.59037704833;82279.5886020572;82755;7829.882551761728;9357.39176953496;9364.01757188493;9386.85919711205;9607.951960240294;20594;20669;20921.267755628785;21176.78823211779;21254.46863828386;5984.910226346608;7431.033965584439;7520.285572870568;7609.771706848804;7710.653020613973;4088.6115818997146;4135.627793498599;4150.527102101002;4185.434966279513;4202.831886724558;3617.0957260684827;3676.356637588411;3687.1335236219466;3688.4098544232793;3697.334479793663;648.6335220600346;661.6427129350097;665.3878431842717;665.9367360100782;668.8227619680788;501.94980501950243;506.4530307268142;534.5597348105885;570.2045646323438;592.9822105336847;8250.958745206235;8355.373346999;8466.830663386698;8483.024452188078;8543.786405339917;533.7411355492583;568.6332315656379;581.831268932005;589.911613073878;597.0626116996274;3721.2361599713245;3743.513343265358;3772;3830.655241028265;3849.2301539692176;93287.53356233175;94433;94606.58090128654;95013;95559;77337;77534;78017.43965120666;78043;78766.21233787703;65035.67482162559;65137.674311628136;65187;65702.34297656962;65835.68328633497;6631.568948018364;7463.589502577411;7660.5403675779635;7796.688132474637;7889.250521200477;48281.75859120682;48920.5107948916;49501;50100;51016;63544.68227658832;69602;69712.60574788577;69826;71113.22216944411;9941.30410871261;9972.800543989224;10175.898241017496;10258.538365773707;10408.063274305348;10929.344239345664;11167.776644466903;11244.718882028015;11399.145064120059;11412.429378531206;10373.844392333971;10381.480925953521;10420.635277765241;10569.418681972258;10826.566937322734;10464.102219099388;10488.370697758159;10493.422861742372;10761.730956726144;10924.617638382619;9699.933007369043;9716.562754676197;10224.182065434896;10339.327943683636;10908.018278354986;8664.696735614223;8816.779580510281;8947.731568052968;9055.230305424113;9169.312301577274;8897.688580899638;8910.643574256957;8952.507612081397;9039.728808135766;9072.138146876036 16:11:01 INFO - PROCESS | 1670 | __end_tp_report 16:11:01 INFO - PROCESS | 1670 | __start_cc_report 16:11:01 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,415 16:11:01 INFO - PROCESS | 1670 | __end_cc_report 16:11:01 INFO - PROCESS | 1670 | __startTimestamp1464649861484__endTimestamp 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | ------- Summary: start ------- 16:11:01 INFO - PROCESS | 1670 | Number of tests: 6 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5731.81 Median:5913.38 stddev:635.92 (10.8%) stddev-sans-first:611.47 16:11:01 INFO - PROCESS | 1670 | Values: 3420.3 3451.4 3462.3 3508.0 3514.3 5717.1 5742.7 5743.8 5746.1 5761.5 5720.0 5742.8 5762.0 5799.4 5830.9 5203.1 5240.7 5243.4 5300.4 5378.6 5149.0 5333.3 5342.1 5370.3 5381.7 5245.2 5262.1 5320.6 5359.7 5369.5 4210.3 5130.3 5312.1 5314.7 5359.6 5080.0 5139.4 5262.6 5277.5 5279.2 5253.9 5512.8 5651.4 5663.9 5759.4 5761.5 6193.9 6219.8 6236.3 6294.5 6166.7 6194.2 6251.2 6270.0 6281.2 5853.2 5896.9 5907.6 5913.4 5920.9 5162.9 5250.4 6117.9 6297.6 6308.7 6069.7 6156.7 6202.5 6206.3 6214.0 5157.5 6264.6 6274.9 6288.5 6311.9 5940.2 6208.3 6230.9 6257.0 6297.6 5593.2 6111.9 6144.3 6172.3 6191.2 4951.8 4986.5 4987.7 4989.7 5001.9 4440.8 4475.0 4476.0 4493.6 4510.6 5588.9 5735.3 5771.9 5899.6 5922.6 6179.8 6218.6 6228.9 6247.7 6260.3 5786.9 5837.4 5847.1 5847.3 5902.8 5348.1 5419.4 5453.5 5485.5 5493.5 6345.1 6377.6 6381.4 6405.1 6412.0 6108.6 6214.0 6230.3 6231.4 6238.7 6304.1 6376.6 6387.3 6390.2 6404.2 6224.2 6268.2 6281.7 6304.7 6316.1 6185.1 6189.6 6250.8 6258.9 6259.9 6074.3 6109.4 6115.9 6167.8 6181.3 4796.8 6117.6 6130.7 6132.2 6145.5 5001.4 5755.3 5926.3 5984.1 6010.4 6118.8 6120.2 6123.3 6139.9 6141.8 5207.5 5692.9 5988.8 6005.2 6005.4 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:67751.38 Median:38115.50 stddev:146493.72 (384.3%) stddev-sans-first:146817.57 16:11:01 INFO - PROCESS | 1670 | Values: 466.3 529.6 576.3 580.5 628.3 29524.9 33176.5 35657.3 38232.0 38233.0 32077.7 32250.8 39924.8 40110.0 40141.4 8350.5 9522.4 9787.1 9828.5 9835.7 9671.9 9692.3 9791.2 9794.0 9812.1 8309.5 9735.2 9789.0 9806.8 9843.8 9783.3 9812.1 9835.0 9854.3 9864.2 9694.8 9825.4 9833.9 9838.5 9862.5 39549.4 39947.2 39952.2 39980.6 40075.0 3164.2 3194.3 3325.4 3346.5 3378.2 28096.0 28201.0 28495.4 28566.7 28732.3 2733.8 2756.8 2781.5 2794.3 2797.6 453382.5 456860.0 456878.0 459702.0 460123.0 65072.4 65092.7 65093.0 65265.3 65330.0 64764.7 66983.7 67442.7 69531.7 71541.0 17588.9 17636.9 17721.8 17785.4 17803.3 13211.7 13300.5 13440.1 13457.8 13506.3 19622.1 19920.0 19924.1 19951.6 19983.9 7336.2 8160.8 8261.6 8457.9 8467.2 948.5 962.6 998.4 1013.3 1163.1 27804.4 28335.6 28608.4 28617.7 28731.4 1114.5 1128.2 1130.7 1150.0 1180.0 14358.4 14720.8 14857.7 15506.5 16126.8 632923.0 633967.0 636902.8 644234.0 644415.0 60974.7 61990.0 62017.8 62118.0 62139.1 1502.9 1524.1 1527.9 1530.2 1538.3 30240.3 30369.5 30669.5 31052.7 31180.1 39945.8 42887.7 47793.8 53459.7 55767.0 423257.0 504828.5 509743.0 603514.0 604356.0 26282.2 27242.7 27275.7 27661.7 27705.2 45263.3 45463.3 46586.8 47061.0 47274.5 37759.4 37832.6 37868.4 37985.6 37999.0 27995.0 36693.4 36821.5 37363.3 37499.0 32010.8 35990.0 37081.0 37411.4 38285.0 30471.0 30722.1 31518.0 32350.2 32647.3 23957.2 24154.5 24477.4 24519.3 24841.0 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:33179.60 Median:407.58 stddev:97468.94 (23914.1%) stddev-sans-first:97711.28 16:11:01 INFO - PROCESS | 1670 | Values: 394.0 395.0 397.2 407.2 408.0 14839.6 16244.9 19917.9 20628.1 20642.2 19955.0 20008.4 20167.0 20321.7 20402.3 5607.3 5693.4 5915.7 5923.3 5928.3 4410.7 5787.4 5869.0 5904.1 5929.1 5775.0 5823.6 5834.1 5846.6 5859.9 5516.5 5712.1 5807.1 5815.9 5820.7 5513.1 5588.7 5642.7 5647.6 5650.7 23509.8 23819.9 24610.8 24692.0 24719.0 2059.7 2154.6 2222.4 2261.4 2279.1 9217.6 9742.9 9798.7 9832.3 10024.9 1709.3 1748.6 1757.9 1762.3 1782.6 84415.0 103393.5 103535.0 109017.5 110213.4 11570.9 11719.2 11725.5 11828.1 11849.3 12734.2 14033.3 14816.9 14880.3 14883.6 8940.5 8967.4 8979.9 8992.6 9022.9 7390.4 9092.6 9101.3 9122.7 9186.4 4589.0 4589.2 4608.4 4662.3 4709.1 1998.7 2016.1 2022.6 2030.0 2082.7 840.0 847.0 886.3 896.5 977.1 18975.9 19027.2 19606.5 19953.0 20029.9 766.0 810.0 955.0 971.0 983.5 9208.9 10596.8 10690.6 10716.3 10722.7 573885.0 577493.0 581324.0 600999.0 601229.0 112857.9 113394.4 115122.0 116019.0 116208.4 1011.8 1028.3 1033.9 1041.8 1084.9 16945.1 17596.6 17687.6 17776.7 17851.3 25998.5 33262.8 46957.4 49611.0 49612.3 65583.0 72864.5 80159.6 83122.6 84109.0 7563.7 7667.0 8111.8 8206.2 8310.0 9011.6 12084.2 12296.8 12430.1 12565.0 8763.6 9912.5 10263.5 10633.7 10878.0 5039.8 7502.6 10036.9 10566.0 10762.2 5696.7 6584.5 10967.7 11027.9 11363.7 8070.5 8150.8 8174.1 8229.0 8291.2 8130.7 9883.7 10958.9 11213.8 11271.3 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5745.57 Median:4957.17 stddev:3223.32 (65.0%) stddev-sans-first:3217.38 16:11:01 INFO - PROCESS | 1670 | Values: 1609.3 1626.0 1876.6 1921.6 2007.5 7841.0 8537.2 8639.5 8838.8 8853.5 8637.0 8645.3 8708.5 8771.9 8795.4 2446.8 2469.7 2470.9 2477.6 2485.5 2085.0 2418.1 2472.1 2491.5 2498.9 2467.6 2468.8 2476.7 2512.0 2527.3 2470.2 2494.4 2496.2 2514.4 2516.5 2446.5 2458.3 2505.5 2505.5 2506.8 7481.4 8931.0 8937.5 8955.3 8988.9 4612.4 4639.5 5341.3 5486.7 5506.2 3413.9 3454.7 3492.4 3522.0 3528.8 1059.6 1077.5 1081.2 1081.6 1084.4 12036.5 12185.3 12194.8 12218.9 12269.0 4858.6 4898.6 4937.9 4958.0 4980.3 10245.5 10262.0 10355.7 10392.9 10401.4 5504.0 5509.9 5547.5 5559.1 5573.5 4426.6 4885.5 4976.8 4991.0 4999.2 4118.3 4137.7 4158.7 4183.5 4227.9 1183.9 1262.1 1287.5 1294.1 1324.5 785.5 893.5 913.6 946.8 955.9 5004.3 5761.7 5851.9 5857.7 5890.2 452.8 466.1 471.4 473.5 478.6 3467.5 3769.2 3831.7 3857.7 3929.7 10478.5 10788.5 10921.0 10964.5 10972.4 10393.9 10750.8 10810.1 10834.0 10945.9 8016.0 8297.5 8409.2 8459.8 8477.2 5641.8 5724.7 5753.0 5776.0 5917.0 9471.8 9477.3 9548.8 9583.4 9695.5 9463.7 9857.0 9922.2 9966.9 10001.2 6836.0 7312.5 7533.2 7574.9 7623.6 8319.3 8343.3 8357.3 8387.1 8404.3 7299.5 7434.7 7499.5 7532.5 7571.2 7470.5 7540.9 7596.8 7629.0 7750.3 6982.5 7020.0 7021.4 7039.6 7049.9 4941.3 4956.4 5102.8 5153.2 5200.2 4305.7 5152.2 5255.1 5286.9 5325.8 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:5741.22 Median:6532.73 stddev:1861.28 (28.5%) stddev-sans-first:1821.16 16:11:01 INFO - PROCESS | 1670 | Values: 300.7 322.1 322.7 326.5 329.7 4816.7 6648.6 6657.2 6697.9 6713.2 5629.4 5657.7 6226.8 6548.3 6561.2 3439.0 4717.2 4846.0 4854.3 4871.7 4786.0 4823.8 4833.6 4841.0 4864.4 4785.0 4799.0 4807.7 4826.2 4827.6 4816.2 4852.9 4866.0 4877.2 4893.8 4182.7 4197.4 4201.2 4201.6 4202.6 5193.3 5815.0 6207.0 6231.9 6296.5 5598.9 5631.3 5649.8 5654.0 5657.9 7078.9 7087.0 7087.6 7120.7 7162.9 4571.0 4582.4 4585.0 4591.5 4602.9 7339.1 7419.1 7424.3 7454.9 7461.7 4435.4 4598.7 4628.1 4631.4 4651.9 4674.7 4722.9 4727.4 4730.3 4730.3 5575.4 5605.9 5606.7 5637.4 5657.0 7069.4 7104.6 7119.3 7121.5 7129.1 1617.6 1642.6 1648.0 1648.5 1650.1 865.6 886.4 1009.8 1029.9 1044.1 6479.7 6590.5 6591.0 6602.8 6619.6 7136.1 7337.9 7352.6 7420.9 7437.4 5194.7 6463.7 6517.1 6574.0 6694.3 3880.2 3902.1 3924.9 3930.6 3935.0 6827.0 7154.8 7542.7 7763.0 7800.9 6379.0 7291.7 7672.0 7707.3 7708.7 7722.3 7737.3 7738.6 7742.5 7762.7 5962.6 7356.3 7376.4 7393.8 7424.0 7133.5 7183.0 7183.4 7220.1 7232.8 7381.6 7665.5 7686.2 7711.3 7736.7 4627.4 4656.4 4681.0 4683.9 4711.4 7381.1 7400.1 7403.6 7418.3 7431.5 7174.3 7183.5 7186.6 7192.4 7198.7 6487.6 6932.6 7046.3 7056.0 7149.0 6946.8 6949.4 6963.2 6968.5 6993.9 7139.3 7154.5 7160.1 7165.9 7213.5 5692.9 5921.3 6709.9 6985.1 6994.4 16:11:01 INFO - PROCESS | 1670 | 16:11:01 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:18045.33 Median:65769.01 stddev:25812.11 (39.2%) stddev-sans-first:25851.39 16:11:01 INFO - PROCESS | 1670 | Values: 626.0 668.4 730.8 747.9 762.3 14757.3 14861.2 14975.7 15023.8 15025.2 13633.2 13659.1 13660.8 13680.2 13693.1 713.2 897.4 903.5 928.5 932.6 913.5 926.4 928.1 929.0 929.5 963.5 980.3 984.5 985.3 988.3 971.7 976.6 976.9 977.3 979.7 713.9 723.5 726.1 727.7 732.7 14278.3 14468.9 14518.8 14522.6 14537.9 12646.4 12675.9 12876.4 12911.6 13065.5 9105.1 9469.4 9725.6 10198.4 10254.3 2920.4 3239.0 3529.3 3529.4 3621.1 81261.0 81894.6 81924.6 82279.6 82755.0 7829.9 9357.4 9364.0 9386.9 9608.0 20594.0 20669.0 20921.3 21176.8 21254.5 5984.9 7431.0 7520.3 7609.8 7710.7 4088.6 4135.6 4150.5 4185.4 4202.8 3617.1 3676.4 3687.1 3688.4 3697.3 648.6 661.6 665.4 665.9 668.8 501.9 506.5 534.6 570.2 593.0 8251.0 8355.4 8466.8 8483.0 8543.8 533.7 568.6 581.8 589.9 597.1 3721.2 3743.5 3772.0 3830.7 3849.2 93287.5 94433.0 94606.6 95013.0 95559.0 77337.0 77534.0 78017.4 78043.0 78766.2 65035.7 65137.7 65187.0 65702.3 65835.7 6631.6 7463.6 7660.5 7796.7 7889.3 48281.8 48920.5 49501.0 50100.0 51016.0 63544.7 69602.0 69712.6 69826.0 71113.2 9941.3 9972.8 10175.9 10258.5 10408.1 10929.3 11167.8 11244.7 11399.1 11412.4 10373.8 10381.5 10420.6 10569.4 10826.6 10464.1 10488.4 10493.4 10761.7 10924.6 9699.9 9716.6 10224.2 10339.3 10908.0 8664.7 8816.8 8947.7 9055.2 9169.3 8897.7 8910.6 8952.5 9039.7 9072.1 16:11:01 INFO - PROCESS | 1670 | -------- Summary: end -------- 16:11:01 INFO - PROCESS | 1670 | 16:11:02 INFO - TEST-INFO | 1670: exit 0 16:11:02 INFO - TEST-OK | dromaeo_css | took 1111174ms 16:11:02 INFO - TEST-START | kraken 16:11:02 INFO - operating with platform_type : mac_ 16:11:02 INFO - Initialising browser for kraken test... 16:11:02 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHJpKDC/profile http://localhost:49277/getInfo.html) 16:11:05 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 16:11:05 INFO - PROCESS | 1677 | colorDepth:24 16:11:05 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 16:11:05 INFO - PROCESS | 1677 | __metrics 16:11:05 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 16:11:05 INFO - TEST-INFO | 1677: exit 0 16:11:05 INFO - Browser initialized. 16:11:05 INFO - Running cycle 1/1 for kraken test... 16:11:05 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpHJpKDC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:17 INFO - PROCESS | 1680 | RSS: Main: 221233152 16:11:17 INFO - PROCESS | 1680 | 16:11:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:21 INFO - PROCESS | 1680 | RSS: Main: 232849408 16:11:21 INFO - PROCESS | 1680 | 16:11:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 16:11:25 INFO - PROCESS | 1680 | RSS: Main: 241659904 16:11:25 INFO - PROCESS | 1680 | 16:11:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 16:11:29 INFO - PROCESS | 1680 | RSS: Main: 245555200 16:11:29 INFO - PROCESS | 1680 | 16:11:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 16:11:31 INFO - PROCESS | 1680 | RSS: Main: 246628352 16:11:31 INFO - PROCESS | 1680 | 16:11:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:34 INFO - PROCESS | 1680 | RSS: Main: 246853632 16:11:34 INFO - PROCESS | 1680 | 16:11:37 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:37 INFO - PROCESS | 1680 | RSS: Main: 247570432 16:11:37 INFO - PROCESS | 1680 | 16:11:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:41 INFO - PROCESS | 1680 | RSS: Main: 247693312 16:11:41 INFO - PROCESS | 1680 | 16:11:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 16:11:45 INFO - PROCESS | 1680 | RSS: Main: 247689216 16:11:45 INFO - PROCESS | 1680 | 16:11:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:47 INFO - PROCESS | 1680 | RSS: Main: 246771712 16:11:47 INFO - PROCESS | 1680 | 16:11:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:49 INFO - PROCESS | 1680 | RSS: Main: 246984704 16:11:49 INFO - PROCESS | 1680 | 16:11:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:52 INFO - PROCESS | 1680 | RSS: Main: 247599104 16:11:52 INFO - PROCESS | 1680 | 16:11:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:56 INFO - PROCESS | 1680 | RSS: Main: 247631872 16:11:56 INFO - PROCESS | 1680 | 16:11:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:11:59 INFO - PROCESS | 1680 | RSS: Main: 247676928 16:11:59 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 16:12:01 INFO - PROCESS | 1680 | RSS: Main: 249569280 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | __start_tp_report 16:12:01 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 16:12:01 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 16:12:01 INFO - PROCESS | 1680 | |i|pagename|runs| 16:12:01 INFO - PROCESS | 1680 | |0;ai-astar;113;127;156;171;99;168;151;130;116;115 16:12:01 INFO - PROCESS | 1680 | |1;audio-beat-detection;166;110;185;187;157;166;125;159;166;121 16:12:01 INFO - PROCESS | 1680 | |2;audio-dft;194;243;221;201;197;197;196;199;200;197 16:12:01 INFO - PROCESS | 1680 | |3;audio-fft;60;71;79;140;59;59;58;62;128;57 16:12:01 INFO - PROCESS | 1680 | |4;audio-oscillator;81;102;147;77;80;78;118;82;79;118 16:12:01 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;89;88;90;87;89;87;90;88;90;88 16:12:01 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;100;97;102;146;96;96;96;95;96 16:12:01 INFO - PROCESS | 1680 | |7;imaging-desaturate;149;139;95;82;142;143;96;114;150;152 16:12:01 INFO - PROCESS | 1680 | |8;json-parse-financial;61;67;65;65;65;66;65;86;64;64 16:12:01 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;65;68;63;77;107;115;71;67;71;67 16:12:01 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;81;84;80;83;85;81;84;85;111;112 16:12:01 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;155;125;141;150;138;150;144;132;149;157 16:12:01 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;174;172;167;174;168;166;187;210;167;168 16:12:01 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;71;70;68;67;71;66;68;69;67;70 16:12:01 INFO - PROCESS | 1680 | __end_tp_report 16:12:01 INFO - PROCESS | 1680 | __start_cc_report 16:12:01 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1113 16:12:01 INFO - PROCESS | 1680 | __end_cc_report 16:12:01 INFO - PROCESS | 1680 | __startTimestamp1464649921614__endTimestamp 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | ------- Summary: start ------- 16:12:01 INFO - PROCESS | 1680 | Number of tests: 14 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:134.60 Median:153.50 stddev:25.19 (16.4%) stddev-sans-first:25.48 16:12:01 INFO - PROCESS | 1680 | Values: 113.0 127.0 156.0 171.0 99.0 168.0 151.0 130.0 116.0 115.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:154.20 Median:166.00 stddev:26.63 (16.0%) stddev-sans-first:27.90 16:12:01 INFO - PROCESS | 1680 | Values: 166.0 110.0 185.0 187.0 157.0 166.0 125.0 159.0 166.0 121.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:204.50 Median:199.50 stddev:15.52 (7.8%) stddev-sans-first:15.99 16:12:01 INFO - PROCESS | 1680 | Values: 194.0 243.0 221.0 201.0 197.0 197.0 196.0 199.0 200.0 197.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:77.30 Median:59.50 stddev:30.79 (51.7%) stddev-sans-first:32.01 16:12:01 INFO - PROCESS | 1680 | Values: 60.0 71.0 79.0 140.0 59.0 59.0 58.0 62.0 128.0 57.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:78.50 stddev:24.17 (30.8%) stddev-sans-first:25.00 16:12:01 INFO - PROCESS | 1680 | Values: 81.0 102.0 147.0 77.0 80.0 78.0 118.0 82.0 79.0 118.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:1.17 (1.3%) stddev-sans-first:1.24 16:12:01 INFO - PROCESS | 1680 | Values: 89.0 88.0 90.0 87.0 89.0 87.0 90.0 88.0 90.0 88.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:96.00 stddev:15.66 (16.3%) stddev-sans-first:16.41 16:12:01 INFO - PROCESS | 1680 | Values: 95.0 100.0 97.0 102.0 146.0 96.0 96.0 96.0 95.0 96.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:126.20 Median:151.00 stddev:26.74 (17.7%) stddev-sans-first:27.06 16:12:01 INFO - PROCESS | 1680 | Values: 149.0 139.0 95.0 82.0 142.0 143.0 96.0 114.0 150.0 152.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:65.00 stddev:6.92 (10.7%) stddev-sans-first:7.02 16:12:01 INFO - PROCESS | 1680 | Values: 61.0 67.0 65.0 65.0 65.0 66.0 65.0 86.0 64.0 64.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:67.50 stddev:18.37 (27.2%) stddev-sans-first:18.95 16:12:01 INFO - PROCESS | 1680 | Values: 65.0 68.0 63.0 77.0 107.0 115.0 71.0 67.0 71.0 67.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:83.50 stddev:12.19 (14.6%) stddev-sans-first:12.62 16:12:01 INFO - PROCESS | 1680 | Values: 81.0 84.0 80.0 83.0 85.0 81.0 84.0 85.0 111.0 112.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:149.50 stddev:10.20 (6.8%) stddev-sans-first:10.03 16:12:01 INFO - PROCESS | 1680 | Values: 155.0 125.0 141.0 150.0 138.0 150.0 144.0 132.0 149.0 157.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:173.00 stddev:13.69 (7.9%) stddev-sans-first:14.51 16:12:01 INFO - PROCESS | 1680 | Values: 174.0 172.0 167.0 174.0 168.0 166.0 187.0 210.0 167.0 168.0 16:12:01 INFO - PROCESS | 1680 | 16:12:01 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.50 stddev:1.77 (2.5%) stddev-sans-first:1.67 16:12:01 INFO - PROCESS | 1680 | Values: 71.0 70.0 68.0 67.0 71.0 66.0 68.0 69.0 67.0 70.0 16:12:01 INFO - PROCESS | 1680 | -------- Summary: end -------- 16:12:01 INFO - PROCESS | 1680 | 16:12:02 INFO - TEST-INFO | 1680: exit 0 16:12:02 INFO - TEST-OK | kraken | took 60222ms 16:12:02 INFO - SUITE-END | took 1171s 16:12:02 INFO - Completed test suite (00:19:31) 16:12:02 INFO - javascript benchmark 16:12:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.281740834425, 3451.3787518246704, 3462.2556150427654, 3507.9649203507965, 3514.3322768673966, 5717.142428635707, 5742.741576629051, 5743.827685169446, 5746.0806270996645, 5761.452661997114, 5720.0, 5742.770289188438, 5762.0, 5799.391063938278, 5830.941690583093, 5203.141481655532, 5240.711760853152, 5243.397009343936, 5300.363956325227, 5378.623496355257, 5148.974255128719, 5333.333333333333, 5342.1452567589195, 5370.301860758096, 5381.730913454312, 5245.213218017289, 5262.131748261532, 5320.6275560710765, 5359.678419294835, 5369.5167434930845, 4210.305299625574, 5130.256112863651, 5312.096943519611, 5314.681119132864, 5359.598030147716, 5080.0, 5139.434662187156, 5262.55268302191, 5277.498637629457, 5279.208118782175, 5253.922945796103, 5512.834614961557, 5651.378348381675, 5663.886722265532, 5759.366469688331, 5761.510271626917, 6193.94702900508, 6219.813405597794, 6236.314005459441, 6294.464970477515, 6166.722497487579, 6194.194754681863, 6251.187345645082, 6270.0, 6281.24625044993, 5853.209816674737, 5896.911546326808, 5907.586468947133, 5913.379095195029, 5920.881582368372, 5162.870928226749, 5250.422453530108, 6117.908231376533, 6297.559170858088, 6308.684565771693, 6069.726862291208, 6156.661383623854, 6202.534809889276, 6206.286277078194, 6214.036824292151, 5157.535821776059, 6264.561480696308, 6274.937250627435, 6288.465480434123, 6311.905321420182, 5940.168376427304, 6208.255009398901, 6230.906536401958, 6256.968715156395, 6297.559170858088, 5593.161025846074, 6111.895622426848, 6144.262688477412, 6172.290186628596, 6191.226096737908, 4951.8266860659705, 4986.476419975923, 4987.675801072991, 4989.7006179628615, 5001.924971125436, 4440.800163992628, 4475.015496590745, 4475.977620111878, 4493.618042466427, 4510.639148868148, 5588.944110558924, 5735.340435849931, 5771.8557036074435, 5899.557533184942, 5922.615030023121, 6179.752809887555, 6218.5647004710145, 6228.937710622927, 6247.656378899204, 6260.342664020303, 5786.855328616818, 5837.445442682938, 5847.1229315603, 5847.3275573308765, 5902.76388944446, 5348.1442969124755, 5419.403865574784, 5453.454654534515, 5485.533729632946, 5493.505584497414, 6345.111684364193, 6377.585456945284, 6381.425671689571, 6405.135306733531, 6412.0, 6108.615274519966, 6214.0, 6230.283517395557, 6231.376862313732, 6238.656873871981, 6304.148939893055, 6376.649284289409, 6387.297397286202, 6390.233172019387, 6404.199475065617, 6224.190855188977, 6268.153799237045, 6281.685915704288, 6304.747810087545, 6316.084167795552, 6185.134081228722, 6189.597676151216, 6250.843728906631, 6258.874822503433, 6259.906101408392, 6074.3014553325165, 6109.358517355614, 6115.909724157069, 6167.84580385494, 6181.320054794059, 4796.760161991956, 6117.571769976059, 6130.724117414817, 6132.233470816148, 6145.508359331332, 5001.42483614389, 5755.251817263729, 5926.348101708722, 5984.072468767174, 6010.429009244115, 6118.80192544137, 6120.204373431426, 6123.3139694332585, 6139.890808645427, 6141.802348541992, 5207.531322181022, 5692.886142277214, 5988.790392336247, 6005.219321488354, 6005.429484198994], "value": 5694.770629472615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.2563211677373, 529.5710474515643, 576.3372122059634, 580.4949693766423, 628.271205401734, 29524.852375738144, 33176.50235246473, 35657.28685426293, 38232.0, 38233.0, 32077.67922320776, 32250.83874580624, 39924.80037599815, 40110.0, 40141.397879031836, 8350.540720260384, 9522.428654280731, 9787.070228328297, 9828.508574571242, 9835.70492885211, 9671.903280967206, 9692.321537492378, 9791.167750741195, 9794.020597940185, 9812.067853553937, 8309.501429914224, 9735.172510336595, 9789.0, 9806.754831129207, 9843.753906152368, 9783.266255030865, 9812.067853553901, 9835.0, 9854.260930430246, 9864.210863131004, 9694.757631059209, 9825.4104753715, 9833.85249221255, 9838.508074596242, 9862.50687465631, 39549.406758898636, 39947.20105597901, 39952.20095598072, 39980.60019399827, 40075.0, 3164.240582260264, 3194.34515924235, 3325.434676105068, 3346.4645656695056, 3378.2398960233995, 28096.0, 28201.0, 28495.430091398262, 28566.714332856613, 28732.281692957633, 2733.794965377585, 2756.8483733394655, 2781.513235183855, 2794.3153927287944, 2797.5803629455745, 453382.46617534064, 456860.0, 456878.0, 459702.0, 460123.0, 65072.37319066966, 65092.67453662701, 65093.02360464597, 65265.347346526876, 65330.0, 64764.676176618814, 66983.66508167525, 67442.66278668576, 69531.65234173898, 71541.0, 17588.91205543964, 17636.911815441097, 17721.822781772018, 17785.377511787025, 17803.287868485117, 13211.669708257179, 13300.534481293109, 13440.059195856196, 13457.798133028013, 13506.324683765772, 19622.11700473482, 19920.0, 19924.103415346344, 19951.600967980557, 19983.900080499505, 7336.156342020629, 8160.775883617386, 8261.628226729812, 8457.915420845837, 8467.195616416431, 948.5494390164616, 962.5716556132454, 998.3760149906309, 1013.2704452793977, 1163.0637336943842, 27804.443911122067, 28335.57496637552, 28608.427831443256, 28617.713822861504, 28731.425371492453, 1114.4594871487323, 1128.198978725098, 1130.7229728716352, 1149.9885001150049, 1180.0028975515675, 14358.353874075692, 14720.779188312184, 14857.70284594302, 15506.5348039559, 16126.75809862853, 632923.0, 633967.0, 636902.8154859289, 644234.0, 644415.0, 60974.69512652497, 61990.0, 62017.75964480685, 62118.0, 62139.06791398133, 1502.9473968411053, 1524.0931645670807, 1527.8854085943376, 1530.211940850463, 1538.2770098053898, 30240.33678147723, 30369.544456833606, 30669.53995690111, 31052.689473105882, 31180.064598062094, 39945.80027099962, 42887.71336859899, 47793.76103119462, 53459.663508411046, 55767.0, 423257.0, 504828.4758576184, 509743.0, 603514.0, 604356.0, 26282.211533654023, 27242.72757272402, 27275.72724272732, 27661.72338276672, 27705.168844934684, 45263.32105018493, 45463.31805022861, 46586.76706616445, 47061.0, 47274.52725472839, 37759.433608496445, 37832.62167378291, 37868.431973519866, 37985.62014379821, 37999.0, 27995.0, 36693.449598255516, 36821.52713891414, 37363.252734945694, 37499.0, 32010.839945801054, 35990.0, 37081.0, 37411.43882841705, 38285.0, 30471.0, 30722.078337649906, 31518.0, 32350.19124521906, 32647.347053059282, 23957.16149934744, 24154.516909661357, 24477.38806529851, 24519.26442206737, 24841.0], "value": 19862.754623101235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9566647668757, 394.9861754838581, 397.2055888223553, 407.1571846278204, 408.0, 14839.55481335559, 16244.918775406137, 19917.900410497947, 20628.071736771824, 20642.17431302748, 19955.0, 20008.399748007545, 20167.0, 20321.695174572393, 20402.285919992803, 5607.2897766181295, 5693.402192769761, 5915.733791979371, 5923.318818335903, 5928.347881733006, 4410.669199810011, 5787.4212578742, 5869.002269614176, 5904.143899134644, 5929.080992446177, 5774.989376859033, 5823.621464604808, 5834.124881267801, 5846.649201047951, 5859.9414005860035, 5516.531094856942, 5712.11462223356, 5807.070868661015, 5815.941840581582, 5820.738066787005, 5513.145462453326, 5588.720563971826, 5642.746076426571, 5647.632903861277, 5650.6892120933335, 23509.76490235093, 23819.88090059556, 24610.769461526852, 24692.012319507197, 24719.0, 2059.7116403703494, 2154.6121698094335, 2222.388843068153, 2261.434641339665, 2279.088364654135, 9217.585208665627, 9742.902570974271, 9798.706038818847, 9832.262580306506, 10024.94987525065, 1709.333359989605, 1748.5628592851788, 1757.912104394788, 1762.2510433065895, 1782.6273769197646, 84415.0, 103393.4830325851, 103535.0, 109017.45491272652, 110213.44893275482, 11570.884291157148, 11719.238249513754, 11725.472353744102, 11828.053755699524, 11849.284802867713, 12734.235945843278, 14033.298335083206, 14816.851831481546, 14880.330385132478, 14883.553493395215, 8940.46357218569, 8967.417117887317, 8979.910200897908, 8992.55037248135, 9022.86465703015, 7390.445716571278, 9092.636294548143, 9101.317401194938, 9122.726318210463, 9186.40288381253, 4589.0, 4589.196890544142, 4608.377868987711, 4662.254039353687, 4709.058188362341, 1998.7401637786995, 2016.1229865008768, 2022.646036943529, 2029.9695504567148, 2082.729245198145, 839.9668407858369, 846.9836196564148, 886.2953951608487, 896.4755617963434, 977.0669011094387, 18975.905120474585, 19027.238910443426, 19606.509837254183, 19953.0, 20029.899850500944, 765.9846803063907, 809.9797505062421, 954.9856752148723, 970.9611615535299, 983.5033308179467, 9208.907910920805, 10596.788064238672, 10690.572377104829, 10716.303440276513, 10722.731931701614, 573885.0, 577493.0, 581324.0, 600999.0, 601229.0, 112857.87142128639, 113394.43302783433, 115122.0, 116019.0, 116208.41895790467, 1011.8482227665909, 1028.3469996552244, 1033.9069483746348, 1041.8437234414898, 1084.8535447714773, 16945.06802125895, 17596.648067038845, 17687.64624707473, 17776.733348999518, 17851.285948561912, 25998.480030398907, 33262.835800746856, 46957.35649252396, 49611.0, 49612.25581616351, 65583.0, 72864.54270914658, 80159.59920200362, 83122.5843870801, 84109.0, 7563.659635316534, 7667.0, 8111.837763244584, 8206.179382061746, 8309.95845020771, 9011.639534418811, 12084.214526055779, 12296.75406491883, 12430.12989090755, 12565.0, 8763.561821908752, 9912.513123031604, 10263.486825658538, 10633.726101600094, 10878.0, 5039.798408063783, 7502.649523085897, 10036.89963100389, 10566.0, 10762.246642734934, 5696.743646535833, 6584.473242140713, 10967.725806854893, 11027.944860275968, 11363.749987501204, 8070.515769053875, 8150.75547733569, 8174.059983102019, 8229.0, 8291.212334828182, 8130.7154249603345, 9883.703488895344, 10958.890411096107, 11213.775724485628, 11271.267367621078], "value": 9157.012322885445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1609.291911558914, 1625.9999999999995, 1876.5590086329719, 1921.5580416504213, 2007.4780557055162, 7840.960795196016, 8537.18896704812, 8639.52482613456, 8838.823223535534, 8853.468791872485, 8637.0, 8645.265152462032, 8708.521031343274, 8771.91228087719, 8795.428297160695, 2446.7675570820743, 2469.6912885889265, 2470.888810003545, 2477.578811602032, 2485.515324511721, 2085.0, 2418.093215044358, 2472.072972635262, 2491.4643351679383, 2498.9250322490266, 2467.642191882176, 2468.7531246875346, 2476.715177754554, 2511.962320565193, 2527.304991127436, 2470.234227389506, 2494.3514686181634, 2496.2386472125995, 2514.4342522932375, 2516.471540976397, 2446.5351583199313, 2458.262521243638, 2505.4863752930605, 2505.536475751992, 2506.786923111538, 7481.401487880957, 8931.0, 8937.50843703596, 8955.283577313865, 8988.910110898873, 4612.423447069116, 4639.466461356953, 5341.305630268079, 5486.670799752028, 5506.174073888908, 3413.9487907681155, 3454.671806178409, 3492.4062909305226, 3522.0138361258887, 3528.823558822074, 1059.6026490066224, 1077.450500244873, 1081.1999120650742, 1081.6052140968568, 1084.3981590217427, 12036.45835937385, 12185.32980686062, 12194.81707774384, 12218.938905305506, 12269.0, 4858.58702010333, 4898.583620392306, 4937.876553086202, 4957.950420495821, 4980.302757613937, 10245.48772561369, 10262.0, 10355.689329320132, 10392.948035259775, 10401.427921464392, 5503.954248692736, 5509.862253443696, 5547.5007249347755, 5559.11054231321, 5573.526250268692, 4426.601605855489, 4885.487023862514, 4976.751162441863, 4990.975045124751, 4999.175136102575, 4118.320477121301, 4137.731047481904, 4158.6673066154635, 4183.477065366829, 4227.894302642397, 1183.8753184474783, 1262.066071107374, 1287.4785711786742, 1294.1006000829334, 1324.4768316515115, 785.4737325991599, 893.5487578772678, 913.6208473483563, 946.8248374050822, 955.9113337357895, 5004.3494345734825, 5761.740721667535, 5851.941480585139, 5857.70711464425, 5890.175375447441, 452.81660927323827, 466.0818188169301, 471.4107365792759, 473.46734923211386, 478.5979776987347, 3467.531883195736, 3769.208466222069, 3831.6743076838225, 3857.691384689218, 3929.6856251500385, 10478.476076196159, 10788.46057697112, 10921.0, 10964.451777411097, 10972.396518191576, 10393.896061039444, 10750.78498430027, 10810.081143102925, 10833.970772776574, 10945.945270273756, 8015.959920200362, 8297.460665056751, 8409.20112589303, 8459.788505287417, 8477.194666506795, 5641.774329026957, 5724.685142317213, 5753.0, 5776.0, 5916.964531206935, 9471.763205919908, 9477.289203309641, 9548.761280967754, 9583.424994500352, 9695.466749328572, 9463.716088517356, 9857.0, 9922.206223502248, 9966.900330996597, 10001.199904007808, 6836.0, 7312.5246858954, 7533.171351151259, 7574.924250757422, 7623.618819059136, 8319.251267385964, 8343.290820280345, 8357.289630381485, 8387.077421483755, 8404.327653787805, 7299.489035767658, 7434.73978410753, 7499.512531685423, 7532.472726909064, 7571.167171611229, 7470.4770666052855, 7540.924590754023, 7596.81007974805, 7628.961855190689, 7750.341220996278, 6982.511224214256, 7020.0, 7021.368076873106, 7039.577625342496, 7049.929500704928, 4941.258811178352, 4956.3804524434445, 5102.821401250939, 5153.201253805666, 5200.2199670049795, 4305.74165550068, 5152.175651895679, 5255.106631872514, 5286.894262114659, 5325.813596524103], "value": 4562.956839008906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.6605572175956, 322.0949132936449, 322.72084646780525, 326.4906745477056, 329.74774297662293, 4816.710997340162, 6648.634325112117, 6657.201135863697, 6697.9283314669665, 6713.160854893133, 5629.35262444819, 5657.66054036757, 6226.816904788087, 6548.345165483461, 6561.245456772473, 3438.9656103438956, 4717.150912835688, 4845.975770121145, 4854.2718592211095, 4871.707697538159, 4785.994941062381, 4823.842277853325, 4833.564979151875, 4841.0, 4864.367632207808, 4785.0, 4798.976005119969, 4807.735574543416, 4826.155422801014, 4827.63792715546, 4816.157172494799, 4852.902941941142, 4866.0, 4877.195262781637, 4893.828715994923, 4182.728122672017, 4197.412362269285, 4201.159768046372, 4201.621854033121, 4202.621764041221, 5193.272941788153, 5814.953308404485, 6206.968965155191, 6231.87536249277, 6296.496280297565, 5598.860028499279, 5631.324241091054, 5649.813539156782, 5654.0, 5657.868426314712, 7078.893816592756, 7087.0, 7087.574745515234, 7120.715171393138, 7162.928370716279, 4570.977145114286, 4582.381378513891, 4585.014221942299, 4591.471980722226, 4602.930956035662, 7339.082614673166, 7419.146798118178, 7424.257574242587, 7454.888176677354, 7461.7388391406585, 4435.423394958635, 4598.701084429501, 4628.074385122989, 4631.351610774427, 4651.883702907387, 4674.672772905864, 4722.858314250578, 4727.361806156125, 4730.266808644666, 4730.337752714623, 5575.358833734092, 5605.943940560624, 5606.747696353674, 5637.43625637433, 5657.0, 7069.363757261871, 7104.6447677615915, 7119.288071192943, 7121.537100088478, 7129.108861392326, 1617.5713435939485, 1642.622196894721, 1648.0276636784354, 1648.5301689018572, 1650.1336798180812, 865.6234537975874, 886.3956239207056, 1009.8333774927055, 1029.9330543514648, 1044.1072882685346, 6479.7408103675325, 6590.505712071614, 6590.9670451647435, 6602.768903088465, 6619.569727967667, 7136.0723105995885, 7337.88993165103, 7352.558846469229, 7420.85158296831, 7437.367823734935, 5194.68831870089, 6463.6768161591735, 6517.120188774549, 6574.0, 6694.297098804653, 3880.165764360676, 3902.0635047588376, 3924.941125883114, 3930.6265904739003, 3935.0, 6827.044213810071, 7154.785356439315, 7542.6605802739, 7762.952001479841, 7800.882986755203, 6379.0, 7291.671874765649, 7672.0, 7707.306342429209, 7708.730194443167, 7722.3049925506975, 7737.342325902361, 7738.574378409242, 7742.458027937991, 7762.650680719381, 5962.612430192024, 7356.264373562601, 7376.446766492427, 7393.8151546211775, 7423.960645509741, 7133.464990125865, 7183.03029091066, 7183.425325974014, 7220.0613920190035, 7232.819179520554, 7381.557106573623, 7665.463417560717, 7686.154523002577, 7711.344535714303, 7736.690532378641, 4627.444706635225, 4656.41794775653, 4681.0, 4683.906321873612, 4711.434627844544, 7381.077365329334, 7400.111986561647, 7403.629818509161, 7418.332350088518, 7431.516951398142, 7174.282571742784, 7183.5330703504105, 7186.568805871664, 7192.352688258082, 7198.748043818441, 6487.610743355413, 6932.584044957319, 7046.26014268515, 7055.964720176366, 7148.964255178691, 6946.79159625212, 6949.3745562899585, 6963.1644202696, 6968.5122041458635, 6993.895091573529, 7139.286071392819, 7154.463415243981, 7160.140783106061, 7165.892511612435, 7213.531120477152, 5692.857678558069, 5921.289445266596, 6709.93290067093, 6985.091938048225, 6994.356326263222], "value": 5112.3041988280465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.0030502696399, 668.4218151299126, 730.7917243585579, 747.9214682458341, 762.3177256355565, 14757.262136893167, 14861.182634955074, 14975.700485990288, 15023.774643380359, 15025.248737563134, 13633.182009079437, 13659.112157709742, 13660.838828699547, 13680.179189248653, 13693.109947853383, 713.2439614009141, 897.4166791585473, 903.5211337990843, 928.4754113925644, 932.5943214701626, 913.4701872913728, 926.3885835348672, 928.1275600935134, 929.0477260807659, 929.5166513413021, 963.4893506441597, 980.289290264556, 984.4880662055726, 985.300436689951, 988.293370240278, 971.6696323250059, 976.6386437018349, 976.9169620582259, 977.2621670638658, 979.7158823941085, 713.935745782882, 723.479095051562, 726.0960104669667, 727.6689106456549, 732.6556518436327, 14278.286085695674, 14468.927655361762, 14518.782218266735, 14522.564323070324, 14537.927310363486, 12646.430910609044, 12675.93662031684, 12876.420561074774, 12911.612651620466, 13065.542706005339, 9105.124067681474, 9469.431834089977, 9725.638410622518, 10198.388096714221, 10254.282200245985, 2920.4013177298602, 3238.951415728766, 3529.3117842020815, 3529.400001999666, 3621.112827357288, 81261.0, 81894.59052704758, 81924.59037704833, 82279.5886020572, 82755.0, 7829.882551761728, 9357.39176953496, 9364.01757188493, 9386.85919711205, 9607.951960240294, 20594.0, 20669.0, 20921.267755628785, 21176.78823211779, 21254.46863828386, 5984.910226346608, 7431.033965584439, 7520.285572870568, 7609.771706848804, 7710.653020613973, 4088.6115818997146, 4135.627793498599, 4150.527102101002, 4185.434966279513, 4202.831886724558, 3617.0957260684827, 3676.356637588411, 3687.1335236219466, 3688.4098544232793, 3697.334479793663, 648.6335220600346, 661.6427129350097, 665.3878431842717, 665.9367360100782, 668.8227619680788, 501.94980501950243, 506.4530307268142, 534.5597348105885, 570.2045646323438, 592.9822105336847, 8250.958745206235, 8355.373346999, 8466.830663386698, 8483.024452188078, 8543.786405339917, 533.7411355492583, 568.6332315656379, 581.831268932005, 589.911613073878, 597.0626116996274, 3721.2361599713245, 3743.513343265358, 3772.0, 3830.655241028265, 3849.2301539692176, 93287.53356233175, 94433.0, 94606.58090128654, 95013.0, 95559.0, 77337.0, 77534.0, 78017.43965120666, 78043.0, 78766.21233787703, 65035.67482162559, 65137.674311628136, 65187.0, 65702.34297656962, 65835.68328633497, 6631.568948018364, 7463.589502577411, 7660.5403675779635, 7796.688132474637, 7889.250521200477, 48281.75859120682, 48920.5107948916, 49501.0, 50100.0, 51016.0, 63544.68227658832, 69602.0, 69712.60574788577, 69826.0, 71113.22216944411, 9941.30410871261, 9972.800543989224, 10175.898241017496, 10258.538365773707, 10408.063274305348, 10929.344239345664, 11167.776644466903, 11244.718882028015, 11399.145064120059, 11412.429378531206, 10373.844392333971, 10381.480925953521, 10420.635277765241, 10569.418681972258, 10826.566937322734, 10464.102219099388, 10488.370697758159, 10493.422861742372, 10761.730956726144, 10924.617638382619, 9699.933007369043, 9716.562754676197, 10224.182065434896, 10339.327943683636, 10908.018278354986, 8664.696735614223, 8816.779580510281, 8947.731568052968, 9055.230305424113, 9169.312301577274, 8897.688580899638, 8910.643574256957, 8952.507612081397, 9039.728808135766, 9072.138146876036], "value": 6751.708024517533, "unit": "score"}], "name": "dromaeo_css", "value": 7392.033906992949, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 127.0, 156.0, 171.0, 99.0, 168.0, 151.0, 130.0, 116.0, 115.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 110.0, 185.0, 187.0, 157.0, 166.0, 125.0, 159.0, 166.0, 121.0], "value": 154.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 243.0, 221.0, 201.0, 197.0, 197.0, 196.0, 199.0, 200.0, 197.0], "value": 204.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 71.0, 79.0, 140.0, 59.0, 59.0, 58.0, 62.0, 128.0, 57.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 102.0, 147.0, 77.0, 80.0, 78.0, 118.0, 82.0, 79.0, 118.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 87.0, 89.0, 87.0, 90.0, 88.0, 90.0, 88.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 97.0, 102.0, 146.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 95.0, 82.0, 142.0, 143.0, 96.0, 114.0, 150.0, 152.0], "value": 126.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 65.0, 65.0, 65.0, 66.0, 65.0, 86.0, 64.0, 64.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 63.0, 77.0, 107.0, 115.0, 71.0, 67.0, 71.0, 67.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 80.0, 83.0, 85.0, 81.0, 84.0, 85.0, 111.0, 112.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 141.0, 150.0, 138.0, 150.0, 144.0, 132.0, 149.0, 157.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 167.0, 174.0, 168.0, 166.0, 187.0, 210.0, 167.0, 168.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 68.0, 67.0, 71.0, 66.0, 68.0, 69.0, 67.0, 70.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1604.0999999999997, "alertThreshold": 2.0}]} 16:12:02 INFO - Return code: 0 16:12:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:12:02 ERROR - # TBPL SUCCESS # 16:12:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:02 INFO - 2016-05-30 16:12:02.923 screenresolution[1685:10973] starting screenresolution argv=screenresolution get 16:12:02 INFO - 2016-05-30 16:12:02.925 screenresolution[1685:10973] Display 0: 1600x1200x32@60 16:12:02 INFO - 2016-05-30 16:12:02.931 screenresolution[1686:10976] starting screenresolution argv=screenresolution list 16:12:02 INFO - Available Modes on Display 0 16:12:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:03 INFO - Intel Iris: 16:12:03 INFO - Chipset Model: Intel Iris 16:12:03 INFO - Type: GPU 16:12:03 INFO - Bus: Built-In 16:12:03 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:03 INFO - Vendor: Intel (0x8086) 16:12:03 INFO - Device ID: 0x0a2e 16:12:03 INFO - Revision ID: 0x0009 16:12:03 INFO - Displays: 16:12:03 INFO - Display: 16:12:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:03 INFO - Display Serial Number: 1600x1200 60 16:12:03 INFO - Main Display: Yes 16:12:03 INFO - Mirror: Off 16:12:03 INFO - Online: Yes 16:12:03 INFO - Rotation: Supported 16:12:03 INFO - Return code: 0 16:12:03 INFO - Running post-action listener: _resource_record_post_action 16:12:03 INFO - Running post-run listener: _resource_record_post_run 16:12:04 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 31505920; Write bytes: 657559040; Read time: 195; Write time: 1910 16:12:04 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354777600; Write bytes: 356251136; Read time: 27665; Write time: 778 16:12:04 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29724160; Write bytes: 300345344; Read time: 181; Write time: 1128 16:12:04 INFO - Running post-run listener: _upload_blobber_files 16:12:04 INFO - Blob upload gear active. 16:12:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:04 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 16:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:12:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:12:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:12:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:05 INFO - (blobuploader) - INFO - Done attempting. 16:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:06 INFO - (blobuploader) - INFO - Done attempting. 16:12:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:06 INFO - Return code: 0 16:12:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14d2255da12fa6389b22e22d38b8251e96dfa34a8477cff13fb43f53a49291403dd7fe22cb06f05e33611d2359b950713ac336dec4af5f0028ea217730b76e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd13eb9bc5e6cafade856e660caa3b5501ba93f299007eaab9acb132f62545e5ec23bb58fe5c5e7573e069ddc1266383cca3ff03d53e1141e80fc105e1e102dc"} 16:12:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:06 INFO - Contents: 16:12:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14d2255da12fa6389b22e22d38b8251e96dfa34a8477cff13fb43f53a49291403dd7fe22cb06f05e33611d2359b950713ac336dec4af5f0028ea217730b76e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd13eb9bc5e6cafade856e660caa3b5501ba93f299007eaab9acb132f62545e5ec23bb58fe5c5e7573e069ddc1266383cca3ff03d53e1141e80fc105e1e102dc"} 16:12:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:06 INFO - Copying logs to upload dir... 16:12:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.268720 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-30 16:12:06.341679) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:06.345486) ========= 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.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14d2255da12fa6389b22e22d38b8251e96dfa34a8477cff13fb43f53a49291403dd7fe22cb06f05e33611d2359b950713ac336dec4af5f0028ea217730b76e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd13eb9bc5e6cafade856e660caa3b5501ba93f299007eaab9acb132f62545e5ec23bb58fe5c5e7573e069ddc1266383cca3ff03d53e1141e80fc105e1e102dc"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14d2255da12fa6389b22e22d38b8251e96dfa34a8477cff13fb43f53a49291403dd7fe22cb06f05e33611d2359b950713ac336dec4af5f0028ea217730b76e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd13eb9bc5e6cafade856e660caa3b5501ba93f299007eaab9acb132f62545e5ec23bb58fe5c5e7573e069ddc1266383cca3ff03d53e1141e80fc105e1e102dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:06.376252) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:06.376589) ========= 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.4E5yb4odj8/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.ApoP7bCuRe/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:06.425075) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:06.425405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:06.425793) ========= ========= Total master_lag: 0.20 =========