builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1469028914.54 results: success (0) buildid: 20160720071819 builduid: 58da0a028d44484fa926789a8ae521c3 revision: 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.541454) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.541964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.542258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.567639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.568111) ========= 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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07-20 08:35:14-- 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% 13.4M=0.001s 2016-07-20 08:35:14 (13.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.714833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.715205) ========= 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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.020727 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:14.764035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 08:35:14.764418) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-07-20 08:35:14,838 truncating revision to first 12 chars 2016-07-20 08:35:14,838 Setting DEBUG logging. 2016-07-20 08:35:14,838 attempt 1/10 2016-07-20 08:35:14,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9e887ad7d5eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 08:35:16,028 unpacking tar archive at: mozilla-beta-9e887ad7d5eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.470307 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 08:35:16.375093) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:16.375381) ========= script_repo_revision: 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:16.375757) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:16.376047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 08:35:16.397777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-20 08:35:16.398167) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:35:16 INFO - MultiFileLogger online at 20160720 08:35:16 in /builds/slave/test 08:35:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:35:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:35:16 INFO - {'append_to_log': False, 08:35:16 INFO - 'base_work_dir': '/builds/slave/test', 08:35:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:35:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:35:16 INFO - 'branch': 'Mozilla-Beta', 08:35:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:35:16 INFO - 'config_files': ('talos/mac_config.py',), 08:35:16 INFO - 'default_actions': ('clobber', 08:35:16 INFO - 'read-buildbot-config', 08:35:16 INFO - 'download-and-extract', 08:35:16 INFO - 'populate-webroot', 08:35:16 INFO - 'create-virtualenv', 08:35:16 INFO - 'install', 08:35:16 INFO - 'run-tests'), 08:35:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:35:16 INFO - 'download_minidump_stackwalk': True, 08:35:16 INFO - 'download_symbols': 'ondemand', 08:35:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:35:16 INFO - 'tooltool.py': '/tools/tooltool.py', 08:35:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:35:16 INFO - '/tools/misc-python/virtualenv.py')}, 08:35:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:35:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:35:16 INFO - 'installer_path': 'installer.exe', 08:35:16 INFO - 'log_level': 'info', 08:35:16 INFO - 'log_name': 'talos', 08:35:16 INFO - 'log_to_console': True, 08:35:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:35:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:35:16 INFO - 'opt_config_files': (), 08:35:16 INFO - 'pip_index': False, 08:35:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:16 INFO - 'cmd': ('bash', 08:35:16 INFO - '-c', 08:35:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:16 INFO - 'enabled': True, 08:35:16 INFO - 'halt_on_failure': False, 08:35:16 INFO - 'name': 'check_screen_resolution'},), 08:35:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:35:16 INFO - 'cmd': ('bash', 08:35:16 INFO - '-c', 08:35:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:35:16 INFO - 'enabled': True, 08:35:16 INFO - 'halt_on_failure': False, 08:35:16 INFO - 'name': 'check_screen_resolution'},), 08:35:16 INFO - 'run_cmd_checks_enabled': True, 08:35:16 INFO - 'sps_profile': False, 08:35:16 INFO - 'sps_profile_interval': 0, 08:35:16 INFO - 'suite': 'dromaeojs', 08:35:16 INFO - 'system_bits': '32', 08:35:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:35:16 INFO - 'title': 't-yosemite-r7-0147', 08:35:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:35:16 INFO - 'use_talos_json': True, 08:35:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:35:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:35:16 INFO - 'work_dir': 'build'} 08:35:16 INFO - ##### 08:35:16 INFO - ##### Running clobber step. 08:35:16 INFO - ##### 08:35:16 INFO - Running pre-action listener: _resource_record_pre_action 08:35:16 INFO - Running main action method: clobber 08:35:16 INFO - rmtree: /builds/slave/test/build 08:35:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:35:16 INFO - Running post-action listener: _resource_record_post_action 08:35:16 INFO - ##### 08:35:16 INFO - ##### Running read-buildbot-config step. 08:35:16 INFO - ##### 08:35:16 INFO - Running pre-action listener: _resource_record_pre_action 08:35:16 INFO - Running main action method: read_buildbot_config 08:35:16 INFO - Using buildbot properties: 08:35:16 INFO - { 08:35:16 INFO - "project": "", 08:35:16 INFO - "product": "firefox", 08:35:16 INFO - "script_repo_revision": "production", 08:35:16 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 08:35:16 INFO - "repository": "", 08:35:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 08:35:16 INFO - "buildid": "20160720071819", 08:35:16 INFO - "pgo_build": "False", 08:35:16 INFO - "basedir": "/builds/slave/test", 08:35:16 INFO - "buildnumber": 50, 08:35:16 INFO - "slavename": "t-yosemite-r7-0147", 08:35:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:35:16 INFO - "platform": "yosemite_r7", 08:35:16 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:35:16 INFO - "branch": "mozilla-beta", 08:35:16 INFO - "repo_path": "releases/mozilla-beta", 08:35:16 INFO - "revision": "9e887ad7d5ebd5369782230a86c3a51f84e9ab0e", 08:35:16 INFO - "stage_platform": "macosx64", 08:35:16 INFO - "builduid": "58da0a028d44484fa926789a8ae521c3", 08:35:16 INFO - "slavebuilddir": "test" 08:35:16 INFO - } 08:35:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 08:35:16 INFO - Running post-action listener: _resource_record_post_action 08:35:16 INFO - ##### 08:35:16 INFO - ##### Running download-and-extract step. 08:35:16 INFO - ##### 08:35:16 INFO - Running pre-action listener: _resource_record_pre_action 08:35:16 INFO - Running main action method: download_and_extract 08:35:16 INFO - mkdir: /builds/slave/test/build/tests 08:35:16 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')]} 08:35:16 INFO - https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:35:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 08:35:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 08:35:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 08:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 08:35:27 INFO - Downloaded 1165 bytes. 08:35:27 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 08:35:27 INFO - Using the following test package requirements: 08:35:27 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 08:35:27 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 08:35:27 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 08:35:27 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:35:27 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 08:35:27 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 08:35:27 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 08:35:27 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 08:35:27 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 08:35:27 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 08:35:27 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 08:35:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 08:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:27 INFO - https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:35:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 08:35:30 INFO - Downloaded 18314738 bytes. 08:35:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:35:33 INFO - Return code: 0 08:35:33 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:35:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:33 INFO - https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:35:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 08:35:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 08:35:34 INFO - Downloaded 18314738 bytes. 08:35:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:35:38 INFO - Return code: 0 08:35: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')]} 08:35:38 INFO - https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:35:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 08:35:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 08:35:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 08:35:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 08:35:39 INFO - Downloaded 10840145 bytes. 08:35:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:35:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:35:40 INFO - Return code: 0 08:35:40 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')]} 08:35:40 INFO - https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 08:35:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 08:35:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 08:35:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 08:35:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 08:35:45 INFO - Downloaded 86191408 bytes. 08:35:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 08:35:45 INFO - mkdir: /builds/slave/test/properties 08:35:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:35:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:35:45 INFO - Contents: 08:35:45 INFO - build_url:https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 08:35:46 INFO - Running post-action listener: _resource_record_post_action 08:35:46 INFO - Running post-action listener: set_extra_try_arguments 08:35:46 INFO - ##### 08:35:46 INFO - ##### Running populate-webroot step. 08:35:46 INFO - ##### 08:35:46 INFO - Running pre-action listener: _resource_record_pre_action 08:35:46 INFO - Running main action method: populate_webroot 08:35:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:46 INFO - '--tppagecycles', 08:35:46 INFO - '7'], 08:35:46 INFO - 'tests': ['tsvgm']}}, 08:35:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:35:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['damp', 'tps']}, 08:35:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'tests': ['damp', 'tps']}, 08:35:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['dromaeo_dom']}, 08:35:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['a11yr', 08:35:46 INFO - 'ts_paint', 08:35:46 INFO - 'tpaint', 08:35:46 INFO - 'sessionrestore', 08:35:46 INFO - 'sessionrestore_no_auto_restore', 08:35:46 INFO - 'tabpaint']}, 08:35:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['tsvgx', 08:35:46 INFO - 'tsvgr_opacity', 08:35:46 INFO - 'tart', 08:35:46 INFO - 'tscrollx', 08:35:46 INFO - 'cart']}, 08:35:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:46 INFO - 'tsvgr_opacity', 08:35:46 INFO - 'tart', 08:35:46 INFO - 'tscrollx', 08:35:46 INFO - 'cart']}, 08:35:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'talos_options': ['--disable-e10s'], 08:35:46 INFO - 'tests': ['tp5o']}, 08:35:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'tests': ['tp5o']}, 08:35:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'talos_options': ['--disable-e10s', 08:35:46 INFO - '--xperf_path', 08:35:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:46 INFO - 'tests': ['tp5n']}, 08:35:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:46 INFO - 'talos_options': ['--xperf_path', 08:35:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:46 INFO - 'tests': ['tp5n']}}, 08:35:46 INFO - 'talos.zip': {'path': '', 08:35:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:46 INFO - Running post-action listener: _resource_record_post_action 08:35:46 INFO - ##### 08:35:46 INFO - ##### Running create-virtualenv step. 08:35:46 INFO - ##### 08:35:46 INFO - Running pre-action listener: _resource_record_pre_action 08:35:46 INFO - Running main action method: create_virtualenv 08:35:46 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:46 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 08:35:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:46 INFO - Using real prefix '/tools/python27' 08:35:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:47 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:49 INFO - Installing pip.................done. 08:35:49 INFO - Return code: 0 08:35:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:49 INFO - 'HOME': '/Users/cltbld', 08:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:49 INFO - 'LOGNAME': 'cltbld', 08:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:49 INFO - 'MOZ_NO_REMOTE': '1', 08:35:49 INFO - 'NO_EM_RESTART': '1', 08:35:49 INFO - 'PAGER': '/bin/cat', 08:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:49 INFO - 'PWD': '/builds/slave/test', 08:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:49 INFO - 'SHELL': '/bin/bash', 08:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:35:49 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:49 INFO - 'USER': 'cltbld', 08:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:49 INFO - 'XPC_FLAGS': '0x0', 08:35:49 INFO - 'XPC_SERVICE_NAME': '0', 08:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:50 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 08:35:50 INFO - Cleaning up... 08:35:50 INFO - Return code: 0 08:35:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:35: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')]} 08:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35: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')]} 08:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:50 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:35:50 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 08:35:50 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 08:35:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:35:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:50 INFO - 'HOME': '/Users/cltbld', 08:35:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:50 INFO - 'LOGNAME': 'cltbld', 08:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:50 INFO - 'MOZ_NO_REMOTE': '1', 08:35:50 INFO - 'NO_EM_RESTART': '1', 08:35:50 INFO - 'PAGER': '/bin/cat', 08:35:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:50 INFO - 'PWD': '/builds/slave/test', 08:35:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:50 INFO - 'SHELL': '/bin/bash', 08:35:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:35:50 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:35:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:50 INFO - 'USER': 'cltbld', 08:35:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:50 INFO - 'XPC_FLAGS': '0x0', 08:35:50 INFO - 'XPC_SERVICE_NAME': '0', 08:35:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:50 INFO - Downloading/unpacking psutil>=0.7.1 08:35:50 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 08:35:50 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 08:35:50 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 08:35:50 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 08:35:50 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 08:35:50 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 08:35:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:53 INFO - Installing collected packages: psutil 08:35:53 INFO - Running setup.py install for psutil 08:35:53 INFO - building 'psutil._psutil_osx' extension 08:35:53 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 08:35:53 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 08:35:53 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 08:35:54 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 08:35:54 INFO - building 'psutil._psutil_posix' extension 08:35:54 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 08:35:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:35:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:35:54 INFO - ^ 08:35:54 INFO - 1 warning generated. 08:35:54 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 08:35:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:54 INFO - Successfully installed psutil 08:35:54 INFO - Cleaning up... 08:35:54 INFO - Return code: 0 08:35:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:54 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:35:54 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 08:35:54 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 08:35:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:35:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:54 INFO - 'HOME': '/Users/cltbld', 08:35:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:54 INFO - 'LOGNAME': 'cltbld', 08:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:54 INFO - 'MOZ_NO_REMOTE': '1', 08:35:54 INFO - 'NO_EM_RESTART': '1', 08:35:54 INFO - 'PAGER': '/bin/cat', 08:35:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:54 INFO - 'PWD': '/builds/slave/test', 08:35:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:54 INFO - 'SHELL': '/bin/bash', 08:35:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:35:54 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:35:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:54 INFO - 'USER': 'cltbld', 08:35:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:54 INFO - 'XPC_FLAGS': '0x0', 08:35:54 INFO - 'XPC_SERVICE_NAME': '0', 08:35:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:35:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:35:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:35:57 INFO - Installing collected packages: mozsystemmonitor 08:35:57 INFO - Running setup.py install for mozsystemmonitor 08:35:57 INFO - Successfully installed mozsystemmonitor 08:35:57 INFO - Cleaning up... 08:35:57 INFO - Return code: 0 08:35:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:35:57 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')]} 08:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:57 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')]} 08:35:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:57 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:35:57 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 08:35:57 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 08:35:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:35:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:57 INFO - 'HOME': '/Users/cltbld', 08:35:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:57 INFO - 'LOGNAME': 'cltbld', 08:35:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:57 INFO - 'MOZ_NO_REMOTE': '1', 08:35:57 INFO - 'NO_EM_RESTART': '1', 08:35:57 INFO - 'PAGER': '/bin/cat', 08:35:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:57 INFO - 'PWD': '/builds/slave/test', 08:35:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:57 INFO - 'SHELL': '/bin/bash', 08:35:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:35:57 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:35:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:57 INFO - 'USER': 'cltbld', 08:35:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:57 INFO - 'XPC_FLAGS': '0x0', 08:35:57 INFO - 'XPC_SERVICE_NAME': '0', 08:35:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:57 INFO - Downloading/unpacking blobuploader==1.2.4 08:35:57 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 08:35:57 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 08:35:57 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 08:35:57 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 08:35:57 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 08:35:57 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 08:36:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:36:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:36:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:00 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 08:36:01 INFO - Downloading docopt-0.6.1.tar.gz 08:36:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:36:01 INFO - Installing collected packages: blobuploader, requests, docopt 08:36:01 INFO - Running setup.py install for blobuploader 08:36:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:36:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:36:01 INFO - Running setup.py install for requests 08:36:02 INFO - Running setup.py install for docopt 08:36:02 INFO - Successfully installed blobuploader requests docopt 08:36:02 INFO - Cleaning up... 08:36:02 INFO - Return code: 0 08:36:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:02 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')]} 08:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:02 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')]} 08:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:02 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:36:02 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 08:36:02 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 08:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:02 INFO - 'HOME': '/Users/cltbld', 08:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:02 INFO - 'LOGNAME': 'cltbld', 08:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:02 INFO - 'MOZ_NO_REMOTE': '1', 08:36:02 INFO - 'NO_EM_RESTART': '1', 08:36:02 INFO - 'PAGER': '/bin/cat', 08:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:02 INFO - 'PWD': '/builds/slave/test', 08:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:02 INFO - 'SHELL': '/bin/bash', 08:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:02 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:02 INFO - 'USER': 'cltbld', 08:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:02 INFO - 'XPC_FLAGS': '0x0', 08:36:02 INFO - 'XPC_SERVICE_NAME': '0', 08:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:36:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-rhQDZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:36:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-vTwPog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:36:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-VW_jtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:36:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-hcAbPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-weBQLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-z_B5TM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bthRcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xeAyqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-sZsHQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-TObUXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BhBJqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-iccuyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qkLB89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:36:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-FVN8jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-LWPz90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:36:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qtgiES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:36:04 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-DhCjeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:36:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:36:04 INFO - Running setup.py install for manifestparser 08:36:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:36:04 INFO - Running setup.py install for mozcrash 08:36:04 INFO - Running setup.py install for mozdebug 08:36:04 INFO - Running setup.py install for mozdevice 08:36:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:36:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:36:04 INFO - Running setup.py install for mozfile 08:36:05 INFO - Running setup.py install for mozhttpd 08:36:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Running setup.py install for mozinfo 08:36:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Running setup.py install for mozInstall 08:36:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Running setup.py install for mozleak 08:36:05 INFO - Running setup.py install for mozlog 08:36:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Running setup.py install for moznetwork 08:36:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:36:05 INFO - Running setup.py install for mozprocess 08:36:06 INFO - Running setup.py install for mozprofile 08:36:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:36:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:36:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:36:06 INFO - Running setup.py install for mozrunner 08:36:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:36:06 INFO - Running setup.py install for mozscreenshot 08:36:06 INFO - Running setup.py install for moztest 08:36:06 INFO - Running setup.py install for mozversion 08:36:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:36:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:36:06 INFO - Cleaning up... 08:36:06 INFO - Return code: 0 08:36:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:07 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')]} 08:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:07 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:36:07 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 08:36:07 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 08:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:07 INFO - 'HOME': '/Users/cltbld', 08:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:07 INFO - 'LOGNAME': 'cltbld', 08:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:07 INFO - 'MOZ_NO_REMOTE': '1', 08:36:07 INFO - 'NO_EM_RESTART': '1', 08:36:07 INFO - 'PAGER': '/bin/cat', 08:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:07 INFO - 'PWD': '/builds/slave/test', 08:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:07 INFO - 'SHELL': '/bin/bash', 08:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:07 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:07 INFO - 'USER': 'cltbld', 08:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:07 INFO - 'XPC_FLAGS': '0x0', 08:36:07 INFO - 'XPC_SERVICE_NAME': '0', 08:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:36:07 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xBk1Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:36:07 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)) 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:36:07 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qDWgEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:36:07 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)) 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:36:07 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XCsVb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:36:07 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)) 08:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:36:07 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-EFtqoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qO2afk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2tUhyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-M6nqmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-EvGpIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cVxekg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fn_eXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-DsAoXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-5Q1Lf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:36:08 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)) 08:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:36:08 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2N5TAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:36:09 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)) 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:36:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-m0OKhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:36:09 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)) 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-MQ3gbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:36:09 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)) 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:36:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-9xu4W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:36:09 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)) 08:36:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:36:09 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-03TB3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:36:09 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)) 08:36:09 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)) 08:36:09 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)) 08:36:09 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)) 08:36:09 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)) 08:36:09 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)) 08:36:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:12 INFO - Downloading blessings-1.6.tar.gz 08:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:36:12 INFO - Installing collected packages: blessings 08:36:12 INFO - Running setup.py install for blessings 08:36:12 INFO - Successfully installed blessings 08:36:12 INFO - Cleaning up... 08:36:12 INFO - Return code: 0 08:36:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:36:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:36:12 INFO - Reading from file tmpfile_stdout 08:36:12 INFO - Current package versions: 08:36:12 INFO - blessings == 1.6 08:36:12 INFO - blobuploader == 1.2.4 08:36:12 INFO - docopt == 0.6.1 08:36:12 INFO - manifestparser == 1.1 08:36:12 INFO - mozInstall == 1.12 08:36:12 INFO - mozcrash == 0.17 08:36:12 INFO - mozdebug == 0.1 08:36:12 INFO - mozdevice == 0.48 08:36:12 INFO - mozfile == 1.2 08:36:12 INFO - mozhttpd == 0.7 08:36:12 INFO - mozinfo == 0.9 08:36:12 INFO - mozleak == 0.1 08:36:12 INFO - mozlog == 3.1 08:36:12 INFO - moznetwork == 0.27 08:36:12 INFO - mozprocess == 0.22 08:36:12 INFO - mozprofile == 0.28 08:36:12 INFO - mozrunner == 6.11 08:36:12 INFO - mozscreenshot == 0.1 08:36:12 INFO - mozsystemmonitor == 0.0 08:36:12 INFO - moztest == 0.7 08:36:12 INFO - mozversion == 1.4 08:36:12 INFO - psutil == 3.1.1 08:36:12 INFO - requests == 1.2.3 08:36:12 INFO - wsgiref == 0.1.2 08:36:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36: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')]} 08:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36: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')]} 08:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:12 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:36:12 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 08:36:12 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 08:36:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:12 INFO - 'HOME': '/Users/cltbld', 08:36:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:12 INFO - 'LOGNAME': 'cltbld', 08:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:12 INFO - 'MOZ_NO_REMOTE': '1', 08:36:12 INFO - 'NO_EM_RESTART': '1', 08:36:12 INFO - 'PAGER': '/bin/cat', 08:36:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:12 INFO - 'PWD': '/builds/slave/test', 08:36:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:12 INFO - 'SHELL': '/bin/bash', 08:36:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:12 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:12 INFO - 'USER': 'cltbld', 08:36:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:12 INFO - 'XPC_FLAGS': '0x0', 08:36:12 INFO - 'XPC_SERVICE_NAME': '0', 08:36:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 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)) 08:36:13 INFO - Cleaning up... 08:36:13 INFO - Return code: 0 08:36:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:36:13 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')]} 08:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:13 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')]} 08:36:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:13 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:36:13 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 08:36:13 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 08:36:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:13 INFO - 'HOME': '/Users/cltbld', 08:36:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:13 INFO - 'LOGNAME': 'cltbld', 08:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:13 INFO - 'MOZ_NO_REMOTE': '1', 08:36:13 INFO - 'NO_EM_RESTART': '1', 08:36:13 INFO - 'PAGER': '/bin/cat', 08:36:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:13 INFO - 'PWD': '/builds/slave/test', 08:36:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:13 INFO - 'SHELL': '/bin/bash', 08:36:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:13 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:13 INFO - 'USER': 'cltbld', 08:36:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:13 INFO - 'XPC_FLAGS': '0x0', 08:36:13 INFO - 'XPC_SERVICE_NAME': '0', 08:36:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:13 INFO - Downloading/unpacking jsonschema 08:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:36:15 INFO - Downloading/unpacking functools32 (from jsonschema) 08:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:16 INFO - Downloading functools32-3.2.3-2.tar.gz 08:36:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:36:16 INFO - warning: no files found matching '*.txt' 08:36:16 INFO - no previously-included directories found matching 'build' 08:36:16 INFO - no previously-included directories found matching 'dist' 08:36:16 INFO - no previously-included directories found matching '.git*' 08:36:16 INFO - Installing collected packages: jsonschema, functools32 08:36:16 INFO - Running setup.py install for functools32 08:36:16 INFO - warning: no files found matching '*.txt' 08:36:16 INFO - no previously-included directories found matching 'build' 08:36:16 INFO - no previously-included directories found matching 'dist' 08:36:16 INFO - no previously-included directories found matching '.git*' 08:36:16 INFO - Successfully installed jsonschema functools32 08:36:16 INFO - Cleaning up... 08:36:16 INFO - Return code: 0 08:36:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:36:16 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')]} 08:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:16 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')]} 08:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:16 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 0x1017d4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017e4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1ebce2e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, '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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:36:16 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 08:36:16 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 08:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:16 INFO - 'HOME': '/Users/cltbld', 08:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:16 INFO - 'LOGNAME': 'cltbld', 08:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:16 INFO - 'MOZ_NO_REMOTE': '1', 08:36:16 INFO - 'NO_EM_RESTART': '1', 08:36:16 INFO - 'PAGER': '/bin/cat', 08:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:16 INFO - 'PWD': '/builds/slave/test', 08:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:16 INFO - 'SHELL': '/bin/bash', 08:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:16 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:16 INFO - 'USER': 'cltbld', 08:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:16 INFO - 'XPC_FLAGS': '0x0', 08:36:16 INFO - 'XPC_SERVICE_NAME': '0', 08:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:16 INFO - Downloading/unpacking flake8 08:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:36:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:36:19 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 08:36:19 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 08:36:19 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 08:36:19 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 08:36:19 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 08:36:19 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 08:36:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:36:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:36:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:36:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:36:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:36:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:36:21 INFO - no previously-included directories found matching 'docs/_build' 08:36:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:36:21 INFO - Running setup.py install for pep8 08:36:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:36:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:36:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:36:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:36:21 INFO - no previously-included directories found matching 'docs/_build' 08:36:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:36:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:36:21 INFO - Cleaning up... 08:36:21 INFO - Return code: 0 08:36:21 INFO - Running post-action listener: _resource_record_post_action 08:36:21 INFO - Running post-action listener: _start_resource_monitoring 08:36:21 INFO - Starting resource monitoring. 08:36:21 INFO - ##### 08:36:21 INFO - ##### Running install step. 08:36:21 INFO - ##### 08:36:21 INFO - Running pre-action listener: _resource_record_pre_action 08:36:21 INFO - Running main action method: install 08:36:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:36:21 INFO - Reading from file tmpfile_stdout 08:36:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:36:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:36:21 INFO - Reading from file tmpfile_stdout 08:36:21 INFO - Output received: 08:36:21 INFO - Usage: mozinstall [options] installer 08:36:21 INFO - Options: 08:36:21 INFO - -h, --help show this help message and exit 08:36:21 INFO - -d DEST, --destination=DEST 08:36:21 INFO - Directory to install application into. [default: 08:36:21 INFO - "/builds/slave/test"] 08:36:21 INFO - --app=APP Application being installed. [default: firefox] 08:36:21 INFO - mkdir: /builds/slave/test/build/application 08:36:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:36:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:36:43 INFO - Reading from file tmpfile_stdout 08:36:43 INFO - Output received: 08:36:43 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:36:43 INFO - Running post-action listener: _resource_record_post_action 08:36:43 INFO - ##### 08:36:43 INFO - ##### Running run-tests step. 08:36:43 INFO - ##### 08:36:43 INFO - Running pre-action listener: _resource_record_pre_action 08:36:43 INFO - Running main action method: run_tests 08:36:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:43 INFO - 2016-07-20 08:36:43.563 screenresolution[2601:286562] starting screenresolution argv=screenresolution get 08:36:43 INFO - 2016-07-20 08:36:43.566 screenresolution[2601:286562] Display 0: 1600x1200x32@60 08:36:43 INFO - 2016-07-20 08:36:43.572 screenresolution[2602:286565] starting screenresolution argv=screenresolution list 08:36:43 INFO - Available Modes on Display 0 08:36:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:43 INFO - Intel Iris: 08:36:43 INFO - Chipset Model: Intel Iris 08:36:43 INFO - Type: GPU 08:36:43 INFO - Bus: Built-In 08:36:43 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:43 INFO - Vendor: Intel (0x8086) 08:36:43 INFO - Device ID: 0x0a2e 08:36:43 INFO - Revision ID: 0x0009 08:36:43 INFO - Displays: 08:36:43 INFO - Display: 08:36:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:43 INFO - Display Serial Number: 1600x1200 60 08:36:43 INFO - Main Display: Yes 08:36:43 INFO - Mirror: Off 08:36:43 INFO - Online: Yes 08:36:43 INFO - Rotation: Supported 08:36:43 INFO - Return code: 0 08:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:36:43 INFO - Python 2.7.3 08:36:43 INFO - Return code: 0 08:36:43 INFO - grabbing minidump binary from tooltool 08:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:43 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 0x101673e00>, '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 0x1014dcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10166eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:36:43 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 08:36:43 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 08:36:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:36:43 INFO - Return code: 0 08:36:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:36:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:36:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:36:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:36:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:36:44 INFO - Return code: 0 08:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 08:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 08:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwZ5HoI8MZ/Render', 08:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:44 INFO - 'HOME': '/Users/cltbld', 08:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:44 INFO - 'LOGNAME': 'cltbld', 08:36:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:36:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:44 INFO - 'MOZ_NO_REMOTE': '1', 08:36:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:36:44 INFO - 'NO_EM_RESTART': '1', 08:36:44 INFO - 'PAGER': '/bin/cat', 08:36:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:44 INFO - 'PWD': '/builds/slave/test', 08:36:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:44 INFO - 'SHELL': '/bin/bash', 08:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpJdzZHm0w/Listeners', 08:36:44 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:44 INFO - 'USER': 'cltbld', 08:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:44 INFO - 'XPC_FLAGS': '0x0', 08:36:44 INFO - 'XPC_SERVICE_NAME': '0', 08:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 08:36:45 INFO - mozversion application_buildid: 20160720071819 08:36:45 INFO - mozversion application_changeset: 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e 08:36:45 INFO - mozversion application_display_name: Firefox 08:36:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:36:45 INFO - mozversion application_name: Firefox 08:36:45 INFO - mozversion application_remotingname: firefox 08:36:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:36:45 INFO - mozversion application_vendor: Mozilla 08:36:45 INFO - mozversion application_version: 48.0 08:36:45 INFO - mozversion platform_buildid: 20160720071819 08:36:45 INFO - mozversion platform_changeset: 9e887ad7d5ebd5369782230a86c3a51f84e9ab0e 08:36:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:36:45 INFO - mozversion platform_version: 48.0 08:36:45 INFO - using testdate: 1469029005 08:36:45 INFO - actual date: 1469029005 08:36:45 INFO - starting webserver on 'localhost:51846' 08:36:45 INFO - SUITE-START | Running 2 tests 08:36:45 INFO - TEST-START | dromaeo_css 08:36:45 INFO - operating with platform_type : mac_ 08:36:45 INFO - Initialising browser for dromaeo_css test... 08:36:45 INFO - TEST-INFO | started process 2620 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpCWLDqR/profile http://localhost:51846/getInfo.html) 08:36:47 INFO - PROCESS | 2620 | __metrics Screen width/height:1600/1200 08:36:47 INFO - PROCESS | 2620 | colorDepth:24 08:36:47 INFO - PROCESS | 2620 | Browser inner width/height: 1024/689 08:36:47 INFO - PROCESS | 2620 | __metrics 08:36:47 INFO - PROCESS | 2620 | 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" 08:36:47 ERROR - PROCESS | 2620 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:36:48 INFO - TEST-INFO | 2620: exit 0 08:36:48 INFO - Browser initialized. 08:36:48 INFO - Running cycle 1/1 for dromaeo_css test... 08:36:48 INFO - TEST-INFO | started process 2624 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpCWLDqR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:37:00 INFO - PROCESS | 2624 | RSS: Main: 160460800 08:37:00 INFO - PROCESS | 2624 | 08:37:51 INFO - PROCESS | 2624 | 1469029071580 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] 08:40:01 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51846/tests/dromaeo/cssquery-ext.html) 08:40:03 INFO - PROCESS | 2624 | RSS: Main: 302784512 08:40:03 INFO - PROCESS | 2624 | 08:43:06 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-ext.html (next: http://localhost:51846/tests/dromaeo/cssquery-jquery.html) 08:43:06 INFO - PROCESS | 2624 | RSS: Main: 300285952 08:43:06 INFO - PROCESS | 2624 | 08:46:08 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51846/tests/dromaeo/cssquery-mootools.html) 08:46:09 INFO - PROCESS | 2624 | RSS: Main: 309469184 08:46:09 INFO - PROCESS | 2624 | 08:49:11 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51846/tests/dromaeo/cssquery-prototype.html) 08:49:11 INFO - PROCESS | 2624 | RSS: Main: 312401920 08:49:11 INFO - PROCESS | 2624 | 08:52:15 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51846/tests/dromaeo/cssquery-yui.html) 08:52:15 INFO - PROCESS | 2624 | RSS: Main: 280039424 08:52:15 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | Cycle 1(1): loaded http://localhost:51846/tests/dromaeo/cssquery-yui.html (next: http://localhost:51846/tests/dromaeo/cssquery-dojo.html) 08:55:18 INFO - PROCESS | 2624 | RSS: Main: 315392000 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | __start_tp_report 08:55:18 INFO - PROCESS | 2624 | _x_x_mozilla_page_load 08:55:18 INFO - PROCESS | 2624 | _x_x_mozilla_page_load_details 08:55:18 INFO - PROCESS | 2624 | |i|pagename|runs| 08:55:18 INFO - PROCESS | 2624 | |0;dojo.html;3414.2317978454853;3508.421110516764;3523.9119022024447;3547.526420128486;3602.2435288589395;5347.144456886894;5729.484346408823;5733.971330143343;5738.42615738426;5761.884762304751;5740.253767010283;5742.368339482654;5746.683932383728;5764.74058667361;5776.566757493184;5226.137687281613;5226.7647955841985;5246.7901283948595;5272.261883336336;5366.731663416833;5282.181261904392;5333.653312534673;5341.732913354336;5353.5984801139875;5354.437784032679;5253.921191182116;5300.151975683892;5322.361316641989;5333.38666053403;5352.598555108363;5313.8937221255355;5322.015427145971;5335.33308336458;5353.518183363516;5376.677399356051;5122.76947537358;5176.534111929906;5271;5278.841634750926;5286.709230992294;5613.354464236624;5641.210230567724;5642.266505354278;5646.576506761969;5682.602217844753;6194.1018552310115;6254.405831446005;6261.7182226799905;6278.780242691484;6279.874402511924;6127.142200091991;6174.59865108771;6263.624182549016;6264.6241225526155;6265.968670156665;5863.472287494146;5907.113932910055;5914.615549989237;5918.556108291896;5924.851878703023;6315.494760419156;6324.968375158095;6327.96836015817;6328.968355158195;6331.461825744863;6077.270727512639;6104.908426373608;6153.84615384619;6158.538109641795;6251.874962500725;6210.5652604317265;6233.470155036782;6242.344553821874;6243.656598887014;6248.718807653667;6160.599561028519;6246.312905580383;6263.123162757218;6276.780312689034;6280.183576135073;6176.073588961602;6264.62412255257;6277.058441233851;6285.497160227171;6289.937100629027;4911.901761964741;4972.254161875748;4988.5260900214425;5009.7495125243595;5038.2190760432195;4464.1071785643;4485.170243504962;4489.124620698998;4491.932621010687;4534.79593418297;5657.51911087554;5849.678267695318;5861.970690146607;5888.322842873039;5897.144913987534;6163.38366163389;6173.259208894871;6237;6259.530535209878;6263.6241825490615;5781.53747700183;5813.7383817728305;5817.476427121579;5818.5636077294375;5821.883562328814;5354.7858085676135;5455.0453670607485;5456.7544460499375;5457.454254574511;5462;6243.344448832807;6344.904826427607;6359.554831161774;6380.074889141141;6386.872262554816;6150.907736384047;6238.75044998195;6243.500519958483;6248.031555108965;6252.374762523711;6269.435750782452;6393.104965304954;6406.615603063831;6415.166028416276;6424.775132870327;6175.84560385994;6247.843803904939;6264.091706702594;6279.654618995999;6287.182666253358;4825.855224343275;6214.471769899609;6242.750289988531;6242.937570624235;6247.531435142473;6089.421504957022;6143.849652621725;6161.075838624242;6196.597221180609;6218.595791273553;6129.540284478593;6138.294096178996;6152.477039451518;6228.314885362697;6231.937680623317;4439.866803995886;6042.969785151046;6056.515478761777;6059.091136329585;6074.635521868702;5134.306868572695;6229.937700622936;6233.158523599256;6241.063840423973;6250.499960003098;5804.21643078179;5875.823725288248;5909.2908850938165;5934.9406505934385;6003.549733770072 08:55:18 INFO - PROCESS | 2624 | |1;ext.html;531;542.9972850135749;553.0984495272705;562.4488001758278;611.7491828350377;32515.512267316008;33334.33331333372;40010;40973.59026409735;40999.770006899766;44638.10723785531;45034;45309.546904530944;45350.31974520385;45408.545914540846;8426.241638252555;9655.806883862337;9846.55690493926;9890.950545247264;9892.85160722586;9233.122853328923;12048.879511204907;12331.321777302244;12350.629481115537;12362;11967.521299148024;12065.457054432527;12066.939665301705;12078.818817717742;12097.153199276054;11985.40072996351;12156.209846360047;12167;12182.903538681472;12200.206986545845;11892;11992.280463172237;11996.460159292852;12000.219985700902;12013.099017573717;41907.79046104781;42291;42303.57696423027;42329;42336;3261.184703824044;3261.6901394367496;3309.271960168759;3361.5125806757883;3374.7131493823053;28077.719222807715;28078.859605702044;28090.01684941017;28444.288892777848;29041.41917161645;2705.296622878046;2876.1659118855505;2895.580140879582;2900.7244311790346;2902.259923719459;445322;454301;454355.9999999967;454760;455979;69466.65266673634;69831.95252071224;69907.30092699008;70006;70072.64963675149;73148;73273.6336318315;73350;73383;73387;15967.920160399124;17371.73942390865;19472.513187170152;19655.508612284808;19663;13193;13263.336833158304;13263.602091937257;14739.189344586151;15126.924365378323;16969.35571196535;20159;20527.4868128298;20662.17351305961;20690;8146.429749917449;8211.917880821235;8220.780389294976;8282.50304981703;8313.709020184264;905.5200743605899;946.6970569417721;977.3744803325877;1006.9527691201068;1029.9691009269734;28426;30934.53598196029;32202.516962245583;33051.66948330486;33080.83459582735;898.6854600889767;1168.4099529737427;1209.4134344842744;1223.2110288863812;1256.9944044764152;13889.652758681113;15779;16094.356225750838;16105.919470402572;16242.593935151715;643959;650042;657550;657981;658131;65720.01419978704;66166.33833661601;66284.66857665681;66351;66449.33550664528;1411.1533080151826;1536.2933050796535;1565.616423976133;1571.2143928035982;1591.275969433928;25559.872200638878;29646;30138.849305754207;32176.678233217368;33010.83494582512;59722.104168438374;59783.70108149431;60675.393246066975;60765;60820.695896520236;539855;544045;544805;545519;548268;37817.81091094527;45002.54997449984;45078;45369;46202;45589.77205114086;45900.77049614731;46314.53685463102;46382;46969;36160.91517254487;37225.81387093047;37491.250174996894;37595.81202093972;38285.808570956964;37002.62997369992;37266.06834829151;37800;37824.62175378211;39843.800780997066;39811.80094099627;40162.598374015884;40435.39346909858;41140;42393;32102.506341857104;35078;35416.822915885255;35438.82280588581;35463;22578.548429031656;25771.355716107246;26199;26747.465050699266;26936.865315673294 08:55:18 INFO - PROCESS | 2624 | |2;jquery.html;424.53937477836547;424.70695220297983;425.27915183763525;428.44088464553744;436.50238727850257;20762.99999999998;20847.583048339024;21501.892490537546;22712.77287227127;22770;18084.547886302847;22081.889590552066;22129;22396.77603223967;22406.77593224067;5894.587378883469;6161.476274516649;6210.509477725336;6215.5338349623735;6225.097360882669;4405.713628614146;4491.393661855656;4641.976790116061;6074.967255566565;6196.256449226099;5771.595988280822;6798.592084474947;6799.76200832971;6813.591184528919;6820.863582728342;6407.391297826699;6667.066610674509;6677.666116694146;6699.497537684693;6768.729250829961;4063.5326937402283;5685.40303268155;5875.735591898375;5884;5888.205092312526;26340.078097266694;26419.207423777316;26726;26781.464370712478;26857.717094047028;1767.603593161401;2192.5395666909967;2335.1484558209772;2439.890204940782;2465.927321615103;14439.639009024859;14782.260886955717;15200.695986080327;15229.238538073052;15274.15992120433;1749.2565659594623;1758.141788498908;1809.1044932758236;1815.6550255451555;1876.5121068522146;104082;104743.42884856733;106060.46969765179;107509;107796;9513.714588562354;10794.406307652998;11200.439978001068;11316;11348.432578371048;14259;14279.57161285163;14307.141571505743;14309.427622895177;14355.928220358832;8732.825343493094;8876.51179185138;8890.777730556787;8970.461772293684;8971.551422428984;8796.516191609395;8831.293496520262;8831.470111793313;8930.106989301017;8949.59726812295;6476.773312934025;6567.307403964447;6607.537472376984;6619;6625.900611490832;1800.703493484689;1820.5795626855236;1849.9537511562319;1853.2309091727052;1914.7032210007471;975.0444564326999;995.7261753017978;1001.6544292219173;1008.4604736465956;1047.696168111239;19528;20865.58268834615;21707.240246591293;22681.659775103388;22834.543309133725;1029.0841151375282;1036.6993571864075;1052.3896140238644;1058.111186603257;1073.3988966178965;11831.230969987095;11994.400279985966;12042.81935770964;12046.91577758006;12108.939455302843;489320;539165;640718;662941;665186;147840;149665;151456.48543514643;151758;153765.2311738434;1072.978540429187;1089.6676513663251;1095.9287646302964;1098.2586753941057;1114.2924243105685;13690.589282321545;18515.537111572317;19102.713459297844;19104.140313686195;19192.904035479733;40495.392569110896;49948.001039979725;50497;52501.7374913123;52707.73646131898;68682.65658671675;70558.64720676569;72832.63583682048;73609;73824.63087684527;6893.896591551031;8059;8242.587870606325;8369.83260334802;8745.300375970035;7249.601271930094;10135.783705955333;11685.824712629488;12927.288999104763;13236.272005039815;10763.677089687322;10949.945250273699;11020.779584408427;11721.648350549498;11726.589569365031;6612.570182938093;11155.944220278847;11350.148738844784;11602.535898563963;11672.007879330353;5451.700156491431;5858.531317494675;11285.153613479186;11658.941705291758;11942.984846288164;8290.212429819157;8356.206160414751;8473;8496.830063398575;8738.825223495622;10826;10977.012068913837;11415.885841141482;11590.652280431601;11766.235194712659 08:55:18 INFO - PROCESS | 2624 | |3;mootools.html;1448.1600671610468;1601.575582470645;1622.2213337597952;1877.5024618476107;1977.8121078497538;8365;8869.46783193009;9000.144986226307;9037.277017838574;9048.185663290302;8860.158284962934;9103.317251206154;9113.589888455019;9122.498262595554;9126.452412855233;2551.336652470357;2581.1224183777513;2582.4318649897086;2583.250857251395;2586.056089527328;2532.3795670060863;2596.376869551313;2605.7785088267424;2606.5047640948255;2606.543854825398;2189.408859607905;2537.619357096432;2586.314626623946;2594.247668176227;2599.8050146239107;2541.6314634378004;2567.653366795487;2578.948421031578;2586.560284751578;2587.1203790711247;2166.9566608667897;2541.707703614071;2575.085844525185;2589.961150582743;2593.753593408623;8862.512561809099;9127.726168214964;9195.954020229923;9206.033366496555;9213.585388657526;5653.123765816264;5806.042003069489;5856.677882716449;5859.970700146472;5885.117232415172;3717.5167228260425;3761.492198553188;3838.9826695925603;3866.7486613370043;3873.0704630888667;1065.2649671726483;1083.1389045708681;1088.37962361454;1103.2332528892423;1114.6154576671036;12419.813702794465;12516.186447880858;12666.11337206396;12675.556355527604;12693.365331733468;4837.927431088537;4840.322354870321;4963.205887058053;4974.577160941288;4998.625103117281;9850.162736167505;9856.162226210708;9875.160611347972;9888.258380621483;9930.453825039547;5706.885862282731;5743;5745.482906538348;5749.281339832521;5755.482006619341;4703.153432382135;4829.251466022772;5081.466446023188;5294.258803767476;5339.385970613352;4021.91956160881;4041.5958404159937;4103.753774773523;4127.7936103194725;4129.174165166979;1274.1144904291614;1281.0904257976754;1282.499825068224;1288.7293668329569;1293.9482420703255;950.2493030505823;981.460196891721;981.9459929703792;986.3982970388057;994.9701508954743;4939.75301234944;5934.109883517507;5959;5967.104934259895;6125.326214116443;479.9136155492045;482.12975579080097;482.2332491338718;491.16992283041543;496.36713190681445;3959.012575687019;3980.681545476355;4058.776767277769;4112.177564487115;4125.546189919107;11034.779304413867;11307.151963602599;11601.245919015237;11627.767444651228;11637;11268.605598804003;11373.20387572862;11425;11428.914253145938;11493.252938558968;8653.783655408664;8731.60707768152;8790.25282850952;8850.159234872677;8868.891388576429;5956.017257152521;5988.131720900532;5993.580449368503;6141.324454309933;6208.975519039489;9950.601975920883;9968.152707019695;10333.173346132442;10363.533640986023;10370.170386368922;10428.478576071318;10564.84152737725;10582.888796676547;10771.838422423512;10804.567817287534;7162.606056666725;7355.5954422507275;7630.046244219473;7854.60726963661;8024.839503209786;8627.180417860292;8651.048384677806;8722.127787221227;8733;8734;7506.061742282215;7615.923840761521;7702.845943081219;7728.304452599293;7743.257767002605;7479.252074792477;7554.2823431773895;7587.58268295249;7640.3505702015955;7708.421868359784;7205.495615306878;7304.598247096461;7315.30504602062;7328.670209840677;7341.302576255247;4972.701637901593;5012.77442515095;5102.336696229467;5150.48495150482;5191.92212116811;5132.435432102391;5165.612579056661;5182.455842136521;5193.76628051731;5275.657082289639 08:55:18 INFO - PROCESS | 2624 | |4;prototype.html;337.49038951183707;342.1787709497206;347.04043320219597;354.59398988158574;356.44038858991377;4877.853664390065;6630.801075967716;6722.764703235388;6725.3274672532725;6748.561343512668;6474.449671777893;6609.834754131137;6669.066330713704;6688.097106890568;6734.0572319875255;4960.280759289901;4982.775775090112;5005.5995520358465;5016.974915125437;5022.221555658861;4792.376990991166;4855.155041084383;5017.673851199678;5020.97489512552;5051.873703157414;4985.027919555688;5010.69935803853;5010.747313171707;5011.398632164145;5021.84934451967;5004.349434573519;5006.399232092136;5008.7996480140755;5009.499050094962;5011.899762004776;4271.9960809209815;4280.443542339476;4280.5719428057255;4287.078278170177;4304.117655880537;6439.291677915426;6456.451201647866;6465.224173099211;6470.158879345655;6473.705258948229;5496.560275177976;5672.68800215988;5882.999890018707;5884.941150588482;5901.940980590182;7047.753328633525;7136.785896423115;7152.320529549685;7153.320434558708;7164.21193668696;4659.953400465985;4670.1126785910565;4671.34601155835;4676.649251306166;4682.6253899687945;6120;7311.744088956861;7500.849983000364;7646.655900484491;7650.617469126577;4709.434867815884;4756;4762.976185119052;4769.809207631725;4779.354787103766;4831.033793241366;4849.854504364875;4852.611791056707;4858.611311095104;4866.29438731389;5779.971100144557;5792.913106303409;5807.941920580824;5816;5841.532677385799;7245.166805817293;7251.202367739545;7261.963690181516;7273;7282.526635768764;1624.1797892064433;1667.3664007677005;1669.232153209513;1669.6159883226926;1674;1039.8908114647852;1058.30680904008;1073.039629531565;1073.213100188178;1075.5805235957982;6633.535652504278;6690.464762818963;6713.26154123046;6732.596044237362;6766.25571187169;7186.598613270362;7445.85108297831;7451.403887688972;7465.104187497535;7471.70113195477;5773.740181691834;6701.564398314094;6728.764493242713;6737.090492783412;6751.392374686302;3774.452704357853;3890.2414029264023;3918.9608103919168;3935.4883865097354;3944.2703099926607;7728.033995750531;7740.458167928304;7756.379489640815;7762.7671169865;7778.766637000899;7556.1310449297935;7610.657520411595;7620.314171724572;7640.083190017233;7648.08223013242;7713.112992005879;7722.189170137055;7730.265624765639;7730.497517661334;7733.265339792598;7098.432125430057;7366.226546212785;7383.667734952048;7404.592747398729;7405.8148546286775;7218.169910460364;7258.0927384076995;7280.453965952681;7280.96359518199;7281.890771638324;7631.855221716787;7688.692452301845;7697.191794861459;7706.383489320729;7724.729634462767;3930.155016671429;4516.9096618068115;4636.559526844944;4637.350770892011;4641.83753568637;7217.927820721936;7230.060092188192;7502.287282708134;7509.474336796372;7513.060867391576;7226.963865180641;7232.385247253832;7243.275672432715;7245.746398876225;7246.4202863769715;6556.2788093308745;7216.96391518039;7239.312265334785;7255.09311336083;7264.709411623688;5918.644881306963;6537.1501704778075;7064.222935477196;7093.397061249645;7102.609356485236;6461.1600491937625;7038.225795162631;7160.176579693402;7213.495055346076;7235.602041887747;7062.858742825218;7063.081799366255;7079.929200708133;7080.4335653148655;7087 08:55:18 INFO - PROCESS | 2624 | |5;yui.html;709.7161135545782;761.2387612387611;769.9114601820793;798.6366203377464;801.9278264956152;15408.152551609672;15818.367265309402;15832.050076995374;15839.683206335894;15880.285387157577;13948.651283717913;14533.345999430025;14539.854601453982;14553.345099470524;14554.199519026475;775.1163673412315;796.4146352430959;835.6406745099636;991.9733076266066;994.9403035817838;960.279790157382;968.6900191938582;975.1223898491344;979.2753362511726;980.7253968888723;833.4082801211148;1035.9171266298715;1037.305005646219;1040.126293913113;1040.2250323508997;1026.1893104447477;1039.2257767962917;1045.979080418395;1046.3355769086647;1047.9895201047968;776.4991580430647;776.7592046465613;778.6924164954811;779.4465929190225;779.9821233290554;15289.923550382287;15313.846861531465;15405.537833865003;15558.455454059054;15627.765583516257;12958.416871240723;13298.202107873558;13394;13403.530876419278;13413.731725365536;10336.660463542521;10390.584376625002;10453.529591168415;10732.35605863643;10845.511951962102;3573.10672331917;3611.259691763182;3620.728445366608;3674.503941967827;3832.5209348831395;65814;83367.16632833655;83837;84311.57844210739;85182.99999999937;9714.562844671866;9975.301728878909;10099.293049486612;10111;10277;19719.507012324808;22728;23777;23805.523889522112;23822.88088559581;7906.288434040849;7935.206479352134;8018.759437216893;8090.9886264216975;8134.105248422668;4297.52727200014;4317.41714868495;4352.347147927836;4389.056352884145;4471.977640111843;3755.7746535207975;3815.5039844819994;3816.5992570779667;3818.0073180973145;3820.9617903820604;603.2097951683309;655.7377049180366;707.2750430808462;707.9610621415871;709.1738125084322;531.1422053383758;547.8253520846703;608.9695515224221;609.4728060227959;648.6011103171516;8338.499690018618;8451.577421128919;8465.95767021161;8594.914050859412;8903.065178156201;533.8932213557304;574.379669956446;581.2617975171509;609.8414412252846;612.8621060261405;3799.2021675447913;3985.740926839804;4017.8593749218635;4073.674106071507;4122.505299364096;98017;99445.5083173753;99804.50097749465;100911;101026.4948675252;82296;83794.74307885386;84577.57711211404;85550;86195;72973;74965.25034749582;75544;75823;77008.19204414096;8575;8661.956690216508;8882.955585222033;8904.944548508987;8990.910090899008;48223.27665085097;48285.758571208324;49564.50435495599;50142.498575013786;51836.74081629568;63127.36872631215;67638;69968.65015674889;69998.65000674964;71041.6447917757;11681.649550513499;11786.939175474248;11915.285082895054;12063.336516491678;12330.506779729069;11948.880511194777;12097.455614497192;12237.082218833444;12245.612105639315;12634.052446066398;8785.522259573929;11820.76358472843;12097.57658481949;12393.75212495727;12622.712519165994;9731.854022189815;11479.942600286946;11832.053435724949;12239.388030598613;12281.140320177501;10701.571937122428;11541.826872597087;11654.88345116538;11788.587399440978;11795.823062654239;9358.44409908586;9892.307538472238;10082.243831712503;10085.999653027278;10130.341527800669;10046.346987445793;10262.794744105226;10263.538140783534;10268.383896966207;10299.3820370778 08:55:18 INFO - PROCESS | 2624 | __end_tp_report 08:55:18 INFO - PROCESS | 2624 | __start_cc_report 08:55:18 INFO - PROCESS | 2624 | _x_x_mozilla_cycle_collect,1302 08:55:18 INFO - PROCESS | 2624 | __end_cc_report 08:55:18 INFO - PROCESS | 2624 | __startTimestamp1469030118600__endTimestamp 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | ------- Summary: start ------- 08:55:18 INFO - PROCESS | 2624 | Number of tests: 6 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#0] dojo.html Cycles:165 Average:5775.26 Median:6003.55 stddev:628.85 (10.5%) stddev-sans-first:602.88 08:55:18 INFO - PROCESS | 2624 | Values: 3414.2 3508.4 3523.9 3547.5 3602.2 5347.1 5729.5 5734.0 5738.4 5761.9 5740.3 5742.4 5746.7 5764.7 5776.6 5226.1 5226.8 5246.8 5272.3 5366.7 5282.2 5333.7 5341.7 5353.6 5354.4 5253.9 5300.2 5322.4 5333.4 5352.6 5313.9 5322.0 5335.3 5353.5 5376.7 5122.8 5176.5 5271.0 5278.8 5286.7 5613.4 5641.2 5642.3 5646.6 5682.6 6194.1 6254.4 6261.7 6278.8 6279.9 6127.1 6174.6 6263.6 6264.6 6266.0 5863.5 5907.1 5914.6 5918.6 5924.9 6315.5 6325.0 6328.0 6329.0 6331.5 6077.3 6104.9 6153.8 6158.5 6251.9 6210.6 6233.5 6242.3 6243.7 6248.7 6160.6 6246.3 6263.1 6276.8 6280.2 6176.1 6264.6 6277.1 6285.5 6289.9 4911.9 4972.3 4988.5 5009.7 5038.2 4464.1 4485.2 4489.1 4491.9 4534.8 5657.5 5849.7 5862.0 5888.3 5897.1 6163.4 6173.3 6237.0 6259.5 6263.6 5781.5 5813.7 5817.5 5818.6 5821.9 5354.8 5455.0 5456.8 5457.5 5462.0 6243.3 6344.9 6359.6 6380.1 6386.9 6150.9 6238.8 6243.5 6248.0 6252.4 6269.4 6393.1 6406.6 6415.2 6424.8 6175.8 6247.8 6264.1 6279.7 6287.2 4825.9 6214.5 6242.8 6242.9 6247.5 6089.4 6143.8 6161.1 6196.6 6218.6 6129.5 6138.3 6152.5 6228.3 6231.9 4439.9 6043.0 6056.5 6059.1 6074.6 5134.3 6229.9 6233.2 6241.1 6250.5 5804.2 5875.8 5909.3 5934.9 6003.5 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#1] ext.html Cycles:180 Average:70721.09 Median:37378.66 stddev:148301.08 (396.8%) stddev-sans-first:148623.47 08:55:18 INFO - PROCESS | 2624 | Values: 531.0 543.0 553.1 562.4 611.7 32515.5 33334.3 40010.0 40973.6 40999.8 44638.1 45034.0 45309.5 45350.3 45408.5 8426.2 9655.8 9846.6 9891.0 9892.9 9233.1 12048.9 12331.3 12350.6 12362.0 11967.5 12065.5 12066.9 12078.8 12097.2 11985.4 12156.2 12167.0 12182.9 12200.2 11892.0 11992.3 11996.5 12000.2 12013.1 41907.8 42291.0 42303.6 42329.0 42336.0 3261.2 3261.7 3309.3 3361.5 3374.7 28077.7 28078.9 28090.0 28444.3 29041.4 2705.3 2876.2 2895.6 2900.7 2902.3 445322.0 454301.0 454356.0 454760.0 455979.0 69466.7 69832.0 69907.3 70006.0 70072.6 73148.0 73273.6 73350.0 73383.0 73387.0 15967.9 17371.7 19472.5 19655.5 19663.0 13193.0 13263.3 13263.6 14739.2 15126.9 16969.4 20159.0 20527.5 20662.2 20690.0 8146.4 8211.9 8220.8 8282.5 8313.7 905.5 946.7 977.4 1007.0 1030.0 28426.0 30934.5 32202.5 33051.7 33080.8 898.7 1168.4 1209.4 1223.2 1257.0 13889.7 15779.0 16094.4 16105.9 16242.6 643959.0 650042.0 657550.0 657981.0 658131.0 65720.0 66166.3 66284.7 66351.0 66449.3 1411.2 1536.3 1565.6 1571.2 1591.3 25559.9 29646.0 30138.8 32176.7 33010.8 59722.1 59783.7 60675.4 60765.0 60820.7 539855.0 544045.0 544805.0 545519.0 548268.0 37817.8 45002.5 45078.0 45369.0 46202.0 45589.8 45900.8 46314.5 46382.0 46969.0 36160.9 37225.8 37491.3 37595.8 38285.8 37002.6 37266.1 37800.0 37824.6 39843.8 39811.8 40162.6 40435.4 41140.0 42393.0 32102.5 35078.0 35416.8 35438.8 35463.0 22578.5 25771.4 26199.0 26747.5 26936.9 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#2] jquery.html Cycles:180 Average:35348.57 Median:22544.22 stddev:101032.19 (448.2%) stddev-sans-first:101281.58 08:55:18 INFO - PROCESS | 2624 | Values: 424.5 424.7 425.3 428.4 436.5 20763.0 20847.6 21501.9 22712.8 22770.0 18084.5 22081.9 22129.0 22396.8 22406.8 5894.6 6161.5 6210.5 6215.5 6225.1 4405.7 4491.4 4642.0 6075.0 6196.3 5771.6 6798.6 6799.8 6813.6 6820.9 6407.4 6667.1 6677.7 6699.5 6768.7 4063.5 5685.4 5875.7 5884.0 5888.2 26340.1 26419.2 26726.0 26781.5 26857.7 1767.6 2192.5 2335.1 2439.9 2465.9 14439.6 14782.3 15200.7 15229.2 15274.2 1749.3 1758.1 1809.1 1815.7 1876.5 104082.0 104743.4 106060.5 107509.0 107796.0 9513.7 10794.4 11200.4 11316.0 11348.4 14259.0 14279.6 14307.1 14309.4 14355.9 8732.8 8876.5 8890.8 8970.5 8971.6 8796.5 8831.3 8831.5 8930.1 8949.6 6476.8 6567.3 6607.5 6619.0 6625.9 1800.7 1820.6 1850.0 1853.2 1914.7 975.0 995.7 1001.7 1008.5 1047.7 19528.0 20865.6 21707.2 22681.7 22834.5 1029.1 1036.7 1052.4 1058.1 1073.4 11831.2 11994.4 12042.8 12046.9 12108.9 489320.0 539165.0 640718.0 662941.0 665186.0 147840.0 149665.0 151456.5 151758.0 153765.2 1073.0 1089.7 1095.9 1098.3 1114.3 13690.6 18515.5 19102.7 19104.1 19192.9 40495.4 49948.0 50497.0 52501.7 52707.7 68682.7 70558.6 72832.6 73609.0 73824.6 6893.9 8059.0 8242.6 8369.8 8745.3 7249.6 10135.8 11685.8 12927.3 13236.3 10763.7 10949.9 11020.8 11721.6 11726.6 6612.6 11155.9 11350.1 11602.5 11672.0 5451.7 5858.5 11285.2 11658.9 11943.0 8290.2 8356.2 8473.0 8496.8 8738.8 10826.0 10977.0 11415.9 11590.7 11766.2 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#3] mootools.html Cycles:180 Average:5963.57 Median:4986.60 stddev:3363.06 (67.4%) stddev-sans-first:3355.37 08:55:18 INFO - PROCESS | 2624 | Values: 1448.2 1601.6 1622.2 1877.5 1977.8 8365.0 8869.5 9000.1 9037.3 9048.2 8860.2 9103.3 9113.6 9122.5 9126.5 2551.3 2581.1 2582.4 2583.3 2586.1 2532.4 2596.4 2605.8 2606.5 2606.5 2189.4 2537.6 2586.3 2594.2 2599.8 2541.6 2567.7 2578.9 2586.6 2587.1 2167.0 2541.7 2575.1 2590.0 2593.8 8862.5 9127.7 9196.0 9206.0 9213.6 5653.1 5806.0 5856.7 5860.0 5885.1 3717.5 3761.5 3839.0 3866.7 3873.1 1065.3 1083.1 1088.4 1103.2 1114.6 12419.8 12516.2 12666.1 12675.6 12693.4 4837.9 4840.3 4963.2 4974.6 4998.6 9850.2 9856.2 9875.2 9888.3 9930.5 5706.9 5743.0 5745.5 5749.3 5755.5 4703.2 4829.3 5081.5 5294.3 5339.4 4021.9 4041.6 4103.8 4127.8 4129.2 1274.1 1281.1 1282.5 1288.7 1293.9 950.2 981.5 981.9 986.4 995.0 4939.8 5934.1 5959.0 5967.1 6125.3 479.9 482.1 482.2 491.2 496.4 3959.0 3980.7 4058.8 4112.2 4125.5 11034.8 11307.2 11601.2 11627.8 11637.0 11268.6 11373.2 11425.0 11428.9 11493.3 8653.8 8731.6 8790.3 8850.2 8868.9 5956.0 5988.1 5993.6 6141.3 6209.0 9950.6 9968.2 10333.2 10363.5 10370.2 10428.5 10564.8 10582.9 10771.8 10804.6 7162.6 7355.6 7630.0 7854.6 8024.8 8627.2 8651.0 8722.1 8733.0 8734.0 7506.1 7615.9 7702.8 7728.3 7743.3 7479.3 7554.3 7587.6 7640.4 7708.4 7205.5 7304.6 7315.3 7328.7 7341.3 4972.7 5012.8 5102.3 5150.5 5191.9 5132.4 5165.6 5182.5 5193.8 5275.7 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#4] prototype.html Cycles:180 Average:5867.35 Median:6651.30 stddev:1867.52 (28.1%) stddev-sans-first:1826.06 08:55:18 INFO - PROCESS | 2624 | Values: 337.5 342.2 347.0 354.6 356.4 4877.9 6630.8 6722.8 6725.3 6748.6 6474.4 6609.8 6669.1 6688.1 6734.1 4960.3 4982.8 5005.6 5017.0 5022.2 4792.4 4855.2 5017.7 5021.0 5051.9 4985.0 5010.7 5010.7 5011.4 5021.8 5004.3 5006.4 5008.8 5009.5 5011.9 4272.0 4280.4 4280.6 4287.1 4304.1 6439.3 6456.5 6465.2 6470.2 6473.7 5496.6 5672.7 5883.0 5884.9 5901.9 7047.8 7136.8 7152.3 7153.3 7164.2 4660.0 4670.1 4671.3 4676.6 4682.6 6120.0 7311.7 7500.8 7646.7 7650.6 4709.4 4756.0 4763.0 4769.8 4779.4 4831.0 4849.9 4852.6 4858.6 4866.3 5780.0 5792.9 5807.9 5816.0 5841.5 7245.2 7251.2 7262.0 7273.0 7282.5 1624.2 1667.4 1669.2 1669.6 1674.0 1039.9 1058.3 1073.0 1073.2 1075.6 6633.5 6690.5 6713.3 6732.6 6766.3 7186.6 7445.9 7451.4 7465.1 7471.7 5773.7 6701.6 6728.8 6737.1 6751.4 3774.5 3890.2 3919.0 3935.5 3944.3 7728.0 7740.5 7756.4 7762.8 7778.8 7556.1 7610.7 7620.3 7640.1 7648.1 7713.1 7722.2 7730.3 7730.5 7733.3 7098.4 7366.2 7383.7 7404.6 7405.8 7218.2 7258.1 7280.5 7281.0 7281.9 7631.9 7688.7 7697.2 7706.4 7724.7 3930.2 4516.9 4636.6 4637.4 4641.8 7217.9 7230.1 7502.3 7509.5 7513.1 7227.0 7232.4 7243.3 7245.7 7246.4 6556.3 7217.0 7239.3 7255.1 7264.7 5918.6 6537.2 7064.2 7093.4 7102.6 6461.2 7038.2 7160.2 7213.5 7235.6 7062.9 7063.1 7079.9 7080.4 7087.0 08:55:18 INFO - PROCESS | 2624 | 08:55:18 INFO - PROCESS | 2624 | [#5] yui.html Cycles:180 Average:19102.64 Median:4307.47 stddev:27001.87 (626.9%) stddev-sans-first:27042.30 08:55:18 INFO - PROCESS | 2624 | Values: 709.7 761.2 769.9 798.6 801.9 15408.2 15818.4 15832.1 15839.7 15880.3 13948.7 14533.3 14539.9 14553.3 14554.2 775.1 796.4 835.6 992.0 994.9 960.3 968.7 975.1 979.3 980.7 833.4 1035.9 1037.3 1040.1 1040.2 1026.2 1039.2 1046.0 1046.3 1048.0 776.5 776.8 778.7 779.4 780.0 15289.9 15313.8 15405.5 15558.5 15627.8 12958.4 13298.2 13394.0 13403.5 13413.7 10336.7 10390.6 10453.5 10732.4 10845.5 3573.1 3611.3 3620.7 3674.5 3832.5 65814.0 83367.2 83837.0 84311.6 85183.0 9714.6 9975.3 10099.3 10111.0 10277.0 19719.5 22728.0 23777.0 23805.5 23822.9 7906.3 7935.2 8018.8 8091.0 8134.1 4297.5 4317.4 4352.3 4389.1 4472.0 3755.8 3815.5 3816.6 3818.0 3821.0 603.2 655.7 707.3 708.0 709.2 531.1 547.8 609.0 609.5 648.6 8338.5 8451.6 8466.0 8594.9 8903.1 533.9 574.4 581.3 609.8 612.9 3799.2 3985.7 4017.9 4073.7 4122.5 98017.0 99445.5 99804.5 100911.0 101026.5 82296.0 83794.7 84577.6 85550.0 86195.0 72973.0 74965.3 75544.0 75823.0 77008.2 8575.0 8662.0 8883.0 8904.9 8990.9 48223.3 48285.8 49564.5 50142.5 51836.7 63127.4 67638.0 69968.7 69998.7 71041.6 11681.6 11786.9 11915.3 12063.3 12330.5 11948.9 12097.5 12237.1 12245.6 12634.1 8785.5 11820.8 12097.6 12393.8 12622.7 9731.9 11479.9 11832.1 12239.4 12281.1 10701.6 11541.8 11654.9 11788.6 11795.8 9358.4 9892.3 10082.2 10086.0 10130.3 10046.3 10262.8 10263.5 10268.4 10299.4 08:55:18 INFO - PROCESS | 2624 | -------- Summary: end -------- 08:55:18 INFO - PROCESS | 2624 | 08:55:19 INFO - TEST-INFO | 2624: exit 0 08:55:19 INFO - TEST-OK | dromaeo_css | took 1113994ms 08:55:19 INFO - TEST-START | kraken 08:55:19 INFO - operating with platform_type : mac_ 08:55:19 INFO - Initialising browser for kraken test... 08:55:19 INFO - TEST-INFO | started process 2626 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmptYYpuV/profile http://localhost:51846/getInfo.html) 08:55:21 INFO - PROCESS | 2626 | __metrics Screen width/height:1600/1200 08:55:21 INFO - PROCESS | 2626 | colorDepth:24 08:55:21 INFO - PROCESS | 2626 | Browser inner width/height: 1024/689 08:55:21 INFO - PROCESS | 2626 | __metrics 08:55:21 INFO - PROCESS | 2626 | 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" 08:55:21 ERROR - PROCESS | 2626 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:55:21 INFO - TEST-INFO | 2626: exit 0 08:55:21 INFO - Browser initialized. 08:55:21 INFO - Running cycle 1/1 for kraken test... 08:55:21 INFO - TEST-INFO | started process 2629 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmptYYpuV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:33 INFO - PROCESS | 2629 | RSS: Main: 160743424 08:55:33 INFO - PROCESS | 2629 | 08:55:36 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51846/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:37 INFO - PROCESS | 2629 | RSS: Main: 225591296 08:55:37 INFO - PROCESS | 2629 | 08:55:40 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51846/tests/kraken/driver.html?testName=audio-dft) 08:55:40 INFO - PROCESS | 2629 | RSS: Main: 240963584 08:55:40 INFO - PROCESS | 2629 | 08:55:43 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51846/tests/kraken/driver.html?testName=audio-fft) 08:55:44 INFO - PROCESS | 2629 | RSS: Main: 325005312 08:55:44 INFO - PROCESS | 2629 | 08:55:46 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51846/tests/kraken/driver.html?testName=audio-oscillator) 08:55:47 INFO - PROCESS | 2629 | RSS: Main: 231284736 08:55:47 INFO - PROCESS | 2629 | 08:55:49 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51846/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:55:49 INFO - PROCESS | 2629 | RSS: Main: 231579648 08:55:49 INFO - PROCESS | 2629 | 08:55:52 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51846/tests/kraken/driver.html?testName=imaging-darkroom) 08:55:53 INFO - PROCESS | 2629 | RSS: Main: 269344768 08:55:53 INFO - PROCESS | 2629 | 08:55:55 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51846/tests/kraken/driver.html?testName=imaging-desaturate) 08:55:56 INFO - PROCESS | 2629 | RSS: Main: 270884864 08:55:56 INFO - PROCESS | 2629 | 08:55:59 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51846/tests/kraken/driver.html?testName=json-parse-financial) 08:56:00 INFO - PROCESS | 2629 | RSS: Main: 268701696 08:56:00 INFO - PROCESS | 2629 | 08:56:01 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51846/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:56:02 INFO - PROCESS | 2629 | RSS: Main: 258916352 08:56:02 INFO - PROCESS | 2629 | 08:56:04 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:56:04 INFO - PROCESS | 2629 | RSS: Main: 265940992 08:56:04 INFO - PROCESS | 2629 | 08:56:06 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:56:07 INFO - PROCESS | 2629 | RSS: Main: 270319616 08:56:07 INFO - PROCESS | 2629 | 08:56:10 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:56:10 INFO - PROCESS | 2629 | RSS: Main: 277581824 08:56:10 INFO - PROCESS | 2629 | 08:56:13 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:56:14 INFO - PROCESS | 2629 | RSS: Main: 274817024 08:56:14 INFO - PROCESS | 2629 | 08:56:15 INFO - PROCESS | 2629 | Cycle 1(1): loaded http://localhost:51846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51846/tests/kraken/driver.html?testName=ai-astar) 08:56:16 INFO - PROCESS | 2629 | RSS: Main: 336175104 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | __start_tp_report 08:56:16 INFO - PROCESS | 2629 | _x_x_mozilla_page_load 08:56:16 INFO - PROCESS | 2629 | _x_x_mozilla_page_load_details 08:56:16 INFO - PROCESS | 2629 | |i|pagename|runs| 08:56:16 INFO - PROCESS | 2629 | |0;ai-astar;114;121;171;155;113;112;118;165;167;136 08:56:16 INFO - PROCESS | 2629 | |1;audio-beat-detection;156;116;138;184;144;166;137;111;165;117 08:56:16 INFO - PROCESS | 2629 | |2;audio-dft;186;182;183;184;184;182;182;185;185;185 08:56:16 INFO - PROCESS | 2629 | |3;audio-fft;70;81;74;155;55;56;56;68;134;55 08:56:16 INFO - PROCESS | 2629 | |4;audio-oscillator;91;89;142;78;78;88;126;80;77;126 08:56:16 INFO - PROCESS | 2629 | |5;imaging-gaussian-blur;88;84;87;85;86;83;88;84;87;83 08:56:16 INFO - PROCESS | 2629 | |6;imaging-darkroom;93;93;93;93;93;94;94;94;93;93 08:56:16 INFO - PROCESS | 2629 | |7;imaging-desaturate;90;86;86;85;85;85;83;111;111;87 08:56:16 INFO - PROCESS | 2629 | |8;json-parse-financial;59;60;59;85;93;94;50;75;85;80 08:56:16 INFO - PROCESS | 2629 | |9;json-stringify-tinderbox;62;61;65;65;75;69;68;67;66;66 08:56:16 INFO - PROCESS | 2629 | |10;stanford-crypto-aes;79;82;79;78;79;77;78;81;81;84 08:56:16 INFO - PROCESS | 2629 | |11;stanford-crypto-ccm;125;124;124;124;124;126;122;127;145;139 08:56:16 INFO - PROCESS | 2629 | |12;stanford-crypto-pbkdf2;165;169;163;159;165;164;163;157;157;162 08:56:16 INFO - PROCESS | 2629 | |13;stanford-crypto-sha256-iterative;66;67;68;65;69;60;58;66;93;92 08:56:16 INFO - PROCESS | 2629 | __end_tp_report 08:56:16 INFO - PROCESS | 2629 | __start_cc_report 08:56:16 INFO - PROCESS | 2629 | _x_x_mozilla_cycle_collect,1905 08:56:16 INFO - PROCESS | 2629 | __end_cc_report 08:56:16 INFO - PROCESS | 2629 | __startTimestamp1469030176230__endTimestamp 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | ------- Summary: start ------- 08:56:16 INFO - PROCESS | 2629 | Number of tests: 14 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#0] ai-astar Cycles:10 Average:137.20 Median:145.50 stddev:24.75 (17.0%) stddev-sans-first:24.78 08:56:16 INFO - PROCESS | 2629 | Values: 114.0 121.0 171.0 155.0 113.0 112.0 118.0 165.0 167.0 136.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:150.00 stddev:24.39 (16.3%) stddev-sans-first:25.44 08:56:16 INFO - PROCESS | 2629 | Values: 156.0 116.0 138.0 184.0 144.0 166.0 137.0 111.0 165.0 117.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.48 (0.8%) stddev-sans-first:1.33 08:56:16 INFO - PROCESS | 2629 | Values: 186.0 182.0 183.0 184.0 184.0 182.0 182.0 185.0 185.0 185.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#3] audio-fft Cycles:10 Average:80.40 Median:62.00 stddev:35.31 (57.0%) stddev-sans-first:37.25 08:56:16 INFO - PROCESS | 2629 | Values: 70.0 81.0 74.0 155.0 55.0 56.0 56.0 68.0 134.0 55.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:79.00 stddev:24.26 (30.7%) stddev-sans-first:25.62 08:56:16 INFO - PROCESS | 2629 | Values: 91.0 89.0 142.0 78.0 78.0 88.0 126.0 80.0 77.0 126.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.96 (2.3%) stddev-sans-first:1.86 08:56:16 INFO - PROCESS | 2629 | Values: 88.0 84.0 87.0 85.0 86.0 83.0 88.0 84.0 87.0 83.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:56:16 INFO - PROCESS | 2629 | Values: 93.0 93.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 93.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:85.50 stddev:10.74 (12.6%) stddev-sans-first:11.39 08:56:16 INFO - PROCESS | 2629 | Values: 90.0 86.0 86.0 85.0 85.0 85.0 83.0 111.0 111.0 87.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#8] json-parse-financial Cycles:10 Average:74.00 Median:82.50 stddev:15.85 (19.2%) stddev-sans-first:15.86 08:56:16 INFO - PROCESS | 2629 | Values: 59.0 60.0 59.0 85.0 93.0 94.0 50.0 75.0 85.0 80.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:3.89 (5.9%) stddev-sans-first:3.79 08:56:16 INFO - PROCESS | 2629 | Values: 62.0 61.0 65.0 65.0 75.0 69.0 68.0 67.0 66.0 66.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:2.15 (2.7%) stddev-sans-first:2.26 08:56:16 INFO - PROCESS | 2629 | Values: 79.0 82.0 79.0 78.0 79.0 77.0 78.0 81.0 81.0 84.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:125.50 stddev:7.63 (6.1%) stddev-sans-first:8.02 08:56:16 INFO - PROCESS | 2629 | Values: 125.0 124.0 124.0 124.0 124.0 126.0 122.0 127.0 145.0 139.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:163.50 stddev:3.81 (2.3%) stddev-sans-first:3.92 08:56:16 INFO - PROCESS | 2629 | Values: 165.0 169.0 163.0 159.0 165.0 164.0 163.0 157.0 157.0 162.0 08:56:16 INFO - PROCESS | 2629 | 08:56:16 INFO - PROCESS | 2629 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:67.50 stddev:12.14 (18.0%) stddev-sans-first:12.77 08:56:16 INFO - PROCESS | 2629 | Values: 66.0 67.0 68.0 65.0 69.0 60.0 58.0 66.0 93.0 92.0 08:56:16 INFO - PROCESS | 2629 | -------- Summary: end -------- 08:56:16 INFO - PROCESS | 2629 | 08:56:17 INFO - TEST-INFO | 2629: exit 0 08:56:17 INFO - TEST-OK | kraken | took 57886ms 08:56:17 INFO - SUITE-END | took 1171s 08:56:17 INFO - Completed test suite (00:19:32) 08:56:17 INFO - javascript benchmark 08:56:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.2317978454853, 3508.421110516764, 3523.9119022024447, 3547.526420128486, 3602.2435288589395, 5347.144456886894, 5729.484346408823, 5733.971330143343, 5738.42615738426, 5761.884762304751, 5740.253767010283, 5742.368339482654, 5746.683932383728, 5764.74058667361, 5776.566757493184, 5226.137687281613, 5226.7647955841985, 5246.7901283948595, 5272.261883336336, 5366.731663416833, 5282.181261904392, 5333.653312534673, 5341.732913354336, 5353.5984801139875, 5354.437784032679, 5253.921191182116, 5300.151975683892, 5322.361316641989, 5333.38666053403, 5352.598555108363, 5313.8937221255355, 5322.015427145971, 5335.33308336458, 5353.518183363516, 5376.677399356051, 5122.76947537358, 5176.534111929906, 5271.0, 5278.841634750926, 5286.709230992294, 5613.354464236624, 5641.210230567724, 5642.266505354278, 5646.576506761969, 5682.602217844753, 6194.1018552310115, 6254.405831446005, 6261.7182226799905, 6278.780242691484, 6279.874402511924, 6127.142200091991, 6174.59865108771, 6263.624182549016, 6264.6241225526155, 6265.968670156665, 5863.472287494146, 5907.113932910055, 5914.615549989237, 5918.556108291896, 5924.851878703023, 6315.494760419156, 6324.968375158095, 6327.96836015817, 6328.968355158195, 6331.461825744863, 6077.270727512639, 6104.908426373608, 6153.84615384619, 6158.538109641795, 6251.874962500725, 6210.5652604317265, 6233.470155036782, 6242.344553821874, 6243.656598887014, 6248.718807653667, 6160.599561028519, 6246.312905580383, 6263.123162757218, 6276.780312689034, 6280.183576135073, 6176.073588961602, 6264.62412255257, 6277.058441233851, 6285.497160227171, 6289.937100629027, 4911.901761964741, 4972.254161875748, 4988.5260900214425, 5009.7495125243595, 5038.2190760432195, 4464.1071785643, 4485.170243504962, 4489.124620698998, 4491.932621010687, 4534.79593418297, 5657.51911087554, 5849.678267695318, 5861.970690146607, 5888.322842873039, 5897.144913987534, 6163.38366163389, 6173.259208894871, 6237.0, 6259.530535209878, 6263.6241825490615, 5781.53747700183, 5813.7383817728305, 5817.476427121579, 5818.5636077294375, 5821.883562328814, 5354.7858085676135, 5455.0453670607485, 5456.7544460499375, 5457.454254574511, 5462.0, 6243.344448832807, 6344.904826427607, 6359.554831161774, 6380.074889141141, 6386.872262554816, 6150.907736384047, 6238.75044998195, 6243.500519958483, 6248.031555108965, 6252.374762523711, 6269.435750782452, 6393.104965304954, 6406.615603063831, 6415.166028416276, 6424.775132870327, 6175.84560385994, 6247.843803904939, 6264.091706702594, 6279.654618995999, 6287.182666253358, 4825.855224343275, 6214.471769899609, 6242.750289988531, 6242.937570624235, 6247.531435142473, 6089.421504957022, 6143.849652621725, 6161.075838624242, 6196.597221180609, 6218.595791273553, 6129.540284478593, 6138.294096178996, 6152.477039451518, 6228.314885362697, 6231.937680623317, 4439.866803995886, 6042.969785151046, 6056.515478761777, 6059.091136329585, 6074.635521868702, 5134.306868572695, 6229.937700622936, 6233.158523599256, 6241.063840423973, 6250.499960003098, 5804.21643078179, 5875.823725288248, 5909.2908850938165, 5934.9406505934385, 6003.549733770072], "value": 5738.36489503136, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.0, 542.9972850135749, 553.0984495272705, 562.4488001758278, 611.7491828350377, 32515.512267316008, 33334.33331333372, 40010.0, 40973.59026409735, 40999.770006899766, 44638.10723785531, 45034.0, 45309.546904530944, 45350.31974520385, 45408.545914540846, 8426.241638252555, 9655.806883862337, 9846.55690493926, 9890.950545247264, 9892.85160722586, 9233.122853328923, 12048.879511204907, 12331.321777302244, 12350.629481115537, 12362.0, 11967.521299148024, 12065.457054432527, 12066.939665301705, 12078.818817717742, 12097.153199276054, 11985.40072996351, 12156.209846360047, 12167.0, 12182.903538681472, 12200.206986545845, 11892.0, 11992.280463172237, 11996.460159292852, 12000.219985700902, 12013.099017573717, 41907.79046104781, 42291.0, 42303.57696423027, 42329.0, 42336.0, 3261.184703824044, 3261.6901394367496, 3309.271960168759, 3361.5125806757883, 3374.7131493823053, 28077.719222807715, 28078.859605702044, 28090.01684941017, 28444.288892777848, 29041.41917161645, 2705.296622878046, 2876.1659118855505, 2895.580140879582, 2900.7244311790346, 2902.259923719459, 445322.0, 454301.0, 454355.9999999967, 454760.0, 455979.0, 69466.65266673634, 69831.95252071224, 69907.30092699008, 70006.0, 70072.64963675149, 73148.0, 73273.6336318315, 73350.0, 73383.0, 73387.0, 15967.920160399124, 17371.73942390865, 19472.513187170152, 19655.508612284808, 19663.0, 13193.0, 13263.336833158304, 13263.602091937257, 14739.189344586151, 15126.924365378323, 16969.35571196535, 20159.0, 20527.4868128298, 20662.17351305961, 20690.0, 8146.429749917449, 8211.917880821235, 8220.780389294976, 8282.50304981703, 8313.709020184264, 905.5200743605899, 946.6970569417721, 977.3744803325877, 1006.9527691201068, 1029.9691009269734, 28426.0, 30934.53598196029, 32202.516962245583, 33051.66948330486, 33080.83459582735, 898.6854600889767, 1168.4099529737427, 1209.4134344842744, 1223.2110288863812, 1256.9944044764152, 13889.652758681113, 15779.0, 16094.356225750838, 16105.919470402572, 16242.593935151715, 643959.0, 650042.0, 657550.0, 657981.0, 658131.0, 65720.01419978704, 66166.33833661601, 66284.66857665681, 66351.0, 66449.33550664528, 1411.1533080151826, 1536.2933050796535, 1565.616423976133, 1571.2143928035982, 1591.275969433928, 25559.872200638878, 29646.0, 30138.849305754207, 32176.678233217368, 33010.83494582512, 59722.104168438374, 59783.70108149431, 60675.393246066975, 60765.0, 60820.695896520236, 539855.0, 544045.0, 544805.0, 545519.0, 548268.0, 37817.81091094527, 45002.54997449984, 45078.0, 45369.0, 46202.0, 45589.77205114086, 45900.77049614731, 46314.53685463102, 46382.0, 46969.0, 36160.91517254487, 37225.81387093047, 37491.250174996894, 37595.81202093972, 38285.808570956964, 37002.62997369992, 37266.06834829151, 37800.0, 37824.62175378211, 39843.800780997066, 39811.80094099627, 40162.598374015884, 40435.39346909858, 41140.0, 42393.0, 32102.506341857104, 35078.0, 35416.822915885255, 35438.82280588581, 35463.0, 22578.548429031656, 25771.355716107246, 26199.0, 26747.465050699266, 26936.865315673294], "value": 21392.540641652533, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.53937477836547, 424.70695220297983, 425.27915183763525, 428.44088464553744, 436.50238727850257, 20762.99999999998, 20847.583048339024, 21501.892490537546, 22712.77287227127, 22770.0, 18084.547886302847, 22081.889590552066, 22129.0, 22396.77603223967, 22406.77593224067, 5894.587378883469, 6161.476274516649, 6210.509477725336, 6215.5338349623735, 6225.097360882669, 4405.713628614146, 4491.393661855656, 4641.976790116061, 6074.967255566565, 6196.256449226099, 5771.595988280822, 6798.592084474947, 6799.76200832971, 6813.591184528919, 6820.863582728342, 6407.391297826699, 6667.066610674509, 6677.666116694146, 6699.497537684693, 6768.729250829961, 4063.5326937402283, 5685.40303268155, 5875.735591898375, 5884.0, 5888.205092312526, 26340.078097266694, 26419.207423777316, 26726.0, 26781.464370712478, 26857.717094047028, 1767.603593161401, 2192.5395666909967, 2335.1484558209772, 2439.890204940782, 2465.927321615103, 14439.639009024859, 14782.260886955717, 15200.695986080327, 15229.238538073052, 15274.15992120433, 1749.2565659594623, 1758.141788498908, 1809.1044932758236, 1815.6550255451555, 1876.5121068522146, 104082.0, 104743.42884856733, 106060.46969765179, 107509.0, 107796.0, 9513.714588562354, 10794.406307652998, 11200.439978001068, 11316.0, 11348.432578371048, 14259.0, 14279.57161285163, 14307.141571505743, 14309.427622895177, 14355.928220358832, 8732.825343493094, 8876.51179185138, 8890.777730556787, 8970.461772293684, 8971.551422428984, 8796.516191609395, 8831.293496520262, 8831.470111793313, 8930.106989301017, 8949.59726812295, 6476.773312934025, 6567.307403964447, 6607.537472376984, 6619.0, 6625.900611490832, 1800.703493484689, 1820.5795626855236, 1849.9537511562319, 1853.2309091727052, 1914.7032210007471, 975.0444564326999, 995.7261753017978, 1001.6544292219173, 1008.4604736465956, 1047.696168111239, 19528.0, 20865.58268834615, 21707.240246591293, 22681.659775103388, 22834.543309133725, 1029.0841151375282, 1036.6993571864075, 1052.3896140238644, 1058.111186603257, 1073.3988966178965, 11831.230969987095, 11994.400279985966, 12042.81935770964, 12046.91577758006, 12108.939455302843, 489320.0, 539165.0, 640718.0, 662941.0, 665186.0, 147840.0, 149665.0, 151456.48543514643, 151758.0, 153765.2311738434, 1072.978540429187, 1089.6676513663251, 1095.9287646302964, 1098.2586753941057, 1114.2924243105685, 13690.589282321545, 18515.537111572317, 19102.713459297844, 19104.140313686195, 19192.904035479733, 40495.392569110896, 49948.001039979725, 50497.0, 52501.7374913123, 52707.73646131898, 68682.65658671675, 70558.64720676569, 72832.63583682048, 73609.0, 73824.63087684527, 6893.896591551031, 8059.0, 8242.587870606325, 8369.83260334802, 8745.300375970035, 7249.601271930094, 10135.783705955333, 11685.824712629488, 12927.288999104763, 13236.272005039815, 10763.677089687322, 10949.945250273699, 11020.779584408427, 11721.648350549498, 11726.589569365031, 6612.570182938093, 11155.944220278847, 11350.148738844784, 11602.535898563963, 11672.007879330353, 5451.700156491431, 5858.531317494675, 11285.153613479186, 11658.941705291758, 11942.984846288164, 8290.212429819157, 8356.206160414751, 8473.0, 8496.830063398575, 8738.825223495622, 10826.0, 10977.012068913837, 11415.885841141482, 11590.652280431601, 11766.235194712659], "value": 9865.981043174206, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.1600671610468, 1601.575582470645, 1622.2213337597952, 1877.5024618476107, 1977.8121078497538, 8365.0, 8869.46783193009, 9000.144986226307, 9037.277017838574, 9048.185663290302, 8860.158284962934, 9103.317251206154, 9113.589888455019, 9122.498262595554, 9126.452412855233, 2551.336652470357, 2581.1224183777513, 2582.4318649897086, 2583.250857251395, 2586.056089527328, 2532.3795670060863, 2596.376869551313, 2605.7785088267424, 2606.5047640948255, 2606.543854825398, 2189.408859607905, 2537.619357096432, 2586.314626623946, 2594.247668176227, 2599.8050146239107, 2541.6314634378004, 2567.653366795487, 2578.948421031578, 2586.560284751578, 2587.1203790711247, 2166.9566608667897, 2541.707703614071, 2575.085844525185, 2589.961150582743, 2593.753593408623, 8862.512561809099, 9127.726168214964, 9195.954020229923, 9206.033366496555, 9213.585388657526, 5653.123765816264, 5806.042003069489, 5856.677882716449, 5859.970700146472, 5885.117232415172, 3717.5167228260425, 3761.492198553188, 3838.9826695925603, 3866.7486613370043, 3873.0704630888667, 1065.2649671726483, 1083.1389045708681, 1088.37962361454, 1103.2332528892423, 1114.6154576671036, 12419.813702794465, 12516.186447880858, 12666.11337206396, 12675.556355527604, 12693.365331733468, 4837.927431088537, 4840.322354870321, 4963.205887058053, 4974.577160941288, 4998.625103117281, 9850.162736167505, 9856.162226210708, 9875.160611347972, 9888.258380621483, 9930.453825039547, 5706.885862282731, 5743.0, 5745.482906538348, 5749.281339832521, 5755.482006619341, 4703.153432382135, 4829.251466022772, 5081.466446023188, 5294.258803767476, 5339.385970613352, 4021.91956160881, 4041.5958404159937, 4103.753774773523, 4127.7936103194725, 4129.174165166979, 1274.1144904291614, 1281.0904257976754, 1282.499825068224, 1288.7293668329569, 1293.9482420703255, 950.2493030505823, 981.460196891721, 981.9459929703792, 986.3982970388057, 994.9701508954743, 4939.75301234944, 5934.109883517507, 5959.0, 5967.104934259895, 6125.326214116443, 479.9136155492045, 482.12975579080097, 482.2332491338718, 491.16992283041543, 496.36713190681445, 3959.012575687019, 3980.681545476355, 4058.776767277769, 4112.177564487115, 4125.546189919107, 11034.779304413867, 11307.151963602599, 11601.245919015237, 11627.767444651228, 11637.0, 11268.605598804003, 11373.20387572862, 11425.0, 11428.914253145938, 11493.252938558968, 8653.783655408664, 8731.60707768152, 8790.25282850952, 8850.159234872677, 8868.891388576429, 5956.017257152521, 5988.131720900532, 5993.580449368503, 6141.324454309933, 6208.975519039489, 9950.601975920883, 9968.152707019695, 10333.173346132442, 10363.533640986023, 10370.170386368922, 10428.478576071318, 10564.84152737725, 10582.888796676547, 10771.838422423512, 10804.567817287534, 7162.606056666725, 7355.5954422507275, 7630.046244219473, 7854.60726963661, 8024.839503209786, 8627.180417860292, 8651.048384677806, 8722.127787221227, 8733.0, 8734.0, 7506.061742282215, 7615.923840761521, 7702.845943081219, 7728.304452599293, 7743.257767002605, 7479.252074792477, 7554.2823431773895, 7587.58268295249, 7640.3505702015955, 7708.421868359784, 7205.495615306878, 7304.598247096461, 7315.30504602062, 7328.670209840677, 7341.302576255247, 4972.701637901593, 5012.77442515095, 5102.336696229467, 5150.48495150482, 5191.92212116811, 5132.435432102391, 5165.612579056661, 5182.455842136521, 5193.76628051731, 5275.657082289639], "value": 4723.500809948949, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.49038951183707, 342.1787709497206, 347.04043320219597, 354.59398988158574, 356.44038858991377, 4877.853664390065, 6630.801075967716, 6722.764703235388, 6725.3274672532725, 6748.561343512668, 6474.449671777893, 6609.834754131137, 6669.066330713704, 6688.097106890568, 6734.0572319875255, 4960.280759289901, 4982.775775090112, 5005.5995520358465, 5016.974915125437, 5022.221555658861, 4792.376990991166, 4855.155041084383, 5017.673851199678, 5020.97489512552, 5051.873703157414, 4985.027919555688, 5010.69935803853, 5010.747313171707, 5011.398632164145, 5021.84934451967, 5004.349434573519, 5006.399232092136, 5008.7996480140755, 5009.499050094962, 5011.899762004776, 4271.9960809209815, 4280.443542339476, 4280.5719428057255, 4287.078278170177, 4304.117655880537, 6439.291677915426, 6456.451201647866, 6465.224173099211, 6470.158879345655, 6473.705258948229, 5496.560275177976, 5672.68800215988, 5882.999890018707, 5884.941150588482, 5901.940980590182, 7047.753328633525, 7136.785896423115, 7152.320529549685, 7153.320434558708, 7164.21193668696, 4659.953400465985, 4670.1126785910565, 4671.34601155835, 4676.649251306166, 4682.6253899687945, 6120.0, 7311.744088956861, 7500.849983000364, 7646.655900484491, 7650.617469126577, 4709.434867815884, 4756.0, 4762.976185119052, 4769.809207631725, 4779.354787103766, 4831.033793241366, 4849.854504364875, 4852.611791056707, 4858.611311095104, 4866.29438731389, 5779.971100144557, 5792.913106303409, 5807.941920580824, 5816.0, 5841.532677385799, 7245.166805817293, 7251.202367739545, 7261.963690181516, 7273.0, 7282.526635768764, 1624.1797892064433, 1667.3664007677005, 1669.232153209513, 1669.6159883226926, 1674.0, 1039.8908114647852, 1058.30680904008, 1073.039629531565, 1073.213100188178, 1075.5805235957982, 6633.535652504278, 6690.464762818963, 6713.26154123046, 6732.596044237362, 6766.25571187169, 7186.598613270362, 7445.85108297831, 7451.403887688972, 7465.104187497535, 7471.70113195477, 5773.740181691834, 6701.564398314094, 6728.764493242713, 6737.090492783412, 6751.392374686302, 3774.452704357853, 3890.2414029264023, 3918.9608103919168, 3935.4883865097354, 3944.2703099926607, 7728.033995750531, 7740.458167928304, 7756.379489640815, 7762.7671169865, 7778.766637000899, 7556.1310449297935, 7610.657520411595, 7620.314171724572, 7640.083190017233, 7648.08223013242, 7713.112992005879, 7722.189170137055, 7730.265624765639, 7730.497517661334, 7733.265339792598, 7098.432125430057, 7366.226546212785, 7383.667734952048, 7404.592747398729, 7405.8148546286775, 7218.169910460364, 7258.0927384076995, 7280.453965952681, 7280.96359518199, 7281.890771638324, 7631.855221716787, 7688.692452301845, 7697.191794861459, 7706.383489320729, 7724.729634462767, 3930.155016671429, 4516.9096618068115, 4636.559526844944, 4637.350770892011, 4641.83753568637, 7217.927820721936, 7230.060092188192, 7502.287282708134, 7509.474336796372, 7513.060867391576, 7226.963865180641, 7232.385247253832, 7243.275672432715, 7245.746398876225, 7246.4202863769715, 6556.2788093308745, 7216.96391518039, 7239.312265334785, 7255.09311336083, 7264.709411623688, 5918.644881306963, 6537.1501704778075, 7064.222935477196, 7093.397061249645, 7102.609356485236, 6461.1600491937625, 7038.225795162631, 7160.176579693402, 7213.495055346076, 7235.602041887747, 7062.858742825218, 7063.081799366255, 7079.929200708133, 7080.4335653148655, 7087.0], "value": 5244.469143191294, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7161135545782, 761.2387612387611, 769.9114601820793, 798.6366203377464, 801.9278264956152, 15408.152551609672, 15818.367265309402, 15832.050076995374, 15839.683206335894, 15880.285387157577, 13948.651283717913, 14533.345999430025, 14539.854601453982, 14553.345099470524, 14554.199519026475, 775.1163673412315, 796.4146352430959, 835.6406745099636, 991.9733076266066, 994.9403035817838, 960.279790157382, 968.6900191938582, 975.1223898491344, 979.2753362511726, 980.7253968888723, 833.4082801211148, 1035.9171266298715, 1037.305005646219, 1040.126293913113, 1040.2250323508997, 1026.1893104447477, 1039.2257767962917, 1045.979080418395, 1046.3355769086647, 1047.9895201047968, 776.4991580430647, 776.7592046465613, 778.6924164954811, 779.4465929190225, 779.9821233290554, 15289.923550382287, 15313.846861531465, 15405.537833865003, 15558.455454059054, 15627.765583516257, 12958.416871240723, 13298.202107873558, 13394.0, 13403.530876419278, 13413.731725365536, 10336.660463542521, 10390.584376625002, 10453.529591168415, 10732.35605863643, 10845.511951962102, 3573.10672331917, 3611.259691763182, 3620.728445366608, 3674.503941967827, 3832.5209348831395, 65814.0, 83367.16632833655, 83837.0, 84311.57844210739, 85182.99999999937, 9714.562844671866, 9975.301728878909, 10099.293049486612, 10111.0, 10277.0, 19719.507012324808, 22728.0, 23777.0, 23805.523889522112, 23822.88088559581, 7906.288434040849, 7935.206479352134, 8018.759437216893, 8090.9886264216975, 8134.105248422668, 4297.52727200014, 4317.41714868495, 4352.347147927836, 4389.056352884145, 4471.977640111843, 3755.7746535207975, 3815.5039844819994, 3816.5992570779667, 3818.0073180973145, 3820.9617903820604, 603.2097951683309, 655.7377049180366, 707.2750430808462, 707.9610621415871, 709.1738125084322, 531.1422053383758, 547.8253520846703, 608.9695515224221, 609.4728060227959, 648.6011103171516, 8338.499690018618, 8451.577421128919, 8465.95767021161, 8594.914050859412, 8903.065178156201, 533.8932213557304, 574.379669956446, 581.2617975171509, 609.8414412252846, 612.8621060261405, 3799.2021675447913, 3985.740926839804, 4017.8593749218635, 4073.674106071507, 4122.505299364096, 98017.0, 99445.5083173753, 99804.50097749465, 100911.0, 101026.4948675252, 82296.0, 83794.74307885386, 84577.57711211404, 85550.0, 86195.0, 72973.0, 74965.25034749582, 75544.0, 75823.0, 77008.19204414096, 8575.0, 8661.956690216508, 8882.955585222033, 8904.944548508987, 8990.910090899008, 48223.27665085097, 48285.758571208324, 49564.50435495599, 50142.498575013786, 51836.74081629568, 63127.36872631215, 67638.0, 69968.65015674889, 69998.65000674964, 71041.6447917757, 11681.649550513499, 11786.939175474248, 11915.285082895054, 12063.336516491678, 12330.506779729069, 11948.880511194777, 12097.455614497192, 12237.082218833444, 12245.612105639315, 12634.052446066398, 8785.522259573929, 11820.76358472843, 12097.57658481949, 12393.75212495727, 12622.712519165994, 9731.854022189815, 11479.942600286946, 11832.053435724949, 12239.388030598613, 12281.140320177501, 10701.571937122428, 11541.826872597087, 11654.88345116538, 11788.587399440978, 11795.823062654239, 9358.44409908586, 9892.307538472238, 10082.243831712503, 10085.999653027278, 10130.341527800669, 10046.346987445793, 10262.794744105226, 10263.538140783534, 10268.383896966207, 10299.3820370778], "value": 7219.7154693887605, "unit": "score"}], "name": "dromaeo_css", "value": 7749.717042862556, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 121.0, 171.0, 155.0, 113.0, 112.0, 118.0, 165.0, 167.0, 136.0], "value": 137.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 116.0, 138.0, 184.0, 144.0, 166.0, 137.0, 111.0, 165.0, 117.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 183.0, 184.0, 184.0, 182.0, 182.0, 185.0, 185.0, 185.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 74.0, 155.0, 55.0, 56.0, 56.0, 68.0, 134.0, 55.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 142.0, 78.0, 78.0, 88.0, 126.0, 80.0, 77.0, 126.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 86.0, 83.0, 88.0, 84.0, 87.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 86.0, 85.0, 85.0, 85.0, 83.0, 111.0, 111.0, 87.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 85.0, 93.0, 94.0, 50.0, 75.0, 85.0, 80.0], "value": 74.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 65.0, 65.0, 75.0, 69.0, 68.0, 67.0, 66.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 79.0, 78.0, 79.0, 77.0, 78.0, 81.0, 81.0, 84.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 124.0, 124.0, 124.0, 126.0, 122.0, 127.0, 145.0, 139.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 163.0, 159.0, 165.0, 164.0, 163.0, 157.0, 157.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 65.0, 69.0, 60.0, 58.0, 66.0, 93.0, 92.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1493.0000000000002, "alertThreshold": 2.0}]} 08:56:17 INFO - Return code: 0 08:56:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:56:17 ERROR - # TBPL SUCCESS # 08:56:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:17 INFO - 2016-07-20 08:56:17.493 screenresolution[2633:288591] starting screenresolution argv=screenresolution get 08:56:17 INFO - 2016-07-20 08:56:17.496 screenresolution[2633:288591] Display 0: 1600x1200x32@60 08:56:17 INFO - 2016-07-20 08:56:17.503 screenresolution[2634:288594] starting screenresolution argv=screenresolution list 08:56:17 INFO - Available Modes on Display 0 08:56:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:17 INFO - Intel Iris: 08:56:17 INFO - Chipset Model: Intel Iris 08:56:17 INFO - Type: GPU 08:56:17 INFO - Bus: Built-In 08:56:17 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:17 INFO - Vendor: Intel (0x8086) 08:56:17 INFO - Device ID: 0x0a2e 08:56:17 INFO - Revision ID: 0x0009 08:56:17 INFO - Displays: 08:56:17 INFO - Display: 08:56:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:17 INFO - Display Serial Number: 1600x1200 60 08:56:17 INFO - Main Display: Yes 08:56:17 INFO - Mirror: Off 08:56:17 INFO - Online: Yes 08:56:17 INFO - Rotation: Supported 08:56:17 INFO - Return code: 0 08:56:17 INFO - Running post-action listener: _resource_record_post_action 08:56:17 INFO - Running post-run listener: _resource_record_post_run 08:56:18 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 6517760; Write bytes: 492056576; Read time: 58; Write time: 1699 08:56:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 193217024; Write bytes: 188348416; Read time: 17856; Write time: 452 08:56:18 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 6493184; Write bytes: 293963776; Read time: 57; Write time: 1213 08:56:18 INFO - Running post-run listener: _upload_blobber_files 08:56:18 INFO - Blob upload gear active. 08:56:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:56:18 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 08:56:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:56:19 INFO - (blobuploader) - INFO - Open directory for files ... 08:56:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:56:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:56:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:20 INFO - (blobuploader) - INFO - Done attempting. 08:56:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:56:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:56:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:56:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:56:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:56:21 INFO - (blobuploader) - INFO - Done attempting. 08:56:21 INFO - (blobuploader) - INFO - Iteration through files over. 08:56:21 INFO - Return code: 0 08:56:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:56:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:56:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edc962e4d83f591fca76dd0ac9a5747b1c86411c156b6d78fa80bf5d6b141de85a7f279c301fda5eaea294ce69b3d7103995927174903ead2285f89e12aaa1d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/651003e6e4f5a66a0e4afc621baff97fade1521622e0d788fca10c716d5b51a242e5062e5df52aa2af40a1fa6d5a803f7c2a52f62c0d78c51ef86e8fc51ceb44"} 08:56:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:56:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:56:21 INFO - Contents: 08:56:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edc962e4d83f591fca76dd0ac9a5747b1c86411c156b6d78fa80bf5d6b141de85a7f279c301fda5eaea294ce69b3d7103995927174903ead2285f89e12aaa1d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/651003e6e4f5a66a0e4afc621baff97fade1521622e0d788fca10c716d5b51a242e5062e5df52aa2af40a1fa6d5a803f7c2a52f62c0d78c51ef86e8fc51ceb44"} 08:56:21 INFO - Running post-run listener: copy_logs_to_upload_dir 08:56:21 INFO - Copying logs to upload dir... 08:56:21 INFO - mkdir: /builds/slave/test/build/upload/logs 08:56:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.720205 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-20 08:56:21.157171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 08:56:21.160295) ========= 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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/Mozilla-Beta/sha512/edc962e4d83f591fca76dd0ac9a5747b1c86411c156b6d78fa80bf5d6b141de85a7f279c301fda5eaea294ce69b3d7103995927174903ead2285f89e12aaa1d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/651003e6e4f5a66a0e4afc621baff97fade1521622e0d788fca10c716d5b51a242e5062e5df52aa2af40a1fa6d5a803f7c2a52f62c0d78c51ef86e8fc51ceb44"} build_url:https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/W_FQVZAfS4eUOCn6MeHuhw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edc962e4d83f591fca76dd0ac9a5747b1c86411c156b6d78fa80bf5d6b141de85a7f279c301fda5eaea294ce69b3d7103995927174903ead2285f89e12aaa1d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/651003e6e4f5a66a0e4afc621baff97fade1521622e0d788fca10c716d5b51a242e5062e5df52aa2af40a1fa6d5a803f7c2a52f62c0d78c51ef86e8fc51ceb44"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 08:56:21.192608) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:56:21.192993) ========= 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.bwZ5HoI8MZ/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.cpJdzZHm0w/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005293 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 08:56:21.242418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 08:56:21.243103) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 08:56:21.243633) ========= ========= Total master_lag: 0.29 =========