builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0162 starttime: 1464069977.72 results: success (0) buildid: 20160523220203 builduid: ef737131239b46eea1c42de17647cc5f revision: 9b9a73a98de81f22189dd3972c922459bce28393 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:17.721966) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:17.722426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:17.722701) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:17.743962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:17.744230) ========= 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.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-23 23:06:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.0M=0s 2016-05-23 23:06:18 (29.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.502403 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:18.265709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:18.266033) ========= 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.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.025958 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:18.314561) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 23:06:18.314916) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9b9a73a98de81f22189dd3972c922459bce28393 --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 9b9a73a98de81f22189dd3972c922459bce28393 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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-23 23:06:18,392 truncating revision to first 12 chars 2016-05-23 23:06:18,392 Setting DEBUG logging. 2016-05-23 23:06:18,392 attempt 1/10 2016-05-23 23:06:18,392 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9b9a73a98de8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 23:06:19,469 unpacking tar archive at: mozilla-aurora-9b9a73a98de8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.320354 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 23:06:19.653455) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:19.653778) ========= script_repo_revision: 9b9a73a98de81f22189dd3972c922459bce28393 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:19.654191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:19.654463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:19.670757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-23 23:06:19.671170) ========= /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.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:06:19 INFO - MultiFileLogger online at 20160523 23:06:19 in /builds/slave/test 23:06:19 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 23:06:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:06:19 INFO - {'append_to_log': False, 23:06:19 INFO - 'base_work_dir': '/builds/slave/test', 23:06:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:06:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:06:19 INFO - 'branch': 'Mozilla-Aurora', 23:06:19 INFO - 'buildbot_json_path': 'buildprops.json', 23:06:19 INFO - 'config_files': ('talos/mac_config.py',), 23:06:19 INFO - 'default_actions': ('clobber', 23:06:19 INFO - 'read-buildbot-config', 23:06:19 INFO - 'download-and-extract', 23:06:19 INFO - 'populate-webroot', 23:06:19 INFO - 'create-virtualenv', 23:06:19 INFO - 'install', 23:06:19 INFO - 'run-tests'), 23:06:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:06:19 INFO - 'download_minidump_stackwalk': True, 23:06:19 INFO - 'download_symbols': 'ondemand', 23:06:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:06:19 INFO - 'tooltool.py': '/tools/tooltool.py', 23:06:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:06:19 INFO - '/tools/misc-python/virtualenv.py')}, 23:06:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:06:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:06:19 INFO - 'installer_path': 'installer.exe', 23:06:19 INFO - 'log_level': 'info', 23:06:19 INFO - 'log_name': 'talos', 23:06:19 INFO - 'log_to_console': True, 23:06:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:06:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:06:19 INFO - 'opt_config_files': (), 23:06:19 INFO - 'pip_index': False, 23:06:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:06:19 INFO - 'cmd': ('bash', 23:06:19 INFO - '-c', 23:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:06:19 INFO - 'enabled': True, 23:06:19 INFO - 'halt_on_failure': False, 23:06:19 INFO - 'name': 'check_screen_resolution'},), 23:06:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:06:19 INFO - 'cmd': ('bash', 23:06:19 INFO - '-c', 23:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:06:19 INFO - 'enabled': True, 23:06:19 INFO - 'halt_on_failure': False, 23:06:19 INFO - 'name': 'check_screen_resolution'},), 23:06:19 INFO - 'run_cmd_checks_enabled': True, 23:06:19 INFO - 'sps_profile': False, 23:06:19 INFO - 'sps_profile_interval': 0, 23:06:19 INFO - 'suite': 'dromaeojs-e10s', 23:06:19 INFO - 'system_bits': '32', 23:06:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:06:19 INFO - 'title': 't-yosemite-r7-0162', 23:06:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:06:19 INFO - 'use_talos_json': True, 23:06:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:06:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:06:19 INFO - 'work_dir': 'build'} 23:06:19 INFO - ##### 23:06:19 INFO - ##### Running clobber step. 23:06:19 INFO - ##### 23:06:19 INFO - Running pre-action listener: _resource_record_pre_action 23:06:19 INFO - Running main action method: clobber 23:06:19 INFO - rmtree: /builds/slave/test/build 23:06:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:06:21 INFO - Running post-action listener: _resource_record_post_action 23:06:21 INFO - ##### 23:06:21 INFO - ##### Running read-buildbot-config step. 23:06:21 INFO - ##### 23:06:21 INFO - Running pre-action listener: _resource_record_pre_action 23:06:21 INFO - Running main action method: read_buildbot_config 23:06:21 INFO - Using buildbot properties: 23:06:21 INFO - { 23:06:21 INFO - "project": "", 23:06:21 INFO - "product": "firefox", 23:06:21 INFO - "script_repo_revision": "production", 23:06:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 23:06:21 INFO - "repository": "", 23:06:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 23:06:21 INFO - "buildid": "20160523220203", 23:06:21 INFO - "pgo_build": "False", 23:06:21 INFO - "basedir": "/builds/slave/test", 23:06:21 INFO - "buildnumber": 8, 23:06:21 INFO - "slavename": "t-yosemite-r7-0162", 23:06:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 23:06:21 INFO - "platform": "yosemite_r7", 23:06:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:06:21 INFO - "branch": "mozilla-aurora", 23:06:21 INFO - "repo_path": "releases/mozilla-aurora", 23:06:21 INFO - "revision": "9b9a73a98de81f22189dd3972c922459bce28393", 23:06:21 INFO - "stage_platform": "macosx64", 23:06:21 INFO - "builduid": "ef737131239b46eea1c42de17647cc5f", 23:06:21 INFO - "slavebuilddir": "test" 23:06:21 INFO - } 23:06:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 23:06:21 INFO - Running post-action listener: _resource_record_post_action 23:06:21 INFO - ##### 23:06:21 INFO - ##### Running download-and-extract step. 23:06:21 INFO - ##### 23:06:21 INFO - Running pre-action listener: _resource_record_pre_action 23:06:21 INFO - Running main action method: download_and_extract 23:06:21 INFO - mkdir: /builds/slave/test/build/tests 23:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:21 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:06:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:29 INFO - Downloaded 1199 bytes. 23:06:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:29 INFO - Using the following test package requirements: 23:06:29 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:06:29 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 23:06:29 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 23:06:29 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'jsshell-mac.zip'], 23:06:29 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 23:06:29 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:06:29 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 23:06:29 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 23:06:29 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 23:06:29 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 23:06:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:06: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')]} 23:06:29 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:32 INFO - Downloaded 18102373 bytes. 23:06:32 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'] 23:06:32 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 23:06:35 INFO - Return code: 0 23:06:35 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 23:06:35 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')]} 23:06:35 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:06:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:39 INFO - Downloaded 18102373 bytes. 23:06:39 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'] 23:06:39 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 23:06:42 INFO - Return code: 0 23:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:42 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:06:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:06:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:06:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:43 INFO - Downloaded 10840145 bytes. 23:06:43 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'] 23:06:43 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 23:06:44 INFO - Return code: 0 23:06: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')]} 23:06:44 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 23:06:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 23:06:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:46 INFO - Downloaded 114133571 bytes. 23:06:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:46 INFO - mkdir: /builds/slave/test/properties 23:06:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:06:46 INFO - Writing to file /builds/slave/test/properties/build_url 23:06:46 INFO - Contents: 23:06:46 INFO - build_url:https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:48 INFO - Running post-action listener: _resource_record_post_action 23:06:48 INFO - Running post-action listener: set_extra_try_arguments 23:06:48 INFO - ##### 23:06:48 INFO - ##### Running populate-webroot step. 23:06:48 INFO - ##### 23:06:48 INFO - Running pre-action listener: _resource_record_pre_action 23:06:48 INFO - Running main action method: populate_webroot 23:06:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:06:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:06:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:06:48 INFO - '--tppagecycles', 23:06:48 INFO - '7'], 23:06:48 INFO - 'tests': ['tsvgm']}}, 23:06:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:06:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:06:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:06:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:06:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:06:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:06:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['damp', 'tps']}, 23:06:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'tests': ['damp', 'tps']}, 23:06:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['dromaeo_dom']}, 23:06:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:06:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'other-e10s': {'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['a11yr', 23:06:48 INFO - 'ts_paint', 23:06:48 INFO - 'tpaint', 23:06:48 INFO - 'sessionrestore', 23:06:48 INFO - 'sessionrestore_no_auto_restore', 23:06:48 INFO - 'tabpaint']}, 23:06:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['tsvgx', 23:06:48 INFO - 'tsvgr_opacity', 23:06:48 INFO - 'tart', 23:06:48 INFO - 'tscrollx', 23:06:48 INFO - 'cart']}, 23:06:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:06:48 INFO - 'tsvgr_opacity', 23:06:48 INFO - 'tart', 23:06:48 INFO - 'tscrollx', 23:06:48 INFO - 'cart']}, 23:06:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'talos_options': ['--disable-e10s'], 23:06:48 INFO - 'tests': ['tp5o']}, 23:06:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'tests': ['tp5o']}, 23:06:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'talos_options': ['--disable-e10s', 23:06:48 INFO - '--xperf_path', 23:06:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:06:48 INFO - 'tests': ['tp5n']}, 23:06:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:48 INFO - 'talos_options': ['--xperf_path', 23:06:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:06:48 INFO - 'tests': ['tp5n']}}, 23:06:48 INFO - 'talos.zip': {'path': '', 23:06:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:06:48 INFO - Running post-action listener: _resource_record_post_action 23:06:48 INFO - ##### 23:06:48 INFO - ##### Running create-virtualenv step. 23:06:48 INFO - ##### 23:06:48 INFO - Running pre-action listener: _resource_record_pre_action 23:06:48 INFO - Running main action method: create_virtualenv 23:06:48 INFO - Creating virtualenv /builds/slave/test/build/venv 23:06:48 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 23:06:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:06:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:06:48 INFO - Using real prefix '/tools/python27' 23:06:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:06:48 INFO - Installing distribute.............................................................................................................................................................................................done. 23:06:51 INFO - Installing pip.................done. 23:06:51 INFO - Return code: 0 23:06:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:06:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:06:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:06:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:51 INFO - 'HOME': '/Users/cltbld', 23:06:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:51 INFO - 'LOGNAME': 'cltbld', 23:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:51 INFO - 'MOZ_NO_REMOTE': '1', 23:06:51 INFO - 'NO_EM_RESTART': '1', 23:06:51 INFO - 'PAGER': '/bin/cat', 23:06:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:51 INFO - 'PWD': '/builds/slave/test', 23:06:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:51 INFO - 'SHELL': '/bin/bash', 23:06:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:06:51 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:06:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:51 INFO - 'USER': 'cltbld', 23:06:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:51 INFO - 'XPC_FLAGS': '0x0', 23:06:51 INFO - 'XPC_SERVICE_NAME': '0', 23:06:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:52 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 23:06:52 INFO - Cleaning up... 23:06:52 INFO - Return code: 0 23:06:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:06: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')]} 23:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06: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')]} 23:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06: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', '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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:06: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', 'psutil>=0.7.1'] in /builds/slave/test/build 23:06: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 psutil>=0.7.1 23:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:52 INFO - 'HOME': '/Users/cltbld', 23:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:52 INFO - 'LOGNAME': 'cltbld', 23:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:52 INFO - 'MOZ_NO_REMOTE': '1', 23:06:52 INFO - 'NO_EM_RESTART': '1', 23:06:52 INFO - 'PAGER': '/bin/cat', 23:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:52 INFO - 'PWD': '/builds/slave/test', 23:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:52 INFO - 'SHELL': '/bin/bash', 23:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:06:52 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:52 INFO - 'USER': 'cltbld', 23:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:52 INFO - 'XPC_FLAGS': '0x0', 23:06:52 INFO - 'XPC_SERVICE_NAME': '0', 23:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:52 INFO - Downloading/unpacking psutil>=0.7.1 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:06:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:55 INFO - Installing collected packages: psutil 23:06:55 INFO - Running setup.py install for psutil 23:06:55 INFO - building 'psutil._psutil_osx' extension 23:06:55 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 23:06:55 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 23:06:55 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 23:06:56 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 23:06:56 INFO - building 'psutil._psutil_posix' extension 23:06:56 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 23:06:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:06:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:06:56 INFO - ^ 23:06:56 INFO - 1 warning generated. 23:06:56 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 23:06:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:56 INFO - Successfully installed psutil 23:06:56 INFO - Cleaning up... 23:06:56 INFO - Return code: 0 23:06:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:56 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:06:56 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 23:06:56 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 23:06:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:06:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:56 INFO - 'HOME': '/Users/cltbld', 23:06:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:56 INFO - 'LOGNAME': 'cltbld', 23:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:56 INFO - 'MOZ_NO_REMOTE': '1', 23:06:56 INFO - 'NO_EM_RESTART': '1', 23:06:56 INFO - 'PAGER': '/bin/cat', 23:06:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:56 INFO - 'PWD': '/builds/slave/test', 23:06:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:56 INFO - 'SHELL': '/bin/bash', 23:06:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:06:56 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:06:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:56 INFO - 'USER': 'cltbld', 23:06:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:56 INFO - 'XPC_FLAGS': '0x0', 23:06:56 INFO - 'XPC_SERVICE_NAME': '0', 23:06:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:06:59 INFO - Installing collected packages: mozsystemmonitor 23:06:59 INFO - Running setup.py install for mozsystemmonitor 23:06:59 INFO - Successfully installed mozsystemmonitor 23:06:59 INFO - Cleaning up... 23:06:59 INFO - Return code: 0 23:06:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:59 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:06:59 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 23:06:59 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 23:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:59 INFO - 'HOME': '/Users/cltbld', 23:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:59 INFO - 'LOGNAME': 'cltbld', 23:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:59 INFO - 'MOZ_NO_REMOTE': '1', 23:06:59 INFO - 'NO_EM_RESTART': '1', 23:06:59 INFO - 'PAGER': '/bin/cat', 23:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:59 INFO - 'PWD': '/builds/slave/test', 23:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:59 INFO - 'SHELL': '/bin/bash', 23:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:06:59 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:59 INFO - 'USER': 'cltbld', 23:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:59 INFO - 'XPC_FLAGS': '0x0', 23:06:59 INFO - 'XPC_SERVICE_NAME': '0', 23:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:59 INFO - Downloading/unpacking blobuploader==1.2.4 23:06:59 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 23:06:59 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 23:06:59 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 23:06:59 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 23:06:59 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 23:06:59 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 23:07:02 INFO - Downloading blobuploader-1.2.4.tar.gz 23:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:07:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:07:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:02 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 23:07:03 INFO - Downloading docopt-0.6.1.tar.gz 23:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:07:03 INFO - Installing collected packages: blobuploader, requests, docopt 23:07:03 INFO - Running setup.py install for blobuploader 23:07:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:07:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:07:03 INFO - Running setup.py install for requests 23:07:04 INFO - Running setup.py install for docopt 23:07:04 INFO - Successfully installed blobuploader requests docopt 23:07:04 INFO - Cleaning up... 23:07:04 INFO - Return code: 0 23:07:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07: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')]} 23:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07: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')]} 23:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:04 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:07:04 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 23:07:04 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 23:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:04 INFO - 'HOME': '/Users/cltbld', 23:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:04 INFO - 'LOGNAME': 'cltbld', 23:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:04 INFO - 'MOZ_NO_REMOTE': '1', 23:07:04 INFO - 'NO_EM_RESTART': '1', 23:07:04 INFO - 'PAGER': '/bin/cat', 23:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:04 INFO - 'PWD': '/builds/slave/test', 23:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:04 INFO - 'SHELL': '/bin/bash', 23:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:04 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:04 INFO - 'USER': 'cltbld', 23:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:04 INFO - 'XPC_FLAGS': '0x0', 23:07:04 INFO - 'XPC_SERVICE_NAME': '0', 23:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:04 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-XPtrxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:04 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-3s8cFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:04 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-wNvulf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:04 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-YeFn0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:04 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-7jQUpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-ChLsgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-s6RT1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-KPaRdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-DeeOpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-SZlxlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-AnHnfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-309rr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-4uTQdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:05 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-v0yK6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:06 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-YUaIFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:06 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-_tcw4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:06 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-iwn2A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:07:06 INFO - Running setup.py install for manifestparser 23:07:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Running setup.py install for mozcrash 23:07:06 INFO - Running setup.py install for mozdebug 23:07:06 INFO - Running setup.py install for mozdevice 23:07:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Running setup.py install for mozfile 23:07:07 INFO - Running setup.py install for mozhttpd 23:07:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Running setup.py install for mozinfo 23:07:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Running setup.py install for mozInstall 23:07:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Running setup.py install for mozleak 23:07:07 INFO - Running setup.py install for mozlog 23:07:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Running setup.py install for moznetwork 23:07:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:07:07 INFO - Running setup.py install for mozprocess 23:07:08 INFO - Running setup.py install for mozprofile 23:07:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:07:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:07:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:07:08 INFO - Running setup.py install for mozrunner 23:07:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:07:08 INFO - Running setup.py install for mozscreenshot 23:07:08 INFO - Running setup.py install for moztest 23:07:08 INFO - Running setup.py install for mozversion 23:07:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:07:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:07:08 INFO - Cleaning up... 23:07:08 INFO - Return code: 0 23:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:08 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:07:08 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 23:07:08 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 23:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:08 INFO - 'HOME': '/Users/cltbld', 23:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:08 INFO - 'LOGNAME': 'cltbld', 23:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:08 INFO - 'MOZ_NO_REMOTE': '1', 23:07:08 INFO - 'NO_EM_RESTART': '1', 23:07:08 INFO - 'PAGER': '/bin/cat', 23:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:08 INFO - 'PWD': '/builds/slave/test', 23:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:08 INFO - 'SHELL': '/bin/bash', 23:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:08 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:08 INFO - 'USER': 'cltbld', 23:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:08 INFO - 'XPC_FLAGS': '0x0', 23:07:08 INFO - 'XPC_SERVICE_NAME': '0', 23:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-L0I7tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-6dZHMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-yVvZJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Gw1x8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-rkQSVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-5fAfkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-lPQTgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:09 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)) 23:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:09 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Nx97Pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-yDN76m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Xd9Nds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-Za7o3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-QHfzFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-pyT_yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-SkPZks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-_jUkN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-u743HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:10 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)) 23:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:10 INFO - Running setup.py (path:/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/pip-IMCCa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:10 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)) 23:07:10 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)) 23:07:10 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)) 23:07:10 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)) 23:07:10 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)) 23:07:10 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)) 23:07:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:13 INFO - Downloading blessings-1.6.tar.gz 23:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:07:13 INFO - Installing collected packages: blessings 23:07:13 INFO - Running setup.py install for blessings 23:07:13 INFO - Successfully installed blessings 23:07:13 INFO - Cleaning up... 23:07:13 INFO - Return code: 0 23:07:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:13 INFO - Reading from file tmpfile_stdout 23:07:14 INFO - Current package versions: 23:07:14 INFO - blessings == 1.6 23:07:14 INFO - blobuploader == 1.2.4 23:07:14 INFO - docopt == 0.6.1 23:07:14 INFO - manifestparser == 1.1 23:07:14 INFO - mozInstall == 1.12 23:07:14 INFO - mozcrash == 0.17 23:07:14 INFO - mozdebug == 0.1 23:07:14 INFO - mozdevice == 0.48 23:07:14 INFO - mozfile == 1.2 23:07:14 INFO - mozhttpd == 0.7 23:07:14 INFO - mozinfo == 0.9 23:07:14 INFO - mozleak == 0.1 23:07:14 INFO - mozlog == 3.1 23:07:14 INFO - moznetwork == 0.27 23:07:14 INFO - mozprocess == 0.22 23:07:14 INFO - mozprofile == 0.28 23:07:14 INFO - mozrunner == 6.11 23:07:14 INFO - mozscreenshot == 0.1 23:07:14 INFO - mozsystemmonitor == 0.0 23:07:14 INFO - moztest == 0.7 23:07:14 INFO - mozversion == 1.4 23:07:14 INFO - psutil == 3.1.1 23:07:14 INFO - requests == 1.2.3 23:07:14 INFO - wsgiref == 0.1.2 23:07:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:14 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')]} 23:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 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')]} 23:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:07:14 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 23:07:14 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 23:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:14 INFO - 'HOME': '/Users/cltbld', 23:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:14 INFO - 'LOGNAME': 'cltbld', 23:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:14 INFO - 'MOZ_NO_REMOTE': '1', 23:07:14 INFO - 'NO_EM_RESTART': '1', 23:07:14 INFO - 'PAGER': '/bin/cat', 23:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:14 INFO - 'PWD': '/builds/slave/test', 23:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:14 INFO - 'SHELL': '/bin/bash', 23:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:14 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:14 INFO - 'USER': 'cltbld', 23:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:14 INFO - 'XPC_FLAGS': '0x0', 23:07:14 INFO - 'XPC_SERVICE_NAME': '0', 23:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 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)) 23:07:14 INFO - Cleaning up... 23:07:14 INFO - Return code: 0 23:07:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:07:14 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')]} 23:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 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')]} 23:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:14 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:07:14 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 23:07:14 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 23:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:14 INFO - 'HOME': '/Users/cltbld', 23:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:14 INFO - 'LOGNAME': 'cltbld', 23:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:14 INFO - 'MOZ_NO_REMOTE': '1', 23:07:14 INFO - 'NO_EM_RESTART': '1', 23:07:14 INFO - 'PAGER': '/bin/cat', 23:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:14 INFO - 'PWD': '/builds/slave/test', 23:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:14 INFO - 'SHELL': '/bin/bash', 23:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:14 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:14 INFO - 'USER': 'cltbld', 23:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:14 INFO - 'XPC_FLAGS': '0x0', 23:07:14 INFO - 'XPC_SERVICE_NAME': '0', 23:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:14 INFO - Downloading/unpacking jsonschema 23:07:14 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 23:07:14 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 23:07:14 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 23:07:14 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 23:07:14 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 23:07:14 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 23:07:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:07:16 INFO - Downloading/unpacking functools32 (from jsonschema) 23:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:17 INFO - Downloading functools32-3.2.3-2.tar.gz 23:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:07:17 INFO - warning: no files found matching '*.txt' 23:07:17 INFO - no previously-included directories found matching 'build' 23:07:17 INFO - no previously-included directories found matching 'dist' 23:07:17 INFO - no previously-included directories found matching '.git*' 23:07:17 INFO - Installing collected packages: jsonschema, functools32 23:07:17 INFO - Running setup.py install for functools32 23:07:17 INFO - warning: no files found matching '*.txt' 23:07:17 INFO - no previously-included directories found matching 'build' 23:07:17 INFO - no previously-included directories found matching 'dist' 23:07:17 INFO - no previously-included directories found matching '.git*' 23:07:17 INFO - Successfully installed jsonschema functools32 23:07:17 INFO - Cleaning up... 23:07:17 INFO - Return code: 0 23:07:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:07: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')]} 23:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07: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')]} 23:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:17 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 0x10c294a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2a4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0ca56fb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, '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.6CpX75rjzT/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.HOnSLmBlAo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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 23:07:17 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 23:07:17 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 23:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:17 INFO - 'HOME': '/Users/cltbld', 23:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:17 INFO - 'LOGNAME': 'cltbld', 23:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:17 INFO - 'MOZ_NO_REMOTE': '1', 23:07:17 INFO - 'NO_EM_RESTART': '1', 23:07:17 INFO - 'PAGER': '/bin/cat', 23:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:17 INFO - 'PWD': '/builds/slave/test', 23:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:17 INFO - 'SHELL': '/bin/bash', 23:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:17 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:17 INFO - 'USER': 'cltbld', 23:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:17 INFO - 'XPC_FLAGS': '0x0', 23:07:17 INFO - 'XPC_SERVICE_NAME': '0', 23:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:17 INFO - Downloading/unpacking flake8 23:07:17 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 23:07:17 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 23:07:17 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 23:07:17 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 23:07:17 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 23:07:17 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 23:07:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:07:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:07:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:07:21 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 23:07:21 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 23:07:21 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 23:07:21 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 23:07:21 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 23:07:21 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 23:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:07:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:21 INFO - no previously-included directories found matching 'docs/_build' 23:07:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:07:21 INFO - Running setup.py install for pep8 23:07:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:21 INFO - no previously-included directories found matching 'docs/_build' 23:07:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:07:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:07:21 INFO - Cleaning up... 23:07:21 INFO - Return code: 0 23:07:21 INFO - Running post-action listener: _resource_record_post_action 23:07:21 INFO - Running post-action listener: _start_resource_monitoring 23:07:22 INFO - Starting resource monitoring. 23:07:22 INFO - ##### 23:07:22 INFO - ##### Running install step. 23:07:22 INFO - ##### 23:07:22 INFO - Running pre-action listener: _resource_record_pre_action 23:07:22 INFO - Running main action method: install 23:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:22 INFO - Reading from file tmpfile_stdout 23:07:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:07:22 INFO - Reading from file tmpfile_stdout 23:07:22 INFO - Output received: 23:07:22 INFO - Usage: mozinstall [options] installer 23:07:22 INFO - Options: 23:07:22 INFO - -h, --help show this help message and exit 23:07:22 INFO - -d DEST, --destination=DEST 23:07:22 INFO - Directory to install application into. [default: 23:07:22 INFO - "/builds/slave/test"] 23:07:22 INFO - --app=APP Application being installed. [default: firefox] 23:07:22 INFO - mkdir: /builds/slave/test/build/application 23:07:22 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'] 23:07:22 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 23:07:54 INFO - Reading from file tmpfile_stdout 23:07:54 INFO - Output received: 23:07:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:07:54 INFO - Running post-action listener: _resource_record_post_action 23:07:54 INFO - ##### 23:07:54 INFO - ##### Running run-tests step. 23:07:54 INFO - ##### 23:07:54 INFO - Running pre-action listener: _resource_record_pre_action 23:07:54 INFO - Running main action method: run_tests 23:07:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:07:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:07:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:07:54 INFO - 2016-05-23 23:07:54.128 screenresolution[1649:8132] starting screenresolution argv=screenresolution get 23:07:54 INFO - 2016-05-23 23:07:54.131 screenresolution[1649:8132] Display 0: 1600x1200x32@60 23:07:54 INFO - 2016-05-23 23:07:54.136 screenresolution[1650:8135] starting screenresolution argv=screenresolution list 23:07:54 INFO - Available Modes on Display 0 23:07:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:07:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:07:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:07:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:07:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:07:54 INFO - Intel Iris: 23:07:54 INFO - Chipset Model: Intel Iris 23:07:54 INFO - Type: GPU 23:07:54 INFO - Bus: Built-In 23:07:54 INFO - VRAM (Dynamic, Max): 1536 MB 23:07:54 INFO - Vendor: Intel (0x8086) 23:07:54 INFO - Device ID: 0x0a2e 23:07:54 INFO - Revision ID: 0x0009 23:07:54 INFO - Displays: 23:07:54 INFO - Display: 23:07:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:07:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:07:54 INFO - Display Serial Number: 1600x1200 60 23:07:54 INFO - Main Display: Yes 23:07:54 INFO - Mirror: Off 23:07:54 INFO - Online: Yes 23:07:54 INFO - Rotation: Supported 23:07:54 INFO - Return code: 0 23:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:07:54 INFO - Python 2.7.3 23:07:54 INFO - Return code: 0 23:07:54 INFO - grabbing minidump binary from tooltool 23:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:54 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 0x10c133e00>, '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 0x10bf9cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c12eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:07:54 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 23:07:54 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 23:07:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:07:54 INFO - Return code: 0 23:07:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:07:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:07:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:07:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:07:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:07:55 INFO - Return code: 0 23:07:55 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 23:07:55 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0162 --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 23:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6CpX75rjzT/Render', 23:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:55 INFO - 'HOME': '/Users/cltbld', 23:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:55 INFO - 'LOGNAME': 'cltbld', 23:07:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:07:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:55 INFO - 'MOZ_NO_REMOTE': '1', 23:07:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:07:55 INFO - 'NO_EM_RESTART': '1', 23:07:55 INFO - 'PAGER': '/bin/cat', 23:07:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:55 INFO - 'PWD': '/builds/slave/test', 23:07:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:55 INFO - 'SHELL': '/bin/bash', 23:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HOnSLmBlAo/Listeners', 23:07:55 INFO - 'TMPDIR': '/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/', 23:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:55 INFO - 'USER': 'cltbld', 23:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:55 INFO - 'XPC_FLAGS': '0x0', 23:07:55 INFO - 'XPC_SERVICE_NAME': '0', 23:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:55 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0162', '--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 23:07:55 INFO - mozversion application_buildid: 20160523220203 23:07:55 INFO - mozversion application_changeset: 9b9a73a98de81f22189dd3972c922459bce28393 23:07:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:07:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:07:55 INFO - mozversion application_name: Firefox 23:07:55 INFO - mozversion application_remotingname: firefox-dev 23:07:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:07:55 INFO - mozversion application_vendor: Mozilla 23:07:55 INFO - mozversion application_version: 48.0a2 23:07:55 INFO - mozversion platform_buildid: 20160523220203 23:07:55 INFO - mozversion platform_changeset: 9b9a73a98de81f22189dd3972c922459bce28393 23:07:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:07:55 INFO - mozversion platform_version: 48.0a2 23:07:55 INFO - using testdate: 1464070075 23:07:55 INFO - actual date: 1464070075 23:07:55 INFO - starting webserver on 'localhost:49270' 23:07:55 INFO - SUITE-START | Running 2 tests 23:07:55 INFO - TEST-START | dromaeo_css 23:07:55 INFO - operating with platform_type : mac_ 23:07:55 INFO - Initialising browser for dromaeo_css test... 23:07:55 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpKrZ_aq/profile http://localhost:49270/getInfo.html) 23:07:58 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 23:07:58 INFO - PROCESS | 1668 | colorDepth:24 23:07:58 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 23:07:58 INFO - PROCESS | 1668 | __metrics 23:07:58 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:07:59 INFO - TEST-INFO | 1668: exit 0 23:07:59 INFO - Browser initialized. 23:07:59 INFO - Running cycle 1/1 for dromaeo_css test... 23:07:59 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpKrZ_aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:11 INFO - PROCESS | 1672 | RSS: Main: 220999680 23:08:11 INFO - PROCESS | 1672 | 23:09:01 INFO - PROCESS | 1672 | 1464070141063 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] 23:11:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 23:11:13 INFO - PROCESS | 1672 | RSS: Main: 231440384 23:11:13 INFO - PROCESS | 1672 | 23:14:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:14:16 INFO - PROCESS | 1672 | RSS: Main: 243204096 23:14:16 INFO - PROCESS | 1672 | 23:17:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:17:18 INFO - PROCESS | 1672 | RSS: Main: 245190656 23:17:18 INFO - PROCESS | 1672 | 23:20:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:20:20 INFO - PROCESS | 1672 | RSS: Main: 247574528 23:20:20 INFO - PROCESS | 1672 | 23:23:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:23:23 INFO - PROCESS | 1672 | RSS: Main: 249704448 23:23:23 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:26:26 INFO - PROCESS | 1672 | RSS: Main: 250707968 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | __start_tp_report 23:26:26 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 23:26:26 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 23:26:26 INFO - PROCESS | 1672 | |i|pagename|runs| 23:26:26 INFO - PROCESS | 1672 | |0;dojo.html;3054.9541756873646;3517.5954765201996;3525.6474352564746;3532.1699400640828;3575.2313252650692;4073.47044884165;4851.296561998508;5742.5118864896485;5766.250387449631;5783.479486846183;5804.970975145118;5823.854403639901;5827.883442331151;5842.883142337161;5853.531717462603;4342.522322544533;5171.96560687863;5212.4266330703595;5287.444818294081;5351.224072509483;4258.44640196774;4292.65658747301;5103.106956282654;5257;5329.680219186842;5299.576033917297;5303.204519322094;5306.761195746181;5307;5308.522232999029;4622.1217968586025;5149.227615857614;5253.369595648508;5266.473352664703;5301.840944771644;4040.7777572233517;5138.614603904684;5158.92261616076;5199.688018718889;5226.55574276187;5483.890322193533;5590.91613625796;5596.888062238773;5644.604877658566;5677.290338707661;6162.815115546541;6182.350853160398;6191.938080619181;6254.405831446005;6282.434580887696;6196.411340922605;6217.160683307786;6257.217847769029;6259.155014073133;6263.937360626426;5861.238039054895;5878.882422351572;5886.617369870987;5892.882142357214;5904.321003084658;6137;6224.937750622526;6249.7187626556915;6269.341719119518;6272.2159730033745;6204;6241.594296370812;6250.312465628777;6252.406021427957;6257.374262573797;6262.906056409066;6287.276963149205;6292.622442653455;6297.685115744194;6308.337624549448;6120.14317995481;6144.03390914903;6243.313235544087;6250.749970001149;6267.4045965633195;6176.197094377702;6239.469645080219;6246.6876656167005;6262.812115636538;6267.310595834455;4945.579625731782;4973.626977976666;4974.17926042199;4976.90046199074;4984.526469985346;4363.650907927358;4403.3394990751;4406.537313582092;4493.7977790999485;4554.795034223468;5788.305403351541;5799.3040835100055;5843.707814609253;5850.12248162779;5898.852528686732;6237.064440333896;6251.437370636573;6287.088372186007;6290.811275661646;6297.181366422427;4869.513048695102;5656.773729050792;5752.568557358215;5756.597038207286;5834.3290521589715;5394.703291319015;5400.540954018952;5429.294191755047;5435.320584926884;5454.91817622736;6408.743650253938;6424.453921416639;6425.678716064178;6434.195725534309;6450.3549645036055;6140.28953073628;6228.190335256388;6257.906131407942;6307.96846015767;6312.621242725451;6355.30091689923;6364.713587888464;6371.522135839923;6402.903956440564;6510.316416776171;6091.238595175603;6204.81385558434;6210.968945155245;6224.408681175281;6229.190205273286;6180.567360284737;6197.783077592263;6260.906086408617;6288.685565721604;6293;6127.264728232462;6127.754889804358;6136.171616831671;6207.81376558704;6232.906506402498;5371.274877891435;5892.970535147297;6102.9694851525455;6189.6286222826775;6238.844028899314;5976.760929562943;6006.459418652343;6008.7296071676;6076.270847498328;6131.540134489842;5610.214569960127;6085.817425477243;6165.106059621419;6171.84570385744;6212.4408803207925;5910.586258961831;6007.879842403215;6073.726682299395;6081.057436097412;6109.969450152721 23:26:26 INFO - PROCESS | 1672 | |1;ext.html;516.3674498739045;524.3576618642167;539.0701040704782;593.57262770805;625.7590827531402;37700;38808;38819;38887.80556097223;38945.805270973644;39835;40169;40518;40581.18837623246;40803.795981020055;9361.906380936189;9531.189848862856;9569;9573.186279166244;9579.329446938717;9451.905480945172;9693.321467497242;9709.12617864392;9715.46564938928;9798.6080556778;9673.709788706316;9719.75700607487;9726.805463890752;9754.0733630305;9784.36401633895;9645.324827262095;9714.562844672007;9725.659601913934;9732.318737688365;9751.024897510264;9545.331826772128;9594.856077158849;9596.424214547149;9672.274579406503;9711.077447642534;27013;31545.684543154504;33190.336193276;37226.25547489062;37455;2797.9720202797916;3075.8000729952705;3151.2279491524496;3264.461363874958;3268.624108227537;25756;26293.605595916077;26747.73252267472;28428;28908.277293067633;2775.5697866830674;2805.074325472589;2812.5218712818664;2855.471737728527;2882.6829048804616;338956;341301;343371;343771;345073.27463362523;35561.11097222538;51289;55135.172972405446;58936.526586834814;61604.07593886095;34955.47566786449;35277;35324.76363327271;35521.64478355235;35647.4652880207;14961.177135257665;15163.241837908061;15300.540983770505;15452.459163929434;15463.149526775911;12791.808122878165;12892.935535322264;12977.935110324577;12983.415746291439;13002.219866808022;8072;8172.591370431454;8199.180081991872;8290.046644635824;8330.208630180123;3002.9549556756665;3455.015320633607;3459.39460594395;3493.1530802395964;3499.4051011328133;822.8477731619549;977.2572844637984;1082.2694681090234;1131.8075927092414;1177.9646610601696;28656.14031579056;29750.553741693893;29807.552886706715;30204.697953020626;30926.69073309283;964.8697425847561;1022.6406502133342;1059.4543809937888;1104.4808939798281;1121.7251773315427;12743.490260389666;13991.670791274813;14078.31103961239;14896.553103406915;15804.762928556082;588239;588458;595405;597593;598559;58038;59094.40905590975;59444.70277648584;59499.70250148808;59775.701121494116;1477.9852201478063;1480.541032279975;1484.1911158418689;1488.672492051747;1578.2030074812376;29665.25836854096;30472.238194045327;30780.98716301102;30927.69072309248;31067.378652427276;50867;50985.23522147097;51480.48519514757;51594.96810063853;51654.741726291126;484975;488023;491423;491586.5420672874;491620;25778.226653200432;26491.33771655647;26670.06654767074;41953.951151220244;43246;41753.79123104365;42057;42694.359584605634;43663.56336436722;43828.780856095516;35547;35748.821255893556;35924;36055.459168113026;36069.45895811617;35267.11832204215;36182.819085904404;36687.63312366842;36790;36809.07977300589;36375.09062273464;36506.634933650326;36595.90212293636;36833.63166368302;37766.62233377631;32411.67588324181;32721;33197.50203746898;33206.16984575405;33400;25217;25232.369190770376;25367.36581585475;25490.23529294124;25625.615615765404 23:26:26 INFO - PROCESS | 1672 | |2;jquery.html;334.43146650693825;393.2587073366704;402.0892678084138;409.7848629469528;413.71453696949123;19344.419667409966;21172.83549404782;21190.25834095807;21240.89379553102;21278.14887404504;17395.73906391402;19570.804291957076;20536.589268214626;21406.785932140672;21491;5352.545033672132;5458.744488767586;5536.169574563827;5537;5551.666899986014;5865.208196893429;6024;6069.302030266532;6085.878282434349;6090.817275481742;5677.744501497443;6031.849203769897;6047.5766696331275;6053.757849686007;6055.455009049185;5905.616134951236;5912.645241285537;5934.287885453751;6020.397960203988;6043.244594425697;5288.788448462057;5307.840764777063;5385.676859388449;5409.053415652245;5411.918821217646;9861.950690246575;13829.170249785046;13915.930420347833;14119.152850828881;14218.573442796733;2128.6381315176304;2238.9664155037526;2325.023490134131;2640.3267166872515;2829.6180015697823;10285.434301113435;10473.63342283024;11816.704582385422;14331.355089020915;15073.246337683073;1620.9463848498453;1747.7902651681752;1776.0586888948749;1790.4718108158124;1823.917923693437;91968.24095277152;100587;101754;108354.45822770835;111266.44366778195;9687.951560242154;10912.96326848948;11778.52885884555;12131.696707582381;12135.635930922086;14053.859461405254;15598.376064957502;15671.216439177995;15688.76466852998;15693.686126277411;8971.775705607412;9104.317176211678;9129.543522823833;9150.084991500797;9157.496337701357;6119.632822030692;8389.412741108186;8558.229759321692;8656.307495400353;9080.046595107551;4712.59942904857;4799.088173247072;4814.242801377542;4855.247436647325;4863.659543831898;1655.5033489952966;1771.4597047900513;1872.569309058924;2031.7460317460318;2050.559129787103;727.436236916394;847.9067302596709;927.8700981862545;946.654471118037;997.6159178716101;15278.236088195546;20216;20911.22311492565;20996;21860.562788744137;974.2498276327331;999.480270259461;1018.165104614224;1030.8402197659375;1053.3995622495258;8717.738467845975;11116.61091861781;11241.94379028116;11333.659990200307;11357.432128393548;554769;580167;583485;586376;619927;89555.10444895599;97173;101773.49113254534;108111.45944270228;118264;456.1060321769303;474.335929698425;477.99044019119424;479.43426756427056;479.7769037397627;10664.306820056672;11048.779024419306;11500.654980350602;11625.941870290595;11779.469923853618;43264.78367608142;53298.73350633222;55035.72482137564;55432;55538.72230638821;77943.2205677936;79535;79901.60049199717;81606;83980;8128.390370722338;8136.796580085545;8274.875876861972;8361.080281169188;8496;9242.214411775074;11845.822312665145;12816.102872799187;12864.163829351437;13000;9502.857457138287;11146.996770290712;11162.49768760424;11255.04332131778;11297.096232301232;10900.672979810619;11089.445527723421;11167.38579378142;11260.211785174959;11470.426478675867;11131.888681113085;11484.19610627248;11488.885111148782;11544.422778860855;11718.355490448108;8311.709090181814;8520.275776559061;8622.223999840044;8630.82738345242;8733.737987860375;10858.67423977282;11222.607208747655;11235.213535052468;11345.092392608736;11369.54521819151 23:26:26 INFO - PROCESS | 1672 | |3;mootools.html;1543.7607170888514;1547.4197176058979;1553.9067655940644;1859.330640969251;1928.1805232776067;7900.130985591594;8557.657693692245;8719;8890.06654301298;8900.777480562989;7332.633368331589;8475.872861907077;8508.829823403543;8615.35384846136;8666.090060543647;2362.6810380598613;2363.4918492524102;2365.6333268343346;2387.8328517003815;2392.6650268962358;2421.878906054699;2431.7689819467123;2459.8155138364605;2464.1745015419856;2464.6549483072386;2442.3405680466353;2443.951120977579;2459.139301244559;2465.3097132802845;2478.120267305108;2437.475942672325;2439.121916110199;2452.5585394629047;2459.32368598635;2461.4707837814954;2432.1609044879488;2439.5364880672614;2440.12155623977;2443.362946825613;2458.5943319352286;7658.61706914652;8721.171488708562;8740.082291359442;8786.077461866476;8798.12018798115;5321.627486075976;5414.07960646691;5429.647072940285;5456.208849716769;5481.75332110052;3481.0601137692934;3564.679178873888;3616.168281295317;3619.402798538238;3661.377565813818;1046.9528871200814;1066.7919755647652;1069.1820757120809;1069.850220969065;1070.769784496337;11826.231294965886;11882.346470944094;12043.458044388024;12050.216735912138;12121.575744848977;4066.1664358806374;4670.929936050962;4699.835505757282;4783.091212669582;4815.3499277597075;8599.656013759504;10104.343217690826;10123;10125.443100629536;10141.9858014198;5279.234510995884;5317.73411329432;5364.624476286701;5380.7309634518115;5387.326584176978;3956;4184.748915065045;4366.759828209415;4684.672072954929;4687.062587482517;3329.9677100099047;3982.163745613396;4066.79666016698;4090.795460226977;4102.343625019982;1357.7759669654397;1360.8639136086313;1366.8223130992908;1378.4486205517874;1379.7447472217673;907.3331101640289;928.2527565309855;934.2012579244783;939.6899023322287;940.0740270833223;5280.656757310762;5958.195643588147;5970.582059255898;5977.252843394575;6110.633361998294;456.30185815702026;464.7095565271705;466.6803239780761;470.064571502714;472.5510764773479;3977.164795392942;3977.8209980550946;4016.819243134066;4020.1356708307853;4055.614716601918;10532.684019479428;10877.456127193609;10903.182261330274;10980.450977451095;11027.61403350895;10659.040686338267;10678.893211067945;10714.785704286027;10799.78400431987;10871.239013268996;8238.917610823815;8372.246497815226;8382.664693412195;8439.831203375898;8450.87323690145;5673.971630141823;5718.085106382959;5734.283214598176;5855.590108692521;5923.111533270044;9116.453012818987;9350.018248083854;9408.623655053994;9428.010058943986;9459.621615135593;9335.019822918768;9537.52312384364;9612.711618651452;9633.470159141314;9665.613375464902;6728.495362847904;7125.714971401086;7214.819629509095;7310.268973102648;7442;7991.640376183202;8019.719309824128;8029.397795165269;8089.6764129436515;8098.3521318293215;7101.183363913216;7159.677814498465;7193.604351760703;7268.527545709512;7335.083114610674;7050.647467626701;7059.752908648173;7072.08062951813;7135.036770035978;7171;6827.829304267234;6946.097007389007;7015.894761578478;7057.470689698191;7060.470464715064;4805.567498925114;4833.275008748716;4839.128956787812;4967.652264341461;4974.427940786856;4486.730796152241;4535.4103966484145;4550.541588392779;4875.293082502946;4951.950480495149 23:26:26 INFO - PROCESS | 1672 | |4;prototype.html;314.78279986809116;324.27686259641;327.95244689520007;331.6037335384216;331.8291080093752;6258.311585725578;6525.15173027507;6675;6713.496487763419;6721;5829.679367634779;6217.533684973634;6620.735170593182;6687.99680047993;6710.563813352129;4260.995997821528;4910.091633047898;4912.263160525914;4935.851924442272;4951.529604687549;4666.789994450258;4959.256111583255;4963.45402005779;4971.651984361096;4975.8258460953875;4874.3419638348905;4874.415070191582;4889.217725163974;4915.680480768757;4917;4774.7135171889795;4852.1508735971065;4880.853574392774;4894.853154405374;4895.6083513318845;3586.9820650896577;4152.024274295538;4173;4201.096764195712;4208.431861698662;6305.211848518935;6315.905261421082;6327.683615819236;6333.55665103443;6353.142325786052;5891.322497912752;5892.675902825343;5896;5903.439173278532;5909.23179986599;7149.392301654366;7177.3899218566485;7198.100237470317;7204.783856484314;7206.387457066156;4400.6259467945265;4671;4727.149113159629;4731;4737.076270127326;7427.517211381243;7504.174540800507;7548.131964824061;7552.055993000875;7559.3196612305155;4693.06138772247;4694.600958918462;4695.389599352063;4698.671093023524;4699.060187962353;4778.187708089633;4784.521547845257;4793.448753393334;4794.712317260975;4796.290096095695;5720.48515633595;5729.4843464088435;5731.398203188689;5743.396943320891;5744.971275143681;5359.785608575691;7095.361417472453;7179.389751871149;7186.065811444583;7226.566406015656;1621.9700490188816;1631.600257936813;1649.8432648898336;1651.1991684033235;1657.4778944632644;1030.9690709278734;1031.4945676618554;1045.6810672744887;1047.675220681591;1048.984265236022;6526.673666316665;6598;6691;6703.061571380011;6717.328267173243;6331.2085989251345;7080.291970802981;7430.256974302634;7464.962675186589;7494.4753867229865;5848.239728835224;6238.8770021396585;6673.099131617169;6689.632070236184;6707.5975441473665;3879.1271963807917;3915.7258991870294;3916.138449541096;3926.430667553189;3936.8622098226424;7467.327940485382;7781.92218077812;7791.961040194763;7797.064352277763;7809.023872015998;7631.503952243086;7686.500377475446;7689.077310722749;7704.614769261514;7725.768226953201;7769.028871391076;7780.2219778021745;7782.377409807315;7789.7273595423885;7811.45319827618;6361.522885783492;7094.751683691046;7359.8160045999275;7419.369353605004;7442.776716698508;5914.940850591439;7308.086489188851;7333.230010848997;7342.302481264272;7342.889856652261;6188.907166392598;6201.596896201913;6540.444062254762;7685.5004424712215;7750.0699916010435;4236.745795252295;4646.326282688923;4650.186217412007;4653.60444362233;4658.976705116453;7330.780076597711;7335.413166946525;7346.191918888811;7363.374113200438;7409.851802964018;6857.657117144023;7151.463640227108;7152.713891444284;7181.640917954186;7187.460940429384;7089.82275443118;7122.323379278961;7140.321669441395;7145.964270178616;7167.8924816126755;6953.930460695531;6980.406665433292;7009.1939426966555;7009.824754381181;7061.2585678505075;6992.056072430157;7162.5344352618995;7193.784186474414;7209.603471808891;7276.490645654753;6757.831054223684;6827.863442731217;6938.202106757787;7108.004879316796;7173.569585824867 23:26:26 INFO - PROCESS | 1672 | |5;yui.html;665.8002599220239;686.763066741974;746.5968377076382;755.7354925775979;758.1054355860086;14708.632284192887;14765.77851332231;14966.25168741564;14991.175485348314;14999.999999999973;13620.931895340509;13703.725925481484;13710.725785484285;13743.931280343584;13828.239446830421;712.2770388056127;797.7885860247039;909.4588719711794;933.2207606648457;934.9485778282192;910.886139232596;920.2270093121776;923.3213588012806;925.5418567808944;925.7315378540249;980.9705708828747;982.327105932435;982.79852630211;984.5372674842848;986.5412583148834;935.0789472369711;938.7746940734243;974.7611835100375;975.4488713876673;976.2970661123979;650.177525430331;664.1134085995154;668.9464842812563;697.0554898113085;699.7096205074888;14271.357788899524;14314.284285785774;14354.923380746486;14368.928155359261;14570.980031397807;12600.369981500888;12610.621681349574;12697.301648409333;12742;12874.806877896839;8680.696175633886;9965.701028969142;10065.647702330383;10337.89662103377;10339.276250662457;3494.5981212160673;3500.9824950875336;3521.454174602941;3556.982215088908;3709.8887033388773;71348.71907685556;72348.91476627855;73690;73872.63063684701;74002.25997740007;9141.085891410805;9316.114969078062;9475.431474111576;9533.713988580354;9584.233261339075;20265.696014559795;20484.873331966588;20500;20577.69133462999;20618.690719639217;7198.640067996579;7330.450216233804;7390.593517356489;7409.481336306407;7440.7395741149885;3902.2195560887935;3934.11482416454;3965.7025723070883;4067.51189857219;4069.5726948670554;3553.626869178699;3625.3836847735947;3634.7637403568683;3644.1618427761764;3658.2683463307444;633.5058654249692;643.7457204404328;644.4618743349262;660.577230572434;668.0747165176266;495.98818410442294;566.931968163823;569.866081470854;587.6797145555682;609.1593600830913;7819.296263336327;7875.881861772078;8241.217084376975;8358;8376.623051962677;512.6513970500022;578.4100217777927;587.72376982818;615.5567991046439;626.1890851347516;3793.8103094845146;3823.3500304948225;3859.903502412406;3878.301905657009;4068.2473742357165;93868.90064080157;95194;96707.03292967122;97087.51456242673;97194.05611887723;64801.02798458027;73260;74212.25787742161;76168;76453.61773191098;71729.28270717226;72148.63925680338;72171.19572010636;72176.27823721801;73942;6312.331920889807;7649.31156195945;7759.72840950564;7790.2209779023915;7806.765797026098;50909.49090509196;51139.977200456524;51484.45546633607;52274.477255228485;53021.73489132529;64092.3590764105;66019;69141.3085869155;69150.65424673045;69671;8996.595153218252;10969.396683182502;10990.945045274722;11090.5563777448;11462.025727813229;11915.2255103418;11926.224795388272;11937.641870743892;12114.818277726017;12327.568535101229;11271.718207044561;11424.257423267461;11450.312981221154;11560.94219528897;11751.353675547585;11372.260803047775;11499.367534785668;11536.94231528837;11547.480363383836;12073.215241009306;11022.063124634175;11189.608363707232;11300.208985370946;11381.829272560753;11622.18644694897;8156.632951517315;9419.623215071595;9616.667828581136;9619.270392674121;9894.15899648538;9323.347365684336;9656.855147172657;9714.368566043184;9724.854127188239;9802 23:26:26 INFO - PROCESS | 1672 | __end_tp_report 23:26:26 INFO - PROCESS | 1672 | __start_cc_report 23:26:26 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,424 23:26:26 INFO - PROCESS | 1672 | __end_cc_report 23:26:26 INFO - PROCESS | 1672 | __startTimestamp1464071186309__endTimestamp 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | ------- Summary: start ------- 23:26:26 INFO - PROCESS | 1672 | Number of tests: 6 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5734.63 Median:6008.73 stddev:699.17 (11.6%) stddev-sans-first:668.97 23:26:26 INFO - PROCESS | 1672 | Values: 3055.0 3517.6 3525.6 3532.2 3575.2 4073.5 4851.3 5742.5 5766.3 5783.5 5805.0 5823.9 5827.9 5842.9 5853.5 4342.5 5172.0 5212.4 5287.4 5351.2 4258.4 4292.7 5103.1 5257.0 5329.7 5299.6 5303.2 5306.8 5307.0 5308.5 4622.1 5149.2 5253.4 5266.5 5301.8 4040.8 5138.6 5158.9 5199.7 5226.6 5483.9 5590.9 5596.9 5644.6 5677.3 6162.8 6182.4 6191.9 6254.4 6282.4 6196.4 6217.2 6257.2 6259.2 6263.9 5861.2 5878.9 5886.6 5892.9 5904.3 6137.0 6224.9 6249.7 6269.3 6272.2 6204.0 6241.6 6250.3 6252.4 6257.4 6262.9 6287.3 6292.6 6297.7 6308.3 6120.1 6144.0 6243.3 6250.7 6267.4 6176.2 6239.5 6246.7 6262.8 6267.3 4945.6 4973.6 4974.2 4976.9 4984.5 4363.7 4403.3 4406.5 4493.8 4554.8 5788.3 5799.3 5843.7 5850.1 5898.9 6237.1 6251.4 6287.1 6290.8 6297.2 4869.5 5656.8 5752.6 5756.6 5834.3 5394.7 5400.5 5429.3 5435.3 5454.9 6408.7 6424.5 6425.7 6434.2 6450.4 6140.3 6228.2 6257.9 6308.0 6312.6 6355.3 6364.7 6371.5 6402.9 6510.3 6091.2 6204.8 6211.0 6224.4 6229.2 6180.6 6197.8 6260.9 6288.7 6293.0 6127.3 6127.8 6136.2 6207.8 6232.9 5371.3 5893.0 6103.0 6189.6 6238.8 5976.8 6006.5 6008.7 6076.3 6131.5 5610.2 6085.8 6165.1 6171.8 6212.4 5910.6 6007.9 6073.7 6081.1 6110.0 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:60540.94 Median:36278.95 stddev:129925.80 (358.1%) stddev-sans-first:130212.11 23:26:26 INFO - PROCESS | 1672 | Values: 516.4 524.4 539.1 593.6 625.8 37700.0 38808.0 38819.0 38887.8 38945.8 39835.0 40169.0 40518.0 40581.2 40803.8 9361.9 9531.2 9569.0 9573.2 9579.3 9451.9 9693.3 9709.1 9715.5 9798.6 9673.7 9719.8 9726.8 9754.1 9784.4 9645.3 9714.6 9725.7 9732.3 9751.0 9545.3 9594.9 9596.4 9672.3 9711.1 27013.0 31545.7 33190.3 37226.3 37455.0 2798.0 3075.8 3151.2 3264.5 3268.6 25756.0 26293.6 26747.7 28428.0 28908.3 2775.6 2805.1 2812.5 2855.5 2882.7 338956.0 341301.0 343371.0 343771.0 345073.3 35561.1 51289.0 55135.2 58936.5 61604.1 34955.5 35277.0 35324.8 35521.6 35647.5 14961.2 15163.2 15300.5 15452.5 15463.1 12791.8 12892.9 12977.9 12983.4 13002.2 8072.0 8172.6 8199.2 8290.0 8330.2 3003.0 3455.0 3459.4 3493.2 3499.4 822.8 977.3 1082.3 1131.8 1178.0 28656.1 29750.6 29807.6 30204.7 30926.7 964.9 1022.6 1059.5 1104.5 1121.7 12743.5 13991.7 14078.3 14896.6 15804.8 588239.0 588458.0 595405.0 597593.0 598559.0 58038.0 59094.4 59444.7 59499.7 59775.7 1478.0 1480.5 1484.2 1488.7 1578.2 29665.3 30472.2 30781.0 30927.7 31067.4 50867.0 50985.2 51480.5 51595.0 51654.7 484975.0 488023.0 491423.0 491586.5 491620.0 25778.2 26491.3 26670.1 41954.0 43246.0 41753.8 42057.0 42694.4 43663.6 43828.8 35547.0 35748.8 35924.0 36055.5 36069.5 35267.1 36182.8 36687.6 36790.0 36809.1 36375.1 36506.6 36595.9 36833.6 37766.6 32411.7 32721.0 33197.5 33206.2 33400.0 25217.0 25232.4 25367.4 25490.2 25625.6 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:33115.69 Median:363.85 stddev:97026.89 (26667.1%) stddev-sans-first:97267.86 23:26:26 INFO - PROCESS | 1672 | Values: 334.4 393.3 402.1 409.8 413.7 19344.4 21172.8 21190.3 21240.9 21278.1 17395.7 19570.8 20536.6 21406.8 21491.0 5352.5 5458.7 5536.2 5537.0 5551.7 5865.2 6024.0 6069.3 6085.9 6090.8 5677.7 6031.8 6047.6 6053.8 6055.5 5905.6 5912.6 5934.3 6020.4 6043.2 5288.8 5307.8 5385.7 5409.1 5411.9 9862.0 13829.2 13915.9 14119.2 14218.6 2128.6 2239.0 2325.0 2640.3 2829.6 10285.4 10473.6 11816.7 14331.4 15073.2 1620.9 1747.8 1776.1 1790.5 1823.9 91968.2 100587.0 101754.0 108354.5 111266.4 9688.0 10913.0 11778.5 12131.7 12135.6 14053.9 15598.4 15671.2 15688.8 15693.7 8971.8 9104.3 9129.5 9150.1 9157.5 6119.6 8389.4 8558.2 8656.3 9080.0 4712.6 4799.1 4814.2 4855.2 4863.7 1655.5 1771.5 1872.6 2031.7 2050.6 727.4 847.9 927.9 946.7 997.6 15278.2 20216.0 20911.2 20996.0 21860.6 974.2 999.5 1018.2 1030.8 1053.4 8717.7 11116.6 11241.9 11333.7 11357.4 554769.0 580167.0 583485.0 586376.0 619927.0 89555.1 97173.0 101773.5 108111.5 118264.0 456.1 474.3 478.0 479.4 479.8 10664.3 11048.8 11500.7 11625.9 11779.5 43264.8 53298.7 55035.7 55432.0 55538.7 77943.2 79535.0 79901.6 81606.0 83980.0 8128.4 8136.8 8274.9 8361.1 8496.0 9242.2 11845.8 12816.1 12864.2 13000.0 9502.9 11147.0 11162.5 11255.0 11297.1 10900.7 11089.4 11167.4 11260.2 11470.4 11131.9 11484.2 11488.9 11544.4 11718.4 8311.7 8520.3 8622.2 8630.8 8733.7 10858.7 11222.6 11235.2 11345.1 11369.5 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5633.61 Median:4794.33 stddev:3159.56 (65.9%) stddev-sans-first:3153.48 23:26:26 INFO - PROCESS | 1672 | Values: 1543.8 1547.4 1553.9 1859.3 1928.2 7900.1 8557.7 8719.0 8890.1 8900.8 7332.6 8475.9 8508.8 8615.4 8666.1 2362.7 2363.5 2365.6 2387.8 2392.7 2421.9 2431.8 2459.8 2464.2 2464.7 2442.3 2444.0 2459.1 2465.3 2478.1 2437.5 2439.1 2452.6 2459.3 2461.5 2432.2 2439.5 2440.1 2443.4 2458.6 7658.6 8721.2 8740.1 8786.1 8798.1 5321.6 5414.1 5429.6 5456.2 5481.8 3481.1 3564.7 3616.2 3619.4 3661.4 1047.0 1066.8 1069.2 1069.9 1070.8 11826.2 11882.3 12043.5 12050.2 12121.6 4066.2 4670.9 4699.8 4783.1 4815.3 8599.7 10104.3 10123.0 10125.4 10142.0 5279.2 5317.7 5364.6 5380.7 5387.3 3956.0 4184.7 4366.8 4684.7 4687.1 3330.0 3982.2 4066.8 4090.8 4102.3 1357.8 1360.9 1366.8 1378.4 1379.7 907.3 928.3 934.2 939.7 940.1 5280.7 5958.2 5970.6 5977.3 6110.6 456.3 464.7 466.7 470.1 472.6 3977.2 3977.8 4016.8 4020.1 4055.6 10532.7 10877.5 10903.2 10980.5 11027.6 10659.0 10678.9 10714.8 10799.8 10871.2 8238.9 8372.2 8382.7 8439.8 8450.9 5674.0 5718.1 5734.3 5855.6 5923.1 9116.5 9350.0 9408.6 9428.0 9459.6 9335.0 9537.5 9612.7 9633.5 9665.6 6728.5 7125.7 7214.8 7310.3 7442.0 7991.6 8019.7 8029.4 8089.7 8098.4 7101.2 7159.7 7193.6 7268.5 7335.1 7050.6 7059.8 7072.1 7135.0 7171.0 6827.8 6946.1 7015.9 7057.5 7060.5 4805.6 4833.3 4839.1 4967.7 4974.4 4486.7 4535.4 4550.5 4875.3 4952.0 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5801.27 Median:6443.34 stddev:1858.96 (28.9%) stddev-sans-first:1817.99 23:26:26 INFO - PROCESS | 1672 | Values: 314.8 324.3 328.0 331.6 331.8 6258.3 6525.2 6675.0 6713.5 6721.0 5829.7 6217.5 6620.7 6688.0 6710.6 4261.0 4910.1 4912.3 4935.9 4951.5 4666.8 4959.3 4963.5 4971.7 4975.8 4874.3 4874.4 4889.2 4915.7 4917.0 4774.7 4852.2 4880.9 4894.9 4895.6 3587.0 4152.0 4173.0 4201.1 4208.4 6305.2 6315.9 6327.7 6333.6 6353.1 5891.3 5892.7 5896.0 5903.4 5909.2 7149.4 7177.4 7198.1 7204.8 7206.4 4400.6 4671.0 4727.1 4731.0 4737.1 7427.5 7504.2 7548.1 7552.1 7559.3 4693.1 4694.6 4695.4 4698.7 4699.1 4778.2 4784.5 4793.4 4794.7 4796.3 5720.5 5729.5 5731.4 5743.4 5745.0 5359.8 7095.4 7179.4 7186.1 7226.6 1622.0 1631.6 1649.8 1651.2 1657.5 1031.0 1031.5 1045.7 1047.7 1049.0 6526.7 6598.0 6691.0 6703.1 6717.3 6331.2 7080.3 7430.3 7465.0 7494.5 5848.2 6238.9 6673.1 6689.6 6707.6 3879.1 3915.7 3916.1 3926.4 3936.9 7467.3 7781.9 7792.0 7797.1 7809.0 7631.5 7686.5 7689.1 7704.6 7725.8 7769.0 7780.2 7782.4 7789.7 7811.5 6361.5 7094.8 7359.8 7419.4 7442.8 5914.9 7308.1 7333.2 7342.3 7342.9 6188.9 6201.6 6540.4 7685.5 7750.1 4236.7 4646.3 4650.2 4653.6 4659.0 7330.8 7335.4 7346.2 7363.4 7409.9 6857.7 7151.5 7152.7 7181.6 7187.5 7089.8 7122.3 7140.3 7146.0 7167.9 6953.9 6980.4 7009.2 7009.8 7061.3 6992.1 7162.5 7193.8 7209.6 7276.5 6757.8 6827.9 6938.2 7108.0 7173.6 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:18066.12 Median:644.10 stddev:25409.74 (3945.0%) stddev-sans-first:25447.43 23:26:26 INFO - PROCESS | 1672 | Values: 665.8 686.8 746.6 755.7 758.1 14708.6 14765.8 14966.3 14991.2 15000.0 13620.9 13703.7 13710.7 13743.9 13828.2 712.3 797.8 909.5 933.2 934.9 910.9 920.2 923.3 925.5 925.7 981.0 982.3 982.8 984.5 986.5 935.1 938.8 974.8 975.4 976.3 650.2 664.1 668.9 697.1 699.7 14271.4 14314.3 14354.9 14368.9 14571.0 12600.4 12610.6 12697.3 12742.0 12874.8 8680.7 9965.7 10065.6 10337.9 10339.3 3494.6 3501.0 3521.5 3557.0 3709.9 71348.7 72348.9 73690.0 73872.6 74002.3 9141.1 9316.1 9475.4 9533.7 9584.2 20265.7 20484.9 20500.0 20577.7 20618.7 7198.6 7330.5 7390.6 7409.5 7440.7 3902.2 3934.1 3965.7 4067.5 4069.6 3553.6 3625.4 3634.8 3644.2 3658.3 633.5 643.7 644.5 660.6 668.1 496.0 566.9 569.9 587.7 609.2 7819.3 7875.9 8241.2 8358.0 8376.6 512.7 578.4 587.7 615.6 626.2 3793.8 3823.4 3859.9 3878.3 4068.2 93868.9 95194.0 96707.0 97087.5 97194.1 64801.0 73260.0 74212.3 76168.0 76453.6 71729.3 72148.6 72171.2 72176.3 73942.0 6312.3 7649.3 7759.7 7790.2 7806.8 50909.5 51140.0 51484.5 52274.5 53021.7 64092.4 66019.0 69141.3 69150.7 69671.0 8996.6 10969.4 10990.9 11090.6 11462.0 11915.2 11926.2 11937.6 12114.8 12327.6 11271.7 11424.3 11450.3 11560.9 11751.4 11372.3 11499.4 11536.9 11547.5 12073.2 11022.1 11189.6 11300.2 11381.8 11622.2 8156.6 9419.6 9616.7 9619.3 9894.2 9323.3 9656.9 9714.4 9724.9 9802.0 23:26:26 INFO - PROCESS | 1672 | -------- Summary: end -------- 23:26:26 INFO - PROCESS | 1672 | 23:26:26 INFO - TEST-INFO | 1672: exit 0 23:26:26 INFO - TEST-OK | dromaeo_css | took 1111088ms 23:26:26 INFO - TEST-START | kraken 23:26:26 INFO - operating with platform_type : mac_ 23:26:26 INFO - Initialising browser for kraken test... 23:26:26 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpL0jcQH/profile http://localhost:49270/getInfo.html) 23:26:29 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 23:26:29 INFO - PROCESS | 1679 | colorDepth:24 23:26:29 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 23:26:29 INFO - PROCESS | 1679 | __metrics 23:26:29 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 23:26:30 INFO - TEST-INFO | 1679: exit 0 23:26:30 INFO - Browser initialized. 23:26:30 INFO - Running cycle 1/1 for kraken test... 23:26:30 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/T/tmpL0jcQH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:26:42 INFO - PROCESS | 1682 | RSS: Main: 220971008 23:26:42 INFO - PROCESS | 1682 | 23:26:44 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 23:26:45 INFO - PROCESS | 1682 | RSS: Main: 232808448 23:26:45 INFO - PROCESS | 1682 | 23:26:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 23:26:49 INFO - PROCESS | 1682 | RSS: Main: 241684480 23:26:49 INFO - PROCESS | 1682 | 23:26:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 23:26:52 INFO - PROCESS | 1682 | RSS: Main: 245972992 23:26:52 INFO - PROCESS | 1682 | 23:26:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 23:26:55 INFO - PROCESS | 1682 | RSS: Main: 245452800 23:26:55 INFO - PROCESS | 1682 | 23:26:58 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:26:58 INFO - PROCESS | 1682 | RSS: Main: 246210560 23:26:58 INFO - PROCESS | 1682 | 23:27:01 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 23:27:02 INFO - PROCESS | 1682 | RSS: Main: 247664640 23:27:02 INFO - PROCESS | 1682 | 23:27:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 23:27:05 INFO - PROCESS | 1682 | RSS: Main: 247779328 23:27:05 INFO - PROCESS | 1682 | 23:27:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 23:27:08 INFO - PROCESS | 1682 | RSS: Main: 247808000 23:27:08 INFO - PROCESS | 1682 | 23:27:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:27:11 INFO - PROCESS | 1682 | RSS: Main: 246988800 23:27:11 INFO - PROCESS | 1682 | 23:27:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:27:13 INFO - PROCESS | 1682 | RSS: Main: 247160832 23:27:13 INFO - PROCESS | 1682 | 23:27:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:27:16 INFO - PROCESS | 1682 | RSS: Main: 247382016 23:27:16 INFO - PROCESS | 1682 | 23:27:19 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:27:20 INFO - PROCESS | 1682 | RSS: Main: 247468032 23:27:20 INFO - PROCESS | 1682 | 23:27:22 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:27:23 INFO - PROCESS | 1682 | RSS: Main: 248147968 23:27:23 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 23:27:25 INFO - PROCESS | 1682 | RSS: Main: 249405440 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | __start_tp_report 23:27:25 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 23:27:25 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 23:27:25 INFO - PROCESS | 1682 | |i|pagename|runs| 23:27:25 INFO - PROCESS | 1682 | |0;ai-astar;113;119;109;117;117;118;109;116;115;116 23:27:25 INFO - PROCESS | 1682 | |1;audio-beat-detection;148;125;185;188;117;139;118;147;156;124 23:27:25 INFO - PROCESS | 1682 | |2;audio-dft;197;197;193;201;192;195;200;196;204;196 23:27:25 INFO - PROCESS | 1682 | |3;audio-fft;72;95;120;182;63;85;81;86;135;58 23:27:25 INFO - PROCESS | 1682 | |4;audio-oscillator;114;64;161;111;115;67;149;121;118;152 23:27:25 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;88;89;92;87;90;87;91;88;90;88 23:27:25 INFO - PROCESS | 1682 | |6;imaging-darkroom;96;96;96;103;134;96;110;124;97;97 23:27:25 INFO - PROCESS | 1682 | |7;imaging-desaturate;90;91;92;94;93;90;91;92;90;90 23:27:25 INFO - PROCESS | 1682 | |8;json-parse-financial;62;65;66;66;67;66;66;81;63;61 23:27:25 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;66;66;67;72;71;72;72;70;68;66 23:27:25 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;81;68;81;100;107;104;108;82;84;87 23:27:25 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;132;141;148;150;118;147;149;148;168;175 23:27:25 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;170;167;168;166;172;163;170;167;166;163 23:27:25 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;70;85;64;68;68;68;69;67;69;73 23:27:25 INFO - PROCESS | 1682 | __end_tp_report 23:27:25 INFO - PROCESS | 1682 | __start_cc_report 23:27:25 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1109 23:27:25 INFO - PROCESS | 1682 | __end_cc_report 23:27:25 INFO - PROCESS | 1682 | __startTimestamp1464071245481__endTimestamp 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | ------- Summary: start ------- 23:27:25 INFO - PROCESS | 1682 | Number of tests: 14 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:114.90 Median:116.50 stddev:3.51 (3.0%) stddev-sans-first:3.66 23:27:25 INFO - PROCESS | 1682 | Values: 113.0 119.0 109.0 117.0 117.0 118.0 109.0 116.0 115.0 116.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:147.50 stddev:25.76 (17.5%) stddev-sans-first:27.29 23:27:25 INFO - PROCESS | 1682 | Values: 148.0 125.0 185.0 188.0 117.0 139.0 118.0 147.0 156.0 124.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:197.10 Median:197.00 stddev:3.67 (1.9%) stddev-sans-first:3.89 23:27:25 INFO - PROCESS | 1682 | Values: 197.0 197.0 193.0 201.0 192.0 195.0 200.0 196.0 204.0 196.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:97.70 Median:76.50 stddev:38.04 (49.7%) stddev-sans-first:39.19 23:27:25 INFO - PROCESS | 1682 | Values: 72.0 95.0 120.0 182.0 63.0 85.0 81.0 86.0 135.0 58.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:117.20 Median:150.50 stddev:32.59 (21.7%) stddev-sans-first:34.55 23:27:25 INFO - PROCESS | 1682 | Values: 114.0 64.0 161.0 111.0 115.0 67.0 149.0 121.0 118.0 152.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.50 stddev:1.70 (1.9%) stddev-sans-first:1.76 23:27:25 INFO - PROCESS | 1682 | Values: 88.0 89.0 92.0 87.0 90.0 87.0 91.0 88.0 90.0 88.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:96.00 stddev:13.67 (14.2%) stddev-sans-first:14.12 23:27:25 INFO - PROCESS | 1682 | Values: 96.0 96.0 96.0 103.0 134.0 96.0 110.0 124.0 97.0 97.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:91.50 stddev:1.42 (1.5%) stddev-sans-first:1.42 23:27:25 INFO - PROCESS | 1682 | Values: 90.0 91.0 92.0 94.0 93.0 90.0 91.0 92.0 90.0 90.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:66.00 stddev:5.54 (8.4%) stddev-sans-first:5.65 23:27:25 INFO - PROCESS | 1682 | Values: 62.0 65.0 66.0 66.0 67.0 66.0 66.0 81.0 63.0 61.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.50 stddev:2.67 (3.8%) stddev-sans-first:2.60 23:27:25 INFO - PROCESS | 1682 | Values: 66.0 66.0 67.0 72.0 71.0 72.0 72.0 70.0 68.0 66.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:81.00 stddev:13.60 (16.8%) stddev-sans-first:14.01 23:27:25 INFO - PROCESS | 1682 | Values: 81.0 68.0 81.0 100.0 107.0 104.0 108.0 82.0 84.0 87.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:148.50 stddev:16.12 (10.9%) stddev-sans-first:16.08 23:27:25 INFO - PROCESS | 1682 | Values: 132.0 141.0 148.0 150.0 118.0 147.0 149.0 148.0 168.0 175.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:2.94 (1.8%) stddev-sans-first:2.93 23:27:25 INFO - PROCESS | 1682 | Values: 170.0 167.0 168.0 166.0 172.0 163.0 170.0 167.0 166.0 163.0 23:27:25 INFO - PROCESS | 1682 | 23:27:25 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.00 stddev:5.70 (8.3%) stddev-sans-first:6.05 23:27:25 INFO - PROCESS | 1682 | Values: 70.0 85.0 64.0 68.0 68.0 68.0 69.0 67.0 69.0 73.0 23:27:25 INFO - PROCESS | 1682 | -------- Summary: end -------- 23:27:25 INFO - PROCESS | 1682 | 23:27:26 INFO - TEST-INFO | 1682: exit 0 23:27:26 INFO - TEST-OK | kraken | took 59468ms 23:27:26 INFO - SUITE-END | took 1170s 23:27:26 INFO - Completed test suite (00:19:30) 23:27:26 INFO - javascript benchmark 23:27:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3054.9541756873646, 3517.5954765201996, 3525.6474352564746, 3532.1699400640828, 3575.2313252650692, 4073.47044884165, 4851.296561998508, 5742.5118864896485, 5766.250387449631, 5783.479486846183, 5804.970975145118, 5823.854403639901, 5827.883442331151, 5842.883142337161, 5853.531717462603, 4342.522322544533, 5171.96560687863, 5212.4266330703595, 5287.444818294081, 5351.224072509483, 4258.44640196774, 4292.65658747301, 5103.106956282654, 5257.0, 5329.680219186842, 5299.576033917297, 5303.204519322094, 5306.761195746181, 5307.0, 5308.522232999029, 4622.1217968586025, 5149.227615857614, 5253.369595648508, 5266.473352664703, 5301.840944771644, 4040.7777572233517, 5138.614603904684, 5158.92261616076, 5199.688018718889, 5226.55574276187, 5483.890322193533, 5590.91613625796, 5596.888062238773, 5644.604877658566, 5677.290338707661, 6162.815115546541, 6182.350853160398, 6191.938080619181, 6254.405831446005, 6282.434580887696, 6196.411340922605, 6217.160683307786, 6257.217847769029, 6259.155014073133, 6263.937360626426, 5861.238039054895, 5878.882422351572, 5886.617369870987, 5892.882142357214, 5904.321003084658, 6137.0, 6224.937750622526, 6249.7187626556915, 6269.341719119518, 6272.2159730033745, 6204.0, 6241.594296370812, 6250.312465628777, 6252.406021427957, 6257.374262573797, 6262.906056409066, 6287.276963149205, 6292.622442653455, 6297.685115744194, 6308.337624549448, 6120.14317995481, 6144.03390914903, 6243.313235544087, 6250.749970001149, 6267.4045965633195, 6176.197094377702, 6239.469645080219, 6246.6876656167005, 6262.812115636538, 6267.310595834455, 4945.579625731782, 4973.626977976666, 4974.17926042199, 4976.90046199074, 4984.526469985346, 4363.650907927358, 4403.3394990751, 4406.537313582092, 4493.7977790999485, 4554.795034223468, 5788.305403351541, 5799.3040835100055, 5843.707814609253, 5850.12248162779, 5898.852528686732, 6237.064440333896, 6251.437370636573, 6287.088372186007, 6290.811275661646, 6297.181366422427, 4869.513048695102, 5656.773729050792, 5752.568557358215, 5756.597038207286, 5834.3290521589715, 5394.703291319015, 5400.540954018952, 5429.294191755047, 5435.320584926884, 5454.91817622736, 6408.743650253938, 6424.453921416639, 6425.678716064178, 6434.195725534309, 6450.3549645036055, 6140.28953073628, 6228.190335256388, 6257.906131407942, 6307.96846015767, 6312.621242725451, 6355.30091689923, 6364.713587888464, 6371.522135839923, 6402.903956440564, 6510.316416776171, 6091.238595175603, 6204.81385558434, 6210.968945155245, 6224.408681175281, 6229.190205273286, 6180.567360284737, 6197.783077592263, 6260.906086408617, 6288.685565721604, 6293.0, 6127.264728232462, 6127.754889804358, 6136.171616831671, 6207.81376558704, 6232.906506402498, 5371.274877891435, 5892.970535147297, 6102.9694851525455, 6189.6286222826775, 6238.844028899314, 5976.760929562943, 6006.459418652343, 6008.7296071676, 6076.270847498328, 6131.540134489842, 5610.214569960127, 6085.817425477243, 6165.106059621419, 6171.84570385744, 6212.4408803207925, 5910.586258961831, 6007.879842403215, 6073.726682299395, 6081.057436097412, 6109.969450152721], "value": 5689.995869015447, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.3674498739045, 524.3576618642167, 539.0701040704782, 593.57262770805, 625.7590827531402, 37700.0, 38808.0, 38819.0, 38887.80556097223, 38945.805270973644, 39835.0, 40169.0, 40518.0, 40581.18837623246, 40803.795981020055, 9361.906380936189, 9531.189848862856, 9569.0, 9573.186279166244, 9579.329446938717, 9451.905480945172, 9693.321467497242, 9709.12617864392, 9715.46564938928, 9798.6080556778, 9673.709788706316, 9719.75700607487, 9726.805463890752, 9754.0733630305, 9784.36401633895, 9645.324827262095, 9714.562844672007, 9725.659601913934, 9732.318737688365, 9751.024897510264, 9545.331826772128, 9594.856077158849, 9596.424214547149, 9672.274579406503, 9711.077447642534, 27013.0, 31545.684543154504, 33190.336193276, 37226.25547489062, 37455.0, 2797.9720202797916, 3075.8000729952705, 3151.2279491524496, 3264.461363874958, 3268.624108227537, 25756.0, 26293.605595916077, 26747.73252267472, 28428.0, 28908.277293067633, 2775.5697866830674, 2805.074325472589, 2812.5218712818664, 2855.471737728527, 2882.6829048804616, 338956.0, 341301.0, 343371.0, 343771.0, 345073.27463362523, 35561.11097222538, 51289.0, 55135.172972405446, 58936.526586834814, 61604.07593886095, 34955.47566786449, 35277.0, 35324.76363327271, 35521.64478355235, 35647.4652880207, 14961.177135257665, 15163.241837908061, 15300.540983770505, 15452.459163929434, 15463.149526775911, 12791.808122878165, 12892.935535322264, 12977.935110324577, 12983.415746291439, 13002.219866808022, 8072.0, 8172.591370431454, 8199.180081991872, 8290.046644635824, 8330.208630180123, 3002.9549556756665, 3455.015320633607, 3459.39460594395, 3493.1530802395964, 3499.4051011328133, 822.8477731619549, 977.2572844637984, 1082.2694681090234, 1131.8075927092414, 1177.9646610601696, 28656.14031579056, 29750.553741693893, 29807.552886706715, 30204.697953020626, 30926.69073309283, 964.8697425847561, 1022.6406502133342, 1059.4543809937888, 1104.4808939798281, 1121.7251773315427, 12743.490260389666, 13991.670791274813, 14078.31103961239, 14896.553103406915, 15804.762928556082, 588239.0, 588458.0, 595405.0, 597593.0, 598559.0, 58038.0, 59094.40905590975, 59444.70277648584, 59499.70250148808, 59775.701121494116, 1477.9852201478063, 1480.541032279975, 1484.1911158418689, 1488.672492051747, 1578.2030074812376, 29665.25836854096, 30472.238194045327, 30780.98716301102, 30927.69072309248, 31067.378652427276, 50867.0, 50985.23522147097, 51480.48519514757, 51594.96810063853, 51654.741726291126, 484975.0, 488023.0, 491423.0, 491586.5420672874, 491620.0, 25778.226653200432, 26491.33771655647, 26670.06654767074, 41953.951151220244, 43246.0, 41753.79123104365, 42057.0, 42694.359584605634, 43663.56336436722, 43828.780856095516, 35547.0, 35748.821255893556, 35924.0, 36055.459168113026, 36069.45895811617, 35267.11832204215, 36182.819085904404, 36687.63312366842, 36790.0, 36809.07977300589, 36375.09062273464, 36506.634933650326, 36595.90212293636, 36833.63166368302, 37766.62233377631, 32411.67588324181, 32721.0, 33197.50203746898, 33206.16984575405, 33400.0, 25217.0, 25232.369190770376, 25367.36581585475, 25490.23529294124, 25625.615615765404], "value": 18173.70399352865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.43146650693825, 393.2587073366704, 402.0892678084138, 409.7848629469528, 413.71453696949123, 19344.419667409966, 21172.83549404782, 21190.25834095807, 21240.89379553102, 21278.14887404504, 17395.73906391402, 19570.804291957076, 20536.589268214626, 21406.785932140672, 21491.0, 5352.545033672132, 5458.744488767586, 5536.169574563827, 5537.0, 5551.666899986014, 5865.208196893429, 6024.0, 6069.302030266532, 6085.878282434349, 6090.817275481742, 5677.744501497443, 6031.849203769897, 6047.5766696331275, 6053.757849686007, 6055.455009049185, 5905.616134951236, 5912.645241285537, 5934.287885453751, 6020.397960203988, 6043.244594425697, 5288.788448462057, 5307.840764777063, 5385.676859388449, 5409.053415652245, 5411.918821217646, 9861.950690246575, 13829.170249785046, 13915.930420347833, 14119.152850828881, 14218.573442796733, 2128.6381315176304, 2238.9664155037526, 2325.023490134131, 2640.3267166872515, 2829.6180015697823, 10285.434301113435, 10473.63342283024, 11816.704582385422, 14331.355089020915, 15073.246337683073, 1620.9463848498453, 1747.7902651681752, 1776.0586888948749, 1790.4718108158124, 1823.917923693437, 91968.24095277152, 100587.0, 101754.0, 108354.45822770835, 111266.44366778195, 9687.951560242154, 10912.96326848948, 11778.52885884555, 12131.696707582381, 12135.635930922086, 14053.859461405254, 15598.376064957502, 15671.216439177995, 15688.76466852998, 15693.686126277411, 8971.775705607412, 9104.317176211678, 9129.543522823833, 9150.084991500797, 9157.496337701357, 6119.632822030692, 8389.412741108186, 8558.229759321692, 8656.307495400353, 9080.046595107551, 4712.59942904857, 4799.088173247072, 4814.242801377542, 4855.247436647325, 4863.659543831898, 1655.5033489952966, 1771.4597047900513, 1872.569309058924, 2031.7460317460318, 2050.559129787103, 727.436236916394, 847.9067302596709, 927.8700981862545, 946.654471118037, 997.6159178716101, 15278.236088195546, 20216.0, 20911.22311492565, 20996.0, 21860.562788744137, 974.2498276327331, 999.480270259461, 1018.165104614224, 1030.8402197659375, 1053.3995622495258, 8717.738467845975, 11116.61091861781, 11241.94379028116, 11333.659990200307, 11357.432128393548, 554769.0, 580167.0, 583485.0, 586376.0, 619927.0, 89555.10444895599, 97173.0, 101773.49113254534, 108111.45944270228, 118264.0, 456.1060321769303, 474.335929698425, 477.99044019119424, 479.43426756427056, 479.7769037397627, 10664.306820056672, 11048.779024419306, 11500.654980350602, 11625.941870290595, 11779.469923853618, 43264.78367608142, 53298.73350633222, 55035.72482137564, 55432.0, 55538.72230638821, 77943.2205677936, 79535.0, 79901.60049199717, 81606.0, 83980.0, 8128.390370722338, 8136.796580085545, 8274.875876861972, 8361.080281169188, 8496.0, 9242.214411775074, 11845.822312665145, 12816.102872799187, 12864.163829351437, 13000.0, 9502.857457138287, 11146.996770290712, 11162.49768760424, 11255.04332131778, 11297.096232301232, 10900.672979810619, 11089.445527723421, 11167.38579378142, 11260.211785174959, 11470.426478675867, 11131.888681113085, 11484.19610627248, 11488.885111148782, 11544.422778860855, 11718.355490448108, 8311.709090181814, 8520.275776559061, 8622.223999840044, 8630.82738345242, 8733.737987860375, 10858.67423977282, 11222.607208747655, 11235.213535052468, 11345.092392608736, 11369.54521819151], "value": 9074.22154474295, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.7607170888514, 1547.4197176058979, 1553.9067655940644, 1859.330640969251, 1928.1805232776067, 7900.130985591594, 8557.657693692245, 8719.0, 8890.06654301298, 8900.777480562989, 7332.633368331589, 8475.872861907077, 8508.829823403543, 8615.35384846136, 8666.090060543647, 2362.6810380598613, 2363.4918492524102, 2365.6333268343346, 2387.8328517003815, 2392.6650268962358, 2421.878906054699, 2431.7689819467123, 2459.8155138364605, 2464.1745015419856, 2464.6549483072386, 2442.3405680466353, 2443.951120977579, 2459.139301244559, 2465.3097132802845, 2478.120267305108, 2437.475942672325, 2439.121916110199, 2452.5585394629047, 2459.32368598635, 2461.4707837814954, 2432.1609044879488, 2439.5364880672614, 2440.12155623977, 2443.362946825613, 2458.5943319352286, 7658.61706914652, 8721.171488708562, 8740.082291359442, 8786.077461866476, 8798.12018798115, 5321.627486075976, 5414.07960646691, 5429.647072940285, 5456.208849716769, 5481.75332110052, 3481.0601137692934, 3564.679178873888, 3616.168281295317, 3619.402798538238, 3661.377565813818, 1046.9528871200814, 1066.7919755647652, 1069.1820757120809, 1069.850220969065, 1070.769784496337, 11826.231294965886, 11882.346470944094, 12043.458044388024, 12050.216735912138, 12121.575744848977, 4066.1664358806374, 4670.929936050962, 4699.835505757282, 4783.091212669582, 4815.3499277597075, 8599.656013759504, 10104.343217690826, 10123.0, 10125.443100629536, 10141.9858014198, 5279.234510995884, 5317.73411329432, 5364.624476286701, 5380.7309634518115, 5387.326584176978, 3956.0, 4184.748915065045, 4366.759828209415, 4684.672072954929, 4687.062587482517, 3329.9677100099047, 3982.163745613396, 4066.79666016698, 4090.795460226977, 4102.343625019982, 1357.7759669654397, 1360.8639136086313, 1366.8223130992908, 1378.4486205517874, 1379.7447472217673, 907.3331101640289, 928.2527565309855, 934.2012579244783, 939.6899023322287, 940.0740270833223, 5280.656757310762, 5958.195643588147, 5970.582059255898, 5977.252843394575, 6110.633361998294, 456.30185815702026, 464.7095565271705, 466.6803239780761, 470.064571502714, 472.5510764773479, 3977.164795392942, 3977.8209980550946, 4016.819243134066, 4020.1356708307853, 4055.614716601918, 10532.684019479428, 10877.456127193609, 10903.182261330274, 10980.450977451095, 11027.61403350895, 10659.040686338267, 10678.893211067945, 10714.785704286027, 10799.78400431987, 10871.239013268996, 8238.917610823815, 8372.246497815226, 8382.664693412195, 8439.831203375898, 8450.87323690145, 5673.971630141823, 5718.085106382959, 5734.283214598176, 5855.590108692521, 5923.111533270044, 9116.453012818987, 9350.018248083854, 9408.623655053994, 9428.010058943986, 9459.621615135593, 9335.019822918768, 9537.52312384364, 9612.711618651452, 9633.470159141314, 9665.613375464902, 6728.495362847904, 7125.714971401086, 7214.819629509095, 7310.268973102648, 7442.0, 7991.640376183202, 8019.719309824128, 8029.397795165269, 8089.6764129436515, 8098.3521318293215, 7101.183363913216, 7159.677814498465, 7193.604351760703, 7268.527545709512, 7335.083114610674, 7050.647467626701, 7059.752908648173, 7072.08062951813, 7135.036770035978, 7171.0, 6827.829304267234, 6946.097007389007, 7015.894761578478, 7057.470689698191, 7060.470464715064, 4805.567498925114, 4833.275008748716, 4839.128956787812, 4967.652264341461, 4974.427940786856, 4486.730796152241, 4535.4103966484145, 4550.541588392779, 4875.293082502946, 4951.950480495149], "value": 4489.46401613696, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.78279986809116, 324.27686259641, 327.95244689520007, 331.6037335384216, 331.8291080093752, 6258.311585725578, 6525.15173027507, 6675.0, 6713.496487763419, 6721.0, 5829.679367634779, 6217.533684973634, 6620.735170593182, 6687.99680047993, 6710.563813352129, 4260.995997821528, 4910.091633047898, 4912.263160525914, 4935.851924442272, 4951.529604687549, 4666.789994450258, 4959.256111583255, 4963.45402005779, 4971.651984361096, 4975.8258460953875, 4874.3419638348905, 4874.415070191582, 4889.217725163974, 4915.680480768757, 4917.0, 4774.7135171889795, 4852.1508735971065, 4880.853574392774, 4894.853154405374, 4895.6083513318845, 3586.9820650896577, 4152.024274295538, 4173.0, 4201.096764195712, 4208.431861698662, 6305.211848518935, 6315.905261421082, 6327.683615819236, 6333.55665103443, 6353.142325786052, 5891.322497912752, 5892.675902825343, 5896.0, 5903.439173278532, 5909.23179986599, 7149.392301654366, 7177.3899218566485, 7198.100237470317, 7204.783856484314, 7206.387457066156, 4400.6259467945265, 4671.0, 4727.149113159629, 4731.0, 4737.076270127326, 7427.517211381243, 7504.174540800507, 7548.131964824061, 7552.055993000875, 7559.3196612305155, 4693.06138772247, 4694.600958918462, 4695.389599352063, 4698.671093023524, 4699.060187962353, 4778.187708089633, 4784.521547845257, 4793.448753393334, 4794.712317260975, 4796.290096095695, 5720.48515633595, 5729.4843464088435, 5731.398203188689, 5743.396943320891, 5744.971275143681, 5359.785608575691, 7095.361417472453, 7179.389751871149, 7186.065811444583, 7226.566406015656, 1621.9700490188816, 1631.600257936813, 1649.8432648898336, 1651.1991684033235, 1657.4778944632644, 1030.9690709278734, 1031.4945676618554, 1045.6810672744887, 1047.675220681591, 1048.984265236022, 6526.673666316665, 6598.0, 6691.0, 6703.061571380011, 6717.328267173243, 6331.2085989251345, 7080.291970802981, 7430.256974302634, 7464.962675186589, 7494.4753867229865, 5848.239728835224, 6238.8770021396585, 6673.099131617169, 6689.632070236184, 6707.5975441473665, 3879.1271963807917, 3915.7258991870294, 3916.138449541096, 3926.430667553189, 3936.8622098226424, 7467.327940485382, 7781.92218077812, 7791.961040194763, 7797.064352277763, 7809.023872015998, 7631.503952243086, 7686.500377475446, 7689.077310722749, 7704.614769261514, 7725.768226953201, 7769.028871391076, 7780.2219778021745, 7782.377409807315, 7789.7273595423885, 7811.45319827618, 6361.522885783492, 7094.751683691046, 7359.8160045999275, 7419.369353605004, 7442.776716698508, 5914.940850591439, 7308.086489188851, 7333.230010848997, 7342.302481264272, 7342.889856652261, 6188.907166392598, 6201.596896201913, 6540.444062254762, 7685.5004424712215, 7750.0699916010435, 4236.745795252295, 4646.326282688923, 4650.186217412007, 4653.60444362233, 4658.976705116453, 7330.780076597711, 7335.413166946525, 7346.191918888811, 7363.374113200438, 7409.851802964018, 6857.657117144023, 7151.463640227108, 7152.713891444284, 7181.640917954186, 7187.460940429384, 7089.82275443118, 7122.323379278961, 7140.321669441395, 7145.964270178616, 7167.8924816126755, 6953.930460695531, 6980.406665433292, 7009.1939426966555, 7009.824754381181, 7061.2585678505075, 6992.056072430157, 7162.5344352618995, 7193.784186474414, 7209.603471808891, 7276.490645654753, 6757.831054223684, 6827.863442731217, 6938.202106757787, 7108.004879316796, 7173.569585824867], "value": 5175.730773975428, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8002599220239, 686.763066741974, 746.5968377076382, 755.7354925775979, 758.1054355860086, 14708.632284192887, 14765.77851332231, 14966.25168741564, 14991.175485348314, 14999.999999999973, 13620.931895340509, 13703.725925481484, 13710.725785484285, 13743.931280343584, 13828.239446830421, 712.2770388056127, 797.7885860247039, 909.4588719711794, 933.2207606648457, 934.9485778282192, 910.886139232596, 920.2270093121776, 923.3213588012806, 925.5418567808944, 925.7315378540249, 980.9705708828747, 982.327105932435, 982.79852630211, 984.5372674842848, 986.5412583148834, 935.0789472369711, 938.7746940734243, 974.7611835100375, 975.4488713876673, 976.2970661123979, 650.177525430331, 664.1134085995154, 668.9464842812563, 697.0554898113085, 699.7096205074888, 14271.357788899524, 14314.284285785774, 14354.923380746486, 14368.928155359261, 14570.980031397807, 12600.369981500888, 12610.621681349574, 12697.301648409333, 12742.0, 12874.806877896839, 8680.696175633886, 9965.701028969142, 10065.647702330383, 10337.89662103377, 10339.276250662457, 3494.5981212160673, 3500.9824950875336, 3521.454174602941, 3556.982215088908, 3709.8887033388773, 71348.71907685556, 72348.91476627855, 73690.0, 73872.63063684701, 74002.25997740007, 9141.085891410805, 9316.114969078062, 9475.431474111576, 9533.713988580354, 9584.233261339075, 20265.696014559795, 20484.873331966588, 20500.0, 20577.69133462999, 20618.690719639217, 7198.640067996579, 7330.450216233804, 7390.593517356489, 7409.481336306407, 7440.7395741149885, 3902.2195560887935, 3934.11482416454, 3965.7025723070883, 4067.51189857219, 4069.5726948670554, 3553.626869178699, 3625.3836847735947, 3634.7637403568683, 3644.1618427761764, 3658.2683463307444, 633.5058654249692, 643.7457204404328, 644.4618743349262, 660.577230572434, 668.0747165176266, 495.98818410442294, 566.931968163823, 569.866081470854, 587.6797145555682, 609.1593600830913, 7819.296263336327, 7875.881861772078, 8241.217084376975, 8358.0, 8376.623051962677, 512.6513970500022, 578.4100217777927, 587.72376982818, 615.5567991046439, 626.1890851347516, 3793.8103094845146, 3823.3500304948225, 3859.903502412406, 3878.301905657009, 4068.2473742357165, 93868.90064080157, 95194.0, 96707.03292967122, 97087.51456242673, 97194.05611887723, 64801.02798458027, 73260.0, 74212.25787742161, 76168.0, 76453.61773191098, 71729.28270717226, 72148.63925680338, 72171.19572010636, 72176.27823721801, 73942.0, 6312.331920889807, 7649.31156195945, 7759.72840950564, 7790.2209779023915, 7806.765797026098, 50909.49090509196, 51139.977200456524, 51484.45546633607, 52274.477255228485, 53021.73489132529, 64092.3590764105, 66019.0, 69141.3085869155, 69150.65424673045, 69671.0, 8996.595153218252, 10969.396683182502, 10990.945045274722, 11090.5563777448, 11462.025727813229, 11915.2255103418, 11926.224795388272, 11937.641870743892, 12114.818277726017, 12327.568535101229, 11271.718207044561, 11424.257423267461, 11450.312981221154, 11560.94219528897, 11751.353675547585, 11372.260803047775, 11499.367534785668, 11536.94231528837, 11547.480363383836, 12073.215241009306, 11022.063124634175, 11189.608363707232, 11300.208985370946, 11381.829272560753, 11622.18644694897, 8156.632951517315, 9419.623215071595, 9616.667828581136, 9619.270392674121, 9894.15899648538, 9323.347365684336, 9656.855147172657, 9714.368566043184, 9724.854127188239, 9802.0], "value": 6837.311115201341, "unit": "score"}], "name": "dromaeo_css", "value": 7281.850885608605, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 109.0, 117.0, 117.0, 118.0, 109.0, 116.0, 115.0, 116.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 125.0, 185.0, 188.0, 117.0, 139.0, 118.0, 147.0, 156.0, 124.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 193.0, 201.0, 192.0, 195.0, 200.0, 196.0, 204.0, 196.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 95.0, 120.0, 182.0, 63.0, 85.0, 81.0, 86.0, 135.0, 58.0], "value": 97.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 64.0, 161.0, 111.0, 115.0, 67.0, 149.0, 121.0, 118.0, 152.0], "value": 117.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 92.0, 87.0, 90.0, 87.0, 91.0, 88.0, 90.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 103.0, 134.0, 96.0, 110.0, 124.0, 97.0, 97.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 94.0, 93.0, 90.0, 91.0, 92.0, 90.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 66.0, 66.0, 67.0, 66.0, 66.0, 81.0, 63.0, 61.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 72.0, 71.0, 72.0, 72.0, 70.0, 68.0, 66.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 68.0, 81.0, 100.0, 107.0, 104.0, 108.0, 82.0, 84.0, 87.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 141.0, 148.0, 150.0, 118.0, 147.0, 149.0, 148.0, 168.0, 175.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 168.0, 166.0, 172.0, 163.0, 170.0, 167.0, 166.0, 163.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 85.0, 64.0, 68.0, 68.0, 68.0, 69.0, 67.0, 69.0, 73.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1567.1999999999998, "alertThreshold": 2.0}]} 23:27:26 INFO - Return code: 0 23:27:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:27:26 ERROR - # TBPL SUCCESS # 23:27:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:27:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:27:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:27:26 INFO - 2016-05-23 23:27:26.812 screenresolution[1687:10894] starting screenresolution argv=screenresolution get 23:27:26 INFO - 2016-05-23 23:27:26.815 screenresolution[1687:10894] Display 0: 1600x1200x32@60 23:27:26 INFO - 2016-05-23 23:27:26.821 screenresolution[1688:10897] starting screenresolution argv=screenresolution list 23:27:26 INFO - Available Modes on Display 0 23:27:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:27:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:27:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:27:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:27:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:27:27 INFO - Intel Iris: 23:27:27 INFO - Chipset Model: Intel Iris 23:27:27 INFO - Type: GPU 23:27:27 INFO - Bus: Built-In 23:27:27 INFO - VRAM (Dynamic, Max): 1536 MB 23:27:27 INFO - Vendor: Intel (0x8086) 23:27:27 INFO - Device ID: 0x0a2e 23:27:27 INFO - Revision ID: 0x0009 23:27:27 INFO - Displays: 23:27:27 INFO - Display: 23:27:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:27:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:27:27 INFO - Display Serial Number: 1600x1200 60 23:27:27 INFO - Main Display: Yes 23:27:27 INFO - Mirror: Off 23:27:27 INFO - Online: Yes 23:27:27 INFO - Rotation: Supported 23:27:27 INFO - Return code: 0 23:27:27 INFO - Running post-action listener: _resource_record_post_action 23:27:27 INFO - Running post-run listener: _resource_record_post_run 23:27:28 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 31890944; Write bytes: 656541696; Read time: 374; Write time: 1849 23:27:28 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354648576; Write bytes: 355653632; Read time: 27649; Write time: 706 23:27:28 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 30080512; Write bytes: 300040192; Read time: 353; Write time: 1141 23:27:28 INFO - Running post-run listener: _upload_blobber_files 23:27:28 INFO - Blob upload gear active. 23:27:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:27:28 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 23:27:28 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'] 23:27:28 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 23:27:28 INFO - (blobuploader) - INFO - Open directory for files ... 23:27:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:27:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:27:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:30 INFO - (blobuploader) - INFO - Done attempting. 23:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:31 INFO - (blobuploader) - INFO - Done attempting. 23:27:31 INFO - (blobuploader) - INFO - Iteration through files over. 23:27:31 INFO - Return code: 0 23:27:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:27:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:27:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fa11a5f13b22afef11784077a18bea88d7b1940ab68a37f36856018f6399ea3294b041ce2a79af65954475ca1b2505fafa5a9f0cba30ada3f7f6976b9461be3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a2ba3f75808f0f9fe2c80f2fae721bdbdf2d02138d2e48b8603e55e44a3afce9137ac441934454ecc4f32ac5ad575bab8ef2a5301721c2e16b8da74552a6e48"} 23:27:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:27:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:27:31 INFO - Contents: 23:27:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fa11a5f13b22afef11784077a18bea88d7b1940ab68a37f36856018f6399ea3294b041ce2a79af65954475ca1b2505fafa5a9f0cba30ada3f7f6976b9461be3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a2ba3f75808f0f9fe2c80f2fae721bdbdf2d02138d2e48b8603e55e44a3afce9137ac441934454ecc4f32ac5ad575bab8ef2a5301721c2e16b8da74552a6e48"} 23:27:31 INFO - Running post-run listener: copy_logs_to_upload_dir 23:27:31 INFO - Copying logs to upload dir... 23:27:31 INFO - mkdir: /builds/slave/test/build/upload/logs 23:27:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.893535 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-23 23:27:31.606363) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:31.609926) ========= 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.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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/7fa11a5f13b22afef11784077a18bea88d7b1940ab68a37f36856018f6399ea3294b041ce2a79af65954475ca1b2505fafa5a9f0cba30ada3f7f6976b9461be3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a2ba3f75808f0f9fe2c80f2fae721bdbdf2d02138d2e48b8603e55e44a3afce9137ac441934454ecc4f32ac5ad575bab8ef2a5301721c2e16b8da74552a6e48"} build_url:https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010623 build_url: 'https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/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/7fa11a5f13b22afef11784077a18bea88d7b1940ab68a37f36856018f6399ea3294b041ce2a79af65954475ca1b2505fafa5a9f0cba30ada3f7f6976b9461be3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a2ba3f75808f0f9fe2c80f2fae721bdbdf2d02138d2e48b8603e55e44a3afce9137ac441934454ecc4f32ac5ad575bab8ef2a5301721c2e16b8da74552a6e48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:31.639184) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:31.639533) ========= 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.6CpX75rjzT/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.HOnSLmBlAo/Listeners TMPDIR=/var/folders/4s/dwjnph5j5jzfjbt8cm0511q000000w/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.005094 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:31.687857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:27:31.688205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:27:31.688572) ========= ========= Total master_lag: 0.16 =========