builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0053 starttime: 1462495569.52 results: success (0) buildid: 20160505165542 builduid: 54cccca5540141cfbc662dd54cafc05f revision: 8000d7a6ff44b128850bd4d08e0d1867f6df557d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.517642) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.518076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.518344) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.535760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.536018) ========= 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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-05 17:46:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.29M=0.001s 2016-05-05 17:46:09 (8.29 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097127 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.649684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.649988) ========= 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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.020407 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:09.698351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 17:46:09.698701) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8000d7a6ff44b128850bd4d08e0d1867f6df557d --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 8000d7a6ff44b128850bd4d08e0d1867f6df557d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-05 17:46:09,810 truncating revision to first 12 chars 2016-05-05 17:46:09,810 Setting DEBUG logging. 2016-05-05 17:46:09,810 attempt 1/10 2016-05-05 17:46:09,811 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8000d7a6ff44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 17:46:10,868 unpacking tar archive at: fx-team-8000d7a6ff44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.306745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 17:46:11.022122) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:11.022442) ========= script_repo_revision: 8000d7a6ff44b128850bd4d08e0d1867f6df557d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:11.022838) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:11.023096) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 17:46:11.036311) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-05 17:46:11.036747) ========= /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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:11 INFO - MultiFileLogger online at 20160505 17:46:11 in /builds/slave/test 17:46:11 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 17:46:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:46:11 INFO - {'append_to_log': False, 17:46:11 INFO - 'base_work_dir': '/builds/slave/test', 17:46:11 INFO - 'blob_upload_branch': 'Fx-Team', 17:46:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:46:11 INFO - 'branch': 'Fx-Team', 17:46:11 INFO - 'buildbot_json_path': 'buildprops.json', 17:46:11 INFO - 'config_files': ('talos/mac_config.py',), 17:46:11 INFO - 'default_actions': ('clobber', 17:46:11 INFO - 'read-buildbot-config', 17:46:11 INFO - 'download-and-extract', 17:46:11 INFO - 'populate-webroot', 17:46:11 INFO - 'create-virtualenv', 17:46:11 INFO - 'install', 17:46:11 INFO - 'run-tests'), 17:46:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:46:11 INFO - 'download_minidump_stackwalk': True, 17:46:11 INFO - 'download_symbols': 'ondemand', 17:46:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:46:11 INFO - 'tooltool.py': '/tools/tooltool.py', 17:46:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:46:11 INFO - '/tools/misc-python/virtualenv.py')}, 17:46:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:46:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:46:11 INFO - 'installer_path': 'installer.exe', 17:46:11 INFO - 'log_level': 'info', 17:46:11 INFO - 'log_name': 'talos', 17:46:11 INFO - 'log_to_console': True, 17:46:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:46:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:46:11 INFO - 'opt_config_files': (), 17:46:11 INFO - 'pip_index': False, 17:46:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:46:11 INFO - 'cmd': ('bash', 17:46:11 INFO - '-c', 17:46:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:46:11 INFO - 'enabled': True, 17:46:11 INFO - 'halt_on_failure': False, 17:46:11 INFO - 'name': 'check_screen_resolution'},), 17:46:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:46:11 INFO - 'cmd': ('bash', 17:46:11 INFO - '-c', 17:46:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:46:11 INFO - 'enabled': True, 17:46:11 INFO - 'halt_on_failure': False, 17:46:11 INFO - 'name': 'check_screen_resolution'},), 17:46:11 INFO - 'run_cmd_checks_enabled': True, 17:46:11 INFO - 'sps_profile': False, 17:46:11 INFO - 'sps_profile_interval': 0, 17:46:11 INFO - 'suite': 'dromaeojs', 17:46:11 INFO - 'system_bits': '32', 17:46:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:46:11 INFO - 'title': 't-yosemite-r7-0053', 17:46:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:46:11 INFO - 'use_talos_json': True, 17:46:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:46:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:46:11 INFO - 'work_dir': 'build'} 17:46:11 INFO - ##### 17:46:11 INFO - ##### Running clobber step. 17:46:11 INFO - ##### 17:46:11 INFO - Running pre-action listener: _resource_record_pre_action 17:46:11 INFO - Running main action method: clobber 17:46:11 INFO - rmtree: /builds/slave/test/build 17:46:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:46:12 INFO - Running post-action listener: _resource_record_post_action 17:46:12 INFO - ##### 17:46:12 INFO - ##### Running read-buildbot-config step. 17:46:12 INFO - ##### 17:46:12 INFO - Running pre-action listener: _resource_record_pre_action 17:46:12 INFO - Running main action method: read_buildbot_config 17:46:12 INFO - Using buildbot properties: 17:46:12 INFO - { 17:46:12 INFO - "project": "", 17:46:12 INFO - "product": "firefox", 17:46:12 INFO - "script_repo_revision": "production", 17:46:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:46:12 INFO - "repository": "", 17:46:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:46:12 INFO - "buildid": "20160505165542", 17:46:12 INFO - "pgo_build": "False", 17:46:12 INFO - "basedir": "/builds/slave/test", 17:46:12 INFO - "buildnumber": 0, 17:46:12 INFO - "slavename": "t-yosemite-r7-0053", 17:46:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:46:12 INFO - "platform": "yosemite_r7", 17:46:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:46:12 INFO - "branch": "fx-team", 17:46:12 INFO - "repo_path": "integration/fx-team", 17:46:12 INFO - "revision": "8000d7a6ff44b128850bd4d08e0d1867f6df557d", 17:46:12 INFO - "stage_platform": "macosx64", 17:46:12 INFO - "builduid": "54cccca5540141cfbc662dd54cafc05f", 17:46:12 INFO - "slavebuilddir": "test" 17:46:12 INFO - } 17:46:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:46:12 INFO - Running post-action listener: _resource_record_post_action 17:46:12 INFO - ##### 17:46:12 INFO - ##### Running download-and-extract step. 17:46:12 INFO - ##### 17:46:12 INFO - Running pre-action listener: _resource_record_pre_action 17:46:12 INFO - Running main action method: download_and_extract 17:46:12 INFO - mkdir: /builds/slave/test/build/tests 17:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:12 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:46:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:46:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:46:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:14 INFO - Downloaded 1199 bytes. 17:46:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:46:14 INFO - Using the following test package requirements: 17:46:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:46:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:46:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:46:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'jsshell-mac.zip'], 17:46:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:46:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:46:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:46:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:46:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:46:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:46:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:46:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:14 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:15 INFO - Downloaded 18144963 bytes. 17:46:15 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'] 17:46:15 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 17:46:18 INFO - Return code: 0 17:46:18 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 17:46: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')]} 17:46:18 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:46:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:46:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:46:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:19 INFO - Downloaded 18144963 bytes. 17:46:19 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'] 17:46:19 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 17:46:23 INFO - Return code: 0 17:46:23 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')]} 17:46:23 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:46:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:46:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:46:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:24 INFO - Downloaded 10840152 bytes. 17:46:24 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'] 17:46:24 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 17:46:24 INFO - Return code: 0 17:46:24 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')]} 17:46:24 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:46:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:46:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:46:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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 17:46:27 INFO - Downloaded 110398578 bytes. 17:46:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:46:27 INFO - mkdir: /builds/slave/test/properties 17:46:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:46:27 INFO - Writing to file /builds/slave/test/properties/build_url 17:46:27 INFO - Contents: 17:46:27 INFO - build_url:https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:46:28 INFO - Running post-action listener: _resource_record_post_action 17:46:28 INFO - Running post-action listener: set_extra_try_arguments 17:46:28 INFO - ##### 17:46:28 INFO - ##### Running populate-webroot step. 17:46:28 INFO - ##### 17:46:28 INFO - Running pre-action listener: _resource_record_pre_action 17:46:28 INFO - Running main action method: populate_webroot 17:46:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:46:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:46:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:46:28 INFO - '--tppagecycles', 17:46:28 INFO - '7'], 17:46:28 INFO - 'tests': ['tsvgm']}}, 17:46:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:46:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:46:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:46:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:46:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:46:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:46:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['damp', 'tps']}, 17:46:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'tests': ['damp', 'tps']}, 17:46:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['dromaeo_dom']}, 17:46:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:46:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'other-e10s': {'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['a11yr', 17:46:28 INFO - 'ts_paint', 17:46:28 INFO - 'tpaint', 17:46:28 INFO - 'sessionrestore', 17:46:28 INFO - 'sessionrestore_no_auto_restore', 17:46:28 INFO - 'tabpaint']}, 17:46:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['tsvgx', 17:46:28 INFO - 'tsvgr_opacity', 17:46:28 INFO - 'tart', 17:46:28 INFO - 'tscrollx', 17:46:28 INFO - 'cart']}, 17:46:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:46:28 INFO - 'tsvgr_opacity', 17:46:28 INFO - 'tart', 17:46:28 INFO - 'tscrollx', 17:46:28 INFO - 'cart']}, 17:46:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'talos_options': ['--disable-e10s'], 17:46:28 INFO - 'tests': ['tp5o']}, 17:46:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'tests': ['tp5o']}, 17:46:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'talos_options': ['--disable-e10s', 17:46:28 INFO - '--xperf_path', 17:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:46:28 INFO - 'tests': ['tp5n']}, 17:46:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:28 INFO - 'talos_options': ['--xperf_path', 17:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:46:28 INFO - 'tests': ['tp5n']}}, 17:46:28 INFO - 'talos.zip': {'path': '', 17:46:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:46:28 INFO - Running post-action listener: _resource_record_post_action 17:46:28 INFO - ##### 17:46:28 INFO - ##### Running create-virtualenv step. 17:46:28 INFO - ##### 17:46:28 INFO - Running pre-action listener: _resource_record_pre_action 17:46:28 INFO - Running main action method: create_virtualenv 17:46:28 INFO - Creating virtualenv /builds/slave/test/build/venv 17:46:28 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 17:46:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:46:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:46:28 INFO - Using real prefix '/tools/python27' 17:46:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:46:29 INFO - Installing distribute.............................................................................................................................................................................................done. 17:46:32 INFO - Installing pip.................done. 17:46:32 INFO - Return code: 0 17:46:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:46: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')]} 17:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46: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')]} 17:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:32 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:32 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 17:46:32 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 17:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:32 INFO - 'HOME': '/Users/cltbld', 17:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:32 INFO - 'LOGNAME': 'cltbld', 17:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:32 INFO - 'MOZ_NO_REMOTE': '1', 17:46:32 INFO - 'NO_EM_RESTART': '1', 17:46:32 INFO - 'PAGER': '/bin/cat', 17:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:32 INFO - 'PWD': '/builds/slave/test', 17:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:32 INFO - 'SHELL': '/bin/bash', 17:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:32 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:32 INFO - 'USER': 'cltbld', 17:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:32 INFO - 'XPC_FLAGS': '0x0', 17:46:32 INFO - 'XPC_SERVICE_NAME': '0', 17:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:32 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 17:46:32 INFO - Cleaning up... 17:46:32 INFO - Return code: 0 17:46:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:46: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')]} 17:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46: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')]} 17:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:32 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:32 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 17:46:32 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 17:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:32 INFO - 'HOME': '/Users/cltbld', 17:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:32 INFO - 'LOGNAME': 'cltbld', 17:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:32 INFO - 'MOZ_NO_REMOTE': '1', 17:46:32 INFO - 'NO_EM_RESTART': '1', 17:46:32 INFO - 'PAGER': '/bin/cat', 17:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:32 INFO - 'PWD': '/builds/slave/test', 17:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:32 INFO - 'SHELL': '/bin/bash', 17:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:32 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:32 INFO - 'USER': 'cltbld', 17:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:32 INFO - 'XPC_FLAGS': '0x0', 17:46:32 INFO - 'XPC_SERVICE_NAME': '0', 17:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:33 INFO - Downloading/unpacking psutil>=0.7.1 17:46: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 17:46: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 17:46: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 17:46: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 17:46: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 17:46: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 17:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:46:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:46:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:46:36 INFO - Installing collected packages: psutil 17:46:36 INFO - Running setup.py install for psutil 17:46:36 INFO - building 'psutil._psutil_osx' extension 17:46:36 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 17:46:36 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 17:46:36 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 17:46:36 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 17:46:36 INFO - building 'psutil._psutil_posix' extension 17:46:36 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 17:46:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:46:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:46:36 INFO - ^ 17:46:36 INFO - 1 warning generated. 17:46:36 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 17:46:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:46:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:46:36 INFO - Successfully installed psutil 17:46:36 INFO - Cleaning up... 17:46:37 INFO - Return code: 0 17:46:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:46:37 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')]} 17:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:37 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')]} 17:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:37 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:37 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 17:46:37 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 17:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:37 INFO - 'HOME': '/Users/cltbld', 17:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:37 INFO - 'LOGNAME': 'cltbld', 17:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:37 INFO - 'MOZ_NO_REMOTE': '1', 17:46:37 INFO - 'NO_EM_RESTART': '1', 17:46:37 INFO - 'PAGER': '/bin/cat', 17:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:37 INFO - 'PWD': '/builds/slave/test', 17:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:37 INFO - 'SHELL': '/bin/bash', 17:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:37 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:37 INFO - 'USER': 'cltbld', 17:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:37 INFO - 'XPC_FLAGS': '0x0', 17:46:37 INFO - 'XPC_SERVICE_NAME': '0', 17:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:46:39 INFO - Installing collected packages: mozsystemmonitor 17:46:39 INFO - Running setup.py install for mozsystemmonitor 17:46:39 INFO - Successfully installed mozsystemmonitor 17:46:39 INFO - Cleaning up... 17:46:39 INFO - Return code: 0 17:46:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:39 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:39 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 17:46:39 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 17:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:39 INFO - 'HOME': '/Users/cltbld', 17:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:39 INFO - 'LOGNAME': 'cltbld', 17:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:39 INFO - 'MOZ_NO_REMOTE': '1', 17:46:39 INFO - 'NO_EM_RESTART': '1', 17:46:39 INFO - 'PAGER': '/bin/cat', 17:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:39 INFO - 'PWD': '/builds/slave/test', 17:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:39 INFO - 'SHELL': '/bin/bash', 17:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:39 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:39 INFO - 'USER': 'cltbld', 17:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:39 INFO - 'XPC_FLAGS': '0x0', 17:46:39 INFO - 'XPC_SERVICE_NAME': '0', 17:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:40 INFO - Downloading/unpacking blobuploader==1.2.4 17:46: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 17:46: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 17:46: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 17:46: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 17:46: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 17:46: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 17:46:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:46:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:46:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:46:44 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 17:46:44 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 17:46:44 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 17:46:44 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 17:46:44 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 17:46:44 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 17:46:44 INFO - Downloading docopt-0.6.1.tar.gz 17:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:46:44 INFO - Installing collected packages: blobuploader, requests, docopt 17:46:44 INFO - Running setup.py install for blobuploader 17:46:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:46:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:46:45 INFO - Running setup.py install for requests 17:46:45 INFO - Running setup.py install for docopt 17:46:45 INFO - Successfully installed blobuploader requests docopt 17:46:45 INFO - Cleaning up... 17:46:45 INFO - Return code: 0 17:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:46:45 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')]} 17:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:45 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')]} 17:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:45 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:45 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 17:46:45 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 17:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:45 INFO - 'HOME': '/Users/cltbld', 17:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:45 INFO - 'LOGNAME': 'cltbld', 17:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:45 INFO - 'MOZ_NO_REMOTE': '1', 17:46:45 INFO - 'NO_EM_RESTART': '1', 17:46:45 INFO - 'PAGER': '/bin/cat', 17:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:45 INFO - 'PWD': '/builds/slave/test', 17:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:45 INFO - 'SHELL': '/bin/bash', 17:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:45 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:45 INFO - 'USER': 'cltbld', 17:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:45 INFO - 'XPC_FLAGS': '0x0', 17:46:45 INFO - 'XPC_SERVICE_NAME': '0', 17:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-6y7S5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Io2Pnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-vVElB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-rIrjn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-nBIWnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-7D9tXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-nMSBk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-CZQ_fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-uKy2zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:46:46 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-oGA7Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-GATDWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-qMxcfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-zRP6Bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-jek6L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-rENWvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-DmKau0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:46:47 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-8YDiCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:46:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:46:47 INFO - Running setup.py install for manifestparser 17:46:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:46:47 INFO - Running setup.py install for mozcrash 17:46:48 INFO - Running setup.py install for mozdebug 17:46:48 INFO - Running setup.py install for mozdevice 17:46:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Running setup.py install for mozfile 17:46:48 INFO - Running setup.py install for mozhttpd 17:46:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Running setup.py install for mozinfo 17:46:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Running setup.py install for mozInstall 17:46:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:46:48 INFO - Running setup.py install for mozleak 17:46:49 INFO - Running setup.py install for mozlog 17:46:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Running setup.py install for moznetwork 17:46:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Running setup.py install for mozprocess 17:46:49 INFO - Running setup.py install for mozprofile 17:46:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Running setup.py install for mozrunner 17:46:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:46:49 INFO - Running setup.py install for mozscreenshot 17:46:50 INFO - Running setup.py install for moztest 17:46:50 INFO - Running setup.py install for mozversion 17:46:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:46:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:46:50 INFO - Cleaning up... 17:46:50 INFO - Return code: 0 17:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:46:50 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')]} 17:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:50 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')]} 17:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:50 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:50 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 17:46:50 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 17:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:50 INFO - 'HOME': '/Users/cltbld', 17:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:50 INFO - 'LOGNAME': 'cltbld', 17:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:50 INFO - 'MOZ_NO_REMOTE': '1', 17:46:50 INFO - 'NO_EM_RESTART': '1', 17:46:50 INFO - 'PAGER': '/bin/cat', 17:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:50 INFO - 'PWD': '/builds/slave/test', 17:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:50 INFO - 'SHELL': '/bin/bash', 17:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:50 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:50 INFO - 'USER': 'cltbld', 17:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:50 INFO - 'XPC_FLAGS': '0x0', 17:46:50 INFO - 'XPC_SERVICE_NAME': '0', 17:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:46:50 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-UQeEQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:46:50 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)) 17:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:46:50 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-NwDK1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:46:50 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)) 17:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:46:50 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-DfTyxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-DiA9qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-tff8yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-BrBWqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ZMOzpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-g4D7yJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-PTe47x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ZL0naW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-b8W1ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-jF4Ph8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:46:51 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)) 17:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:46:51 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-dCjNsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:46:52 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)) 17:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:46:52 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-opPmZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:46:52 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)) 17:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:46:52 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-fEw7cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:46:52 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)) 17:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:46:52 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-UAOilG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:46:52 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)) 17:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:46:52 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-L9t8QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:46:52 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)) 17:46:52 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)) 17:46:52 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)) 17:46:52 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)) 17:46:52 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)) 17:46:52 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)) 17:46:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:54 INFO - Downloading blessings-1.6.tar.gz 17:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:46:54 INFO - Installing collected packages: blessings 17:46:54 INFO - Running setup.py install for blessings 17:46:55 INFO - Successfully installed blessings 17:46:55 INFO - Cleaning up... 17:46:55 INFO - Return code: 0 17:46:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:46:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:46:55 INFO - Reading from file tmpfile_stdout 17:46:55 INFO - Current package versions: 17:46:55 INFO - blessings == 1.6 17:46:55 INFO - blobuploader == 1.2.4 17:46:55 INFO - docopt == 0.6.1 17:46:55 INFO - manifestparser == 1.1 17:46:55 INFO - mozInstall == 1.12 17:46:55 INFO - mozcrash == 0.17 17:46:55 INFO - mozdebug == 0.1 17:46:55 INFO - mozdevice == 0.48 17:46:55 INFO - mozfile == 1.2 17:46:55 INFO - mozhttpd == 0.7 17:46:55 INFO - mozinfo == 0.9 17:46:55 INFO - mozleak == 0.1 17:46:55 INFO - mozlog == 3.1 17:46:55 INFO - moznetwork == 0.27 17:46:55 INFO - mozprocess == 0.22 17:46:55 INFO - mozprofile == 0.28 17:46:55 INFO - mozrunner == 6.11 17:46:55 INFO - mozscreenshot == 0.1 17:46:55 INFO - mozsystemmonitor == 0.0 17:46:55 INFO - moztest == 0.7 17:46:55 INFO - mozversion == 1.4 17:46:55 INFO - psutil == 3.1.1 17:46:55 INFO - requests == 1.2.3 17:46:55 INFO - wsgiref == 0.1.2 17:46:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:46: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')]} 17:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46: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')]} 17:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:55 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:55 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 17:46:55 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 17:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:55 INFO - 'HOME': '/Users/cltbld', 17:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:55 INFO - 'LOGNAME': 'cltbld', 17:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:55 INFO - 'MOZ_NO_REMOTE': '1', 17:46:55 INFO - 'NO_EM_RESTART': '1', 17:46:55 INFO - 'PAGER': '/bin/cat', 17:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:55 INFO - 'PWD': '/builds/slave/test', 17:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:55 INFO - 'SHELL': '/bin/bash', 17:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:55 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:55 INFO - 'USER': 'cltbld', 17:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:55 INFO - 'XPC_FLAGS': '0x0', 17:46:55 INFO - 'XPC_SERVICE_NAME': '0', 17:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 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)) 17:46:55 INFO - Cleaning up... 17:46:55 INFO - Return code: 0 17:46:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:46: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')]} 17:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46: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')]} 17:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:55 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 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:55 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 17:46:55 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 17:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:55 INFO - 'HOME': '/Users/cltbld', 17:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:55 INFO - 'LOGNAME': 'cltbld', 17:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:55 INFO - 'MOZ_NO_REMOTE': '1', 17:46:55 INFO - 'NO_EM_RESTART': '1', 17:46:55 INFO - 'PAGER': '/bin/cat', 17:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:55 INFO - 'PWD': '/builds/slave/test', 17:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:55 INFO - 'SHELL': '/bin/bash', 17:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:55 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:55 INFO - 'USER': 'cltbld', 17:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:55 INFO - 'XPC_FLAGS': '0x0', 17:46:55 INFO - 'XPC_SERVICE_NAME': '0', 17:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:56 INFO - Downloading/unpacking jsonschema 17:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:46:58 INFO - Downloading/unpacking functools32 (from jsonschema) 17:46:58 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 17:46:58 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 17:46:58 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 17:46:58 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 17:46:58 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 17:46:58 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 17:46:58 INFO - Downloading functools32-3.2.3-2.tar.gz 17:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:46:59 INFO - warning: no files found matching '*.txt' 17:46:59 INFO - no previously-included directories found matching 'build' 17:46:59 INFO - no previously-included directories found matching 'dist' 17:46:59 INFO - no previously-included directories found matching '.git*' 17:46:59 INFO - Installing collected packages: jsonschema, functools32 17:46:59 INFO - Running setup.py install for functools32 17:46:59 INFO - warning: no files found matching '*.txt' 17:46:59 INFO - no previously-included directories found matching 'build' 17:46:59 INFO - no previously-included directories found matching 'dist' 17:46:59 INFO - no previously-included directories found matching '.git*' 17:46:59 INFO - Successfully installed jsonschema functools32 17:46:59 INFO - Cleaning up... 17:46:59 INFO - Return code: 0 17:46:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108807a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108817420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec3bcb1530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, '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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 17:46:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:59 INFO - 'HOME': '/Users/cltbld', 17:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:59 INFO - 'LOGNAME': 'cltbld', 17:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:59 INFO - 'MOZ_NO_REMOTE': '1', 17:46:59 INFO - 'NO_EM_RESTART': '1', 17:46:59 INFO - 'PAGER': '/bin/cat', 17:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:59 INFO - 'PWD': '/builds/slave/test', 17:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:59 INFO - 'SHELL': '/bin/bash', 17:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:46:59 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:59 INFO - 'USER': 'cltbld', 17:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:59 INFO - 'XPC_FLAGS': '0x0', 17:46:59 INFO - 'XPC_SERVICE_NAME': '0', 17:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:59 INFO - Downloading/unpacking flake8 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:47:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:47:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:47:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:47:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:47:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:47:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:47:03 INFO - no previously-included directories found matching 'docs/_build' 17:47:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:47:03 INFO - Running setup.py install for pep8 17:47:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:47:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:47:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:47:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:47:03 INFO - no previously-included directories found matching 'docs/_build' 17:47:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:47:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:47:03 INFO - Cleaning up... 17:47:03 INFO - Return code: 0 17:47:03 INFO - Running post-action listener: _resource_record_post_action 17:47:03 INFO - Running post-action listener: _start_resource_monitoring 17:47:03 INFO - Starting resource monitoring. 17:47:03 INFO - ##### 17:47:03 INFO - ##### Running install step. 17:47:03 INFO - ##### 17:47:03 INFO - Running pre-action listener: _resource_record_pre_action 17:47:03 INFO - Running main action method: install 17:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:47:03 INFO - Reading from file tmpfile_stdout 17:47:03 INFO - Detecting whether we're running mozinstall >=1.0... 17:47:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:47:03 INFO - Reading from file tmpfile_stdout 17:47:03 INFO - Output received: 17:47:03 INFO - Usage: mozinstall [options] installer 17:47:03 INFO - Options: 17:47:03 INFO - -h, --help show this help message and exit 17:47:03 INFO - -d DEST, --destination=DEST 17:47:03 INFO - Directory to install application into. [default: 17:47:03 INFO - "/builds/slave/test"] 17:47:03 INFO - --app=APP Application being installed. [default: firefox] 17:47:03 INFO - mkdir: /builds/slave/test/build/application 17:47:03 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'] 17:47:03 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 17:47:37 INFO - Reading from file tmpfile_stdout 17:47:37 INFO - Output received: 17:47:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:47:37 INFO - Running post-action listener: _resource_record_post_action 17:47:37 INFO - ##### 17:47:37 INFO - ##### Running run-tests step. 17:47:37 INFO - ##### 17:47:37 INFO - Running pre-action listener: _resource_record_pre_action 17:47:37 INFO - Running main action method: run_tests 17:47:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:47:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:47:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:47:37 INFO - 2016-05-05 17:47:37.756 screenresolution[2204:34316] starting screenresolution argv=screenresolution get 17:47:37 INFO - 2016-05-05 17:47:37.758 screenresolution[2204:34316] Display 0: 1600x1200x32@60 17:47:37 INFO - 2016-05-05 17:47:37.764 screenresolution[2205:34319] starting screenresolution argv=screenresolution list 17:47:37 INFO - Available Modes on Display 0 17:47:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:47:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:47:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:47:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:47:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:47:37 INFO - Intel Iris: 17:47:37 INFO - Chipset Model: Intel Iris 17:47:37 INFO - Type: GPU 17:47:37 INFO - Bus: Built-In 17:47:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:47:37 INFO - Vendor: Intel (0x8086) 17:47:37 INFO - Device ID: 0x0a2e 17:47:37 INFO - Revision ID: 0x0009 17:47:37 INFO - Displays: 17:47:37 INFO - Display: 17:47:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:47:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:47:38 INFO - Display Serial Number: 1600x1200 60 17:47:38 INFO - Main Display: Yes 17:47:38 INFO - Mirror: Off 17:47:38 INFO - Online: Yes 17:47:38 INFO - Rotation: Supported 17:47:38 INFO - Return code: 0 17:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:47:38 INFO - Python 2.7.3 17:47:38 INFO - Return code: 0 17:47:38 INFO - grabbing minidump binary from tooltool 17:47:38 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')]} 17:47:38 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 0x1086a6e00>, '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 0x1084cfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:47:38 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 17:47:38 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 17:47:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:47:38 INFO - Return code: 0 17:47:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:47:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:47:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:47:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:47:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:47:39 INFO - Return code: 0 17:47:39 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/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 17:47:39 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/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0053 --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 17:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uv3xRNQ4w7/Render', 17:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:39 INFO - 'HOME': '/Users/cltbld', 17:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:39 INFO - 'LOGNAME': 'cltbld', 17:47:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:47:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:39 INFO - 'MOZ_NO_REMOTE': '1', 17:47:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:47:39 INFO - 'NO_EM_RESTART': '1', 17:47:39 INFO - 'PAGER': '/bin/cat', 17:47:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:39 INFO - 'PWD': '/builds/slave/test', 17:47:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:39 INFO - 'SHELL': '/bin/bash', 17:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bFJlweA0ZW/Listeners', 17:47:39 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 17:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:39 INFO - 'USER': 'cltbld', 17:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:39 INFO - 'XPC_FLAGS': '0x0', 17:47:39 INFO - 'XPC_SERVICE_NAME': '0', 17:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:39 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/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 17:47:39 INFO - mozversion application_buildid: 20160505165542 17:47:39 INFO - mozversion application_changeset: 8000d7a6ff44b128850bd4d08e0d1867f6df557d 17:47:39 INFO - mozversion application_display_name: Nightly 17:47:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:47:39 INFO - mozversion application_name: Firefox 17:47:39 INFO - mozversion application_remotingname: firefox 17:47:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:47:39 INFO - mozversion application_vendor: Mozilla 17:47:39 INFO - mozversion application_version: 49.0a1 17:47:39 INFO - mozversion platform_buildid: 20160505165542 17:47:39 INFO - mozversion platform_changeset: 8000d7a6ff44b128850bd4d08e0d1867f6df557d 17:47:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:47:39 INFO - mozversion platform_version: 49.0a1 17:47:39 INFO - using testdate: 1462495659 17:47:39 INFO - actual date: 1462495659 17:47:39 INFO - starting webserver on 'localhost:58782' 17:47:39 INFO - SUITE-START | Running 2 tests 17:47:39 INFO - TEST-START | dromaeo_css 17:47:39 INFO - operating with platform_type : mac_ 17:47:39 INFO - Initialising browser for dromaeo_css test... 17:47:39 INFO - TEST-INFO | started process 2223 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpDtGEI0/profile http://localhost:58782/getInfo.html) 17:47:41 INFO - PROCESS | 2223 | __metrics Screen width/height:1600/1200 17:47:41 INFO - PROCESS | 2223 | colorDepth:24 17:47:41 INFO - PROCESS | 2223 | Browser inner width/height: 1024/689 17:47:41 INFO - PROCESS | 2223 | __metrics 17:47:41 INFO - PROCESS | 2223 | 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" 17:47:41 ERROR - PROCESS | 2223 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:47:42 INFO - TEST-INFO | 2223: exit 0 17:47:42 INFO - Browser initialized. 17:47:42 INFO - Running cycle 1/1 for dromaeo_css test... 17:47:42 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpDtGEI0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:47:54 INFO - PROCESS | 2225 | RSS: Main: 239001600 17:47:54 INFO - PROCESS | 2225 | 17:48:46 INFO - PROCESS | 2225 | 1462495726151 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] 17:50:56 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58782/tests/dromaeo/cssquery-ext.html) 17:50:58 INFO - PROCESS | 2225 | RSS: Main: 373530624 17:50:58 INFO - PROCESS | 2225 | 17:54:01 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-ext.html (next: http://localhost:58782/tests/dromaeo/cssquery-jquery.html) 17:54:01 INFO - PROCESS | 2225 | RSS: Main: 377671680 17:54:01 INFO - PROCESS | 2225 | 17:57:04 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58782/tests/dromaeo/cssquery-mootools.html) 17:57:04 INFO - PROCESS | 2225 | RSS: Main: 372740096 17:57:04 INFO - PROCESS | 2225 | 18:00:07 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58782/tests/dromaeo/cssquery-prototype.html) 18:00:07 INFO - PROCESS | 2225 | RSS: Main: 371728384 18:00:07 INFO - PROCESS | 2225 | 18:03:10 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58782/tests/dromaeo/cssquery-yui.html) 18:03:11 INFO - PROCESS | 2225 | RSS: Main: 338845696 18:03:11 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58782/tests/dromaeo/cssquery-yui.html (next: http://localhost:58782/tests/dromaeo/cssquery-dojo.html) 18:06:14 INFO - PROCESS | 2225 | RSS: Main: 378126336 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | __start_tp_report 18:06:14 INFO - PROCESS | 2225 | _x_x_mozilla_page_load 18:06:14 INFO - PROCESS | 2225 | _x_x_mozilla_page_load_details 18:06:14 INFO - PROCESS | 2225 | |i|pagename|runs| 18:06:14 INFO - PROCESS | 2225 | |0;dojo.html;3448.948265776013;3469.8438570264334;3471.8958431247065;3489.2672538766856;3528.9117772055697;5691.658500489973;5703.771849126035;5736.942630573693;5753.65478071316;5764.36591974883;4793.424789025322;5740.282464691913;5798.855028624286;5832.154337621042;5841.299044114701;5204.141316682752;5253.159494480884;5278.155495120782;5292.629515933886;5330.92003619946;5334.653247538917;5357.1696387060065;5364.195370694388;5364.865878353034;5367.865803354908;5234.084035293828;5252.343457067866;5253.711045892474;5273.57811375091;5336.039512887679;5324.733763311858;5347.866303342409;5350.866228344323;5371.248025276464;5371.543418809406;5153.072446959546;5166;5174.637775355726;5259.368875734936;5279.815206467755;5661.688607126606;5674.829755107354;5699.116636921284;5701.059325211377;5727.541796656257;6256.562040657156;6261;6270.6864656766975;6276.278228003746;6287.559870809046;6130.141780150782;6146.371211628922;6238.4697300729495;6240.719167637467;6260.311365749764;5970.611910225822;5970.701464926737;5986.311574168939;5987.970060149672;5994.3705910878725;6356.078368636522;6361.650109243943;6368.235811702625;6373.681315934185;6387.904181437282;5203.725087353622;6136.488442913466;6141.324454310023;6151.384861513813;6183.103449999815;5243.68537887728;6186.969065154645;6247.625142491465;6249.281332646804;6261.123442718023;6221.688915554204;6227;6230.439260466489;6250.031245157008;6266.373362663697;5076.44159142494;6093.421124993119;6177.042558403455;6219.875602488016;6222.844428889223;4975.278584605212;5009.073321435546;5017.222330538772;5021.297018417425;5056.367954005749;4517.774111294422;4522.909541809146;4535.795889184995;4559.133764584718;4593.885152871138;5812.82561523155;5877.853053673607;5877.853053673693;5918.940810591839;5922.822315330461;6110.3765049759795;6202.689865506707;6235.220597425322;6244.937550624527;6291.370862913764;5931.169636250928;5953.642781433128;5960.5827592068135;5967.075103358986;5982.252218472691;5498.477644623755;5511.669299842022;5516.586256030814;5525.309336332958;5542.806001789999;6209.348018458088;6404.199475065617;6415.743370265137;6486.610803351814;6549.6070235786;6273.153124328157;6283.49732021428;6285.2457705075685;6289.213848268966;6298.496120310272;6265.060240963892;6520.706568204537;6524.67376631176;6547.377999090078;6573.671316434255;6123.679189354688;6129.877402451837;6138;6166.0442631390415;6186.783462578788;6275.309715931336;6276.497880169667;6287.088372185916;6292.182016337847;6308.179936608395;5549.417311182267;6065.72704228302;6092.786752463642;6201.751929922933;6203;6108.5723999319625;6161.5686901918225;6164.7842325518395;6165.753369865155;6171.259448866164;6064.423879731418;6074.696265186634;6079.361667025076;6082.574219804571;6087.3067570994335;5980.730867110903;6110.528382738247;6137.22377000893;6156.168917196122;6204.627722336674;5938.257717785277;5997.13041608973;6019.217501724747;6062.787802426894;6120.051392034242 18:06:14 INFO - PROCESS | 2225 | |1;ext.html;492.72899905052225;500.312070902509;512.7974450092212;560.9594202753891;565.3018522125172;30251.697483025164;43190.136197276035;44689.88275293115;44693.55306446934;47053.529464705345;44988;45198.77400613001;45269;45455.31817022739;45568;8569.785755356104;9610.855837162431;9800.284950133733;9800.509974501247;9809.50952452378;8609.5695215239;11620.941895290513;11979.401029948467;11994.9804266637;12093.516259349615;11874.16880818343;11955.820662690066;11972.982296504808;11980.70048248792;11982.64052078439;8681.696140635047;11526.769464610661;11795.882041179564;11805.468753906094;11998.52005919762;11837.467313970892;11838.644840654708;11855.46650400734;11858.644240672793;11887.881121188764;40796.79601601973;41173;41237.58762412368;41261.587384126076;41305.79347103275;3288.325893191914;3434.553508043938;3793.65857072862;3881.9029524261837;4102.856400025985;29471.41057178866;29851.701482985325;30164.8491757542;30226;30919.227019324473;2702.2028501592076;2770.362816552184;2772.0575004498364;2784.470950519415;2811.1285501494604;476412.6179369116;477435;478321;479326.6033669809;480239;35721.82139089288;62615;64237.67881160658;64570.67714661491;64617.67691161514;36271;37945.430818537745;37971.240575188895;37972.67095651639;37992.62007379946;15179.013364131517;15275.083494990336;15299.694006120038;15308.081515109128;15327.61680957985;11589.304641721523;11845.76308473843;12575.811362829565;12809.423575939105;12841.486340546273;8316.417850750511;8334.62494187763;8343.791405214797;8415.579221038923;8418.368622353348;3385.593728752516;3410.8124053176816;3413.965860341365;3416.0947348952545;3454.9827250863586;871.329076611006;906.3791302957363;969.5055521683992;1023.2428003277521;1169.3159501691605;30012.849935750182;30208.848955755082;30459.8477007618;30498.84750576233;30630;1099.8075336816025;1115.7322242661703;1117.1900372230068;1122.2237171916606;1245.7944439167436;15460.768088478682;15464.5360639181;15464.613384665474;15546.611334716496;15722.383821937945;668785;669020;670927;675687;678254;60356;62503.6874815623;62627.6868615654;62699;62896.37103628905;1298.577962162301;1348.952786652482;1438.3096113865195;1454.4545795326753;1473.4916453823414;29257;31175.532367014966;31289;31561.36877262488;31695.04914852558;52482.47517524776;53257;53277.73361133169;53446.93106137778;53510.197347039044;489978;492509;492535;492710;493403;27031.72968270292;27802.887884484393;28219.2945176364;28597.42805143927;29517;46229;46932.7653361731;47631.76184119196;48756;48972.02055958932;37722.43416348702;38739.612603873604;39067.827965161094;39665.80167099146;39949.80025099856;38355.808220958716;38549;38762.8371148866;39312.41031384589;39486.605133949444;38234.80882595569;38484.42273365846;39411.60588394079;39455.80272098621;39508.2098358037;29890.850545747133;33268.66731332656;33385;34202.82898585491;34915.30169396649;23922.214832389007;24360.756392435847;24909.128180513595;25105.49789004173;25243.49513009766 18:06:14 INFO - PROCESS | 2225 | |2;jquery.html;390.7284437316065;393.1978763322821;395.51944387569097;398.28309043721293;417.1885682347835;19600.019999000047;19777;20799.896000519995;20831.27090551831;20850.687239691415;19817.603647927033;19995;21445.892770536124;21536.569268614658;21621.567568648617;5083.949160508403;5451.890962180754;5459.69971651559;5489.451054894498;5493.121100623901;5384.973075134619;5413.891722165555;5488.368837583669;5493.285872836525;5495.148252020923;5322.4411436799155;5450.155225939986;5451.454854514556;5463;5483.725813709319;5374.838754837322;5419.945800541984;5451.318585176853;5457.345118585756;5473.124300111983;5121.129408000612;5283.233931079972;5323.653962492425;5326.840194794162;5338.91991620126;13498.257595832225;13625.795613065811;13706.93146534261;13913.67820057093;13914.09558378702;2150.827933765295;2283.7830406111393;2431.5866302728577;2680.7855371570226;2757.5450050741806;12176.81734773979;14107.929460352736;14859.331330090172;14894.420055223381;15232.847671523254;1622.951311460658;1626.8129165145922;1630.1930544380489;1681.8889516952654;1686.3844696685744;94725;95785;106915;108716.99999999921;113025;9735.318527702993;11429.428528573539;11585.899339562728;11694.94152529249;11850.762984740257;13461.461541538229;15089.094654320776;15115.168665723491;15140.772888406682;15169.24153792306;7589.468737188344;7895.131535531087;8183.549904755296;8362.829203911457;8684.913150868537;7503.836905279691;8091.271785539329;8541.23128918388;8578.91421085781;8624.09447008055;4546.545345465427;4658.99663144851;4674.509176536482;4685.086408150446;4740.099381117577;1919.7888232294436;1969.6651569233265;1969.6848504239254;1972.5364539333245;2004.662237374784;838.3628442383832;912.452528482905;932.4545141092401;989.569537251298;1009.6819501857038;15953.208188566954;17332.220050097774;18956.43130706081;19789.703154452694;20539.897300513403;805.7341077444429;924.5700749151677;929.3030227329502;955.9330846840654;963.6001059560203;10508.264421490576;10704.89295107039;10728.302660327054;10738.516766745515;10748;604544;611577;614550;619330;620098;101949.98050019554;130312.3484382591;132902;134392.99999999805;137720;877.4647465046316;1014.2089170447063;1022.0392830738933;1038.5015192707392;1038.7299302181486;17665.735013974543;17918.641627167643;17920;17940.820591793916;18335.90832045831;49151.75424122856;50451.49548504615;50865.49134508608;51874.48125518696;51885.48114518807;60948.17155485343;72331.63834180795;73163.26836731564;73412.96228707259;74550.62724686341;7649.732259370895;8127.349812014906;8170.591470426574;8412.495250285003;8438.738531881101;9040.63837446521;10231.721034870641;12489.375531223584;12810.55163069288;12876.678083047998;9782.657606983721;10518.421486818299;10805.513751881026;10923;11206.551737930391;10735.94632026835;10824.621138260123;10919.290246133976;11040;11117.94379533943;9580.425174489554;10724.088452481627;10851.945740271249;11422.143339249757;11502.82745758831;8134.186581342055;8288.70989515364;8294.502329860228;8295.585220738818;8355.415120941476;9775;10054.748631284276;10780.730481738019;11322;11331.943340283246 18:06:14 INFO - PROCESS | 2225 | |3;mootools.html;1421.1189062781077;1590.9840901590985;1605.0851014921495;1823.8176182381762;1968.4980330015844;7836.451448398614;8557.871631925518;8804.251638610709;8823.779405514866;8930.12467381849;8583;8839.823203535942;8861.291096712264;8862.246709029723;8863.069377715343;1956.8825870447747;2166.8916554172306;2181.923632672857;2438.5854404751144;2473.0602371098958;2234.7430045544806;2261.9321420357414;2264.4225722440756;2371.122684606698;2401;2437.171361737009;2443.2059580636273;2450.742672019439;2451.6935383077116;2456.9385765355833;2410.20463247128;2448.816338774599;2460.9138680146107;2461.618449140386;2462.987685061581;2146.8926553672254;2393.6768536247732;2461;2463.2117722328862;2474.1093206445667;8662.13378662135;8747.518886461243;8764.649414023432;8769.035406105388;8774.473531588124;5293.7617807198385;5499.917501237484;5510.5316048135555;5511.889762204773;5527.53015993641;3166.889158879428;3496.4405695088662;3538.5046093546925;3628.7641303315463;3636.6363363663686;852.9914700852975;980.3529670417562;1031.4687935713114;1038.4755698372348;1045.6706137566719;11740.647780566596;11742.295462272292;11795.646130616095;11799.056075513938;11833.349165795964;4739.691920025188;4857.7085374876915;4937.654364194544;4952.653314268039;4953.578945789576;10105.797884042426;10111.59553617847;10122.54448549817;10134.898651013396;10149.949250253701;5519.641223320471;5555;5555.2778138841695;5557.36090349615;5568.025595520768;4496.572825581565;4929.408470983505;4939.703617782872;4990.8752281193265;5093.847184584469;3331.3670402623425;3886.805659717003;3886.84452621892;3892.4550562921213;3893.299206142865;1289.8516670582815;1342.8455727591256;1362.4590139389966;1367.2138520350838;1370.7943808428615;786.6853258696567;813.556611646654;929.4144688845984;938.8450905600499;949.2453499467939;5773.3072031356505;5835.970820145957;5846.269216347957;5866.1494083357675;5963.582549221513;860.8794768732382;976.2239019979133;991.2664628175098;999.4602914426274;1061.3366645846345;3456.395130852141;3820.7516511427225;3869.8197049899486;3871.341871881787;3944.487216661873;8565.828683426422;10335.741606459898;10739.194560408001;10767.515461804238;10776.78446431067;10409.53157107932;10691.893081069245;10694.465276736131;10700.250982431311;10709.625163119254;7986.241307075818;8151.755447336471;8249.8350032999;8376.455530390505;8383.580820958927;5759.337676167127;5854.209681692917;5906.763729450946;5945.494632956074;6002.159697642421;9768;9996.750081248027;9998.500074996076;10102.595896164365;10110.646127385506;8933.240674542476;9655.468949207861;9878.357906736039;10293;10303.845442318221;6961.512694111364;6989.7553585624255;7101;7101.609411482212;7122.18094919091;6886.173659160732;7102.644867756489;7496.912947622455;8156.755297341089;8212.507249565046;7348.926510734825;7404.370628496638;7424.406047516294;7543.7736867894055;7547.773566793005;7251.658443188027;7345.265473452612;7346.853062938818;7354.227806080285;7487.3261406473675;6885.380315771605;6960.199577048705;7041.753538626123;7054.8236294091;7056.717731290896;4857.757112144308;4915.754212289443;4980.8754781130765;5019.824306149268;5021.096202683552;4181.372794080912;4960.925586116139;5006.324146240357;5043.041822053798;5129.204973229155 18:06:14 INFO - PROCESS | 2225 | |4;prototype.html;307.2579719976256;309.9442100421924;325.4889822977924;327.1608324647281;329.90762586475796;4102.6717862570995;4285.442892423988;4748.287756836477;5076.771545280462;5084.745762711868;4749.311349854278;4784.354112194853;4829.492903245153;4916.082727736186;5048.49515048495;3334.7832390894637;3621.420572708367;3884.3685652026215;3886.3587508061137;3913.6477717005464;3425.845836937331;3888.938862402931;3952.8221230044574;3964.1170444038994;3970.3795494973324;3035.7232075943043;3712.7029837613063;3736.9626303737023;3922.901587555489;3935.291647503448;3251.561039259693;3855;3866.955921901099;3893.683258461211;3945.053187235057;2912.6650435200013;3277.901902862544;3402.5406570112964;3403.9149021274666;3459.515667806509;3815.7138214634015;4344.978275108636;4454.198244316021;4849.102915960559;4885.902281954376;4030.879073627796;4163.333866581333;4338.284183109783;4404.229259879508;4444.31113177458;4893.602134953882;5249.816256431007;5265.52610265078;5310.336207974004;5352.564583114673;3439.518467414564;3545.3441113394106;3686.6313368663186;3688.926221475582;3731.0299322176156;5285.894282114375;5495.835124946258;5559.77760889568;5577.581681373913;5585.692786896719;3224.596925384327;3484.564429446319;3741.7006639468777;3741.8961406385183;3767.585565587783;3808.27642747877;3813.275477659236;3829.6553310202216;3836.156045669948;3867.57456679765;4206.284931561642;4378.890527736832;4407.250767369491;4458.7547684877645;4461.196984542814;4588.990422107131;5232.848773269875;5328.813491527778;5336.839894803162;5350.38470575881;1118.6196693124377;1154.4054811771944;1158.164309569342;1194.635636130971;1228.5147366790068;773.072313224122;796.4464697035505;829.0714399872061;829.9045609754955;844.6030365728097;4055.41196526502;4123.134141830249;4239.173361194537;4721.976390118096;4756.215224487921;4303.591158839905;4427.446569178853;4923.335349727812;5195.454477279831;5217.06092903281;3877.534695836461;4607.723536587815;4646.4191976003;4675.462321833033;4711.882202944954;2316.2009106858445;2417.8791060446906;2672.385351369196;2687.6909155446983;2705.4589082183247;4474.009065965614;4614.492405835355;4944;5138.53350391814;5178.6116041297055;4371.303677037801;4606.861794146181;4619.930701039487;5101.897962040738;5251.789928402897;5130.7178105204575;5329.214713071087;5339.196921616182;5351.170568561802;5366.302380690485;4388.429504164438;4670.229412147093;4773.9761301193275;4823.782929768098;4964.609909225423;3957.485526881487;4666.206744853315;4870.610351171968;5101.51535604117;5106.897862042812;5333.493318134771;5371.382291036424;5384.949934762683;5393.514583687487;5410.675359478444;3173.7461003120156;3213.501907204387;3428.879989200366;3513.80674062929;3534.611192768845;4432.73667004902;4511.323301504801;4552.362669226377;4952.087850598131;5223.250211179297;4584.4498660161;4711.858644240679;4743.976280118577;4800.975995120002;5006.649534532694;4966.106100901728;4969.602431805519;4982.427020892644;5004;5004.949950500594;4226.556211597738;4553.954460455353;4604.953950460586;4625.861224163281;4901.627544287605;4556.6126879215635;4882.414110306786;4979.153543897619;5004.3994720633755;5008.298838162587;4343.652507799432;4424.469063712375;4536.3915748444115;4727.929081063718;4734.905301894012 18:06:14 INFO - PROCESS | 2225 | |5;yui.html;656.2715385921626;750.4184257200669;752.6000943232307;753.5328096580117;763.1147868472573;14915.627109322259;14942.77585836212;15929.761053584205;15958.521244362659;15959;14498.347574359153;14621;14670.926645366784;14686.045407048534;14695.044822086556;669.3406994110796;757.7537300377386;932.9626814927394;936.1527817325332;936.6862101196074;763.6372722956579;917.5366439947817;939.7979434421597;940.7601061729283;952.8618350339196;992.5880759484842;994.368575954267;1000.4747507558517;1001.7545701303154;1004.70361243433;777.9727709530139;955.9283053770995;996.1035068438391;996.8255555277798;997.8702768640067;731.1153504259852;731.8755811512056;732.479939243138;734.9412047036225;739.4786675393834;15096.849031509653;15188.848111518853;15189.924050379677;15201.315940782692;15283.694326113415;12307.384630768425;12344.13591048627;12484.937575312157;12694.682632934158;12812.487500499969;10897.073748731287;10948.835767463494;10972.451377431096;11050.723731906686;11071.058959988412;3379.797212167278;3506.193575477629;3731.7201209909094;3973.9006524836823;4378.540253273392;84662;85651;85698;85836.57081714552;86127.56936215279;9371.671991480265;9379.169415277558;9512.952435237918;9548;9786.706398807904;20339.8983005084;20558.177672893216;20600;20601.690974635392;20683.27608533694;7475.327220550176;7482.2517748224745;7504.812379690442;7562.773116806505;7709.614519274014;4028.939565906514;4113.958860411418;4163.979180104141;4166.812493437803;4241.618254357061;3461.394256005189;3577.3918433865783;3688.3360995020616;3692.132348898007;3697.2235830475365;658.4962503684685;673.6530686696356;674.069783698493;677.3125277842993;678.0575000748963;565.1663795901095;583.3087790967717;599.5263741644052;604.6523249131767;627.5262177056293;7114.964425177841;8018.959905200437;8020;8360.414770965974;8412.873806892901;519.6829933740415;583.8627922438224;594.3610618584952;618.2580902916518;622.0296337713182;3406.489026646023;3782.9596860862935;3843.3082045231567;3884.864029758945;3914;79460.60269698614;83338.16661833304;83433;85938;87116.56441717877;74226.62886685532;74660.62669686617;74937.6253118731;75989.62005189939;76905;66668.33331666622;66871.66564167148;67331.66334168297;67377;69652;7623.390128789794;7747.612619369122;7872.252136047066;8038.356931445587;8223.01323841143;43640;43714.90712732207;43757.78121109501;43824.56175438205;44422.777886110365;60383.396166037775;60978.69510652418;61299.38700612937;61323;61328.08007879796;10177.287589868638;10413.791724165625;10542.57829686804;10556.841647375142;10581.841272380767;11171.776564468828;11179.832302515306;11197.72005699864;11220.66338009861;11664.475098620413;10420.270581059252;10504.684859454228;10562.207834412604;10596.576136954436;10990.890091098987;10256.28206025601;10317.071463568314;10419.739506512398;10647.733806654896;10796.514156863117;10384.688459346231;10396.688099357032;10443.791124177322;10753.89246107529;10858.968398002176;7892.329152022143;8507.234348908629;8571.528565928933;8784.033756286675;8795.736127916172;8711.782205444915;8790;8875.911240887508;9048.592813323285;9074.500902450165 18:06:14 INFO - PROCESS | 2225 | __end_tp_report 18:06:14 INFO - PROCESS | 2225 | __start_cc_report 18:06:14 INFO - PROCESS | 2225 | _x_x_mozilla_cycle_collect,1406 18:06:14 INFO - PROCESS | 2225 | __end_cc_report 18:06:14 INFO - PROCESS | 2225 | __startTimestamp1462496774903__endTimestamp 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | ------- Summary: start ------- 18:06:14 INFO - PROCESS | 2225 | Number of tests: 6 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#0] dojo.html Cycles:165 Average:5792.39 Median:6019.22 stddev:626.54 (10.4%) stddev-sans-first:600.89 18:06:14 INFO - PROCESS | 2225 | Values: 3448.9 3469.8 3471.9 3489.3 3528.9 5691.7 5703.8 5736.9 5753.7 5764.4 4793.4 5740.3 5798.9 5832.2 5841.3 5204.1 5253.2 5278.2 5292.6 5330.9 5334.7 5357.2 5364.2 5364.9 5367.9 5234.1 5252.3 5253.7 5273.6 5336.0 5324.7 5347.9 5350.9 5371.2 5371.5 5153.1 5166.0 5174.6 5259.4 5279.8 5661.7 5674.8 5699.1 5701.1 5727.5 6256.6 6261.0 6270.7 6276.3 6287.6 6130.1 6146.4 6238.5 6240.7 6260.3 5970.6 5970.7 5986.3 5988.0 5994.4 6356.1 6361.7 6368.2 6373.7 6387.9 5203.7 6136.5 6141.3 6151.4 6183.1 5243.7 6187.0 6247.6 6249.3 6261.1 6221.7 6227.0 6230.4 6250.0 6266.4 5076.4 6093.4 6177.0 6219.9 6222.8 4975.3 5009.1 5017.2 5021.3 5056.4 4517.8 4522.9 4535.8 4559.1 4593.9 5812.8 5877.9 5877.9 5918.9 5922.8 6110.4 6202.7 6235.2 6244.9 6291.4 5931.2 5953.6 5960.6 5967.1 5982.3 5498.5 5511.7 5516.6 5525.3 5542.8 6209.3 6404.2 6415.7 6486.6 6549.6 6273.2 6283.5 6285.2 6289.2 6298.5 6265.1 6520.7 6524.7 6547.4 6573.7 6123.7 6129.9 6138.0 6166.0 6186.8 6275.3 6276.5 6287.1 6292.2 6308.2 5549.4 6065.7 6092.8 6201.8 6203.0 6108.6 6161.6 6164.8 6165.8 6171.3 6064.4 6074.7 6079.4 6082.6 6087.3 5980.7 6110.5 6137.2 6156.2 6204.6 5938.3 5997.1 6019.2 6062.8 6120.1 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#1] ext.html Cycles:180 Average:67953.67 Median:20758.05 stddev:148350.51 (714.7%) stddev-sans-first:148680.20 18:06:14 INFO - PROCESS | 2225 | Values: 492.7 500.3 512.8 561.0 565.3 30251.7 43190.1 44689.9 44693.6 47053.5 44988.0 45198.8 45269.0 45455.3 45568.0 8569.8 9610.9 9800.3 9800.5 9809.5 8609.6 11620.9 11979.4 11995.0 12093.5 11874.2 11955.8 11973.0 11980.7 11982.6 8681.7 11526.8 11795.9 11805.5 11998.5 11837.5 11838.6 11855.5 11858.6 11887.9 40796.8 41173.0 41237.6 41261.6 41305.8 3288.3 3434.6 3793.7 3881.9 4102.9 29471.4 29851.7 30164.8 30226.0 30919.2 2702.2 2770.4 2772.1 2784.5 2811.1 476412.6 477435.0 478321.0 479326.6 480239.0 35721.8 62615.0 64237.7 64570.7 64617.7 36271.0 37945.4 37971.2 37972.7 37992.6 15179.0 15275.1 15299.7 15308.1 15327.6 11589.3 11845.8 12575.8 12809.4 12841.5 8316.4 8334.6 8343.8 8415.6 8418.4 3385.6 3410.8 3414.0 3416.1 3455.0 871.3 906.4 969.5 1023.2 1169.3 30012.8 30208.8 30459.8 30498.8 30630.0 1099.8 1115.7 1117.2 1122.2 1245.8 15460.8 15464.5 15464.6 15546.6 15722.4 668785.0 669020.0 670927.0 675687.0 678254.0 60356.0 62503.7 62627.7 62699.0 62896.4 1298.6 1349.0 1438.3 1454.5 1473.5 29257.0 31175.5 31289.0 31561.4 31695.0 52482.5 53257.0 53277.7 53446.9 53510.2 489978.0 492509.0 492535.0 492710.0 493403.0 27031.7 27802.9 28219.3 28597.4 29517.0 46229.0 46932.8 47631.8 48756.0 48972.0 37722.4 38739.6 39067.8 39665.8 39949.8 38355.8 38549.0 38762.8 39312.4 39486.6 38234.8 38484.4 39411.6 39455.8 39508.2 29890.9 33268.7 33385.0 34202.8 34915.3 23922.2 24360.8 24909.1 25105.5 25243.5 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#2] jquery.html Cycles:180 Average:34384.81 Median:2556.19 stddev:102045.94 (3992.1%) stddev-sans-first:102300.28 18:06:14 INFO - PROCESS | 2225 | Values: 390.7 393.2 395.5 398.3 417.2 19600.0 19777.0 20799.9 20831.3 20850.7 19817.6 19995.0 21445.9 21536.6 21621.6 5083.9 5451.9 5459.7 5489.5 5493.1 5385.0 5413.9 5488.4 5493.3 5495.1 5322.4 5450.2 5451.5 5463.0 5483.7 5374.8 5419.9 5451.3 5457.3 5473.1 5121.1 5283.2 5323.7 5326.8 5338.9 13498.3 13625.8 13706.9 13913.7 13914.1 2150.8 2283.8 2431.6 2680.8 2757.5 12176.8 14107.9 14859.3 14894.4 15232.8 1623.0 1626.8 1630.2 1681.9 1686.4 94725.0 95785.0 106915.0 108717.0 113025.0 9735.3 11429.4 11585.9 11694.9 11850.8 13461.5 15089.1 15115.2 15140.8 15169.2 7589.5 7895.1 8183.5 8362.8 8684.9 7503.8 8091.3 8541.2 8578.9 8624.1 4546.5 4659.0 4674.5 4685.1 4740.1 1919.8 1969.7 1969.7 1972.5 2004.7 838.4 912.5 932.5 989.6 1009.7 15953.2 17332.2 18956.4 19789.7 20539.9 805.7 924.6 929.3 955.9 963.6 10508.3 10704.9 10728.3 10738.5 10748.0 604544.0 611577.0 614550.0 619330.0 620098.0 101950.0 130312.3 132902.0 134393.0 137720.0 877.5 1014.2 1022.0 1038.5 1038.7 17665.7 17918.6 17920.0 17940.8 18335.9 49151.8 50451.5 50865.5 51874.5 51885.5 60948.2 72331.6 73163.3 73413.0 74550.6 7649.7 8127.3 8170.6 8412.5 8438.7 9040.6 10231.7 12489.4 12810.6 12876.7 9782.7 10518.4 10805.5 10923.0 11206.6 10735.9 10824.6 10919.3 11040.0 11117.9 9580.4 10724.1 10851.9 11422.1 11502.8 8134.2 8288.7 8294.5 8295.6 8355.4 9775.0 10054.7 10780.7 11322.0 11331.9 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#3] mootools.html Cycles:180 Average:5681.85 Median:4998.60 stddev:3164.91 (63.3%) stddev-sans-first:3157.59 18:06:14 INFO - PROCESS | 2225 | Values: 1421.1 1591.0 1605.1 1823.8 1968.5 7836.5 8557.9 8804.3 8823.8 8930.1 8583.0 8839.8 8861.3 8862.2 8863.1 1956.9 2166.9 2181.9 2438.6 2473.1 2234.7 2261.9 2264.4 2371.1 2401.0 2437.2 2443.2 2450.7 2451.7 2456.9 2410.2 2448.8 2460.9 2461.6 2463.0 2146.9 2393.7 2461.0 2463.2 2474.1 8662.1 8747.5 8764.6 8769.0 8774.5 5293.8 5499.9 5510.5 5511.9 5527.5 3166.9 3496.4 3538.5 3628.8 3636.6 853.0 980.4 1031.5 1038.5 1045.7 11740.6 11742.3 11795.6 11799.1 11833.3 4739.7 4857.7 4937.7 4952.7 4953.6 10105.8 10111.6 10122.5 10134.9 10149.9 5519.6 5555.0 5555.3 5557.4 5568.0 4496.6 4929.4 4939.7 4990.9 5093.8 3331.4 3886.8 3886.8 3892.5 3893.3 1289.9 1342.8 1362.5 1367.2 1370.8 786.7 813.6 929.4 938.8 949.2 5773.3 5836.0 5846.3 5866.1 5963.6 860.9 976.2 991.3 999.5 1061.3 3456.4 3820.8 3869.8 3871.3 3944.5 8565.8 10335.7 10739.2 10767.5 10776.8 10409.5 10691.9 10694.5 10700.3 10709.6 7986.2 8151.8 8249.8 8376.5 8383.6 5759.3 5854.2 5906.8 5945.5 6002.2 9768.0 9996.8 9998.5 10102.6 10110.6 8933.2 9655.5 9878.4 10293.0 10303.8 6961.5 6989.8 7101.0 7101.6 7122.2 6886.2 7102.6 7496.9 8156.8 8212.5 7348.9 7404.4 7424.4 7543.8 7547.8 7251.7 7345.3 7346.9 7354.2 7487.3 6885.4 6960.2 7041.8 7054.8 7056.7 4857.8 4915.8 4980.9 5019.8 5021.1 4181.4 4960.9 5006.3 5043.0 5129.2 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#4] prototype.html Cycles:180 Average:4114.51 Median:4544.38 stddev:1228.43 (27.0%) stddev-sans-first:1198.17 18:06:14 INFO - PROCESS | 2225 | Values: 307.3 309.9 325.5 327.2 329.9 4102.7 4285.4 4748.3 5076.8 5084.7 4749.3 4784.4 4829.5 4916.1 5048.5 3334.8 3621.4 3884.4 3886.4 3913.6 3425.8 3888.9 3952.8 3964.1 3970.4 3035.7 3712.7 3737.0 3922.9 3935.3 3251.6 3855.0 3867.0 3893.7 3945.1 2912.7 3277.9 3402.5 3403.9 3459.5 3815.7 4345.0 4454.2 4849.1 4885.9 4030.9 4163.3 4338.3 4404.2 4444.3 4893.6 5249.8 5265.5 5310.3 5352.6 3439.5 3545.3 3686.6 3688.9 3731.0 5285.9 5495.8 5559.8 5577.6 5585.7 3224.6 3484.6 3741.7 3741.9 3767.6 3808.3 3813.3 3829.7 3836.2 3867.6 4206.3 4378.9 4407.3 4458.8 4461.2 4589.0 5232.8 5328.8 5336.8 5350.4 1118.6 1154.4 1158.2 1194.6 1228.5 773.1 796.4 829.1 829.9 844.6 4055.4 4123.1 4239.2 4722.0 4756.2 4303.6 4427.4 4923.3 5195.5 5217.1 3877.5 4607.7 4646.4 4675.5 4711.9 2316.2 2417.9 2672.4 2687.7 2705.5 4474.0 4614.5 4944.0 5138.5 5178.6 4371.3 4606.9 4619.9 5101.9 5251.8 5130.7 5329.2 5339.2 5351.2 5366.3 4388.4 4670.2 4774.0 4823.8 4964.6 3957.5 4666.2 4870.6 5101.5 5106.9 5333.5 5371.4 5384.9 5393.5 5410.7 3173.7 3213.5 3428.9 3513.8 3534.6 4432.7 4511.3 4552.4 4952.1 5223.3 4584.4 4711.9 4744.0 4801.0 5006.6 4966.1 4969.6 4982.4 5004.0 5004.9 4226.6 4554.0 4605.0 4625.9 4901.6 4556.6 4882.4 4979.2 5004.4 5008.3 4343.7 4424.5 4536.4 4727.9 4734.9 18:06:14 INFO - PROCESS | 2225 | 18:06:14 INFO - PROCESS | 2225 | [#5] yui.html Cycles:180 Average:17579.77 Median:30791.67 stddev:24556.58 (79.8%) stddev-sans-first:24592.59 18:06:14 INFO - PROCESS | 2225 | Values: 656.3 750.4 752.6 753.5 763.1 14915.6 14942.8 15929.8 15958.5 15959.0 14498.3 14621.0 14670.9 14686.0 14695.0 669.3 757.8 933.0 936.2 936.7 763.6 917.5 939.8 940.8 952.9 992.6 994.4 1000.5 1001.8 1004.7 778.0 955.9 996.1 996.8 997.9 731.1 731.9 732.5 734.9 739.5 15096.8 15188.8 15189.9 15201.3 15283.7 12307.4 12344.1 12484.9 12694.7 12812.5 10897.1 10948.8 10972.5 11050.7 11071.1 3379.8 3506.2 3731.7 3973.9 4378.5 84662.0 85651.0 85698.0 85836.6 86127.6 9371.7 9379.2 9513.0 9548.0 9786.7 20339.9 20558.2 20600.0 20601.7 20683.3 7475.3 7482.3 7504.8 7562.8 7709.6 4028.9 4114.0 4164.0 4166.8 4241.6 3461.4 3577.4 3688.3 3692.1 3697.2 658.5 673.7 674.1 677.3 678.1 565.2 583.3 599.5 604.7 627.5 7115.0 8019.0 8020.0 8360.4 8412.9 519.7 583.9 594.4 618.3 622.0 3406.5 3783.0 3843.3 3884.9 3914.0 79460.6 83338.2 83433.0 85938.0 87116.6 74226.6 74660.6 74937.6 75989.6 76905.0 66668.3 66871.7 67331.7 67377.0 69652.0 7623.4 7747.6 7872.3 8038.4 8223.0 43640.0 43714.9 43757.8 43824.6 44422.8 60383.4 60978.7 61299.4 61323.0 61328.1 10177.3 10413.8 10542.6 10556.8 10581.8 11171.8 11179.8 11197.7 11220.7 11664.5 10420.3 10504.7 10562.2 10596.6 10990.9 10256.3 10317.1 10419.7 10647.7 10796.5 10384.7 10396.7 10443.8 10753.9 10859.0 7892.3 8507.2 8571.5 8784.0 8795.7 8711.8 8790.0 8875.9 9048.6 9074.5 18:06:14 INFO - PROCESS | 2225 | -------- Summary: end -------- 18:06:14 INFO - PROCESS | 2225 | 18:06:15 INFO - TEST-INFO | 2225: exit 0 18:06:15 INFO - TEST-OK | dromaeo_css | took 1116058ms 18:06:15 INFO - TEST-START | kraken 18:06:15 INFO - operating with platform_type : mac_ 18:06:15 INFO - Initialising browser for kraken test... 18:06:15 INFO - TEST-INFO | started process 2228 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpsdJf8_/profile http://localhost:58782/getInfo.html) 18:06:17 INFO - PROCESS | 2228 | __metrics Screen width/height:1600/1200 18:06:17 INFO - PROCESS | 2228 | colorDepth:24 18:06:17 INFO - PROCESS | 2228 | Browser inner width/height: 1024/689 18:06:17 INFO - PROCESS | 2228 | __metrics 18:06:17 INFO - PROCESS | 2228 | 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" 18:06:17 ERROR - PROCESS | 2228 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:06:18 INFO - TEST-INFO | 2228: exit 0 18:06:18 INFO - Browser initialized. 18:06:18 INFO - Running cycle 1/1 for kraken test... 18:06:18 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpsdJf8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:30 INFO - PROCESS | 2230 | RSS: Main: 239161344 18:06:30 INFO - PROCESS | 2230 | 18:06:33 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58782/tests/kraken/driver.html?testName=audio-beat-detection) 18:06:33 INFO - PROCESS | 2230 | RSS: Main: 286846976 18:06:33 INFO - PROCESS | 2230 | 18:06:36 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58782/tests/kraken/driver.html?testName=audio-dft) 18:06:37 INFO - PROCESS | 2230 | RSS: Main: 295735296 18:06:37 INFO - PROCESS | 2230 | 18:06:40 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58782/tests/kraken/driver.html?testName=audio-fft) 18:06:41 INFO - PROCESS | 2230 | RSS: Main: 383516672 18:06:41 INFO - PROCESS | 2230 | 18:06:43 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58782/tests/kraken/driver.html?testName=audio-oscillator) 18:06:44 INFO - PROCESS | 2230 | RSS: Main: 281956352 18:06:44 INFO - PROCESS | 2230 | 18:06:46 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58782/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:06:46 INFO - PROCESS | 2230 | RSS: Main: 286232576 18:06:46 INFO - PROCESS | 2230 | 18:06:49 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58782/tests/kraken/driver.html?testName=imaging-darkroom) 18:06:50 INFO - PROCESS | 2230 | RSS: Main: 322973696 18:06:50 INFO - PROCESS | 2230 | 18:06:53 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58782/tests/kraken/driver.html?testName=imaging-desaturate) 18:06:53 INFO - PROCESS | 2230 | RSS: Main: 323624960 18:06:53 INFO - PROCESS | 2230 | 18:06:56 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58782/tests/kraken/driver.html?testName=json-parse-financial) 18:06:57 INFO - PROCESS | 2230 | RSS: Main: 322211840 18:06:57 INFO - PROCESS | 2230 | 18:06:58 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58782/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:06:59 INFO - PROCESS | 2230 | RSS: Main: 313073664 18:06:59 INFO - PROCESS | 2230 | 18:07:01 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:07:01 INFO - PROCESS | 2230 | RSS: Main: 319660032 18:07:01 INFO - PROCESS | 2230 | 18:07:04 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:07:04 INFO - PROCESS | 2230 | RSS: Main: 321617920 18:07:04 INFO - PROCESS | 2230 | 18:07:07 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:07:08 INFO - PROCESS | 2230 | RSS: Main: 326393856 18:07:08 INFO - PROCESS | 2230 | 18:07:11 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:07:11 INFO - PROCESS | 2230 | RSS: Main: 324198400 18:07:11 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:58782/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58782/tests/kraken/driver.html?testName=ai-astar) 18:07:13 INFO - PROCESS | 2230 | RSS: Main: 370745344 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | __start_tp_report 18:07:13 INFO - PROCESS | 2230 | _x_x_mozilla_page_load 18:07:13 INFO - PROCESS | 2230 | _x_x_mozilla_page_load_details 18:07:13 INFO - PROCESS | 2230 | |i|pagename|runs| 18:07:13 INFO - PROCESS | 2230 | |0;ai-astar;113;105;112;113;113;113;111;113;113;116 18:07:13 INFO - PROCESS | 2230 | |1;audio-beat-detection;158;119;145;191;141;136;140;116;168;112 18:07:13 INFO - PROCESS | 2230 | |2;audio-dft;197;186;186;186;183;188;185;182;182;182 18:07:13 INFO - PROCESS | 2230 | |3;audio-fft;81;73;58;108;58;62;59;66;137;79 18:07:13 INFO - PROCESS | 2230 | |4;audio-oscillator;95;102;101;80;79;78;103;81;80;104 18:07:13 INFO - PROCESS | 2230 | |5;imaging-gaussian-blur;88;84;88;83;86;83;89;85;88;83 18:07:13 INFO - PROCESS | 2230 | |6;imaging-darkroom;93;93;92;93;93;93;93;93;100;122 18:07:13 INFO - PROCESS | 2230 | |7;imaging-desaturate;84;85;84;86;86;85;84;86;85;86 18:07:13 INFO - PROCESS | 2230 | |8;json-parse-financial;60;61;60;58;58;57;57;74;59;57 18:07:13 INFO - PROCESS | 2230 | |9;json-stringify-tinderbox;66;77;67;76;72;71;70;73;72;71 18:07:13 INFO - PROCESS | 2230 | |10;stanford-crypto-aes;86;85;86;84;84;84;84;84;86;86 18:07:13 INFO - PROCESS | 2230 | |11;stanford-crypto-ccm;142;141;137;137;137;152;147;145;159;140 18:07:13 INFO - PROCESS | 2230 | |12;stanford-crypto-pbkdf2;168;167;168;167;168;170;167;174;219;199 18:07:13 INFO - PROCESS | 2230 | |13;stanford-crypto-sha256-iterative;66;67;66;69;81;84;84;64;58;81 18:07:13 INFO - PROCESS | 2230 | __end_tp_report 18:07:13 INFO - PROCESS | 2230 | __start_cc_report 18:07:13 INFO - PROCESS | 2230 | _x_x_mozilla_cycle_collect,2047 18:07:13 INFO - PROCESS | 2230 | __end_cc_report 18:07:13 INFO - PROCESS | 2230 | __startTimestamp1462496833916__endTimestamp 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | ------- Summary: start ------- 18:07:13 INFO - PROCESS | 2230 | Number of tests: 14 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:2.82 (2.5%) stddev-sans-first:2.98 18:07:13 INFO - PROCESS | 2230 | Values: 113.0 105.0 112.0 113.0 113.0 113.0 111.0 113.0 113.0 116.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:143.00 stddev:24.69 (17.3%) stddev-sans-first:25.55 18:07:13 INFO - PROCESS | 2230 | Values: 158.0 119.0 145.0 191.0 141.0 136.0 140.0 116.0 168.0 112.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:4.50 (2.4%) stddev-sans-first:2.24 18:07:13 INFO - PROCESS | 2230 | Values: 197.0 186.0 186.0 186.0 183.0 188.0 185.0 182.0 182.0 182.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#3] audio-fft Cycles:10 Average:78.10 Median:64.00 stddev:25.77 (40.3%) stddev-sans-first:27.31 18:07:13 INFO - PROCESS | 2230 | Values: 81.0 73.0 58.0 108.0 58.0 62.0 59.0 66.0 137.0 79.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:79.50 stddev:11.55 (14.5%) stddev-sans-first:12.12 18:07:13 INFO - PROCESS | 2230 | Values: 95.0 102.0 101.0 80.0 79.0 78.0 103.0 81.0 80.0 104.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:2.41 (2.8%) stddev-sans-first:2.40 18:07:13 INFO - PROCESS | 2230 | Values: 88.0 84.0 88.0 83.0 86.0 83.0 89.0 85.0 88.0 83.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:93.00 stddev:9.24 (9.9%) stddev-sans-first:9.71 18:07:13 INFO - PROCESS | 2230 | Values: 93.0 93.0 92.0 93.0 93.0 93.0 93.0 93.0 100.0 122.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:0.88 (1.0%) stddev-sans-first:0.83 18:07:13 INFO - PROCESS | 2230 | Values: 84.0 85.0 84.0 86.0 86.0 85.0 84.0 86.0 85.0 86.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:59.50 stddev:5.09 (8.5%) stddev-sans-first:5.40 18:07:13 INFO - PROCESS | 2230 | Values: 60.0 61.0 60.0 58.0 58.0 57.0 57.0 74.0 59.0 57.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:3.44 (4.8%) stddev-sans-first:3.02 18:07:13 INFO - PROCESS | 2230 | Values: 66.0 77.0 67.0 76.0 72.0 71.0 70.0 73.0 72.0 71.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:0.99 (1.2%) stddev-sans-first:0.97 18:07:13 INFO - PROCESS | 2230 | Values: 86.0 85.0 86.0 84.0 84.0 84.0 84.0 84.0 86.0 86.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:143.50 stddev:7.26 (5.1%) stddev-sans-first:7.67 18:07:13 INFO - PROCESS | 2230 | Values: 142.0 141.0 137.0 137.0 137.0 152.0 147.0 145.0 159.0 140.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:169.00 stddev:17.79 (10.5%) stddev-sans-first:18.59 18:07:13 INFO - PROCESS | 2230 | Values: 168.0 167.0 168.0 167.0 168.0 170.0 167.0 174.0 219.0 199.0 18:07:13 INFO - PROCESS | 2230 | 18:07:13 INFO - PROCESS | 2230 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:75.00 stddev:9.52 (12.7%) stddev-sans-first:9.85 18:07:13 INFO - PROCESS | 2230 | Values: 66.0 67.0 66.0 69.0 81.0 84.0 84.0 64.0 58.0 81.0 18:07:13 INFO - PROCESS | 2230 | -------- Summary: end -------- 18:07:13 INFO - PROCESS | 2230 | 18:07:14 INFO - TEST-INFO | 2230: exit 0 18:07:14 INFO - TEST-OK | kraken | took 59311ms 18:07:14 INFO - SUITE-END | took 1175s 18:07:15 INFO - Completed test suite (00:19:35) 18:07:15 INFO - javascript benchmark 18:07:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3448.948265776013, 3469.8438570264334, 3471.8958431247065, 3489.2672538766856, 3528.9117772055697, 5691.658500489973, 5703.771849126035, 5736.942630573693, 5753.65478071316, 5764.36591974883, 4793.424789025322, 5740.282464691913, 5798.855028624286, 5832.154337621042, 5841.299044114701, 5204.141316682752, 5253.159494480884, 5278.155495120782, 5292.629515933886, 5330.92003619946, 5334.653247538917, 5357.1696387060065, 5364.195370694388, 5364.865878353034, 5367.865803354908, 5234.084035293828, 5252.343457067866, 5253.711045892474, 5273.57811375091, 5336.039512887679, 5324.733763311858, 5347.866303342409, 5350.866228344323, 5371.248025276464, 5371.543418809406, 5153.072446959546, 5166.0, 5174.637775355726, 5259.368875734936, 5279.815206467755, 5661.688607126606, 5674.829755107354, 5699.116636921284, 5701.059325211377, 5727.541796656257, 6256.562040657156, 6261.0, 6270.6864656766975, 6276.278228003746, 6287.559870809046, 6130.141780150782, 6146.371211628922, 6238.4697300729495, 6240.719167637467, 6260.311365749764, 5970.611910225822, 5970.701464926737, 5986.311574168939, 5987.970060149672, 5994.3705910878725, 6356.078368636522, 6361.650109243943, 6368.235811702625, 6373.681315934185, 6387.904181437282, 5203.725087353622, 6136.488442913466, 6141.324454310023, 6151.384861513813, 6183.103449999815, 5243.68537887728, 6186.969065154645, 6247.625142491465, 6249.281332646804, 6261.123442718023, 6221.688915554204, 6227.0, 6230.439260466489, 6250.031245157008, 6266.373362663697, 5076.44159142494, 6093.421124993119, 6177.042558403455, 6219.875602488016, 6222.844428889223, 4975.278584605212, 5009.073321435546, 5017.222330538772, 5021.297018417425, 5056.367954005749, 4517.774111294422, 4522.909541809146, 4535.795889184995, 4559.133764584718, 4593.885152871138, 5812.82561523155, 5877.853053673607, 5877.853053673693, 5918.940810591839, 5922.822315330461, 6110.3765049759795, 6202.689865506707, 6235.220597425322, 6244.937550624527, 6291.370862913764, 5931.169636250928, 5953.642781433128, 5960.5827592068135, 5967.075103358986, 5982.252218472691, 5498.477644623755, 5511.669299842022, 5516.586256030814, 5525.309336332958, 5542.806001789999, 6209.348018458088, 6404.199475065617, 6415.743370265137, 6486.610803351814, 6549.6070235786, 6273.153124328157, 6283.49732021428, 6285.2457705075685, 6289.213848268966, 6298.496120310272, 6265.060240963892, 6520.706568204537, 6524.67376631176, 6547.377999090078, 6573.671316434255, 6123.679189354688, 6129.877402451837, 6138.0, 6166.0442631390415, 6186.783462578788, 6275.309715931336, 6276.497880169667, 6287.088372185916, 6292.182016337847, 6308.179936608395, 5549.417311182267, 6065.72704228302, 6092.786752463642, 6201.751929922933, 6203.0, 6108.5723999319625, 6161.5686901918225, 6164.7842325518395, 6165.753369865155, 6171.259448866164, 6064.423879731418, 6074.696265186634, 6079.361667025076, 6082.574219804571, 6087.3067570994335, 5980.730867110903, 6110.528382738247, 6137.22377000893, 6156.168917196122, 6204.627722336674, 5938.257717785277, 5997.13041608973, 6019.217501724747, 6062.787802426894, 6120.051392034242], "value": 5754.885625266384, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.72899905052225, 500.312070902509, 512.7974450092212, 560.9594202753891, 565.3018522125172, 30251.697483025164, 43190.136197276035, 44689.88275293115, 44693.55306446934, 47053.529464705345, 44988.0, 45198.77400613001, 45269.0, 45455.31817022739, 45568.0, 8569.785755356104, 9610.855837162431, 9800.284950133733, 9800.509974501247, 9809.50952452378, 8609.5695215239, 11620.941895290513, 11979.401029948467, 11994.9804266637, 12093.516259349615, 11874.16880818343, 11955.820662690066, 11972.982296504808, 11980.70048248792, 11982.64052078439, 8681.696140635047, 11526.769464610661, 11795.882041179564, 11805.468753906094, 11998.52005919762, 11837.467313970892, 11838.644840654708, 11855.46650400734, 11858.644240672793, 11887.881121188764, 40796.79601601973, 41173.0, 41237.58762412368, 41261.587384126076, 41305.79347103275, 3288.325893191914, 3434.553508043938, 3793.65857072862, 3881.9029524261837, 4102.856400025985, 29471.41057178866, 29851.701482985325, 30164.8491757542, 30226.0, 30919.227019324473, 2702.2028501592076, 2770.362816552184, 2772.0575004498364, 2784.470950519415, 2811.1285501494604, 476412.6179369116, 477435.0, 478321.0, 479326.6033669809, 480239.0, 35721.82139089288, 62615.0, 64237.67881160658, 64570.67714661491, 64617.67691161514, 36271.0, 37945.430818537745, 37971.240575188895, 37972.67095651639, 37992.62007379946, 15179.013364131517, 15275.083494990336, 15299.694006120038, 15308.081515109128, 15327.61680957985, 11589.304641721523, 11845.76308473843, 12575.811362829565, 12809.423575939105, 12841.486340546273, 8316.417850750511, 8334.62494187763, 8343.791405214797, 8415.579221038923, 8418.368622353348, 3385.593728752516, 3410.8124053176816, 3413.965860341365, 3416.0947348952545, 3454.9827250863586, 871.329076611006, 906.3791302957363, 969.5055521683992, 1023.2428003277521, 1169.3159501691605, 30012.849935750182, 30208.848955755082, 30459.8477007618, 30498.84750576233, 30630.0, 1099.8075336816025, 1115.7322242661703, 1117.1900372230068, 1122.2237171916606, 1245.7944439167436, 15460.768088478682, 15464.5360639181, 15464.613384665474, 15546.611334716496, 15722.383821937945, 668785.0, 669020.0, 670927.0, 675687.0, 678254.0, 60356.0, 62503.6874815623, 62627.6868615654, 62699.0, 62896.37103628905, 1298.577962162301, 1348.952786652482, 1438.3096113865195, 1454.4545795326753, 1473.4916453823414, 29257.0, 31175.532367014966, 31289.0, 31561.36877262488, 31695.04914852558, 52482.47517524776, 53257.0, 53277.73361133169, 53446.93106137778, 53510.197347039044, 489978.0, 492509.0, 492535.0, 492710.0, 493403.0, 27031.72968270292, 27802.887884484393, 28219.2945176364, 28597.42805143927, 29517.0, 46229.0, 46932.7653361731, 47631.76184119196, 48756.0, 48972.02055958932, 37722.43416348702, 38739.612603873604, 39067.827965161094, 39665.80167099146, 39949.80025099856, 38355.808220958716, 38549.0, 38762.8371148866, 39312.41031384589, 39486.605133949444, 38234.80882595569, 38484.42273365846, 39411.60588394079, 39455.80272098621, 39508.2098358037, 29890.850545747133, 33268.66731332656, 33385.0, 34202.82898585491, 34915.30169396649, 23922.214832389007, 24360.756392435847, 24909.128180513595, 25105.49789004173, 25243.49513009766], "value": 19332.211619356633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.7284437316065, 393.1978763322821, 395.51944387569097, 398.28309043721293, 417.1885682347835, 19600.019999000047, 19777.0, 20799.896000519995, 20831.27090551831, 20850.687239691415, 19817.603647927033, 19995.0, 21445.892770536124, 21536.569268614658, 21621.567568648617, 5083.949160508403, 5451.890962180754, 5459.69971651559, 5489.451054894498, 5493.121100623901, 5384.973075134619, 5413.891722165555, 5488.368837583669, 5493.285872836525, 5495.148252020923, 5322.4411436799155, 5450.155225939986, 5451.454854514556, 5463.0, 5483.725813709319, 5374.838754837322, 5419.945800541984, 5451.318585176853, 5457.345118585756, 5473.124300111983, 5121.129408000612, 5283.233931079972, 5323.653962492425, 5326.840194794162, 5338.91991620126, 13498.257595832225, 13625.795613065811, 13706.93146534261, 13913.67820057093, 13914.09558378702, 2150.827933765295, 2283.7830406111393, 2431.5866302728577, 2680.7855371570226, 2757.5450050741806, 12176.81734773979, 14107.929460352736, 14859.331330090172, 14894.420055223381, 15232.847671523254, 1622.951311460658, 1626.8129165145922, 1630.1930544380489, 1681.8889516952654, 1686.3844696685744, 94725.0, 95785.0, 106915.0, 108716.99999999921, 113025.0, 9735.318527702993, 11429.428528573539, 11585.899339562728, 11694.94152529249, 11850.762984740257, 13461.461541538229, 15089.094654320776, 15115.168665723491, 15140.772888406682, 15169.24153792306, 7589.468737188344, 7895.131535531087, 8183.549904755296, 8362.829203911457, 8684.913150868537, 7503.836905279691, 8091.271785539329, 8541.23128918388, 8578.91421085781, 8624.09447008055, 4546.545345465427, 4658.99663144851, 4674.509176536482, 4685.086408150446, 4740.099381117577, 1919.7888232294436, 1969.6651569233265, 1969.6848504239254, 1972.5364539333245, 2004.662237374784, 838.3628442383832, 912.452528482905, 932.4545141092401, 989.569537251298, 1009.6819501857038, 15953.208188566954, 17332.220050097774, 18956.43130706081, 19789.703154452694, 20539.897300513403, 805.7341077444429, 924.5700749151677, 929.3030227329502, 955.9330846840654, 963.6001059560203, 10508.264421490576, 10704.89295107039, 10728.302660327054, 10738.516766745515, 10748.0, 604544.0, 611577.0, 614550.0, 619330.0, 620098.0, 101949.98050019554, 130312.3484382591, 132902.0, 134392.99999999805, 137720.0, 877.4647465046316, 1014.2089170447063, 1022.0392830738933, 1038.5015192707392, 1038.7299302181486, 17665.735013974543, 17918.641627167643, 17920.0, 17940.820591793916, 18335.90832045831, 49151.75424122856, 50451.49548504615, 50865.49134508608, 51874.48125518696, 51885.48114518807, 60948.17155485343, 72331.63834180795, 73163.26836731564, 73412.96228707259, 74550.62724686341, 7649.732259370895, 8127.349812014906, 8170.591470426574, 8412.495250285003, 8438.738531881101, 9040.63837446521, 10231.721034870641, 12489.375531223584, 12810.55163069288, 12876.678083047998, 9782.657606983721, 10518.421486818299, 10805.513751881026, 10923.0, 11206.551737930391, 10735.94632026835, 10824.621138260123, 10919.290246133976, 11040.0, 11117.94379533943, 9580.425174489554, 10724.088452481627, 10851.945740271249, 11422.143339249757, 11502.82745758831, 8134.186581342055, 8288.70989515364, 8294.502329860228, 8295.585220738818, 8355.415120941476, 9775.0, 10054.748631284276, 10780.730481738019, 11322.0, 11331.943340283246], "value": 9245.310423361096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.1189062781077, 1590.9840901590985, 1605.0851014921495, 1823.8176182381762, 1968.4980330015844, 7836.451448398614, 8557.871631925518, 8804.251638610709, 8823.779405514866, 8930.12467381849, 8583.0, 8839.823203535942, 8861.291096712264, 8862.246709029723, 8863.069377715343, 1956.8825870447747, 2166.8916554172306, 2181.923632672857, 2438.5854404751144, 2473.0602371098958, 2234.7430045544806, 2261.9321420357414, 2264.4225722440756, 2371.122684606698, 2401.0, 2437.171361737009, 2443.2059580636273, 2450.742672019439, 2451.6935383077116, 2456.9385765355833, 2410.20463247128, 2448.816338774599, 2460.9138680146107, 2461.618449140386, 2462.987685061581, 2146.8926553672254, 2393.6768536247732, 2461.0, 2463.2117722328862, 2474.1093206445667, 8662.13378662135, 8747.518886461243, 8764.649414023432, 8769.035406105388, 8774.473531588124, 5293.7617807198385, 5499.917501237484, 5510.5316048135555, 5511.889762204773, 5527.53015993641, 3166.889158879428, 3496.4405695088662, 3538.5046093546925, 3628.7641303315463, 3636.6363363663686, 852.9914700852975, 980.3529670417562, 1031.4687935713114, 1038.4755698372348, 1045.6706137566719, 11740.647780566596, 11742.295462272292, 11795.646130616095, 11799.056075513938, 11833.349165795964, 4739.691920025188, 4857.7085374876915, 4937.654364194544, 4952.653314268039, 4953.578945789576, 10105.797884042426, 10111.59553617847, 10122.54448549817, 10134.898651013396, 10149.949250253701, 5519.641223320471, 5555.0, 5555.2778138841695, 5557.36090349615, 5568.025595520768, 4496.572825581565, 4929.408470983505, 4939.703617782872, 4990.8752281193265, 5093.847184584469, 3331.3670402623425, 3886.805659717003, 3886.84452621892, 3892.4550562921213, 3893.299206142865, 1289.8516670582815, 1342.8455727591256, 1362.4590139389966, 1367.2138520350838, 1370.7943808428615, 786.6853258696567, 813.556611646654, 929.4144688845984, 938.8450905600499, 949.2453499467939, 5773.3072031356505, 5835.970820145957, 5846.269216347957, 5866.1494083357675, 5963.582549221513, 860.8794768732382, 976.2239019979133, 991.2664628175098, 999.4602914426274, 1061.3366645846345, 3456.395130852141, 3820.7516511427225, 3869.8197049899486, 3871.341871881787, 3944.487216661873, 8565.828683426422, 10335.741606459898, 10739.194560408001, 10767.515461804238, 10776.78446431067, 10409.53157107932, 10691.893081069245, 10694.465276736131, 10700.250982431311, 10709.625163119254, 7986.241307075818, 8151.755447336471, 8249.8350032999, 8376.455530390505, 8383.580820958927, 5759.337676167127, 5854.209681692917, 5906.763729450946, 5945.494632956074, 6002.159697642421, 9768.0, 9996.750081248027, 9998.500074996076, 10102.595896164365, 10110.646127385506, 8933.240674542476, 9655.468949207861, 9878.357906736039, 10293.0, 10303.845442318221, 6961.512694111364, 6989.7553585624255, 7101.0, 7101.609411482212, 7122.18094919091, 6886.173659160732, 7102.644867756489, 7496.912947622455, 8156.755297341089, 8212.507249565046, 7348.926510734825, 7404.370628496638, 7424.406047516294, 7543.7736867894055, 7547.773566793005, 7251.658443188027, 7345.265473452612, 7346.853062938818, 7354.227806080285, 7487.3261406473675, 6885.380315771605, 6960.199577048705, 7041.753538626123, 7054.8236294091, 7056.717731290896, 4857.757112144308, 4915.754212289443, 4980.8754781130765, 5019.824306149268, 5021.096202683552, 4181.372794080912, 4960.925586116139, 5006.324146240357, 5043.041822053798, 5129.204973229155], "value": 4580.103787708403, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.2579719976256, 309.9442100421924, 325.4889822977924, 327.1608324647281, 329.90762586475796, 4102.6717862570995, 4285.442892423988, 4748.287756836477, 5076.771545280462, 5084.745762711868, 4749.311349854278, 4784.354112194853, 4829.492903245153, 4916.082727736186, 5048.49515048495, 3334.7832390894637, 3621.420572708367, 3884.3685652026215, 3886.3587508061137, 3913.6477717005464, 3425.845836937331, 3888.938862402931, 3952.8221230044574, 3964.1170444038994, 3970.3795494973324, 3035.7232075943043, 3712.7029837613063, 3736.9626303737023, 3922.901587555489, 3935.291647503448, 3251.561039259693, 3855.0, 3866.955921901099, 3893.683258461211, 3945.053187235057, 2912.6650435200013, 3277.901902862544, 3402.5406570112964, 3403.9149021274666, 3459.515667806509, 3815.7138214634015, 4344.978275108636, 4454.198244316021, 4849.102915960559, 4885.902281954376, 4030.879073627796, 4163.333866581333, 4338.284183109783, 4404.229259879508, 4444.31113177458, 4893.602134953882, 5249.816256431007, 5265.52610265078, 5310.336207974004, 5352.564583114673, 3439.518467414564, 3545.3441113394106, 3686.6313368663186, 3688.926221475582, 3731.0299322176156, 5285.894282114375, 5495.835124946258, 5559.77760889568, 5577.581681373913, 5585.692786896719, 3224.596925384327, 3484.564429446319, 3741.7006639468777, 3741.8961406385183, 3767.585565587783, 3808.27642747877, 3813.275477659236, 3829.6553310202216, 3836.156045669948, 3867.57456679765, 4206.284931561642, 4378.890527736832, 4407.250767369491, 4458.7547684877645, 4461.196984542814, 4588.990422107131, 5232.848773269875, 5328.813491527778, 5336.839894803162, 5350.38470575881, 1118.6196693124377, 1154.4054811771944, 1158.164309569342, 1194.635636130971, 1228.5147366790068, 773.072313224122, 796.4464697035505, 829.0714399872061, 829.9045609754955, 844.6030365728097, 4055.41196526502, 4123.134141830249, 4239.173361194537, 4721.976390118096, 4756.215224487921, 4303.591158839905, 4427.446569178853, 4923.335349727812, 5195.454477279831, 5217.06092903281, 3877.534695836461, 4607.723536587815, 4646.4191976003, 4675.462321833033, 4711.882202944954, 2316.2009106858445, 2417.8791060446906, 2672.385351369196, 2687.6909155446983, 2705.4589082183247, 4474.009065965614, 4614.492405835355, 4944.0, 5138.53350391814, 5178.6116041297055, 4371.303677037801, 4606.861794146181, 4619.930701039487, 5101.897962040738, 5251.789928402897, 5130.7178105204575, 5329.214713071087, 5339.196921616182, 5351.170568561802, 5366.302380690485, 4388.429504164438, 4670.229412147093, 4773.9761301193275, 4823.782929768098, 4964.609909225423, 3957.485526881487, 4666.206744853315, 4870.610351171968, 5101.51535604117, 5106.897862042812, 5333.493318134771, 5371.382291036424, 5384.949934762683, 5393.514583687487, 5410.675359478444, 3173.7461003120156, 3213.501907204387, 3428.879989200366, 3513.80674062929, 3534.611192768845, 4432.73667004902, 4511.323301504801, 4552.362669226377, 4952.087850598131, 5223.250211179297, 4584.4498660161, 4711.858644240679, 4743.976280118577, 4800.975995120002, 5006.649534532694, 4966.106100901728, 4969.602431805519, 4982.427020892644, 5004.0, 5004.949950500594, 4226.556211597738, 4553.954460455353, 4604.953950460586, 4625.861224163281, 4901.627544287605, 4556.6126879215635, 4882.414110306786, 4979.153543897619, 5004.3994720633755, 5008.298838162587, 4343.652507799432, 4424.469063712375, 4536.3915748444115, 4727.929081063718, 4734.905301894012], "value": 3732.6502965352397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.2715385921626, 750.4184257200669, 752.6000943232307, 753.5328096580117, 763.1147868472573, 14915.627109322259, 14942.77585836212, 15929.761053584205, 15958.521244362659, 15959.0, 14498.347574359153, 14621.0, 14670.926645366784, 14686.045407048534, 14695.044822086556, 669.3406994110796, 757.7537300377386, 932.9626814927394, 936.1527817325332, 936.6862101196074, 763.6372722956579, 917.5366439947817, 939.7979434421597, 940.7601061729283, 952.8618350339196, 992.5880759484842, 994.368575954267, 1000.4747507558517, 1001.7545701303154, 1004.70361243433, 777.9727709530139, 955.9283053770995, 996.1035068438391, 996.8255555277798, 997.8702768640067, 731.1153504259852, 731.8755811512056, 732.479939243138, 734.9412047036225, 739.4786675393834, 15096.849031509653, 15188.848111518853, 15189.924050379677, 15201.315940782692, 15283.694326113415, 12307.384630768425, 12344.13591048627, 12484.937575312157, 12694.682632934158, 12812.487500499969, 10897.073748731287, 10948.835767463494, 10972.451377431096, 11050.723731906686, 11071.058959988412, 3379.797212167278, 3506.193575477629, 3731.7201209909094, 3973.9006524836823, 4378.540253273392, 84662.0, 85651.0, 85698.0, 85836.57081714552, 86127.56936215279, 9371.671991480265, 9379.169415277558, 9512.952435237918, 9548.0, 9786.706398807904, 20339.8983005084, 20558.177672893216, 20600.0, 20601.690974635392, 20683.27608533694, 7475.327220550176, 7482.2517748224745, 7504.812379690442, 7562.773116806505, 7709.614519274014, 4028.939565906514, 4113.958860411418, 4163.979180104141, 4166.812493437803, 4241.618254357061, 3461.394256005189, 3577.3918433865783, 3688.3360995020616, 3692.132348898007, 3697.2235830475365, 658.4962503684685, 673.6530686696356, 674.069783698493, 677.3125277842993, 678.0575000748963, 565.1663795901095, 583.3087790967717, 599.5263741644052, 604.6523249131767, 627.5262177056293, 7114.964425177841, 8018.959905200437, 8020.0, 8360.414770965974, 8412.873806892901, 519.6829933740415, 583.8627922438224, 594.3610618584952, 618.2580902916518, 622.0296337713182, 3406.489026646023, 3782.9596860862935, 3843.3082045231567, 3884.864029758945, 3914.0, 79460.60269698614, 83338.16661833304, 83433.0, 85938.0, 87116.56441717877, 74226.62886685532, 74660.62669686617, 74937.6253118731, 75989.62005189939, 76905.0, 66668.33331666622, 66871.66564167148, 67331.66334168297, 67377.0, 69652.0, 7623.390128789794, 7747.612619369122, 7872.252136047066, 8038.356931445587, 8223.01323841143, 43640.0, 43714.90712732207, 43757.78121109501, 43824.56175438205, 44422.777886110365, 60383.396166037775, 60978.69510652418, 61299.38700612937, 61323.0, 61328.08007879796, 10177.287589868638, 10413.791724165625, 10542.57829686804, 10556.841647375142, 10581.841272380767, 11171.776564468828, 11179.832302515306, 11197.72005699864, 11220.66338009861, 11664.475098620413, 10420.270581059252, 10504.684859454228, 10562.207834412604, 10596.576136954436, 10990.890091098987, 10256.28206025601, 10317.071463568314, 10419.739506512398, 10647.733806654896, 10796.514156863117, 10384.688459346231, 10396.688099357032, 10443.791124177322, 10753.89246107529, 10858.968398002176, 7892.329152022143, 8507.234348908629, 8571.528565928933, 8784.033756286675, 8795.736127916172, 8711.782205444915, 8790.0, 8875.911240887508, 9048.592813323285, 9074.500902450165], "value": 6794.19184038076, "unit": "score"}], "name": "dromaeo_css", "value": 7018.104156146257, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 112.0, 113.0, 113.0, 113.0, 111.0, 113.0, 113.0, 116.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 119.0, 145.0, 191.0, 141.0, 136.0, 140.0, 116.0, 168.0, 112.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 186.0, 186.0, 186.0, 183.0, 188.0, 185.0, 182.0, 182.0, 182.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 58.0, 108.0, 58.0, 62.0, 59.0, 66.0, 137.0, 79.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 101.0, 80.0, 79.0, 78.0, 103.0, 81.0, 80.0, 104.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 83.0, 86.0, 83.0, 89.0, 85.0, 88.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 100.0, 122.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 86.0, 86.0, 85.0, 84.0, 86.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 58.0, 58.0, 57.0, 57.0, 74.0, 59.0, 57.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 67.0, 76.0, 72.0, 71.0, 70.0, 73.0, 72.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 84.0, 84.0, 84.0, 84.0, 86.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 137.0, 137.0, 137.0, 152.0, 147.0, 145.0, 159.0, 140.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 168.0, 167.0, 168.0, 170.0, 167.0, 174.0, 219.0, 199.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 69.0, 81.0, 84.0, 84.0, 64.0, 58.0, 81.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1485.1000000000001, "alertThreshold": 2.0}]} 18:07:15 INFO - Return code: 0 18:07:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:07:15 ERROR - # TBPL SUCCESS # 18:07:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:07:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:07:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:07:15 INFO - 2016-05-05 18:07:15.190 screenresolution[2234:36240] starting screenresolution argv=screenresolution get 18:07:15 INFO - 2016-05-05 18:07:15.193 screenresolution[2234:36240] Display 0: 1600x1200x32@60 18:07:15 INFO - 2016-05-05 18:07:15.199 screenresolution[2235:36243] starting screenresolution argv=screenresolution list 18:07:15 INFO - Available Modes on Display 0 18:07:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:07:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:07:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:07:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:07:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:07:15 INFO - Intel Iris: 18:07:15 INFO - Chipset Model: Intel Iris 18:07:15 INFO - Type: GPU 18:07:15 INFO - Bus: Built-In 18:07:15 INFO - VRAM (Dynamic, Max): 1536 MB 18:07:15 INFO - Vendor: Intel (0x8086) 18:07:15 INFO - Device ID: 0x0a2e 18:07:15 INFO - Revision ID: 0x0009 18:07:15 INFO - Displays: 18:07:15 INFO - Display: 18:07:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:07:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:07:15 INFO - Display Serial Number: 1600x1200 60 18:07:15 INFO - Main Display: Yes 18:07:15 INFO - Mirror: Off 18:07:15 INFO - Online: Yes 18:07:15 INFO - Rotation: Supported 18:07:15 INFO - Return code: 0 18:07:15 INFO - Running post-action listener: _resource_record_post_action 18:07:15 INFO - Running post-run listener: _resource_record_post_run 18:07:16 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 409600; Write bytes: 697223680; Read time: 6; Write time: 1945 18:07:16 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397287936; Write bytes: 400173056; Read time: 29900; Write time: 789 18:07:16 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 409600; Write bytes: 294636544; Read time: 6; Write time: 1145 18:07:16 INFO - Running post-run listener: _upload_blobber_files 18:07:16 INFO - Blob upload gear active. 18:07:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:07:16 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 18:07:16 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'] 18:07:16 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 18:07:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:07:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:07:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:07:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:07:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:07:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:07:18 INFO - (blobuploader) - INFO - Done attempting. 18:07:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:07:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:07:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:07:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:07:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:07:20 INFO - (blobuploader) - INFO - Done attempting. 18:07:20 INFO - (blobuploader) - INFO - Iteration through files over. 18:07:20 INFO - Return code: 0 18:07:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:07:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:07:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60d6a26c1562d940dc5626b91b3749a2ece49c8797087e9e9e09b3307441ea0323e2ed339ed65d58005336671c18871a8475b9f02bdfdc8390e41e75b86383a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68149b5c3ee7ff71710caa177c55f9600217acb2985d4f1f08426432296db66d79ab5a8eee5db564d28309740333162c82a59bf162f325f07f745c599cf468a1"} 18:07:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:07:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:07:20 INFO - Contents: 18:07:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60d6a26c1562d940dc5626b91b3749a2ece49c8797087e9e9e09b3307441ea0323e2ed339ed65d58005336671c18871a8475b9f02bdfdc8390e41e75b86383a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68149b5c3ee7ff71710caa177c55f9600217acb2985d4f1f08426432296db66d79ab5a8eee5db564d28309740333162c82a59bf162f325f07f745c599cf468a1"} 18:07:20 INFO - Running post-run listener: copy_logs_to_upload_dir 18:07:20 INFO - Copying logs to upload dir... 18:07:20 INFO - mkdir: /builds/slave/test/build/upload/logs 18:07:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.110457 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-05 18:07:20.656253) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 18:07:20.660137) ========= 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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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/60d6a26c1562d940dc5626b91b3749a2ece49c8797087e9e9e09b3307441ea0323e2ed339ed65d58005336671c18871a8475b9f02bdfdc8390e41e75b86383a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68149b5c3ee7ff71710caa177c55f9600217acb2985d4f1f08426432296db66d79ab5a8eee5db564d28309740333162c82a59bf162f325f07f745c599cf468a1"} build_url:https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/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/60d6a26c1562d940dc5626b91b3749a2ece49c8797087e9e9e09b3307441ea0323e2ed339ed65d58005336671c18871a8475b9f02bdfdc8390e41e75b86383a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68149b5c3ee7ff71710caa177c55f9600217acb2985d4f1f08426432296db66d79ab5a8eee5db564d28309740333162c82a59bf162f325f07f745c599cf468a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 18:07:20.691173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:07:20.691536) ========= 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.uv3xRNQ4w7/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.bFJlweA0ZW/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:07:20.740387) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 18:07:20.740734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 18:07:20.741050) ========= ========= Total master_lag: 0.63 =========