builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1461702467.0 results: success (0) buildid: 20160426120641 builduid: 7cb853e62e4c4fd68d0ab47d1a77adb5 revision: 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.004879) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.005326) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.005606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.024669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.024931) ========= 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.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 13:27:47-- 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% 207M=0s 2016-04-26 13:27:47 (207 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.199806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.203133) ========= 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.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.021333 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:47.251522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:27:47.251833) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 13:27:47,328 truncating revision to first 12 chars 2016-04-26 13:27:47,328 Setting DEBUG logging. 2016-04-26 13:27:47,328 attempt 1/10 2016-04-26 13:27:47,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6bbcf33e1a70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:27:48,576 unpacking tar archive at: fx-team-6bbcf33e1a70/testing/mozharness/ program finished with exit code 0 elapsedTime=1.497030 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:27:48.771427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:48.771772) ========= script_repo_revision: 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:48.772211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:48.772498) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:27:48.786653) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 8 secs) (at 2016-04-26 13:27:48.786923) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:27:48 INFO - MultiFileLogger online at 20160426 13:27:48 in /builds/slave/test 13:27:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:27:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:27:48 INFO - {'append_to_log': False, 13:27:48 INFO - 'base_work_dir': '/builds/slave/test', 13:27:48 INFO - 'blob_upload_branch': 'Fx-Team', 13:27:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:27:48 INFO - 'branch': 'Fx-Team', 13:27:48 INFO - 'buildbot_json_path': 'buildprops.json', 13:27:48 INFO - 'config_files': ('talos/mac_config.py',), 13:27:48 INFO - 'default_actions': ('clobber', 13:27:48 INFO - 'read-buildbot-config', 13:27:48 INFO - 'download-and-extract', 13:27:48 INFO - 'populate-webroot', 13:27:48 INFO - 'create-virtualenv', 13:27:48 INFO - 'install', 13:27:48 INFO - 'run-tests'), 13:27:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:27:48 INFO - 'download_minidump_stackwalk': True, 13:27:48 INFO - 'download_symbols': 'ondemand', 13:27:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:27:48 INFO - 'tooltool.py': '/tools/tooltool.py', 13:27:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:27:48 INFO - '/tools/misc-python/virtualenv.py')}, 13:27:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:27:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:27:48 INFO - 'installer_path': 'installer.exe', 13:27:48 INFO - 'log_level': 'info', 13:27:48 INFO - 'log_name': 'talos', 13:27:48 INFO - 'log_to_console': True, 13:27:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:27:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:27:48 INFO - 'opt_config_files': (), 13:27:48 INFO - 'pip_index': False, 13:27:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:27:48 INFO - 'cmd': ('bash', 13:27:48 INFO - '-c', 13:27:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:27:48 INFO - 'enabled': True, 13:27:48 INFO - 'halt_on_failure': False, 13:27:48 INFO - 'name': 'check_screen_resolution'},), 13:27:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:27:48 INFO - 'cmd': ('bash', 13:27:48 INFO - '-c', 13:27:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:27:48 INFO - 'enabled': True, 13:27:48 INFO - 'halt_on_failure': False, 13:27:48 INFO - 'name': 'check_screen_resolution'},), 13:27:48 INFO - 'run_cmd_checks_enabled': True, 13:27:48 INFO - 'sps_profile': False, 13:27:48 INFO - 'sps_profile_interval': 0, 13:27:48 INFO - 'suite': 'dromaeojs', 13:27:48 INFO - 'system_bits': '32', 13:27:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:27:48 INFO - 'title': 't-yosemite-r7-0006', 13:27:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:27:48 INFO - 'use_talos_json': True, 13:27:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:27:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:27:48 INFO - 'work_dir': 'build'} 13:27:48 INFO - ##### 13:27:48 INFO - ##### Running clobber step. 13:27:48 INFO - ##### 13:27:48 INFO - Running pre-action listener: _resource_record_pre_action 13:27:48 INFO - Running main action method: clobber 13:27:48 INFO - rmtree: /builds/slave/test/build 13:27:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:27:49 INFO - Running post-action listener: _resource_record_post_action 13:27:49 INFO - ##### 13:27:49 INFO - ##### Running read-buildbot-config step. 13:27:49 INFO - ##### 13:27:49 INFO - Running pre-action listener: _resource_record_pre_action 13:27:49 INFO - Running main action method: read_buildbot_config 13:27:49 INFO - Using buildbot properties: 13:27:49 INFO - { 13:27:49 INFO - "project": "", 13:27:49 INFO - "product": "firefox", 13:27:49 INFO - "script_repo_revision": "production", 13:27:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:27:49 INFO - "repository": "", 13:27:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:27:49 INFO - "buildid": "20160426120641", 13:27:49 INFO - "pgo_build": "False", 13:27:49 INFO - "basedir": "/builds/slave/test", 13:27:49 INFO - "buildnumber": 780, 13:27:49 INFO - "slavename": "t-yosemite-r7-0006", 13:27:49 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:27:49 INFO - "platform": "yosemite_r7", 13:27:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:27:49 INFO - "branch": "fx-team", 13:27:49 INFO - "repo_path": "integration/fx-team", 13:27:49 INFO - "revision": "6bbcf33e1a709cc6bd9a9ab73e303093fc748239", 13:27:49 INFO - "stage_platform": "macosx64", 13:27:49 INFO - "builduid": "7cb853e62e4c4fd68d0ab47d1a77adb5", 13:27:49 INFO - "slavebuilddir": "test" 13:27:49 INFO - } 13:27:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:27:49 INFO - Running post-action listener: _resource_record_post_action 13:27:49 INFO - ##### 13:27:49 INFO - ##### Running download-and-extract step. 13:27:49 INFO - ##### 13:27:49 INFO - Running pre-action listener: _resource_record_pre_action 13:27:49 INFO - Running main action method: download_and_extract 13:27:49 INFO - mkdir: /builds/slave/test/build/tests 13:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:49 INFO - https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:27:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:27:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:27:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:27:52 INFO - Downloaded 1199 bytes. 13:27:52 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:27:52 INFO - Using the following test package requirements: 13:27:52 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:27:52 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:27:52 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:27:52 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'jsshell-mac.zip'], 13:27:52 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:27:52 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:27:52 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:27:52 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:27:52 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:27:52 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:27:52 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:27:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:27: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')]} 13:27:52 INFO - https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:27:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:27:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:27:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:27:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:28:06 INFO - Downloaded 18114090 bytes. 13:28:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:28:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:28:09 INFO - Return code: 0 13:28:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:28:09 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')]} 13:28:09 INFO - https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:28:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:28:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:28:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:28:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:30:48 INFO - Downloaded 18114090 bytes. 13:30:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:30:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:30:52 INFO - Return code: 0 13:30: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')]} 13:30:52 INFO - https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:30:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:30:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:30:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:30:54 INFO - Downloaded 10840145 bytes. 13:30:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:30:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:30:55 INFO - Return code: 0 13:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:55 INFO - https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:30:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:30:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:30:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:30:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:31:05 INFO - Downloaded 110125274 bytes. 13:31:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:31:05 INFO - mkdir: /builds/slave/test/properties 13:31:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:31:05 INFO - Writing to file /builds/slave/test/properties/build_url 13:31:05 INFO - Contents: 13:31:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:31:06 INFO - Running post-action listener: _resource_record_post_action 13:31:06 INFO - Running post-action listener: set_extra_try_arguments 13:31:06 INFO - ##### 13:31:06 INFO - ##### Running populate-webroot step. 13:31:06 INFO - ##### 13:31:06 INFO - Running pre-action listener: _resource_record_pre_action 13:31:06 INFO - Running main action method: populate_webroot 13:31:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:31:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:31:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:31:06 INFO - '--tppagecycles', 13:31:06 INFO - '7'], 13:31:06 INFO - 'tests': ['tsvgm']}}, 13:31:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:31:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:31:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:31:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:31:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['damp', 'tps']}, 13:31:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'tests': ['damp', 'tps']}, 13:31:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['dromaeo_dom']}, 13:31:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:31:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'other-e10s': {'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['a11yr', 13:31:06 INFO - 'ts_paint', 13:31:06 INFO - 'tpaint', 13:31:06 INFO - 'sessionrestore', 13:31:06 INFO - 'sessionrestore_no_auto_restore', 13:31:06 INFO - 'tabpaint']}, 13:31:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['tsvgx', 13:31:06 INFO - 'tsvgr_opacity', 13:31:06 INFO - 'tart', 13:31:06 INFO - 'tscrollx', 13:31:06 INFO - 'cart']}, 13:31:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:31:06 INFO - 'tsvgr_opacity', 13:31:06 INFO - 'tart', 13:31:06 INFO - 'tscrollx', 13:31:06 INFO - 'cart']}, 13:31:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'talos_options': ['--disable-e10s'], 13:31:06 INFO - 'tests': ['tp5o']}, 13:31:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'tests': ['tp5o']}, 13:31:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'talos_options': ['--disable-e10s', 13:31:06 INFO - '--xperf_path', 13:31:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:06 INFO - 'tests': ['tp5n']}, 13:31:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:06 INFO - 'talos_options': ['--xperf_path', 13:31:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:06 INFO - 'tests': ['tp5n']}}, 13:31:06 INFO - 'talos.zip': {'path': '', 13:31:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:31:06 INFO - Running post-action listener: _resource_record_post_action 13:31:06 INFO - ##### 13:31:06 INFO - ##### Running create-virtualenv step. 13:31:06 INFO - ##### 13:31:06 INFO - Running pre-action listener: _resource_record_pre_action 13:31:06 INFO - Running main action method: create_virtualenv 13:31:06 INFO - Creating virtualenv /builds/slave/test/build/venv 13:31:06 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 13:31:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:31:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:31:07 INFO - Using real prefix '/tools/python27' 13:31:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:31:07 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:10 INFO - Installing pip.................done. 13:31:10 INFO - Return code: 0 13:31:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:10 INFO - 'HOME': '/Users/cltbld', 13:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:10 INFO - 'LOGNAME': 'cltbld', 13:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:10 INFO - 'MOZ_NO_REMOTE': '1', 13:31:10 INFO - 'NO_EM_RESTART': '1', 13:31:10 INFO - 'PAGER': '/bin/cat', 13:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:10 INFO - 'PWD': '/builds/slave/test', 13:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:10 INFO - 'SHELL': '/bin/bash', 13:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:10 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:10 INFO - 'USER': 'cltbld', 13:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:10 INFO - 'XPC_FLAGS': '0x0', 13:31:10 INFO - 'XPC_SERVICE_NAME': '0', 13:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:10 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 13:31:10 INFO - Cleaning up... 13:31:10 INFO - Return code: 0 13:31:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:10 INFO - 'HOME': '/Users/cltbld', 13:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:10 INFO - 'LOGNAME': 'cltbld', 13:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:10 INFO - 'MOZ_NO_REMOTE': '1', 13:31:10 INFO - 'NO_EM_RESTART': '1', 13:31:10 INFO - 'PAGER': '/bin/cat', 13:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:10 INFO - 'PWD': '/builds/slave/test', 13:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:10 INFO - 'SHELL': '/bin/bash', 13:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:10 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:10 INFO - 'USER': 'cltbld', 13:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:10 INFO - 'XPC_FLAGS': '0x0', 13:31:10 INFO - 'XPC_SERVICE_NAME': '0', 13:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:11 INFO - Downloading/unpacking psutil>=0.7.1 13:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:14 INFO - Installing collected packages: psutil 13:31:14 INFO - Running setup.py install for psutil 13:31:14 INFO - building 'psutil._psutil_osx' extension 13:31:14 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 13:31:14 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 13:31:14 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 13:31:14 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 13:31:14 INFO - building 'psutil._psutil_posix' extension 13:31:14 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 13:31:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:31:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:31:14 INFO - ^ 13:31:14 INFO - 1 warning generated. 13:31:14 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 13:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:15 INFO - Successfully installed psutil 13:31:15 INFO - Cleaning up... 13:31:15 INFO - Return code: 0 13:31:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:31:15 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')]} 13:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:15 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')]} 13:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:15 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:15 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 13:31:15 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 13:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:15 INFO - 'HOME': '/Users/cltbld', 13:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:15 INFO - 'LOGNAME': 'cltbld', 13:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:15 INFO - 'MOZ_NO_REMOTE': '1', 13:31:15 INFO - 'NO_EM_RESTART': '1', 13:31:15 INFO - 'PAGER': '/bin/cat', 13:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:15 INFO - 'PWD': '/builds/slave/test', 13:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:15 INFO - 'SHELL': '/bin/bash', 13:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:15 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:15 INFO - 'USER': 'cltbld', 13:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:15 INFO - 'XPC_FLAGS': '0x0', 13:31:15 INFO - 'XPC_SERVICE_NAME': '0', 13:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:31:15 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 13:31:15 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 13:31:15 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 13:31:15 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 13:31:15 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 13:31:15 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 13:31:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:31:17 INFO - Installing collected packages: mozsystemmonitor 13:31:17 INFO - Running setup.py install for mozsystemmonitor 13:31:17 INFO - Successfully installed mozsystemmonitor 13:31:17 INFO - Cleaning up... 13:31:18 INFO - Return code: 0 13:31:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:18 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:18 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 13:31:18 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 13:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:18 INFO - 'HOME': '/Users/cltbld', 13:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:18 INFO - 'LOGNAME': 'cltbld', 13:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:18 INFO - 'MOZ_NO_REMOTE': '1', 13:31:18 INFO - 'NO_EM_RESTART': '1', 13:31:18 INFO - 'PAGER': '/bin/cat', 13:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:18 INFO - 'PWD': '/builds/slave/test', 13:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:18 INFO - 'SHELL': '/bin/bash', 13:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:18 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:18 INFO - 'USER': 'cltbld', 13:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:18 INFO - 'XPC_FLAGS': '0x0', 13:31:18 INFO - 'XPC_SERVICE_NAME': '0', 13:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:18 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:20 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31: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 13:31: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 13:31: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 13:31: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 13:31: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 13:31: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 13:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:31:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:31: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 13:31: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 13:31: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 13:31: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 13:31: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 13:31: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 13:31:21 INFO - Downloading docopt-0.6.1.tar.gz 13:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:31:22 INFO - Installing collected packages: blobuploader, requests, docopt 13:31:22 INFO - Running setup.py install for blobuploader 13:31:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:31:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:31:22 INFO - Running setup.py install for requests 13:31:22 INFO - Running setup.py install for docopt 13:31:22 INFO - Successfully installed blobuploader requests docopt 13:31:22 INFO - Cleaning up... 13:31:22 INFO - Return code: 0 13:31:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:22 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:22 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 13:31:22 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 13:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:22 INFO - 'HOME': '/Users/cltbld', 13:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:22 INFO - 'LOGNAME': 'cltbld', 13:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:22 INFO - 'MOZ_NO_REMOTE': '1', 13:31:22 INFO - 'NO_EM_RESTART': '1', 13:31:22 INFO - 'PAGER': '/bin/cat', 13:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:22 INFO - 'PWD': '/builds/slave/test', 13:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:22 INFO - 'SHELL': '/bin/bash', 13:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:22 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:22 INFO - 'USER': 'cltbld', 13:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:22 INFO - 'XPC_FLAGS': '0x0', 13:31:22 INFO - 'XPC_SERVICE_NAME': '0', 13:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ES2qgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UEP_vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-l1Z3tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Y5RGQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-LOZVL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-LoN6ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-53wNZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-AtqD_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bXg25W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xY1S4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-i6LbZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jTC5AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bg2av6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-G5_mys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RV68aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Y98zKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-tRBieh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:24 INFO - Running setup.py install for manifestparser 13:31:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Running setup.py install for mozcrash 13:31:25 INFO - Running setup.py install for mozdebug 13:31:25 INFO - Running setup.py install for mozdevice 13:31:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Running setup.py install for mozfile 13:31:25 INFO - Running setup.py install for mozhttpd 13:31:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Running setup.py install for mozinfo 13:31:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Running setup.py install for mozInstall 13:31:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Running setup.py install for mozleak 13:31:26 INFO - Running setup.py install for mozlog 13:31:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Running setup.py install for moznetwork 13:31:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Running setup.py install for mozprocess 13:31:26 INFO - Running setup.py install for mozprofile 13:31:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:26 INFO - Running setup.py install for mozrunner 13:31:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:27 INFO - Running setup.py install for mozscreenshot 13:31:27 INFO - Running setup.py install for moztest 13:31:27 INFO - Running setup.py install for mozversion 13:31:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:27 INFO - Cleaning up... 13:31:27 INFO - Return code: 0 13:31:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:27 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')]} 13:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:27 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')]} 13:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:27 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:27 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 13:31:27 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 13:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:27 INFO - 'HOME': '/Users/cltbld', 13:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:27 INFO - 'LOGNAME': 'cltbld', 13:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:27 INFO - 'MOZ_NO_REMOTE': '1', 13:31:27 INFO - 'NO_EM_RESTART': '1', 13:31:27 INFO - 'PAGER': '/bin/cat', 13:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:27 INFO - 'PWD': '/builds/slave/test', 13:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:27 INFO - 'SHELL': '/bin/bash', 13:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:27 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:27 INFO - 'USER': 'cltbld', 13:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:27 INFO - 'XPC_FLAGS': '0x0', 13:31:27 INFO - 'XPC_SERVICE_NAME': '0', 13:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:27 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-k8E3gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:27 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-o_HuU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-9SoyXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-o2HjIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-XOHZtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Ffs9b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5I964S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5rm1lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-n5BTox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0OQDt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:28 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)) 13:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:28 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-eJwqpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0Cn198-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OW9xRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-SIg7mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-0zesxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-p0idku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:29 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)) 13:31:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-hj7reM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:29 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)) 13:31:29 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)) 13:31:29 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)) 13:31:29 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)) 13:31:29 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)) 13:31:29 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)) 13:31:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:29 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 13:31:29 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 13:31:29 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 13:31:29 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 13:31:29 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 13:31:29 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 13:31:32 INFO - Downloading blessings-1.6.tar.gz 13:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:32 INFO - Installing collected packages: blessings 13:31:32 INFO - Running setup.py install for blessings 13:31:32 INFO - Successfully installed blessings 13:31:32 INFO - Cleaning up... 13:31:32 INFO - Return code: 0 13:31:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:32 INFO - Reading from file tmpfile_stdout 13:31:32 INFO - Current package versions: 13:31:32 INFO - blessings == 1.6 13:31:32 INFO - blobuploader == 1.2.4 13:31:32 INFO - docopt == 0.6.1 13:31:32 INFO - manifestparser == 1.1 13:31:32 INFO - mozInstall == 1.12 13:31:32 INFO - mozcrash == 0.17 13:31:32 INFO - mozdebug == 0.1 13:31:32 INFO - mozdevice == 0.48 13:31:32 INFO - mozfile == 1.2 13:31:32 INFO - mozhttpd == 0.7 13:31:32 INFO - mozinfo == 0.9 13:31:32 INFO - mozleak == 0.1 13:31:32 INFO - mozlog == 3.1 13:31:32 INFO - moznetwork == 0.27 13:31:32 INFO - mozprocess == 0.22 13:31:32 INFO - mozprofile == 0.28 13:31:32 INFO - mozrunner == 6.11 13:31:32 INFO - mozscreenshot == 0.1 13:31:32 INFO - mozsystemmonitor == 0.0 13:31:32 INFO - moztest == 0.7 13:31:32 INFO - mozversion == 1.4 13:31:32 INFO - psutil == 3.1.1 13:31:32 INFO - requests == 1.2.3 13:31:32 INFO - wsgiref == 0.1.2 13:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:32 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')]} 13:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:32 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')]} 13:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:32 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:32 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 13:31:32 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 13:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:32 INFO - 'HOME': '/Users/cltbld', 13:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:32 INFO - 'LOGNAME': 'cltbld', 13:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:32 INFO - 'MOZ_NO_REMOTE': '1', 13:31:32 INFO - 'NO_EM_RESTART': '1', 13:31:32 INFO - 'PAGER': '/bin/cat', 13:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:32 INFO - 'PWD': '/builds/slave/test', 13:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:32 INFO - 'SHELL': '/bin/bash', 13:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:32 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:32 INFO - 'USER': 'cltbld', 13:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:32 INFO - 'XPC_FLAGS': '0x0', 13:31:32 INFO - 'XPC_SERVICE_NAME': '0', 13:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 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)) 13:31:32 INFO - Cleaning up... 13:31:33 INFO - Return code: 0 13:31:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:33 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:33 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 13:31:33 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 13:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:33 INFO - 'HOME': '/Users/cltbld', 13:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:33 INFO - 'LOGNAME': 'cltbld', 13:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:33 INFO - 'MOZ_NO_REMOTE': '1', 13:31:33 INFO - 'NO_EM_RESTART': '1', 13:31:33 INFO - 'PAGER': '/bin/cat', 13:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:33 INFO - 'PWD': '/builds/slave/test', 13:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:33 INFO - 'SHELL': '/bin/bash', 13:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:33 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:33 INFO - 'USER': 'cltbld', 13:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:33 INFO - 'XPC_FLAGS': '0x0', 13:31:33 INFO - 'XPC_SERVICE_NAME': '0', 13:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:33 INFO - Downloading/unpacking jsonschema 13:31:33 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 13:31:33 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 13:31:33 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 13:31:33 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 13:31:33 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 13:31:33 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 13:31:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:31:35 INFO - Downloading/unpacking functools32 (from jsonschema) 13:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:36 INFO - Downloading functools32-3.2.3-2.tar.gz 13:31:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:31:36 INFO - warning: no files found matching '*.txt' 13:31:36 INFO - no previously-included directories found matching 'build' 13:31:36 INFO - no previously-included directories found matching 'dist' 13:31:36 INFO - no previously-included directories found matching '.git*' 13:31:36 INFO - Installing collected packages: jsonschema, functools32 13:31:36 INFO - Running setup.py install for functools32 13:31:36 INFO - warning: no files found matching '*.txt' 13:31:36 INFO - no previously-included directories found matching 'build' 13:31:36 INFO - no previously-included directories found matching 'dist' 13:31:36 INFO - no previously-included directories found matching '.git*' 13:31:36 INFO - Successfully installed jsonschema functools32 13:31:36 INFO - Cleaning up... 13:31:36 INFO - Return code: 0 13:31:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:31:36 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')]} 13:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:36 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')]} 13:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:36 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 0x10174ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10175c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a0c8e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, '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.x1WPCmJWwt/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.mVm9j90WGo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 13:31:36 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 13:31:36 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 13:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:36 INFO - 'HOME': '/Users/cltbld', 13:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:36 INFO - 'LOGNAME': 'cltbld', 13:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:36 INFO - 'MOZ_NO_REMOTE': '1', 13:31:36 INFO - 'NO_EM_RESTART': '1', 13:31:36 INFO - 'PAGER': '/bin/cat', 13:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:36 INFO - 'PWD': '/builds/slave/test', 13:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:36 INFO - 'SHELL': '/bin/bash', 13:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:31:36 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:36 INFO - 'USER': 'cltbld', 13:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:36 INFO - 'XPC_FLAGS': '0x0', 13:31:36 INFO - 'XPC_SERVICE_NAME': '0', 13:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:36 INFO - Downloading/unpacking flake8 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:31:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:31:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 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 13:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:31:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:31:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:31:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:31:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:31:40 INFO - no previously-included directories found matching 'docs/_build' 13:31:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:31:40 INFO - Running setup.py install for pep8 13:31:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:31:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:31:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:31:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:31:40 INFO - no previously-included directories found matching 'docs/_build' 13:31:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:31:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:31:40 INFO - Cleaning up... 13:31:40 INFO - Return code: 0 13:31:40 INFO - Running post-action listener: _resource_record_post_action 13:31:40 INFO - Running post-action listener: _start_resource_monitoring 13:31:40 INFO - Starting resource monitoring. 13:31:40 INFO - ##### 13:31:40 INFO - ##### Running install step. 13:31:40 INFO - ##### 13:31:40 INFO - Running pre-action listener: _resource_record_pre_action 13:31:40 INFO - Running main action method: install 13:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:41 INFO - Reading from file tmpfile_stdout 13:31:41 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:41 INFO - Reading from file tmpfile_stdout 13:31:41 INFO - Output received: 13:31:41 INFO - Usage: mozinstall [options] installer 13:31:41 INFO - Options: 13:31:41 INFO - -h, --help show this help message and exit 13:31:41 INFO - -d DEST, --destination=DEST 13:31:41 INFO - Directory to install application into. [default: 13:31:41 INFO - "/builds/slave/test"] 13:31:41 INFO - --app=APP Application being installed. [default: firefox] 13:31:41 INFO - mkdir: /builds/slave/test/build/application 13:31:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:32:15 INFO - Reading from file tmpfile_stdout 13:32:15 INFO - Output received: 13:32:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:32:15 INFO - Running post-action listener: _resource_record_post_action 13:32:15 INFO - ##### 13:32:15 INFO - ##### Running run-tests step. 13:32:15 INFO - ##### 13:32:15 INFO - Running pre-action listener: _resource_record_pre_action 13:32:15 INFO - Running main action method: run_tests 13:32:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:15 INFO - 2016-04-26 13:32:15.231 screenresolution[2362:21487] starting screenresolution argv=screenresolution get 13:32:15 INFO - 2016-04-26 13:32:15.234 screenresolution[2362:21487] Display 0: 1600x1200x32@60 13:32:15 INFO - 2016-04-26 13:32:15.240 screenresolution[2363:21490] starting screenresolution argv=screenresolution list 13:32:15 INFO - Available Modes on Display 0 13:32:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:15 INFO - Intel Iris: 13:32:15 INFO - Chipset Model: Intel Iris 13:32:15 INFO - Type: GPU 13:32:15 INFO - Bus: Built-In 13:32:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:15 INFO - Vendor: Intel (0x8086) 13:32:15 INFO - Device ID: 0x0a2e 13:32:15 INFO - Revision ID: 0x0009 13:32:15 INFO - Displays: 13:32:15 INFO - Display: 13:32:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:15 INFO - Display Serial Number: 1600x1200 60 13:32:15 INFO - Main Display: Yes 13:32:15 INFO - Mirror: Off 13:32:15 INFO - Online: Yes 13:32:15 INFO - Rotation: Supported 13:32:15 INFO - Return code: 0 13:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:32:15 INFO - Python 2.7.3 13:32:15 INFO - Return code: 0 13:32:15 INFO - grabbing minidump binary from tooltool 13:32:15 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')]} 13:32:15 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 0x1015ebe00>, '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 0x101414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015e6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:32:15 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 13:32:15 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 13:32:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:32:15 INFO - Return code: 0 13:32:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:32:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:32:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:32:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:32:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:32:16 INFO - Return code: 0 13:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:32:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x1WPCmJWwt/Render', 13:32:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:16 INFO - 'HOME': '/Users/cltbld', 13:32:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:16 INFO - 'LOGNAME': 'cltbld', 13:32:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:32:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:16 INFO - 'MOZ_NO_REMOTE': '1', 13:32:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:32:16 INFO - 'NO_EM_RESTART': '1', 13:32:16 INFO - 'PAGER': '/bin/cat', 13:32:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:16 INFO - 'PWD': '/builds/slave/test', 13:32:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:32:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:16 INFO - 'SHELL': '/bin/bash', 13:32:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVm9j90WGo/Listeners', 13:32:16 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 13:32:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:16 INFO - 'USER': 'cltbld', 13:32:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:16 INFO - 'XPC_FLAGS': '0x0', 13:32:16 INFO - 'XPC_SERVICE_NAME': '0', 13:32:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:32:16 INFO - mozversion application_buildid: 20160426120641 13:32:16 INFO - mozversion application_changeset: 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 13:32:16 INFO - mozversion application_display_name: Nightly 13:32:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:32:16 INFO - mozversion application_name: Firefox 13:32:16 INFO - mozversion application_remotingname: firefox 13:32:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:32:16 INFO - mozversion application_vendor: Mozilla 13:32:16 INFO - mozversion application_version: 49.0a1 13:32:16 INFO - mozversion platform_buildid: 20160426120641 13:32:16 INFO - mozversion platform_changeset: 6bbcf33e1a709cc6bd9a9ab73e303093fc748239 13:32:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:32:16 INFO - mozversion platform_version: 49.0a1 13:32:16 INFO - using testdate: 1461702736 13:32:16 INFO - actual date: 1461702736 13:32:16 INFO - starting webserver on 'localhost:53462' 13:32:16 INFO - SUITE-START | Running 2 tests 13:32:16 INFO - TEST-START | dromaeo_css 13:32:16 INFO - operating with platform_type : mac_ 13:32:16 INFO - Initialising browser for dromaeo_css test... 13:32:16 INFO - TEST-INFO | started process 2381 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp8MNUYW/profile http://localhost:53462/getInfo.html) 13:32:19 INFO - PROCESS | 2381 | __metrics Screen width/height:1600/1200 13:32:19 INFO - PROCESS | 2381 | colorDepth:24 13:32:19 INFO - PROCESS | 2381 | Browser inner width/height: 1024/689 13:32:19 INFO - PROCESS | 2381 | __metrics 13:32:19 INFO - PROCESS | 2381 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:32:19 ERROR - PROCESS | 2381 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:32:19 INFO - TEST-INFO | 2381: exit 0 13:32:19 INFO - Browser initialized. 13:32:19 INFO - Running cycle 1/1 for dromaeo_css test... 13:32:19 INFO - TEST-INFO | started process 2383 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp8MNUYW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:31 INFO - PROCESS | 2383 | RSS: Main: 239341568 13:32:31 INFO - PROCESS | 2383 | 13:33:23 INFO - PROCESS | 2383 | 1461702803318 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] 13:35:33 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53462/tests/dromaeo/cssquery-ext.html) 13:35:35 INFO - PROCESS | 2383 | RSS: Main: 378228736 13:35:35 INFO - PROCESS | 2383 | 13:38:38 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-ext.html (next: http://localhost:53462/tests/dromaeo/cssquery-jquery.html) 13:38:39 INFO - PROCESS | 2383 | RSS: Main: 376127488 13:38:39 INFO - PROCESS | 2383 | 13:41:41 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53462/tests/dromaeo/cssquery-mootools.html) 13:41:42 INFO - PROCESS | 2383 | RSS: Main: 377851904 13:41:42 INFO - PROCESS | 2383 | 13:44:44 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53462/tests/dromaeo/cssquery-prototype.html) 13:44:44 INFO - PROCESS | 2383 | RSS: Main: 376561664 13:44:44 INFO - PROCESS | 2383 | 13:47:48 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53462/tests/dromaeo/cssquery-yui.html) 13:47:48 INFO - PROCESS | 2383 | RSS: Main: 345153536 13:47:48 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | Cycle 1(1): loaded http://localhost:53462/tests/dromaeo/cssquery-yui.html (next: http://localhost:53462/tests/dromaeo/cssquery-dojo.html) 13:50:51 INFO - PROCESS | 2383 | RSS: Main: 387465216 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | __start_tp_report 13:50:51 INFO - PROCESS | 2383 | _x_x_mozilla_page_load 13:50:51 INFO - PROCESS | 2383 | _x_x_mozilla_page_load_details 13:50:51 INFO - PROCESS | 2383 | |i|pagename|runs| 13:50:51 INFO - PROCESS | 2383 | |0;dojo.html;3371.275175837195;3431.519587257783;3443.311337732454;3459.6021457532393;3485.285516469124;5617.606767526275;5713.742881570329;5719.370869204384;5728.598998070137;5733.226014488043;5673.546116310696;5733.627314224573;5733.741981610827;5870.11948207768;5881.059030555112;5183.067047931371;5187.740612969355;5216.452272511369;5243.449437809032;5299.54953828925;5291.3385826771655;5292.206169074631;5332.653377530468;5338.412774594792;5388.703621300827;5130.9230361544605;5167.509086636764;5215.191645294985;5225.6864588124645;5293.153095504701;5155.123628983082;5163.302954101186;5171.560417364491;5171.896562068738;5179;5099.4900509949075;5128.538431541142;5135.486451354872;5141.460146684619;5199.714015729133;5536.501714845684;5635.3519345275;5642.746076426571;5746.82759517211;5775.1337299405;6243.526412238058;6253.656048917308;6255.437010669061;6260.968695156541;6269.780557680505;6172.814815555541;6274.623522588659;6347.52393570484;6367.108604795332;6369.6496692681885;5793.536517078624;5799.478046975751;5859.20900678403;5901.380355062656;5909;5638.731285460821;6254.6872656366995;6293.685315734195;6299.811005669837;6310.7791227306825;6019.458248757545;6054.757809687651;6164.784232551929;6188.938110618837;6193.287771906198;6115.392460734087;6211.472024877936;6241.625502469867;6302.338254483305;6323.30443651198;6225.3774622537385;6253.0620406938415;6255.186825712719;6255.530835187379;6271.78048768291;6284.088807123033;6291.528135389773;6300.401461861115;6308.779192728324;6308.842278943063;4841;4881.389826271716;4920.089783390084;4929.162042452792;4930.482299358516;4396.340548917688;4435.645148388121;4442.089371678798;4540.863774086717;4584.014436896084;5746.971265143731;5757.078867381283;5762.6542407454845;5769.798057067983;5812.738426770806;6245.219347581552;6281.968590157111;6308.52686048539;6320.083587879823;6334.271558770708;5548.86127846799;5652.802151924663;5710.857228569236;5750.453706897838;5758.049921762862;5447.53695935842;5448.073827449343;5457.454254574511;5465.535429488538;5509.724513774376;6295.244570651551;6324.841878952971;6344.49244060474;6346.9682651586445;6352.4918006559365;5717.799262154827;5839.357670656309;6159.84600384994;6285.622862628257;6292.968535157295;6266;6295.779647712308;6313.116163736989;6316.147320111726;6338.58799178052;6230.844228894314;6234.625922444668;6244.063390491463;6262.185915830913;6265.342139075331;6262.405071518198;6297.748090076346;6328.177336946167;6330.177076979963;6340.71466784005;6058.515318774399;6109.75560977556;6121.148138557471;6175.104609831639;6191.752329906753;6140.596275277727;6153.230846144232;6158.445739883432;6168.753249870135;6172.753089876354;4616.422947131608;5946.881062378815;5994.220751302477;6013.458788709037;6034.24571928509;5985.6408615483215;6101.176341193882;6110.26676798787;6129.724162412791;6151.569390142826;5989.940100598938;5990.460858522754;5998.280206375263;6001.639901605918;6029.8191054268445 13:50:51 INFO - PROCESS | 2383 | |1;ext.html;508.57025813187846;524.1822756499861;557.4815421657856;577.8959787238297;610.9461179465422;29203.123906282817;37016.25967480649;37980.733179025425;38053.858384248444;38144.23711525768;41604.79197604008;42052.36921446173;42217.36673949893;42295.57704422955;42340.7297781067;8371.204735550113;9357.391769534976;9610.90389096107;9615.375000624972;9618.27862910281;8449.619767110495;10998.945005274962;11244.437778111062;11268.718282042973;11270.211085224038;11167;11307.886921130807;11311.547538098466;11316.943415282913;11325.263857849213;7107.85784284314;8296.585170741439;8315.708950186774;8530.01904780952;8530.488170709777;8426.325893928471;10902.727431814188;11199.272047316897;11202.327860328325;11202.607908723156;38118;38881;38948.22103557913;39007.8049609753;39137.80431097855;2979.791414600979;3199.168216263765;3241.33552621712;3658.9268214635827;3868.2650296443585;27595.86202068997;28503.00239491608;28554.571681424793;28577;28596.142115736562;2735;2749.2714430675887;2757.5587905935154;2808.7893407994484;2810.2131403207136;375795;376603.1169844161;376974;377248;377387.1130644329;55867.720661397245;57368;57476.42523574794;57519.712401437726;57600.42399576034;58911;59983.40016599865;60486.39513604896;60517.394826052056;60562.69718651467;16465.423710170326;16654.666906661798;16779.748303775454;16793.244304006348;16795.664086718196;11361.670857474362;11559.942200289113;11566.768664626661;11581.42092895352;12812;12722.681932951566;18080.367187148386;18225.4484372188;18547.907260463882;18548.721769173473;7654.349380302625;7802.960985195038;7835.2948234658015;7863.646135923897;7872.890208367663;919.7746552094645;995.5719040812376;1005.3612611443301;1039.667306461925;1141.434989680097;28480.287992800346;28769.71230287712;28938.323041440453;29169.27076823054;29172.416551669274;1078.369154044877;1118.5358076398365;1131.801934661431;1139.732162941708;1207.9939600302118;14695.897807664467;14865.875921510617;14953.659695817516;15014.774778378332;15092.094474331576;471113;479911;480077;480539;480650;55334;56801;56826.7158664204;56938;57230;1338.1569611144917;1346.4479563378968;1448.804411404447;1490.627343164209;1499.0930487055393;25678.486430270917;28054;28175.43649127047;28840.84636614512;28918.85540572368;44914.77542612266;45346.54653453423;45359.77320113379;45412;45505;281102;288780;289952;290017;291092;21642.458938526663;22190.889045554668;22193.44516387103;22214.555708886055;22244.66633000474;40788;40918.79540602397;41301.760947171635;41507.1698566033;41774.37338439986;34241.828790855885;34542.30915381729;34567.4814877772;35267.82366088153;35437.82281088578;33824.49263261102;34011.31977360488;34127.488087678204;34381.484277735355;34616.13459663428;27823;34689.959301221;34695.65304346924;35019.29961400809;35764.82117589395;31081.067567972997;31476.685233147375;31507;31580.526292105176;31648.841755791073;23362.532749344577;24022.759772402052;24075.398115047265;24120.517589648458;24463.36598291076 13:50:51 INFO - PROCESS | 2383 | |2;jquery.html;344.0641455241743;393.91727737175194;400.65743789060355;406.4614385938632;410.36803322882764;19219.32732354366;21058.262960796375;21063.68404473934;21190;21459.67810482844;16015.599610009756;20414.89792551035;20652.586948261065;21038.263660771878;21068.367948961557;5852.180694702734;5932.660605455082;6077.3314935357075;6100.664463454508;6124.1732366130445;4191.329387298033;4476.48520420151;6310.305866354697;6654.168228971379;6663.833404164886;6474;6544.901826472606;6559.672016399185;6595.2745198028415;6604.207495100571;6504.804855854332;6511.609303441761;6522.152120224364;6558.901616475757;6564.146660934096;3797.3354662933875;5446.319210098738;5735.713214339308;5752.568557358215;5768;24325.87837060821;24545.87727061371;24592.508149836904;24877.62683559748;24912.875435622886;1945.659509585831;2157.7518585362727;2174.010825074587;2391.1033362489065;2478.5042991401792;9805.117539421486;9904.405735655811;9959.950200249024;10077.244206684529;10172.491375431198;1669.747689233075;1713.4236502417805;1770.3803668715846;1790.3644206306703;1855.1002763659615;95971;101979.49010254975;109801;116316.09209769739;118143;9268.443893366419;11318.490667919963;11632.360220187802;11632.53469861196;11717.238379505305;14571.562853114423;14781.482648107265;14795.408183672747;14802.925985370219;14814;8312;8535.530545819915;8562.74311770648;8620.13798620133;8632.179942905414;8424.619083813966;8439.70461033861;8460.619272132768;8602.139786021347;8610.655573777103;4154.231467178582;4541.296099104644;4633.814647414066;4647.559256630456;4669.322948172496;1470.029780344967;1598.7769356442332;1623.2208539900678;1949.2787668562723;1974.3682021752902;927.2257664849791;948.9762755931018;972.2999440402899;1009.9596016159271;1015.7308313296983;18191.817531860386;18443.538911527052;19056.2377504901;19736.210551578064;19861;954.7756277274835;975.0882924465648;1010.1666125446536;1028.2956175019985;1030.1449796668746;10714.73213169677;11071.72320691989;11092.1126309895;11098.55605775776;11209.663710088711;491939;498838;499538;583045;586742;112589;118126;119078;120004;123667;991.1921783746224;1033.5555711044174;1034.6275340877278;1035.0270745499206;1063.8616979792732;17761.73357399614;18060.90969545144;18126.909365453088;18178.72731908996;18486.983215923254;47080.7645961768;49935.50064499309;50346.49653503564;50874;51248.48751512437;55567.888642227736;69148;69886.65056674684;72070.55858882898;72145;7718.498297610638;7845.333146682596;7873.133955264801;7978.082520509984;8101.71643992481;7265.243323709002;10760.40817755031;11826.29042257433;12000.75998480043;12703.745925081263;10543.683689489328;10746;10770.299930504492;10863.619773307897;11043.282186657842;8620.568971551273;10655.229640293734;10689.572417103229;10931.56273749041;11097.38964356968;10794.73013174677;10853.511591978222;11267.422560841325;11288;11394.715132121763;7905.209479052279;7944.563049032358;7961;8041.477303975223;8192.54940978252;10431.94784026075;10477.68566942993;10550.89449105499;10727.195460340661;10865.130789536975 13:50:51 INFO - PROCESS | 2383 | |3;mootools.html;1361.6051345109918;1580.9683806323874;1592.3630547780888;1816.8364847163753;1942.7085937109441;6933.479989000826;7784.1826608206175;8752.69365572205;8758.299336053116;8772.859528261333;7829.256220659043;8017.518948863072;8337.124601916803;8587.613557389917;8607.569621518915;2391.0435825669742;2429.137656132074;2429.9757002430015;2430.4652976345174;2433.318670772178;2384.2847145856263;2414.1188466209796;2431.781139697427;2432.4405386761055;2436.135172013936;2007.237249845064;2392.270357540949;2405.783479486846;2410.9035638574437;2428.368624157723;2103.3269353806786;2252.9185990724463;2357.552065107624;2385.7256415512265;2399.1003373734848;2158.2554018863466;2187.8687278763327;2189.2228258968;2378.21518898763;2378.5718570657273;8583.399162058658;8624.741257762214;8693.434926729806;8696.608652610648;8885.733427997106;5087.669301495391;5328.413874473805;5331.893362132736;5338.3060202173465;5352.625316227865;3420.4698271767666;3513.385157597436;3590.174259920233;3596.62235465275;3598.8560457581666;1028.9588416463332;1029.619040954844;1033.101201954297;1056.5298442193227;1057.0803401041067;11307.26502777325;11708.824367634492;11716.648500544998;11719.179657424069;11726.530938762438;4397.340398940185;4501.6848820582245;4565.223911934979;4597.678162528658;4617.238155704338;9257;9279.582418791035;9279.76800579991;9284.582193801296;9286;5174.353205849269;5229.450907654717;5254.527092561688;5257.6845389276;5259.368875734936;4318.827246910088;4324.978375108104;4529.00361920377;4823;4836.492168322346;3175.380800743879;3735.6637902588354;3771.0949372151035;3808.6762625176616;3834.328992426314;1209.612923864355;1215.3133479584008;1218.214251807579;1224.8040313549789;1227.6071657069651;822.2024636102971;853.8804567360575;898.955052247385;943.9669611563562;976.5898322704481;5561.6663000220115;5628.690422026746;5764.7117644117625;5887.35239123696;5943.494802941798;436.1059827353912;451.9796609152596;454.2436842657005;464.2316965422232;467.5161208149549;3807.7334586578672;3824.7513911595656;3830.482884810571;3909.667678247381;3928.685705143582;10258.897411025795;10720.46397680113;10724.571017159382;10726.624568140078;10792.190585706103;10621.309614875008;10702.785944281226;10774.838377424345;10775.084117849914;10797.08224800885;7897.486663366863;8191.549464779494;8244.175582441827;8254.298384637254;8264.132266112092;5577.609567330248;5707.942920570741;5746;5804.65172089676;5857.912131318119;9809.95095024549;9833.901660983298;9935.950320248352;9978.605134767458;9992.300538962203;9941.403515789076;9946.45294508809;10020.298579099393;10319.277650564389;10352.948235259077;6966.1640603125925;7017.473689473412;7067.010618513309;7082.0085188074745;7086.433085353056;8086.231807978233;8183.345332373515;8241.25828675422;8287.254147126787;8290.585470726319;6587.96706016486;7323.670434830339;7421;7482.850342993219;7569.243075692387;7130.500864939404;7168.498205125591;7400.5189662671755;7439.776806695808;7440.2931721486375;6823.863522729418;6906.792796216121;6984.288849231767;7007.649617519206;7012.509124361246;4781.856544303677;4822.483158405042;4883.267509873405;4899.804007839646;4917.704937703749;4877.585405240594;4943.752812359439;5002.17464118431;5004.699718016931;5176.7152806596 13:50:51 INFO - PROCESS | 2383 | |4;prototype.html;313.0889112682095;314;318.6956456583962;319.3150691766162;325.85825166052786;4775.355327030854;5198.324217851678;5405.459444058444;5442.183672449135;5487.6158668893195;4260.680448966325;5372.301600791891;5406.972675191711;5419.672180315828;5422;2968;3541.575010998684;3795.6773674237725;4006.3389540725743;4171.249175148472;3413.539172211757;4075.6331930126285;4130.566290539495;4178.435911152002;4183.644390226835;4078.653314468274;4107.322291821861;4115.320972039624;4128.545859955402;4134.4625198724125;3824.808759562011;3893.435451859493;4118.444010058649;4130.938035929464;4161.835845360426;3002.6488080363706;3309.7683162178655;3571.6428357164077;3606.719614536854;3608.3144202601684;4589.701669391512;5061.6962982221185;5141.971605678879;5146.812604643012;5153.768080436389;4457.9493433020525;4501.029320648389;4566.292224705176;4719.994002548938;4762.952370476286;5653.462921022497;5703.885922281531;5706.229658996065;5716.1425786131995;5734.655920644775;3981.3828856527284;3988.3618621020496;4001.779902105383;4009.518857737061;4011.618896204856;5506.889862202774;5654.717264136777;5988.461038506555;6017.247844019497;6019.277686677627;3831.7317787755146;4026.2953983053435;4043.433919251307;4058.5332686740812;4064.7967601619803;4085.428040074392;4108.8356465741035;4121.216968775923;4121.732087414309;4127.979360103181;4159.417681524589;4159.542450330461;4281.272183728774;4731.716097034189;4735.337052812609;5190.506901844319;5239.318888544465;5707.885842283131;5757;5817.970910145506;1449.4564538298139;1463.8609332113645;1470.4779803169934;1472.709936049432;1477.268751967783;784.1256998446805;809.441485375101;889.1553024626502;974.6101559376306;979.5836769372988;5029.5221953914315;5357.43746906577;5720.2849643794525;5744.310682718101;5749.1663708762;5180.093483640348;5579.118271512427;5665.291838520185;5758.62568933018;5821;5207.589962167534;5250.212468129811;5295.629305948547;5350.892982140335;5355.410904800468;2870.354170311705;2939.6913324101088;3118;3355.496675498646;3403.2853100849097;6319.431251187415;6468.676566171673;6715.3620406061345;6735.831604209934;6752.22349429812;5474.91787623186;5746.827595172152;6610.041543976096;6620.304867988888;6621.271660117383;6122.938770612237;6658.300878407795;6695.933040669628;6711.496637752188;6731.663416829237;5294.973525132504;5811.651300921789;6084.209052823105;6134.846628834315;6414.294427612866;5139.768710408115;5607.514008787756;6251.062340648939;6333.878150819527;6335.303116657256;5801.564882633735;6079.696015199134;6120.95943689565;6670.499712521678;6691.631960242039;3705.4256590228556;3733.9813300934406;4082.3060079785914;4105.876823695294;4125.97937010313;4942.901141977212;5915.231019967377;5952.553558483044;5953.226080609493;5988.970055149696;4894.9021019578695;5402.297701298963;5785.652860828363;5786.247787787729;5787.36339002701;4632.652551058617;5229.660072095302;5634.32388113429;5675.120356344773;5782.703858800443;5293.888283460363;5369.731513424391;5388.632104934416;5638.323401191883;5682.857928551819;4546.499885012712;4713.9292910607055;5541.0256665241495;5769.8557536061935;5799.101139323411;5011.018444025799;5224.399194092728;5584.162375643686;5615.943840561542;5642.517292391335 13:50:51 INFO - PROCESS | 2383 | |5;yui.html;652.4845372155997;715.7530651925084;751.5265382808832;751.7293774241275;776.7669699090271;14637.487687930925;14844.257787110655;15627.92186039071;15651.921740391297;15663.373465061384;10693.518791654375;14228.928855355709;14255.50105746299;14266.144031358126;14757.819374450046;724.2069933422894;744.9776506704808;908.7546362482142;934.8223837470892;937.386012162035;898.4788822482944;934.1592566689966;936.1761649748246;936.8032713130251;936.8454205056157;960.4717405427023;974.9957543729934;978.5889926231;978.9755256118618;979.2998006425404;747.0400535312152;946.4226821638795;970.2868391732071;972.479723348009;980.5881529757519;722.241646271413;722.9385502232317;723.2875617516742;723.4284914917262;723.5477826358526;15043.849561504354;15127.54617361481;15133.092014479167;15137.091774493565;15168.469103581432;12185.207961482563;12207.389630518528;12320.876791232062;12495.500179992789;12602.180858244277;9807.362521436084;9957.203423726085;10010.299279050469;10125.088742013255;10519.195666986632;3427.9314413711586;3460.325236578868;3509.736769742254;3519.894403167909;3583.283343331344;70785;71236.64381678059;71248.93126603105;71482.9277560837;71720;9179.219766319804;9190.035046320174;9395.906040939502;9547.761305967406;9661.13049825519;21121.788782111984;21300.36098917035;21406.357809265748;21527.354179374644;21548.892255538623;6156.384361563808;7467.813304667427;7525.623718814038;7665.540067595962;7865.960670196612;3942.329803933338;4057.411675307064;4077.3883917412627;4145.523264824524;4196;3156.5738625285294;3333.8331583945424;3385.9492107618407;3463.7229021678204;3467.8729412940393;518.5281393931505;523.602062432554;606.2240332374568;617.0220200981495;624.7969409941787;545.1985581195636;577.413924866261;615.8706671599015;632.3076231526471;632.99986022085;7859.543377184671;8110.3511719062335;8113.310368618615;8228.341732661373;8327.541985190872;503.64241388614494;571.2630706388795;611.8069763960207;623.607127509675;626.4925410417576;3646.872359467406;3858.741606565531;3867.284552357823;3886.378179491268;3904.7266691331333;87736.24527509414;90065;90507.54746226358;92760.53619731858;92766.53616731873;76735.61632191915;77868.61065694636;78387;78754.60622696964;80628.59685701533;69775;70708.23229419306;71067.64466177636;71799;72127.63936180285;7563.810904727426;7644.159142494433;7684.807879802826;7773.922260777546;7907.2488113629115;43405.78297108494;44039.77980110079;44084.33873491964;44107.77946110249;44273.11453770971;57068.714656426455;59988.700056501184;60534.69732651485;63485;63488.41278967878;9806.950965245414;10186.337888037255;10289.84565231507;10394.532246049097;10609.787804244026;11111.444427778417;11135.164862635496;11241.60654377093;11274.041706455044;11349.319040857576;8874.801901743182;10236.283460157718;10420.947895260475;10463.68608941733;10525.105366043972;10409.739756505845;10505.527251273865;10591.735206619896;10613.991670791262;10618.415987120783;10225.795484090426;10281.177505799667;10311.432871192157;10325.483725813829;10557.260991730505;8896.421732587361;8956.641734330553;9248.260139188984;9258.35191536586;9400.95299523498;8918;9078.182963533314;9129.954350228207;9177.954110229406;9276.397034192756 13:50:51 INFO - PROCESS | 2383 | __end_tp_report 13:50:51 INFO - PROCESS | 2383 | __start_cc_report 13:50:51 INFO - PROCESS | 2383 | _x_x_mozilla_cycle_collect,1470 13:50:51 INFO - PROCESS | 2383 | __end_cc_report 13:50:51 INFO - PROCESS | 2383 | __startTimestamp1461703851804__endTimestamp 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | ------- Summary: start ------- 13:50:51 INFO - PROCESS | 2383 | Number of tests: 6 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#0] dojo.html Cycles:165 Average:5760.90 Median:5990.46 stddev:641.67 (10.7%) stddev-sans-first:615.65 13:50:51 INFO - PROCESS | 2383 | Values: 3371.3 3431.5 3443.3 3459.6 3485.3 5617.6 5713.7 5719.4 5728.6 5733.2 5673.5 5733.6 5733.7 5870.1 5881.1 5183.1 5187.7 5216.5 5243.4 5299.5 5291.3 5292.2 5332.7 5338.4 5388.7 5130.9 5167.5 5215.2 5225.7 5293.2 5155.1 5163.3 5171.6 5171.9 5179.0 5099.5 5128.5 5135.5 5141.5 5199.7 5536.5 5635.4 5642.7 5746.8 5775.1 6243.5 6253.7 6255.4 6261.0 6269.8 6172.8 6274.6 6347.5 6367.1 6369.6 5793.5 5799.5 5859.2 5901.4 5909.0 5638.7 6254.7 6293.7 6299.8 6310.8 6019.5 6054.8 6164.8 6188.9 6193.3 6115.4 6211.5 6241.6 6302.3 6323.3 6225.4 6253.1 6255.2 6255.5 6271.8 6284.1 6291.5 6300.4 6308.8 6308.8 4841.0 4881.4 4920.1 4929.2 4930.5 4396.3 4435.6 4442.1 4540.9 4584.0 5747.0 5757.1 5762.7 5769.8 5812.7 6245.2 6282.0 6308.5 6320.1 6334.3 5548.9 5652.8 5710.9 5750.5 5758.0 5447.5 5448.1 5457.5 5465.5 5509.7 6295.2 6324.8 6344.5 6347.0 6352.5 5717.8 5839.4 6159.8 6285.6 6293.0 6266.0 6295.8 6313.1 6316.1 6338.6 6230.8 6234.6 6244.1 6262.2 6265.3 6262.4 6297.7 6328.2 6330.2 6340.7 6058.5 6109.8 6121.1 6175.1 6191.8 6140.6 6153.2 6158.4 6168.8 6172.8 4616.4 5946.9 5994.2 6013.5 6034.2 5985.6 6101.2 6110.3 6129.7 6151.6 5989.9 5990.5 5998.3 6001.6 6029.8 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#1] ext.html Cycles:180 Average:53194.09 Median:18532.91 stddev:103037.92 (556.0%) stddev-sans-first:103251.04 13:50:51 INFO - PROCESS | 2383 | Values: 508.6 524.2 557.5 577.9 610.9 29203.1 37016.3 37980.7 38053.9 38144.2 41604.8 42052.4 42217.4 42295.6 42340.7 8371.2 9357.4 9610.9 9615.4 9618.3 8449.6 10998.9 11244.4 11268.7 11270.2 11167.0 11307.9 11311.5 11316.9 11325.3 7107.9 8296.6 8315.7 8530.0 8530.5 8426.3 10902.7 11199.3 11202.3 11202.6 38118.0 38881.0 38948.2 39007.8 39137.8 2979.8 3199.2 3241.3 3658.9 3868.3 27595.9 28503.0 28554.6 28577.0 28596.1 2735.0 2749.3 2757.6 2808.8 2810.2 375795.0 376603.1 376974.0 377248.0 377387.1 55867.7 57368.0 57476.4 57519.7 57600.4 58911.0 59983.4 60486.4 60517.4 60562.7 16465.4 16654.7 16779.7 16793.2 16795.7 11361.7 11559.9 11566.8 11581.4 12812.0 12722.7 18080.4 18225.4 18547.9 18548.7 7654.3 7803.0 7835.3 7863.6 7872.9 919.8 995.6 1005.4 1039.7 1141.4 28480.3 28769.7 28938.3 29169.3 29172.4 1078.4 1118.5 1131.8 1139.7 1208.0 14695.9 14865.9 14953.7 15014.8 15092.1 471113.0 479911.0 480077.0 480539.0 480650.0 55334.0 56801.0 56826.7 56938.0 57230.0 1338.2 1346.4 1448.8 1490.6 1499.1 25678.5 28054.0 28175.4 28840.8 28918.9 44914.8 45346.5 45359.8 45412.0 45505.0 281102.0 288780.0 289952.0 290017.0 291092.0 21642.5 22190.9 22193.4 22214.6 22244.7 40788.0 40918.8 41301.8 41507.2 41774.4 34241.8 34542.3 34567.5 35267.8 35437.8 33824.5 34011.3 34127.5 34381.5 34616.1 27823.0 34690.0 34695.7 35019.3 35764.8 31081.1 31476.7 31507.0 31580.5 31648.8 23362.5 24022.8 24075.4 24120.5 24463.4 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#2] jquery.html Cycles:180 Average:32129.49 Median:13358.49 stddev:89157.72 (667.4%) stddev-sans-first:89375.89 13:50:51 INFO - PROCESS | 2383 | Values: 344.1 393.9 400.7 406.5 410.4 19219.3 21058.3 21063.7 21190.0 21459.7 16015.6 20414.9 20652.6 21038.3 21068.4 5852.2 5932.7 6077.3 6100.7 6124.2 4191.3 4476.5 6310.3 6654.2 6663.8 6474.0 6544.9 6559.7 6595.3 6604.2 6504.8 6511.6 6522.2 6558.9 6564.1 3797.3 5446.3 5735.7 5752.6 5768.0 24325.9 24545.9 24592.5 24877.6 24912.9 1945.7 2157.8 2174.0 2391.1 2478.5 9805.1 9904.4 9960.0 10077.2 10172.5 1669.7 1713.4 1770.4 1790.4 1855.1 95971.0 101979.5 109801.0 116316.1 118143.0 9268.4 11318.5 11632.4 11632.5 11717.2 14571.6 14781.5 14795.4 14802.9 14814.0 8312.0 8535.5 8562.7 8620.1 8632.2 8424.6 8439.7 8460.6 8602.1 8610.7 4154.2 4541.3 4633.8 4647.6 4669.3 1470.0 1598.8 1623.2 1949.3 1974.4 927.2 949.0 972.3 1010.0 1015.7 18191.8 18443.5 19056.2 19736.2 19861.0 954.8 975.1 1010.2 1028.3 1030.1 10714.7 11071.7 11092.1 11098.6 11209.7 491939.0 498838.0 499538.0 583045.0 586742.0 112589.0 118126.0 119078.0 120004.0 123667.0 991.2 1033.6 1034.6 1035.0 1063.9 17761.7 18060.9 18126.9 18178.7 18487.0 47080.8 49935.5 50346.5 50874.0 51248.5 55567.9 69148.0 69886.7 72070.6 72145.0 7718.5 7845.3 7873.1 7978.1 8101.7 7265.2 10760.4 11826.3 12000.8 12703.7 10543.7 10746.0 10770.3 10863.6 11043.3 8620.6 10655.2 10689.6 10931.6 11097.4 10794.7 10853.5 11267.4 11288.0 11394.7 7905.2 7944.6 7961.0 8041.5 8192.5 10431.9 10477.7 10550.9 10727.2 10865.1 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#3] mootools.html Cycles:180 Average:5597.71 Median:4880.43 stddev:3171.06 (65.0%) stddev-sans-first:3163.98 13:50:51 INFO - PROCESS | 2383 | Values: 1361.6 1581.0 1592.4 1816.8 1942.7 6933.5 7784.2 8752.7 8758.3 8772.9 7829.3 8017.5 8337.1 8587.6 8607.6 2391.0 2429.1 2430.0 2430.5 2433.3 2384.3 2414.1 2431.8 2432.4 2436.1 2007.2 2392.3 2405.8 2410.9 2428.4 2103.3 2252.9 2357.6 2385.7 2399.1 2158.3 2187.9 2189.2 2378.2 2378.6 8583.4 8624.7 8693.4 8696.6 8885.7 5087.7 5328.4 5331.9 5338.3 5352.6 3420.5 3513.4 3590.2 3596.6 3598.9 1029.0 1029.6 1033.1 1056.5 1057.1 11307.3 11708.8 11716.6 11719.2 11726.5 4397.3 4501.7 4565.2 4597.7 4617.2 9257.0 9279.6 9279.8 9284.6 9286.0 5174.4 5229.5 5254.5 5257.7 5259.4 4318.8 4325.0 4529.0 4823.0 4836.5 3175.4 3735.7 3771.1 3808.7 3834.3 1209.6 1215.3 1218.2 1224.8 1227.6 822.2 853.9 899.0 944.0 976.6 5561.7 5628.7 5764.7 5887.4 5943.5 436.1 452.0 454.2 464.2 467.5 3807.7 3824.8 3830.5 3909.7 3928.7 10258.9 10720.5 10724.6 10726.6 10792.2 10621.3 10702.8 10774.8 10775.1 10797.1 7897.5 8191.5 8244.2 8254.3 8264.1 5577.6 5707.9 5746.0 5804.7 5857.9 9810.0 9833.9 9936.0 9978.6 9992.3 9941.4 9946.5 10020.3 10319.3 10352.9 6966.2 7017.5 7067.0 7082.0 7086.4 8086.2 8183.3 8241.3 8287.3 8290.6 6588.0 7323.7 7421.0 7482.9 7569.2 7130.5 7168.5 7400.5 7439.8 7440.3 6823.9 6906.8 6984.3 7007.6 7012.5 4781.9 4822.5 4883.3 4899.8 4917.7 4877.6 4943.8 5002.2 5004.7 5176.7 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#4] prototype.html Cycles:180 Average:4707.91 Median:5234.49 stddev:1490.39 (28.5%) stddev-sans-first:1457.61 13:50:51 INFO - PROCESS | 2383 | Values: 313.1 314.0 318.7 319.3 325.9 4775.4 5198.3 5405.5 5442.2 5487.6 4260.7 5372.3 5407.0 5419.7 5422.0 2968.0 3541.6 3795.7 4006.3 4171.2 3413.5 4075.6 4130.6 4178.4 4183.6 4078.7 4107.3 4115.3 4128.5 4134.5 3824.8 3893.4 4118.4 4130.9 4161.8 3002.6 3309.8 3571.6 3606.7 3608.3 4589.7 5061.7 5142.0 5146.8 5153.8 4457.9 4501.0 4566.3 4720.0 4763.0 5653.5 5703.9 5706.2 5716.1 5734.7 3981.4 3988.4 4001.8 4009.5 4011.6 5506.9 5654.7 5988.5 6017.2 6019.3 3831.7 4026.3 4043.4 4058.5 4064.8 4085.4 4108.8 4121.2 4121.7 4128.0 4159.4 4159.5 4281.3 4731.7 4735.3 5190.5 5239.3 5707.9 5757.0 5818.0 1449.5 1463.9 1470.5 1472.7 1477.3 784.1 809.4 889.2 974.6 979.6 5029.5 5357.4 5720.3 5744.3 5749.2 5180.1 5579.1 5665.3 5758.6 5821.0 5207.6 5250.2 5295.6 5350.9 5355.4 2870.4 2939.7 3118.0 3355.5 3403.3 6319.4 6468.7 6715.4 6735.8 6752.2 5474.9 5746.8 6610.0 6620.3 6621.3 6122.9 6658.3 6695.9 6711.5 6731.7 5295.0 5811.7 6084.2 6134.8 6414.3 5139.8 5607.5 6251.1 6333.9 6335.3 5801.6 6079.7 6121.0 6670.5 6691.6 3705.4 3734.0 4082.3 4105.9 4126.0 4942.9 5915.2 5952.6 5953.2 5989.0 4894.9 5402.3 5785.7 5786.2 5787.4 4632.7 5229.7 5634.3 5675.1 5782.7 5293.9 5369.7 5388.6 5638.3 5682.9 4546.5 4713.9 5541.0 5769.9 5799.1 5011.0 5224.4 5584.2 5615.9 5642.5 13:50:51 INFO - PROCESS | 2383 | 13:50:51 INFO - PROCESS | 2383 | [#5] yui.html Cycles:180 Average:17483.04 Median:624.20 stddev:24561.78 (3934.9%) stddev-sans-first:24598.17 13:50:51 INFO - PROCESS | 2383 | Values: 652.5 715.8 751.5 751.7 776.8 14637.5 14844.3 15627.9 15651.9 15663.4 10693.5 14228.9 14255.5 14266.1 14757.8 724.2 745.0 908.8 934.8 937.4 898.5 934.2 936.2 936.8 936.8 960.5 975.0 978.6 979.0 979.3 747.0 946.4 970.3 972.5 980.6 722.2 722.9 723.3 723.4 723.5 15043.8 15127.5 15133.1 15137.1 15168.5 12185.2 12207.4 12320.9 12495.5 12602.2 9807.4 9957.2 10010.3 10125.1 10519.2 3427.9 3460.3 3509.7 3519.9 3583.3 70785.0 71236.6 71248.9 71482.9 71720.0 9179.2 9190.0 9395.9 9547.8 9661.1 21121.8 21300.4 21406.4 21527.4 21548.9 6156.4 7467.8 7525.6 7665.5 7866.0 3942.3 4057.4 4077.4 4145.5 4196.0 3156.6 3333.8 3385.9 3463.7 3467.9 518.5 523.6 606.2 617.0 624.8 545.2 577.4 615.9 632.3 633.0 7859.5 8110.4 8113.3 8228.3 8327.5 503.6 571.3 611.8 623.6 626.5 3646.9 3858.7 3867.3 3886.4 3904.7 87736.2 90065.0 90507.5 92760.5 92766.5 76735.6 77868.6 78387.0 78754.6 80628.6 69775.0 70708.2 71067.6 71799.0 72127.6 7563.8 7644.2 7684.8 7773.9 7907.2 43405.8 44039.8 44084.3 44107.8 44273.1 57068.7 59988.7 60534.7 63485.0 63488.4 9807.0 10186.3 10289.8 10394.5 10609.8 11111.4 11135.2 11241.6 11274.0 11349.3 8874.8 10236.3 10420.9 10463.7 10525.1 10409.7 10505.5 10591.7 10614.0 10618.4 10225.8 10281.2 10311.4 10325.5 10557.3 8896.4 8956.6 9248.3 9258.4 9401.0 8918.0 9078.2 9130.0 9178.0 9276.4 13:50:51 INFO - PROCESS | 2383 | -------- Summary: end -------- 13:50:51 INFO - PROCESS | 2383 | 13:50:52 INFO - TEST-INFO | 2383: exit 0 13:50:52 INFO - TEST-OK | dromaeo_css | took 1115535ms 13:50:52 INFO - TEST-START | kraken 13:50:52 INFO - operating with platform_type : mac_ 13:50:52 INFO - Initialising browser for kraken test... 13:50:52 INFO - TEST-INFO | started process 2386 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp42lo80/profile http://localhost:53462/getInfo.html) 13:50:54 INFO - PROCESS | 2386 | __metrics Screen width/height:1600/1200 13:50:54 INFO - PROCESS | 2386 | colorDepth:24 13:50:54 INFO - PROCESS | 2386 | Browser inner width/height: 1024/689 13:50:54 INFO - PROCESS | 2386 | __metrics 13:50:54 INFO - PROCESS | 2386 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:50:54 ERROR - PROCESS | 2386 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:50:55 INFO - TEST-INFO | 2386: exit 0 13:50:55 INFO - Browser initialized. 13:50:55 INFO - Running cycle 1/1 for kraken test... 13:50:55 INFO - TEST-INFO | started process 2388 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmp42lo80/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:51:07 INFO - PROCESS | 2388 | RSS: Main: 239448064 13:51:07 INFO - PROCESS | 2388 | 13:51:10 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53462/tests/kraken/driver.html?testName=audio-beat-detection) 13:51:10 INFO - PROCESS | 2388 | RSS: Main: 286691328 13:51:10 INFO - PROCESS | 2388 | 13:51:13 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53462/tests/kraken/driver.html?testName=audio-dft) 13:51:14 INFO - PROCESS | 2388 | RSS: Main: 299450368 13:51:14 INFO - PROCESS | 2388 | 13:51:18 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53462/tests/kraken/driver.html?testName=audio-fft) 13:51:18 INFO - PROCESS | 2388 | RSS: Main: 386203648 13:51:18 INFO - PROCESS | 2388 | 13:51:20 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53462/tests/kraken/driver.html?testName=audio-oscillator) 13:51:21 INFO - PROCESS | 2388 | RSS: Main: 289366016 13:51:21 INFO - PROCESS | 2388 | 13:51:23 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:24 INFO - PROCESS | 2388 | RSS: Main: 293879808 13:51:24 INFO - PROCESS | 2388 | 13:51:27 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53462/tests/kraken/driver.html?testName=imaging-darkroom) 13:51:27 INFO - PROCESS | 2388 | RSS: Main: 330403840 13:51:27 INFO - PROCESS | 2388 | 13:51:30 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53462/tests/kraken/driver.html?testName=imaging-desaturate) 13:51:31 INFO - PROCESS | 2388 | RSS: Main: 332439552 13:51:31 INFO - PROCESS | 2388 | 13:51:33 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53462/tests/kraken/driver.html?testName=json-parse-financial) 13:51:34 INFO - PROCESS | 2388 | RSS: Main: 330407936 13:51:34 INFO - PROCESS | 2388 | 13:51:36 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:51:36 INFO - PROCESS | 2388 | RSS: Main: 321609728 13:51:36 INFO - PROCESS | 2388 | 13:51:38 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:51:39 INFO - PROCESS | 2388 | RSS: Main: 328437760 13:51:39 INFO - PROCESS | 2388 | 13:51:41 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:51:42 INFO - PROCESS | 2388 | RSS: Main: 330428416 13:51:42 INFO - PROCESS | 2388 | 13:51:45 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:45 INFO - PROCESS | 2388 | RSS: Main: 335306752 13:51:45 INFO - PROCESS | 2388 | 13:51:48 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:49 INFO - PROCESS | 2388 | RSS: Main: 332881920 13:51:49 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | Cycle 1(1): loaded http://localhost:53462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53462/tests/kraken/driver.html?testName=ai-astar) 13:51:51 INFO - PROCESS | 2388 | RSS: Main: 397451264 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | __start_tp_report 13:51:51 INFO - PROCESS | 2388 | _x_x_mozilla_page_load 13:51:51 INFO - PROCESS | 2388 | _x_x_mozilla_page_load_details 13:51:51 INFO - PROCESS | 2388 | |i|pagename|runs| 13:51:51 INFO - PROCESS | 2388 | |0;ai-astar;114;113;109;112;111;114;113;112;104;113 13:51:51 INFO - PROCESS | 2388 | |1;audio-beat-detection;170;148;177;176;120;145;157;146;215;149 13:51:51 INFO - PROCESS | 2388 | |2;audio-dft;189;199;240;264;210;186;188;187;186;184 13:51:51 INFO - PROCESS | 2388 | |3;audio-fft;77;73;88;114;85;86;57;90;87;72 13:51:51 INFO - PROCESS | 2388 | |4;audio-oscillator;87;98;132;77;81;83;122;80;76;124 13:51:51 INFO - PROCESS | 2388 | |5;imaging-gaussian-blur;89;86;89;86;88;87;88;86;89;86 13:51:51 INFO - PROCESS | 2388 | |6;imaging-darkroom;94;94;95;95;95;94;95;95;94;93 13:51:51 INFO - PROCESS | 2388 | |7;imaging-desaturate;84;84;86;87;84;86;84;85;87;84 13:51:51 INFO - PROCESS | 2388 | |8;json-parse-financial;59;61;61;59;59;57;58;80;60;57 13:51:51 INFO - PROCESS | 2388 | |9;json-stringify-tinderbox;66;64;70;80;71;71;67;69;68;70 13:51:51 INFO - PROCESS | 2388 | |10;stanford-crypto-aes;82;83;83;82;83;96;105;105;82;84 13:51:51 INFO - PROCESS | 2388 | |11;stanford-crypto-ccm;151;163;155;145;138;139;141;137;180;139 13:51:51 INFO - PROCESS | 2388 | |12;stanford-crypto-pbkdf2;264;201;201;201;199;202;200;201;198;197 13:51:51 INFO - PROCESS | 2388 | |13;stanford-crypto-sha256-iterative;73;84;101;101;101;69;70;70;71;72 13:51:51 INFO - PROCESS | 2388 | __end_tp_report 13:51:51 INFO - PROCESS | 2388 | __start_cc_report 13:51:51 INFO - PROCESS | 2388 | _x_x_mozilla_cycle_collect,1990 13:51:51 INFO - PROCESS | 2388 | __end_cc_report 13:51:51 INFO - PROCESS | 2388 | __startTimestamp1461703911722__endTimestamp 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | ------- Summary: start ------- 13:51:51 INFO - PROCESS | 2388 | Number of tests: 14 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.03 (2.7%) stddev-sans-first:3.07 13:51:51 INFO - PROCESS | 2388 | Values: 114.0 113.0 109.0 112.0 111.0 114.0 113.0 112.0 104.0 113.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#1] audio-beat-detection Cycles:10 Average:160.30 Median:163.50 stddev:25.74 (15.7%) stddev-sans-first:27.06 13:51:51 INFO - PROCESS | 2388 | Values: 170.0 148.0 177.0 176.0 120.0 145.0 157.0 146.0 215.0 149.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#2] audio-dft Cycles:10 Average:203.30 Median:194.00 stddev:27.43 (14.1%) stddev-sans-first:28.60 13:51:51 INFO - PROCESS | 2388 | Values: 189.0 199.0 240.0 264.0 210.0 186.0 188.0 187.0 186.0 184.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#3] audio-fft Cycles:10 Average:82.90 Median:85.50 stddev:14.90 (17.4%) stddev-sans-first:15.65 13:51:51 INFO - PROCESS | 2388 | Values: 77.0 73.0 88.0 114.0 85.0 86.0 57.0 90.0 87.0 72.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:80.50 stddev:21.74 (27.0%) stddev-sans-first:22.81 13:51:51 INFO - PROCESS | 2388 | Values: 87.0 98.0 132.0 77.0 81.0 83.0 122.0 80.0 76.0 124.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.35 (1.5%) stddev-sans-first:1.30 13:51:51 INFO - PROCESS | 2388 | Values: 89.0 86.0 89.0 86.0 88.0 87.0 88.0 86.0 89.0 86.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 13:51:51 INFO - PROCESS | 2388 | Values: 94.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 93.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.30 13:51:51 INFO - PROCESS | 2388 | Values: 84.0 84.0 86.0 87.0 84.0 86.0 84.0 85.0 87.0 84.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:59.50 stddev:6.79 (11.4%) stddev-sans-first:7.16 13:51:51 INFO - PROCESS | 2388 | Values: 59.0 61.0 61.0 59.0 59.0 57.0 58.0 80.0 60.0 57.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:4.30 (6.1%) stddev-sans-first:4.36 13:51:51 INFO - PROCESS | 2388 | Values: 66.0 64.0 70.0 80.0 71.0 71.0 67.0 69.0 68.0 70.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:83.00 stddev:9.65 (11.6%) stddev-sans-first:9.95 13:51:51 INFO - PROCESS | 2388 | Values: 82.0 83.0 83.0 82.0 83.0 96.0 105.0 105.0 82.0 84.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:148.00 stddev:13.91 (9.4%) stddev-sans-first:14.73 13:51:51 INFO - PROCESS | 2388 | Values: 151.0 163.0 155.0 145.0 138.0 139.0 141.0 137.0 180.0 139.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.40 Median:201.00 stddev:20.30 (10.1%) stddev-sans-first:1.66 13:51:51 INFO - PROCESS | 2388 | Values: 264.0 201.0 201.0 201.0 199.0 202.0 200.0 201.0 198.0 197.0 13:51:51 INFO - PROCESS | 2388 | 13:51:51 INFO - PROCESS | 2388 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.20 Median:70.50 stddev:14.30 (20.3%) stddev-sans-first:14.85 13:51:51 INFO - PROCESS | 2388 | Values: 73.0 84.0 101.0 101.0 101.0 69.0 70.0 70.0 71.0 72.0 13:51:51 INFO - PROCESS | 2388 | -------- Summary: end -------- 13:51:51 INFO - PROCESS | 2388 | 13:51:52 INFO - TEST-INFO | 2388: exit 0 13:51:52 INFO - TEST-OK | kraken | took 60167ms 13:51:52 INFO - SUITE-END | took 1175s 13:51:52 INFO - Completed test suite (00:19:36) 13:51:52 INFO - javascript benchmark 13:51:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3371.275175837195, 3431.519587257783, 3443.311337732454, 3459.6021457532393, 3485.285516469124, 5617.606767526275, 5713.742881570329, 5719.370869204384, 5728.598998070137, 5733.226014488043, 5673.546116310696, 5733.627314224573, 5733.741981610827, 5870.11948207768, 5881.059030555112, 5183.067047931371, 5187.740612969355, 5216.452272511369, 5243.449437809032, 5299.54953828925, 5291.3385826771655, 5292.206169074631, 5332.653377530468, 5338.412774594792, 5388.703621300827, 5130.9230361544605, 5167.509086636764, 5215.191645294985, 5225.6864588124645, 5293.153095504701, 5155.123628983082, 5163.302954101186, 5171.560417364491, 5171.896562068738, 5179.0, 5099.4900509949075, 5128.538431541142, 5135.486451354872, 5141.460146684619, 5199.714015729133, 5536.501714845684, 5635.3519345275, 5642.746076426571, 5746.82759517211, 5775.1337299405, 6243.526412238058, 6253.656048917308, 6255.437010669061, 6260.968695156541, 6269.780557680505, 6172.814815555541, 6274.623522588659, 6347.52393570484, 6367.108604795332, 6369.6496692681885, 5793.536517078624, 5799.478046975751, 5859.20900678403, 5901.380355062656, 5909.0, 5638.731285460821, 6254.6872656366995, 6293.685315734195, 6299.811005669837, 6310.7791227306825, 6019.458248757545, 6054.757809687651, 6164.784232551929, 6188.938110618837, 6193.287771906198, 6115.392460734087, 6211.472024877936, 6241.625502469867, 6302.338254483305, 6323.30443651198, 6225.3774622537385, 6253.0620406938415, 6255.186825712719, 6255.530835187379, 6271.78048768291, 6284.088807123033, 6291.528135389773, 6300.401461861115, 6308.779192728324, 6308.842278943063, 4841.0, 4881.389826271716, 4920.089783390084, 4929.162042452792, 4930.482299358516, 4396.340548917688, 4435.645148388121, 4442.089371678798, 4540.863774086717, 4584.014436896084, 5746.971265143731, 5757.078867381283, 5762.6542407454845, 5769.798057067983, 5812.738426770806, 6245.219347581552, 6281.968590157111, 6308.52686048539, 6320.083587879823, 6334.271558770708, 5548.86127846799, 5652.802151924663, 5710.857228569236, 5750.453706897838, 5758.049921762862, 5447.53695935842, 5448.073827449343, 5457.454254574511, 5465.535429488538, 5509.724513774376, 6295.244570651551, 6324.841878952971, 6344.49244060474, 6346.9682651586445, 6352.4918006559365, 5717.799262154827, 5839.357670656309, 6159.84600384994, 6285.622862628257, 6292.968535157295, 6266.0, 6295.779647712308, 6313.116163736989, 6316.147320111726, 6338.58799178052, 6230.844228894314, 6234.625922444668, 6244.063390491463, 6262.185915830913, 6265.342139075331, 6262.405071518198, 6297.748090076346, 6328.177336946167, 6330.177076979963, 6340.71466784005, 6058.515318774399, 6109.75560977556, 6121.148138557471, 6175.104609831639, 6191.752329906753, 6140.596275277727, 6153.230846144232, 6158.445739883432, 6168.753249870135, 6172.753089876354, 4616.422947131608, 5946.881062378815, 5994.220751302477, 6013.458788709037, 6034.24571928509, 5985.6408615483215, 6101.176341193882, 6110.26676798787, 6129.724162412791, 6151.569390142826, 5989.940100598938, 5990.460858522754, 5998.280206375263, 6001.639901605918, 6029.8191054268445], "value": 5720.52929163252, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.57025813187846, 524.1822756499861, 557.4815421657856, 577.8959787238297, 610.9461179465422, 29203.123906282817, 37016.25967480649, 37980.733179025425, 38053.858384248444, 38144.23711525768, 41604.79197604008, 42052.36921446173, 42217.36673949893, 42295.57704422955, 42340.7297781067, 8371.204735550113, 9357.391769534976, 9610.90389096107, 9615.375000624972, 9618.27862910281, 8449.619767110495, 10998.945005274962, 11244.437778111062, 11268.718282042973, 11270.211085224038, 11167.0, 11307.886921130807, 11311.547538098466, 11316.943415282913, 11325.263857849213, 7107.85784284314, 8296.585170741439, 8315.708950186774, 8530.01904780952, 8530.488170709777, 8426.325893928471, 10902.727431814188, 11199.272047316897, 11202.327860328325, 11202.607908723156, 38118.0, 38881.0, 38948.22103557913, 39007.8049609753, 39137.80431097855, 2979.791414600979, 3199.168216263765, 3241.33552621712, 3658.9268214635827, 3868.2650296443585, 27595.86202068997, 28503.00239491608, 28554.571681424793, 28577.0, 28596.142115736562, 2735.0, 2749.2714430675887, 2757.5587905935154, 2808.7893407994484, 2810.2131403207136, 375795.0, 376603.1169844161, 376974.0, 377248.0, 377387.1130644329, 55867.720661397245, 57368.0, 57476.42523574794, 57519.712401437726, 57600.42399576034, 58911.0, 59983.40016599865, 60486.39513604896, 60517.394826052056, 60562.69718651467, 16465.423710170326, 16654.666906661798, 16779.748303775454, 16793.244304006348, 16795.664086718196, 11361.670857474362, 11559.942200289113, 11566.768664626661, 11581.42092895352, 12812.0, 12722.681932951566, 18080.367187148386, 18225.4484372188, 18547.907260463882, 18548.721769173473, 7654.349380302625, 7802.960985195038, 7835.2948234658015, 7863.646135923897, 7872.890208367663, 919.7746552094645, 995.5719040812376, 1005.3612611443301, 1039.667306461925, 1141.434989680097, 28480.287992800346, 28769.71230287712, 28938.323041440453, 29169.27076823054, 29172.416551669274, 1078.369154044877, 1118.5358076398365, 1131.801934661431, 1139.732162941708, 1207.9939600302118, 14695.897807664467, 14865.875921510617, 14953.659695817516, 15014.774778378332, 15092.094474331576, 471113.0, 479911.0, 480077.0, 480539.0, 480650.0, 55334.0, 56801.0, 56826.7158664204, 56938.0, 57230.0, 1338.1569611144917, 1346.4479563378968, 1448.804411404447, 1490.627343164209, 1499.0930487055393, 25678.486430270917, 28054.0, 28175.43649127047, 28840.84636614512, 28918.85540572368, 44914.77542612266, 45346.54653453423, 45359.77320113379, 45412.0, 45505.0, 281102.0, 288780.0, 289952.0, 290017.0, 291092.0, 21642.458938526663, 22190.889045554668, 22193.44516387103, 22214.555708886055, 22244.66633000474, 40788.0, 40918.79540602397, 41301.760947171635, 41507.1698566033, 41774.37338439986, 34241.828790855885, 34542.30915381729, 34567.4814877772, 35267.82366088153, 35437.82281088578, 33824.49263261102, 34011.31977360488, 34127.488087678204, 34381.484277735355, 34616.13459663428, 27823.0, 34689.959301221, 34695.65304346924, 35019.29961400809, 35764.82117589395, 31081.067567972997, 31476.685233147375, 31507.0, 31580.526292105176, 31648.841755791073, 23362.532749344577, 24022.759772402052, 24075.398115047265, 24120.517589648458, 24463.36598291076], "value": 18656.513595396267, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.0641455241743, 393.91727737175194, 400.65743789060355, 406.4614385938632, 410.36803322882764, 19219.32732354366, 21058.262960796375, 21063.68404473934, 21190.0, 21459.67810482844, 16015.599610009756, 20414.89792551035, 20652.586948261065, 21038.263660771878, 21068.367948961557, 5852.180694702734, 5932.660605455082, 6077.3314935357075, 6100.664463454508, 6124.1732366130445, 4191.329387298033, 4476.48520420151, 6310.305866354697, 6654.168228971379, 6663.833404164886, 6474.0, 6544.901826472606, 6559.672016399185, 6595.2745198028415, 6604.207495100571, 6504.804855854332, 6511.609303441761, 6522.152120224364, 6558.901616475757, 6564.146660934096, 3797.3354662933875, 5446.319210098738, 5735.713214339308, 5752.568557358215, 5768.0, 24325.87837060821, 24545.87727061371, 24592.508149836904, 24877.62683559748, 24912.875435622886, 1945.659509585831, 2157.7518585362727, 2174.010825074587, 2391.1033362489065, 2478.5042991401792, 9805.117539421486, 9904.405735655811, 9959.950200249024, 10077.244206684529, 10172.491375431198, 1669.747689233075, 1713.4236502417805, 1770.3803668715846, 1790.3644206306703, 1855.1002763659615, 95971.0, 101979.49010254975, 109801.0, 116316.09209769739, 118143.0, 9268.443893366419, 11318.490667919963, 11632.360220187802, 11632.53469861196, 11717.238379505305, 14571.562853114423, 14781.482648107265, 14795.408183672747, 14802.925985370219, 14814.0, 8312.0, 8535.530545819915, 8562.74311770648, 8620.13798620133, 8632.179942905414, 8424.619083813966, 8439.70461033861, 8460.619272132768, 8602.139786021347, 8610.655573777103, 4154.231467178582, 4541.296099104644, 4633.814647414066, 4647.559256630456, 4669.322948172496, 1470.029780344967, 1598.7769356442332, 1623.2208539900678, 1949.2787668562723, 1974.3682021752902, 927.2257664849791, 948.9762755931018, 972.2999440402899, 1009.9596016159271, 1015.7308313296983, 18191.817531860386, 18443.538911527052, 19056.2377504901, 19736.210551578064, 19861.0, 954.7756277274835, 975.0882924465648, 1010.1666125446536, 1028.2956175019985, 1030.1449796668746, 10714.73213169677, 11071.72320691989, 11092.1126309895, 11098.55605775776, 11209.663710088711, 491939.0, 498838.0, 499538.0, 583045.0, 586742.0, 112589.0, 118126.0, 119078.0, 120004.0, 123667.0, 991.1921783746224, 1033.5555711044174, 1034.6275340877278, 1035.0270745499206, 1063.8616979792732, 17761.73357399614, 18060.90969545144, 18126.909365453088, 18178.72731908996, 18486.983215923254, 47080.7645961768, 49935.50064499309, 50346.49653503564, 50874.0, 51248.48751512437, 55567.888642227736, 69148.0, 69886.65056674684, 72070.55858882898, 72145.0, 7718.498297610638, 7845.333146682596, 7873.133955264801, 7978.082520509984, 8101.71643992481, 7265.243323709002, 10760.40817755031, 11826.29042257433, 12000.75998480043, 12703.745925081263, 10543.683689489328, 10746.0, 10770.299930504492, 10863.619773307897, 11043.282186657842, 8620.568971551273, 10655.229640293734, 10689.572417103229, 10931.56273749041, 11097.38964356968, 10794.73013174677, 10853.511591978222, 11267.422560841325, 11288.0, 11394.715132121763, 7905.209479052279, 7944.563049032358, 7961.0, 8041.477303975223, 8192.54940978252, 10431.94784026075, 10477.68566942993, 10550.89449105499, 10727.195460340661, 10865.130789536975], "value": 9356.826982863373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.6051345109918, 1580.9683806323874, 1592.3630547780888, 1816.8364847163753, 1942.7085937109441, 6933.479989000826, 7784.1826608206175, 8752.69365572205, 8758.299336053116, 8772.859528261333, 7829.256220659043, 8017.518948863072, 8337.124601916803, 8587.613557389917, 8607.569621518915, 2391.0435825669742, 2429.137656132074, 2429.9757002430015, 2430.4652976345174, 2433.318670772178, 2384.2847145856263, 2414.1188466209796, 2431.781139697427, 2432.4405386761055, 2436.135172013936, 2007.237249845064, 2392.270357540949, 2405.783479486846, 2410.9035638574437, 2428.368624157723, 2103.3269353806786, 2252.9185990724463, 2357.552065107624, 2385.7256415512265, 2399.1003373734848, 2158.2554018863466, 2187.8687278763327, 2189.2228258968, 2378.21518898763, 2378.5718570657273, 8583.399162058658, 8624.741257762214, 8693.434926729806, 8696.608652610648, 8885.733427997106, 5087.669301495391, 5328.413874473805, 5331.893362132736, 5338.3060202173465, 5352.625316227865, 3420.4698271767666, 3513.385157597436, 3590.174259920233, 3596.62235465275, 3598.8560457581666, 1028.9588416463332, 1029.619040954844, 1033.101201954297, 1056.5298442193227, 1057.0803401041067, 11307.26502777325, 11708.824367634492, 11716.648500544998, 11719.179657424069, 11726.530938762438, 4397.340398940185, 4501.6848820582245, 4565.223911934979, 4597.678162528658, 4617.238155704338, 9257.0, 9279.582418791035, 9279.76800579991, 9284.582193801296, 9286.0, 5174.353205849269, 5229.450907654717, 5254.527092561688, 5257.6845389276, 5259.368875734936, 4318.827246910088, 4324.978375108104, 4529.00361920377, 4823.0, 4836.492168322346, 3175.380800743879, 3735.6637902588354, 3771.0949372151035, 3808.6762625176616, 3834.328992426314, 1209.612923864355, 1215.3133479584008, 1218.214251807579, 1224.8040313549789, 1227.6071657069651, 822.2024636102971, 853.8804567360575, 898.955052247385, 943.9669611563562, 976.5898322704481, 5561.6663000220115, 5628.690422026746, 5764.7117644117625, 5887.35239123696, 5943.494802941798, 436.1059827353912, 451.9796609152596, 454.2436842657005, 464.2316965422232, 467.5161208149549, 3807.7334586578672, 3824.7513911595656, 3830.482884810571, 3909.667678247381, 3928.685705143582, 10258.897411025795, 10720.46397680113, 10724.571017159382, 10726.624568140078, 10792.190585706103, 10621.309614875008, 10702.785944281226, 10774.838377424345, 10775.084117849914, 10797.08224800885, 7897.486663366863, 8191.549464779494, 8244.175582441827, 8254.298384637254, 8264.132266112092, 5577.609567330248, 5707.942920570741, 5746.0, 5804.65172089676, 5857.912131318119, 9809.95095024549, 9833.901660983298, 9935.950320248352, 9978.605134767458, 9992.300538962203, 9941.403515789076, 9946.45294508809, 10020.298579099393, 10319.277650564389, 10352.948235259077, 6966.1640603125925, 7017.473689473412, 7067.010618513309, 7082.0085188074745, 7086.433085353056, 8086.231807978233, 8183.345332373515, 8241.25828675422, 8287.254147126787, 8290.585470726319, 6587.96706016486, 7323.670434830339, 7421.0, 7482.850342993219, 7569.243075692387, 7130.500864939404, 7168.498205125591, 7400.5189662671755, 7439.776806695808, 7440.2931721486375, 6823.863522729418, 6906.792796216121, 6984.288849231767, 7007.649617519206, 7012.509124361246, 4781.856544303677, 4822.483158405042, 4883.267509873405, 4899.804007839646, 4917.704937703749, 4877.585405240594, 4943.752812359439, 5002.17464118431, 5004.699718016931, 5176.7152806596], "value": 4426.115485342777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.0889112682095, 314.0, 318.6956456583962, 319.3150691766162, 325.85825166052786, 4775.355327030854, 5198.324217851678, 5405.459444058444, 5442.183672449135, 5487.6158668893195, 4260.680448966325, 5372.301600791891, 5406.972675191711, 5419.672180315828, 5422.0, 2968.0, 3541.575010998684, 3795.6773674237725, 4006.3389540725743, 4171.249175148472, 3413.539172211757, 4075.6331930126285, 4130.566290539495, 4178.435911152002, 4183.644390226835, 4078.653314468274, 4107.322291821861, 4115.320972039624, 4128.545859955402, 4134.4625198724125, 3824.808759562011, 3893.435451859493, 4118.444010058649, 4130.938035929464, 4161.835845360426, 3002.6488080363706, 3309.7683162178655, 3571.6428357164077, 3606.719614536854, 3608.3144202601684, 4589.701669391512, 5061.6962982221185, 5141.971605678879, 5146.812604643012, 5153.768080436389, 4457.9493433020525, 4501.029320648389, 4566.292224705176, 4719.994002548938, 4762.952370476286, 5653.462921022497, 5703.885922281531, 5706.229658996065, 5716.1425786131995, 5734.655920644775, 3981.3828856527284, 3988.3618621020496, 4001.779902105383, 4009.518857737061, 4011.618896204856, 5506.889862202774, 5654.717264136777, 5988.461038506555, 6017.247844019497, 6019.277686677627, 3831.7317787755146, 4026.2953983053435, 4043.433919251307, 4058.5332686740812, 4064.7967601619803, 4085.428040074392, 4108.8356465741035, 4121.216968775923, 4121.732087414309, 4127.979360103181, 4159.417681524589, 4159.542450330461, 4281.272183728774, 4731.716097034189, 4735.337052812609, 5190.506901844319, 5239.318888544465, 5707.885842283131, 5757.0, 5817.970910145506, 1449.4564538298139, 1463.8609332113645, 1470.4779803169934, 1472.709936049432, 1477.268751967783, 784.1256998446805, 809.441485375101, 889.1553024626502, 974.6101559376306, 979.5836769372988, 5029.5221953914315, 5357.43746906577, 5720.2849643794525, 5744.310682718101, 5749.1663708762, 5180.093483640348, 5579.118271512427, 5665.291838520185, 5758.62568933018, 5821.0, 5207.589962167534, 5250.212468129811, 5295.629305948547, 5350.892982140335, 5355.410904800468, 2870.354170311705, 2939.6913324101088, 3118.0, 3355.496675498646, 3403.2853100849097, 6319.431251187415, 6468.676566171673, 6715.3620406061345, 6735.831604209934, 6752.22349429812, 5474.91787623186, 5746.827595172152, 6610.041543976096, 6620.304867988888, 6621.271660117383, 6122.938770612237, 6658.300878407795, 6695.933040669628, 6711.496637752188, 6731.663416829237, 5294.973525132504, 5811.651300921789, 6084.209052823105, 6134.846628834315, 6414.294427612866, 5139.768710408115, 5607.514008787756, 6251.062340648939, 6333.878150819527, 6335.303116657256, 5801.564882633735, 6079.696015199134, 6120.95943689565, 6670.499712521678, 6691.631960242039, 3705.4256590228556, 3733.9813300934406, 4082.3060079785914, 4105.876823695294, 4125.97937010313, 4942.901141977212, 5915.231019967377, 5952.553558483044, 5953.226080609493, 5988.970055149696, 4894.9021019578695, 5402.297701298963, 5785.652860828363, 5786.247787787729, 5787.36339002701, 4632.652551058617, 5229.660072095302, 5634.32388113429, 5675.120356344773, 5782.703858800443, 5293.888283460363, 5369.731513424391, 5388.632104934416, 5638.323401191883, 5682.857928551819, 4546.499885012712, 4713.9292910607055, 5541.0256665241495, 5769.8557536061935, 5799.101139323411, 5011.018444025799, 5224.399194092728, 5584.162375643686, 5615.943840561542, 5642.517292391335], "value": 4238.885611676519, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.4845372155997, 715.7530651925084, 751.5265382808832, 751.7293774241275, 776.7669699090271, 14637.487687930925, 14844.257787110655, 15627.92186039071, 15651.921740391297, 15663.373465061384, 10693.518791654375, 14228.928855355709, 14255.50105746299, 14266.144031358126, 14757.819374450046, 724.2069933422894, 744.9776506704808, 908.7546362482142, 934.8223837470892, 937.386012162035, 898.4788822482944, 934.1592566689966, 936.1761649748246, 936.8032713130251, 936.8454205056157, 960.4717405427023, 974.9957543729934, 978.5889926231, 978.9755256118618, 979.2998006425404, 747.0400535312152, 946.4226821638795, 970.2868391732071, 972.479723348009, 980.5881529757519, 722.241646271413, 722.9385502232317, 723.2875617516742, 723.4284914917262, 723.5477826358526, 15043.849561504354, 15127.54617361481, 15133.092014479167, 15137.091774493565, 15168.469103581432, 12185.207961482563, 12207.389630518528, 12320.876791232062, 12495.500179992789, 12602.180858244277, 9807.362521436084, 9957.203423726085, 10010.299279050469, 10125.088742013255, 10519.195666986632, 3427.9314413711586, 3460.325236578868, 3509.736769742254, 3519.894403167909, 3583.283343331344, 70785.0, 71236.64381678059, 71248.93126603105, 71482.9277560837, 71720.0, 9179.219766319804, 9190.035046320174, 9395.906040939502, 9547.761305967406, 9661.13049825519, 21121.788782111984, 21300.36098917035, 21406.357809265748, 21527.354179374644, 21548.892255538623, 6156.384361563808, 7467.813304667427, 7525.623718814038, 7665.540067595962, 7865.960670196612, 3942.329803933338, 4057.411675307064, 4077.3883917412627, 4145.523264824524, 4196.0, 3156.5738625285294, 3333.8331583945424, 3385.9492107618407, 3463.7229021678204, 3467.8729412940393, 518.5281393931505, 523.602062432554, 606.2240332374568, 617.0220200981495, 624.7969409941787, 545.1985581195636, 577.413924866261, 615.8706671599015, 632.3076231526471, 632.99986022085, 7859.543377184671, 8110.3511719062335, 8113.310368618615, 8228.341732661373, 8327.541985190872, 503.64241388614494, 571.2630706388795, 611.8069763960207, 623.607127509675, 626.4925410417576, 3646.872359467406, 3858.741606565531, 3867.284552357823, 3886.378179491268, 3904.7266691331333, 87736.24527509414, 90065.0, 90507.54746226358, 92760.53619731858, 92766.53616731873, 76735.61632191915, 77868.61065694636, 78387.0, 78754.60622696964, 80628.59685701533, 69775.0, 70708.23229419306, 71067.64466177636, 71799.0, 72127.63936180285, 7563.810904727426, 7644.159142494433, 7684.807879802826, 7773.922260777546, 7907.2488113629115, 43405.78297108494, 44039.77980110079, 44084.33873491964, 44107.77946110249, 44273.11453770971, 57068.714656426455, 59988.700056501184, 60534.69732651485, 63485.0, 63488.41278967878, 9806.950965245414, 10186.337888037255, 10289.84565231507, 10394.532246049097, 10609.787804244026, 11111.444427778417, 11135.164862635496, 11241.60654377093, 11274.041706455044, 11349.319040857576, 8874.801901743182, 10236.283460157718, 10420.947895260475, 10463.68608941733, 10525.105366043972, 10409.739756505845, 10505.527251273865, 10591.735206619896, 10613.991670791262, 10618.415987120783, 10225.795484090426, 10281.177505799667, 10311.432871192157, 10325.483725813829, 10557.260991730505, 8896.421732587361, 8956.641734330553, 9248.260139188984, 9258.35191536586, 9400.95299523498, 8918.0, 9078.182963533314, 9129.954350228207, 9177.954110229406, 9276.397034192756], "value": 6693.246155274037, "unit": "score"}], "name": "dromaeo_css", "value": 7074.974547651772, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 109.0, 112.0, 111.0, 114.0, 113.0, 112.0, 104.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 148.0, 177.0, 176.0, 120.0, 145.0, 157.0, 146.0, 215.0, 149.0], "value": 160.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 199.0, 240.0, 264.0, 210.0, 186.0, 188.0, 187.0, 186.0, 184.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 88.0, 114.0, 85.0, 86.0, 57.0, 90.0, 87.0, 72.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 98.0, 132.0, 77.0, 81.0, 83.0, 122.0, 80.0, 76.0, 124.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 88.0, 87.0, 88.0, 86.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 87.0, 84.0, 86.0, 84.0, 85.0, 87.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 59.0, 59.0, 57.0, 58.0, 80.0, 60.0, 57.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 70.0, 80.0, 71.0, 71.0, 67.0, 69.0, 68.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 82.0, 83.0, 96.0, 105.0, 105.0, 82.0, 84.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 163.0, 155.0, 145.0, 138.0, 139.0, 141.0, 137.0, 180.0, 139.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [264.0, 201.0, 201.0, 201.0, 199.0, 202.0, 200.0, 201.0, 198.0, 197.0], "value": 206.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 84.0, 101.0, 101.0, 101.0, 69.0, 70.0, 70.0, 71.0, 72.0], "value": 81.2, "unit": "score"}], "name": "kraken", "value": 1576.5, "alertThreshold": 2.0}]} 13:51:52 INFO - Return code: 0 13:51:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:51:53 ERROR - # TBPL SUCCESS # 13:51:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:53 INFO - 2016-04-26 13:51:53.030 screenresolution[2392:23504] starting screenresolution argv=screenresolution get 13:51:53 INFO - 2016-04-26 13:51:53.033 screenresolution[2392:23504] Display 0: 1600x1200x32@60 13:51:53 INFO - 2016-04-26 13:51:53.039 screenresolution[2393:23507] starting screenresolution argv=screenresolution list 13:51:53 INFO - Available Modes on Display 0 13:51:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:53 INFO - Intel Iris: 13:51:53 INFO - Chipset Model: Intel Iris 13:51:53 INFO - Type: GPU 13:51:53 INFO - Bus: Built-In 13:51:53 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:53 INFO - Vendor: Intel (0x8086) 13:51:53 INFO - Device ID: 0x0a2e 13:51:53 INFO - Revision ID: 0x0009 13:51:53 INFO - Displays: 13:51:53 INFO - Display: 13:51:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:53 INFO - Display Serial Number: 1600x1200 60 13:51:53 INFO - Main Display: Yes 13:51:53 INFO - Mirror: Off 13:51:53 INFO - Online: Yes 13:51:53 INFO - Rotation: Supported 13:51:53 INFO - Return code: 0 13:51:53 INFO - Running post-action listener: _resource_record_post_action 13:51:53 INFO - Running post-run listener: _resource_record_post_run 13:51:54 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 852480; Write bytes: 695207936; Read time: 12; Write time: 1858 13:51:54 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392892928; Write bytes: 387024896; Read time: 29764; Write time: 711 13:51:54 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 852480; Write bytes: 296751104; Read time: 12; Write time: 1117 13:51:54 INFO - Running post-run listener: _upload_blobber_files 13:51:54 INFO - Blob upload gear active. 13:51:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:54 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 13:51:54 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:51:54 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:51:54 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:55 INFO - (blobuploader) - INFO - Done attempting. 13:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:56 INFO - (blobuploader) - INFO - Done attempting. 13:51:56 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:56 INFO - Return code: 0 13:51:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9f5663266ed0877397562a5d9415947cc66579d0512d079aedec99449ba1f2035bc4539ef7cf4a09cc52d66a51d6f388995f992268416f6838ab7ff1242366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f941d4677582b98ddd1018ebb70d44156cf73f9abea8220fe2bac29bcbcc05b7110b8120fe711aa98295005057899461ae0c4d27707380f9b3ba689d803336ee"} 13:51:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:56 INFO - Contents: 13:51:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9f5663266ed0877397562a5d9415947cc66579d0512d079aedec99449ba1f2035bc4539ef7cf4a09cc52d66a51d6f388995f992268416f6838ab7ff1242366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f941d4677582b98ddd1018ebb70d44156cf73f9abea8220fe2bac29bcbcc05b7110b8120fe711aa98295005057899461ae0c4d27707380f9b3ba689d803336ee"} 13:51:56 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:56 INFO - Copying logs to upload dir... 13:51:56 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1448.187613 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 8 secs) (at 2016-04-26 13:51:57.039516) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:51:57.046521) ========= 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.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9f5663266ed0877397562a5d9415947cc66579d0512d079aedec99449ba1f2035bc4539ef7cf4a09cc52d66a51d6f388995f992268416f6838ab7ff1242366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f941d4677582b98ddd1018ebb70d44156cf73f9abea8220fe2bac29bcbcc05b7110b8120fe711aa98295005057899461ae0c4d27707380f9b3ba689d803336ee"} build_url:https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010805 build_url: 'https://queue.taskcluster.net/v1/task/Uh-jmUaBS6-cJQTeBKaKZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd9f5663266ed0877397562a5d9415947cc66579d0512d079aedec99449ba1f2035bc4539ef7cf4a09cc52d66a51d6f388995f992268416f6838ab7ff1242366", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f941d4677582b98ddd1018ebb70d44156cf73f9abea8220fe2bac29bcbcc05b7110b8120fe711aa98295005057899461ae0c4d27707380f9b3ba689d803336ee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:51:57.073597) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:51:57.073909) ========= 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.x1WPCmJWwt/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.mVm9j90WGo/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:51:57.123633) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:51:57.123906) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:51:57.126286) ========= ========= Total master_lag: 0.19 =========