builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0122 starttime: 1462499223.93 results: success (0) buildid: 20160505165542 builduid: 54cccca5540141cfbc662dd54cafc05f revision: 8000d7a6ff44b128850bd4d08e0d1867f6df557d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:03.926406) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:03.927134) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:03.927680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:03.974768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:03.975214) ========= 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.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-05 18:47:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-05-05 18:47:04 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:04.119642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:04.120341) ========= 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.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.026395 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:04.169316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 18:47:04.169700) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8000d7a6ff44b128850bd4d08e0d1867f6df557d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8000d7a6ff44b128850bd4d08e0d1867f6df557d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-05 18:47:04,236 truncating revision to first 12 chars 2016-05-05 18:47:04,236 Setting DEBUG logging. 2016-05-05 18:47:04,236 attempt 1/10 2016-05-05 18:47:04,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8000d7a6ff44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 18:47:05,646 unpacking tar archive at: fx-team-8000d7a6ff44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.651086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 18:47:05.838417) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:05.838840) ========= script_repo_revision: 8000d7a6ff44b128850bd4d08e0d1867f6df557d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:05.839338) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:05.839704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 18:47:05.853340) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-05 18:47:05.853707) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:06 INFO - MultiFileLogger online at 20160505 18:47:06 in /builds/slave/test 18:47:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:47:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:47:06 INFO - {'append_to_log': False, 18:47:06 INFO - 'base_work_dir': '/builds/slave/test', 18:47:06 INFO - 'blob_upload_branch': 'Fx-Team', 18:47:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:47:06 INFO - 'branch': 'Fx-Team', 18:47:06 INFO - 'buildbot_json_path': 'buildprops.json', 18:47:06 INFO - 'config_files': ('talos/mac_config.py',), 18:47:06 INFO - 'default_actions': ('clobber', 18:47:06 INFO - 'read-buildbot-config', 18:47:06 INFO - 'download-and-extract', 18:47:06 INFO - 'populate-webroot', 18:47:06 INFO - 'create-virtualenv', 18:47:06 INFO - 'install', 18:47:06 INFO - 'run-tests'), 18:47:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:47:06 INFO - 'download_minidump_stackwalk': True, 18:47:06 INFO - 'download_symbols': 'ondemand', 18:47:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:47:06 INFO - 'tooltool.py': '/tools/tooltool.py', 18:47:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:47:06 INFO - '/tools/misc-python/virtualenv.py')}, 18:47:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:47:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:47:06 INFO - 'installer_path': 'installer.exe', 18:47:06 INFO - 'log_level': 'info', 18:47:06 INFO - 'log_name': 'talos', 18:47:06 INFO - 'log_to_console': True, 18:47:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:47:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:47:06 INFO - 'opt_config_files': (), 18:47:06 INFO - 'pip_index': False, 18:47:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:06 INFO - 'cmd': ('bash', 18:47:06 INFO - '-c', 18:47:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:06 INFO - 'enabled': True, 18:47:06 INFO - 'halt_on_failure': False, 18:47:06 INFO - 'name': 'check_screen_resolution'},), 18:47:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:06 INFO - 'cmd': ('bash', 18:47:06 INFO - '-c', 18:47:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:06 INFO - 'enabled': True, 18:47:06 INFO - 'halt_on_failure': False, 18:47:06 INFO - 'name': 'check_screen_resolution'},), 18:47:06 INFO - 'run_cmd_checks_enabled': True, 18:47:06 INFO - 'sps_profile': False, 18:47:06 INFO - 'sps_profile_interval': 0, 18:47:06 INFO - 'suite': 'dromaeojs-e10s', 18:47:06 INFO - 'system_bits': '32', 18:47:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:47:06 INFO - 'title': 't-yosemite-r7-0122', 18:47:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:47:06 INFO - 'use_talos_json': True, 18:47:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:47:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:47:06 INFO - 'work_dir': 'build'} 18:47:06 INFO - ##### 18:47:06 INFO - ##### Running clobber step. 18:47:06 INFO - ##### 18:47:06 INFO - Running pre-action listener: _resource_record_pre_action 18:47:06 INFO - Running main action method: clobber 18:47:06 INFO - rmtree: /builds/slave/test/build 18:47:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:47:08 INFO - Running post-action listener: _resource_record_post_action 18:47:08 INFO - ##### 18:47:08 INFO - ##### Running read-buildbot-config step. 18:47:08 INFO - ##### 18:47:08 INFO - Running pre-action listener: _resource_record_pre_action 18:47:08 INFO - Running main action method: read_buildbot_config 18:47:08 INFO - Using buildbot properties: 18:47:08 INFO - { 18:47:08 INFO - "project": "", 18:47:08 INFO - "product": "firefox", 18:47:08 INFO - "script_repo_revision": "production", 18:47:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:47:08 INFO - "repository": "", 18:47:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:47:08 INFO - "buildid": "20160505165542", 18:47:08 INFO - "pgo_build": "False", 18:47:08 INFO - "basedir": "/builds/slave/test", 18:47:08 INFO - "buildnumber": 0, 18:47:08 INFO - "slavename": "t-yosemite-r7-0122", 18:47:08 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:47:08 INFO - "platform": "yosemite_r7", 18:47:08 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:47:08 INFO - "branch": "fx-team", 18:47:08 INFO - "repo_path": "integration/fx-team", 18:47:08 INFO - "revision": "8000d7a6ff44b128850bd4d08e0d1867f6df557d", 18:47:08 INFO - "stage_platform": "macosx64", 18:47:08 INFO - "builduid": "54cccca5540141cfbc662dd54cafc05f", 18:47:08 INFO - "slavebuilddir": "test" 18:47:08 INFO - } 18:47:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:47:08 INFO - Running post-action listener: _resource_record_post_action 18:47:08 INFO - ##### 18:47:08 INFO - ##### Running download-and-extract step. 18:47:08 INFO - ##### 18:47:08 INFO - Running pre-action listener: _resource_record_pre_action 18:47:08 INFO - Running main action method: download_and_extract 18:47:08 INFO - mkdir: /builds/slave/test/build/tests 18:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:08 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:47:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:47:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:47:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 18:47:09 INFO - Downloaded 1199 bytes. 18:47:09 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:47:09 INFO - Using the following test package requirements: 18:47:09 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:47:09 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:47:09 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:47:09 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'jsshell-mac.zip'], 18:47:09 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:47:09 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:47:09 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:47:09 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:47:09 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:47:09 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:47:09 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:47:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:09 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:47:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:47:10 INFO - Downloaded 18144963 bytes. 18:47:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:47:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:47:13 INFO - Return code: 0 18:47:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:47: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')]} 18:47:13 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:47:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:47:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:47:14 INFO - Downloaded 18144963 bytes. 18:47:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:47:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:47:18 INFO - Return code: 0 18:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:18 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:47:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:47:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:47:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:47:19 INFO - Downloaded 10840152 bytes. 18:47:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:47:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:47:19 INFO - Return code: 0 18:47:19 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')]} 18:47:19 INFO - https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:47:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:47:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:47:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 18:47:25 INFO - Downloaded 110398578 bytes. 18:47:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:47:25 INFO - mkdir: /builds/slave/test/properties 18:47:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:25 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:25 INFO - Contents: 18:47:25 INFO - build_url:https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:47:26 INFO - Running post-action listener: _resource_record_post_action 18:47:26 INFO - Running post-action listener: set_extra_try_arguments 18:47:26 INFO - ##### 18:47:26 INFO - ##### Running populate-webroot step. 18:47:26 INFO - ##### 18:47:26 INFO - Running pre-action listener: _resource_record_pre_action 18:47:26 INFO - Running main action method: populate_webroot 18:47:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:47:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:47:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:47:26 INFO - '--tppagecycles', 18:47:26 INFO - '7'], 18:47:26 INFO - 'tests': ['tsvgm']}}, 18:47:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['damp', 'tps']}, 18:47:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'tests': ['damp', 'tps']}, 18:47:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['dromaeo_dom']}, 18:47:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['a11yr', 18:47:26 INFO - 'ts_paint', 18:47:26 INFO - 'tpaint', 18:47:26 INFO - 'sessionrestore', 18:47:26 INFO - 'sessionrestore_no_auto_restore', 18:47:26 INFO - 'tabpaint']}, 18:47:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['tsvgx', 18:47:26 INFO - 'tsvgr_opacity', 18:47:26 INFO - 'tart', 18:47:26 INFO - 'tscrollx', 18:47:26 INFO - 'cart']}, 18:47:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:26 INFO - 'tsvgr_opacity', 18:47:26 INFO - 'tart', 18:47:26 INFO - 'tscrollx', 18:47:26 INFO - 'cart']}, 18:47:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'talos_options': ['--disable-e10s'], 18:47:26 INFO - 'tests': ['tp5o']}, 18:47:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'tests': ['tp5o']}, 18:47:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'talos_options': ['--disable-e10s', 18:47:26 INFO - '--xperf_path', 18:47:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:26 INFO - 'tests': ['tp5n']}, 18:47:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:26 INFO - 'talos_options': ['--xperf_path', 18:47:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:26 INFO - 'tests': ['tp5n']}}, 18:47:26 INFO - 'talos.zip': {'path': '', 18:47:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:47:26 INFO - Running post-action listener: _resource_record_post_action 18:47:26 INFO - ##### 18:47:26 INFO - ##### Running create-virtualenv step. 18:47:26 INFO - ##### 18:47:26 INFO - Running pre-action listener: _resource_record_pre_action 18:47:26 INFO - Running main action method: create_virtualenv 18:47:26 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:26 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 18:47:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:26 INFO - Using real prefix '/tools/python27' 18:47:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:30 INFO - Installing pip.................done. 18:47:30 INFO - Return code: 0 18:47:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47:30 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')]} 18:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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')]} 18:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:30 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 18:47:30 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 18:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:30 INFO - 'HOME': '/Users/cltbld', 18:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:30 INFO - 'LOGNAME': 'cltbld', 18:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:30 INFO - 'MOZ_NO_REMOTE': '1', 18:47:30 INFO - 'NO_EM_RESTART': '1', 18:47:30 INFO - 'PAGER': '/bin/cat', 18:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:30 INFO - 'PWD': '/builds/slave/test', 18:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:30 INFO - 'SHELL': '/bin/bash', 18:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:30 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:30 INFO - 'USER': 'cltbld', 18:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:30 INFO - 'XPC_FLAGS': '0x0', 18:47:30 INFO - 'XPC_SERVICE_NAME': '0', 18:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:30 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 18:47:30 INFO - Cleaning up... 18:47:30 INFO - Return code: 0 18:47:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:47:30 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')]} 18:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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')]} 18:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:30 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:30 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 18:47:30 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 18:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:30 INFO - 'HOME': '/Users/cltbld', 18:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:30 INFO - 'LOGNAME': 'cltbld', 18:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:30 INFO - 'MOZ_NO_REMOTE': '1', 18:47:30 INFO - 'NO_EM_RESTART': '1', 18:47:30 INFO - 'PAGER': '/bin/cat', 18:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:30 INFO - 'PWD': '/builds/slave/test', 18:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:30 INFO - 'SHELL': '/bin/bash', 18:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:30 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:30 INFO - 'USER': 'cltbld', 18:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:30 INFO - 'XPC_FLAGS': '0x0', 18:47:30 INFO - 'XPC_SERVICE_NAME': '0', 18:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:30 INFO - Downloading/unpacking psutil>=0.7.1 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:30 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 18:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:33 INFO - Installing collected packages: psutil 18:47:33 INFO - Running setup.py install for psutil 18:47:34 INFO - building 'psutil._psutil_osx' extension 18:47:34 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 18:47:34 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 18:47:34 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 18:47:34 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 18:47:34 INFO - building 'psutil._psutil_posix' extension 18:47:34 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 18:47:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:47:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:47:34 INFO - ^ 18:47:34 INFO - 1 warning generated. 18:47:34 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 18:47:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:35 INFO - Successfully installed psutil 18:47:35 INFO - Cleaning up... 18:47:35 INFO - Return code: 0 18:47:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:35 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:35 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 18:47:35 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 18:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:35 INFO - 'HOME': '/Users/cltbld', 18:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:35 INFO - 'LOGNAME': 'cltbld', 18:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:35 INFO - 'MOZ_NO_REMOTE': '1', 18:47:35 INFO - 'NO_EM_RESTART': '1', 18:47:35 INFO - 'PAGER': '/bin/cat', 18:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:35 INFO - 'PWD': '/builds/slave/test', 18:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:35 INFO - 'SHELL': '/bin/bash', 18:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:35 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:35 INFO - 'USER': 'cltbld', 18:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:35 INFO - 'XPC_FLAGS': '0x0', 18:47:35 INFO - 'XPC_SERVICE_NAME': '0', 18:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:47:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:47:37 INFO - Installing collected packages: mozsystemmonitor 18:47:37 INFO - Running setup.py install for mozsystemmonitor 18:47:38 INFO - Successfully installed mozsystemmonitor 18:47:38 INFO - Cleaning up... 18:47:38 INFO - Return code: 0 18:47:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:38 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:38 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 18:47:38 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 18:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:38 INFO - 'HOME': '/Users/cltbld', 18:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:38 INFO - 'LOGNAME': 'cltbld', 18:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:38 INFO - 'MOZ_NO_REMOTE': '1', 18:47:38 INFO - 'NO_EM_RESTART': '1', 18:47:38 INFO - 'PAGER': '/bin/cat', 18:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:38 INFO - 'PWD': '/builds/slave/test', 18:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:38 INFO - 'SHELL': '/bin/bash', 18:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:38 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:38 INFO - 'USER': 'cltbld', 18:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:38 INFO - 'XPC_FLAGS': '0x0', 18:47:38 INFO - 'XPC_SERVICE_NAME': '0', 18:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:38 INFO - Downloading/unpacking blobuploader==1.2.4 18:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:40 INFO - Downloading blobuploader-1.2.4.tar.gz 18:47:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:47:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:47:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:47:41 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 18:47:41 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 18:47:41 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 18:47:41 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 18:47:41 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 18:47:41 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 18:47:41 INFO - Downloading docopt-0.6.1.tar.gz 18:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:47:42 INFO - Installing collected packages: blobuploader, requests, docopt 18:47:42 INFO - Running setup.py install for blobuploader 18:47:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:42 INFO - Running setup.py install for requests 18:47:42 INFO - Running setup.py install for docopt 18:47:42 INFO - Successfully installed blobuploader requests docopt 18:47:42 INFO - Cleaning up... 18:47:42 INFO - Return code: 0 18:47:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:42 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:42 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 18:47:42 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 18:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:42 INFO - 'HOME': '/Users/cltbld', 18:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:42 INFO - 'LOGNAME': 'cltbld', 18:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:42 INFO - 'MOZ_NO_REMOTE': '1', 18:47:42 INFO - 'NO_EM_RESTART': '1', 18:47:42 INFO - 'PAGER': '/bin/cat', 18:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:42 INFO - 'PWD': '/builds/slave/test', 18:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:42 INFO - 'SHELL': '/bin/bash', 18:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:42 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:42 INFO - 'USER': 'cltbld', 18:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:42 INFO - 'XPC_FLAGS': '0x0', 18:47:42 INFO - 'XPC_SERVICE_NAME': '0', 18:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fQLLIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-imAmqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-oOScce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-vg9gNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-v0LdJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5V6Q1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XxSU4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:43 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_nzI3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fxL3bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-f4k05n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-__Mwb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_iPFQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-F47KeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-foYIlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-bu3SaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-hwGIrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:44 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-33qLDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:45 INFO - Running setup.py install for manifestparser 18:47:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:45 INFO - Running setup.py install for mozcrash 18:47:45 INFO - Running setup.py install for mozdebug 18:47:45 INFO - Running setup.py install for mozdevice 18:47:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:47:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:45 INFO - Running setup.py install for mozfile 18:47:45 INFO - Running setup.py install for mozhttpd 18:47:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:45 INFO - Running setup.py install for mozinfo 18:47:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Running setup.py install for mozInstall 18:47:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Running setup.py install for mozleak 18:47:46 INFO - Running setup.py install for mozlog 18:47:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Running setup.py install for moznetwork 18:47:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Running setup.py install for mozprocess 18:47:46 INFO - Running setup.py install for mozprofile 18:47:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:47 INFO - Running setup.py install for mozrunner 18:47:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:47 INFO - Running setup.py install for mozscreenshot 18:47:47 INFO - Running setup.py install for moztest 18:47:47 INFO - Running setup.py install for mozversion 18:47:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:47 INFO - Cleaning up... 18:47:47 INFO - Return code: 0 18:47:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:47 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')]} 18:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:47 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')]} 18:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:47 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:47 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 18:47:47 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 18:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:47 INFO - 'HOME': '/Users/cltbld', 18:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:47 INFO - 'LOGNAME': 'cltbld', 18:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:47 INFO - 'MOZ_NO_REMOTE': '1', 18:47:47 INFO - 'NO_EM_RESTART': '1', 18:47:47 INFO - 'PAGER': '/bin/cat', 18:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:47 INFO - 'PWD': '/builds/slave/test', 18:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:47 INFO - 'SHELL': '/bin/bash', 18:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:47 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:47 INFO - 'USER': 'cltbld', 18:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:47 INFO - 'XPC_FLAGS': '0x0', 18:47:47 INFO - 'XPC_SERVICE_NAME': '0', 18:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:47 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-HnNxmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-i2LTxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-PcRU1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Ptpqw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-8Fv0E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XLdiYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ZVXjMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-RpDnG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-OCiubh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-YuaDn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:48 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)) 18:47:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:48 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-SvLiIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-MbJqAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_QCteD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-OxaecP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-M6LnMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-AzZqin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:49 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)) 18:47:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:49 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-muYMRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:49 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)) 18:47:49 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)) 18:47:49 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)) 18:47:49 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)) 18:47:49 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)) 18:47:49 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)) 18:47:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:52 INFO - Downloading blessings-1.6.tar.gz 18:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:52 INFO - Installing collected packages: blessings 18:47:52 INFO - Running setup.py install for blessings 18:47:52 INFO - Successfully installed blessings 18:47:52 INFO - Cleaning up... 18:47:52 INFO - Return code: 0 18:47:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:52 INFO - Reading from file tmpfile_stdout 18:47:52 INFO - Current package versions: 18:47:52 INFO - blessings == 1.6 18:47:52 INFO - blobuploader == 1.2.4 18:47:52 INFO - docopt == 0.6.1 18:47:52 INFO - manifestparser == 1.1 18:47:52 INFO - mozInstall == 1.12 18:47:52 INFO - mozcrash == 0.17 18:47:52 INFO - mozdebug == 0.1 18:47:52 INFO - mozdevice == 0.48 18:47:52 INFO - mozfile == 1.2 18:47:52 INFO - mozhttpd == 0.7 18:47:52 INFO - mozinfo == 0.9 18:47:52 INFO - mozleak == 0.1 18:47:52 INFO - mozlog == 3.1 18:47:52 INFO - moznetwork == 0.27 18:47:52 INFO - mozprocess == 0.22 18:47:52 INFO - mozprofile == 0.28 18:47:52 INFO - mozrunner == 6.11 18:47:52 INFO - mozscreenshot == 0.1 18:47:52 INFO - mozsystemmonitor == 0.0 18:47:52 INFO - moztest == 0.7 18:47:52 INFO - mozversion == 1.4 18:47:52 INFO - psutil == 3.1.1 18:47:52 INFO - requests == 1.2.3 18:47:52 INFO - wsgiref == 0.1.2 18:47:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:52 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:52 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 18:47:52 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 18:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:52 INFO - 'HOME': '/Users/cltbld', 18:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:52 INFO - 'LOGNAME': 'cltbld', 18:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:52 INFO - 'MOZ_NO_REMOTE': '1', 18:47:52 INFO - 'NO_EM_RESTART': '1', 18:47:52 INFO - 'PAGER': '/bin/cat', 18:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:52 INFO - 'PWD': '/builds/slave/test', 18:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:52 INFO - 'SHELL': '/bin/bash', 18:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:52 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:52 INFO - 'USER': 'cltbld', 18:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:52 INFO - 'XPC_FLAGS': '0x0', 18:47:52 INFO - 'XPC_SERVICE_NAME': '0', 18:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 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)) 18:47:53 INFO - Cleaning up... 18:47:53 INFO - Return code: 0 18:47:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47:53 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')]} 18:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:53 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')]} 18:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:53 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 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:53 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 18:47:53 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 18:47:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:53 INFO - 'HOME': '/Users/cltbld', 18:47:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:53 INFO - 'LOGNAME': 'cltbld', 18:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:53 INFO - 'MOZ_NO_REMOTE': '1', 18:47:53 INFO - 'NO_EM_RESTART': '1', 18:47:53 INFO - 'PAGER': '/bin/cat', 18:47:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:53 INFO - 'PWD': '/builds/slave/test', 18:47:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:53 INFO - 'SHELL': '/bin/bash', 18:47:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:53 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:53 INFO - 'USER': 'cltbld', 18:47:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:53 INFO - 'XPC_FLAGS': '0x0', 18:47:53 INFO - 'XPC_SERVICE_NAME': '0', 18:47:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:53 INFO - Downloading/unpacking jsonschema 18:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:47:55 INFO - Downloading/unpacking functools32 (from jsonschema) 18:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:56 INFO - Downloading functools32-3.2.3-2.tar.gz 18:47:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:47:56 INFO - warning: no files found matching '*.txt' 18:47:56 INFO - no previously-included directories found matching 'build' 18:47:56 INFO - no previously-included directories found matching 'dist' 18:47:56 INFO - no previously-included directories found matching '.git*' 18:47:56 INFO - Installing collected packages: jsonschema, functools32 18:47:56 INFO - Running setup.py install for functools32 18:47:56 INFO - warning: no files found matching '*.txt' 18:47:56 INFO - no previously-included directories found matching 'build' 18:47:56 INFO - no previously-included directories found matching 'dist' 18:47:56 INFO - no previously-included directories found matching '.git*' 18:47:56 INFO - Successfully installed jsonschema functools32 18:47:56 INFO - Cleaning up... 18:47:56 INFO - Return code: 0 18:47:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b07a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b17420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff24bc87860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, '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.xy5Vja93Te/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.qX7qWRc2or/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 18:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:56 INFO - 'HOME': '/Users/cltbld', 18:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:56 INFO - 'LOGNAME': 'cltbld', 18:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:56 INFO - 'MOZ_NO_REMOTE': '1', 18:47:56 INFO - 'NO_EM_RESTART': '1', 18:47:56 INFO - 'PAGER': '/bin/cat', 18:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:56 INFO - 'PWD': '/builds/slave/test', 18:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:56 INFO - 'SHELL': '/bin/bash', 18:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:47:56 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:56 INFO - 'USER': 'cltbld', 18:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:56 INFO - 'XPC_FLAGS': '0x0', 18:47:56 INFO - 'XPC_SERVICE_NAME': '0', 18:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:56 INFO - Downloading/unpacking flake8 18:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:47:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:47:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:48:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:48:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:48:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:48:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:48:00 INFO - no previously-included directories found matching 'docs/_build' 18:48:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:48:00 INFO - Running setup.py install for pep8 18:48:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:48:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:48:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:48:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:48:00 INFO - no previously-included directories found matching 'docs/_build' 18:48:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:48:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:48:00 INFO - Cleaning up... 18:48:00 INFO - Return code: 0 18:48:00 INFO - Running post-action listener: _resource_record_post_action 18:48:00 INFO - Running post-action listener: _start_resource_monitoring 18:48:00 INFO - Starting resource monitoring. 18:48:00 INFO - ##### 18:48:00 INFO - ##### Running install step. 18:48:00 INFO - ##### 18:48:00 INFO - Running pre-action listener: _resource_record_pre_action 18:48:00 INFO - Running main action method: install 18:48:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:01 INFO - Reading from file tmpfile_stdout 18:48:01 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:01 INFO - Reading from file tmpfile_stdout 18:48:01 INFO - Output received: 18:48:01 INFO - Usage: mozinstall [options] installer 18:48:01 INFO - Options: 18:48:01 INFO - -h, --help show this help message and exit 18:48:01 INFO - -d DEST, --destination=DEST 18:48:01 INFO - Directory to install application into. [default: 18:48:01 INFO - "/builds/slave/test"] 18:48:01 INFO - --app=APP Application being installed. [default: firefox] 18:48:01 INFO - mkdir: /builds/slave/test/build/application 18:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:48:34 INFO - Reading from file tmpfile_stdout 18:48:34 INFO - Output received: 18:48:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:48:34 INFO - Running post-action listener: _resource_record_post_action 18:48:34 INFO - ##### 18:48:34 INFO - ##### Running run-tests step. 18:48:34 INFO - ##### 18:48:34 INFO - Running pre-action listener: _resource_record_pre_action 18:48:34 INFO - Running main action method: run_tests 18:48:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:34 INFO - 2016-05-05 18:48:34.268 screenresolution[1638:7470] starting screenresolution argv=screenresolution get 18:48:34 INFO - 2016-05-05 18:48:34.270 screenresolution[1638:7470] Display 0: 1600x1200x32@60 18:48:34 INFO - 2016-05-05 18:48:34.276 screenresolution[1639:7473] starting screenresolution argv=screenresolution list 18:48:34 INFO - Available Modes on Display 0 18:48:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:34 INFO - Intel Iris: 18:48:34 INFO - Chipset Model: Intel Iris 18:48:34 INFO - Type: GPU 18:48:34 INFO - Bus: Built-In 18:48:34 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:34 INFO - Vendor: Intel (0x8086) 18:48:34 INFO - Device ID: 0x0a2e 18:48:34 INFO - Revision ID: 0x0009 18:48:34 INFO - Displays: 18:48:34 INFO - Display: 18:48:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:34 INFO - Display Serial Number: 1600x1200 60 18:48:34 INFO - Main Display: Yes 18:48:34 INFO - Mirror: Off 18:48:34 INFO - Online: Yes 18:48:34 INFO - Rotation: Supported 18:48:34 INFO - Return code: 0 18:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:34 INFO - Python 2.7.3 18:48:34 INFO - Return code: 0 18:48:34 INFO - grabbing minidump binary from tooltool 18:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:48:34 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 0x1039a6e00>, '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 0x10380fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039a1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:34 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 18:48:34 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 18:48:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:34 INFO - Return code: 0 18:48:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:48:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:48:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:48:35 INFO - Return code: 0 18:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xy5Vja93Te/Render', 18:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:35 INFO - 'HOME': '/Users/cltbld', 18:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:35 INFO - 'LOGNAME': 'cltbld', 18:48:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:35 INFO - 'MOZ_NO_REMOTE': '1', 18:48:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:35 INFO - 'NO_EM_RESTART': '1', 18:48:35 INFO - 'PAGER': '/bin/cat', 18:48:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:35 INFO - 'PWD': '/builds/slave/test', 18:48:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:35 INFO - 'SHELL': '/bin/bash', 18:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qX7qWRc2or/Listeners', 18:48:35 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 18:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:35 INFO - 'USER': 'cltbld', 18:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:35 INFO - 'XPC_FLAGS': '0x0', 18:48:35 INFO - 'XPC_SERVICE_NAME': '0', 18:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:48:35 INFO - mozversion application_buildid: 20160505165542 18:48:35 INFO - mozversion application_changeset: 8000d7a6ff44b128850bd4d08e0d1867f6df557d 18:48:35 INFO - mozversion application_display_name: Nightly 18:48:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:35 INFO - mozversion application_name: Firefox 18:48:35 INFO - mozversion application_remotingname: firefox 18:48:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:48:35 INFO - mozversion application_vendor: Mozilla 18:48:35 INFO - mozversion application_version: 49.0a1 18:48:35 INFO - mozversion platform_buildid: 20160505165542 18:48:35 INFO - mozversion platform_changeset: 8000d7a6ff44b128850bd4d08e0d1867f6df557d 18:48:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:48:35 INFO - mozversion platform_version: 49.0a1 18:48:35 INFO - using testdate: 1462499315 18:48:35 INFO - actual date: 1462499315 18:48:35 INFO - starting webserver on 'localhost:49272' 18:48:35 INFO - SUITE-START | Running 2 tests 18:48:35 INFO - TEST-START | dromaeo_css 18:48:35 INFO - operating with platform_type : mac_ 18:48:35 INFO - Initialising browser for dromaeo_css test... 18:48:35 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpWq2XkJ/profile http://localhost:49272/getInfo.html) 18:48:38 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:48:38 INFO - PROCESS | 1657 | colorDepth:24 18:48:38 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:48:38 INFO - PROCESS | 1657 | __metrics 18:48:38 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:48:39 INFO - TEST-INFO | 1657: exit 0 18:48:39 INFO - Browser initialized. 18:48:39 INFO - Running cycle 1/1 for dromaeo_css test... 18:48:39 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpWq2XkJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:51 INFO - PROCESS | 1660 | RSS: Main: 249966592 18:48:51 INFO - PROCESS | 1660 | 18:49:41 INFO - PROCESS | 1660 | 1462499381297 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] 18:51:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 18:51:54 INFO - PROCESS | 1660 | RSS: Main: 264830976 18:51:54 INFO - PROCESS | 1660 | 18:54:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 18:54:57 INFO - PROCESS | 1660 | RSS: Main: 275394560 18:54:57 INFO - PROCESS | 1660 | 18:57:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 18:57:59 INFO - PROCESS | 1660 | RSS: Main: 277360640 18:57:59 INFO - PROCESS | 1660 | 19:01:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 19:01:02 INFO - PROCESS | 1660 | RSS: Main: 278982656 19:01:02 INFO - PROCESS | 1660 | 19:04:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 19:04:05 INFO - PROCESS | 1660 | RSS: Main: 281878528 19:04:05 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 19:07:08 INFO - PROCESS | 1660 | RSS: Main: 283004928 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | __start_tp_report 19:07:08 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:07:08 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:07:08 INFO - PROCESS | 1660 | |i|pagename|runs| 19:07:08 INFO - PROCESS | 1660 | |0;dojo.html;3500.334936362093;3502.474628805678;3528.700060494858;3533.9116522086942;3540.274243780024;4788.425388953322;4990.825321113762;5371.630234290255;5869.090291004901;5918.467337939595;5792.275965504312;5888.910551547956;5912.231409916715;5964.3737407572235;6027.728752206151;4417.933730994037;5298.390685071208;5322.760475778599;5349.277847490587;5395.001924643934;4939.046046410799;5424.701641409721;5479.835604931858;5513.2005859150395;5547.500724934755;5258.684478931276;5424.240606315119;5429.212764149216;5449.509544141007;5480.232767412565;5214.113600687892;5304.946950530484;5334;5353.384360798519;5416.891662166774;5290.656107353048;5294.179402192628;5308.893822123575;5342.011727830325;5419.105847535152;5665.54675625953;5676.716164191774;5791.739371728282;5820.941790582125;5839.094940284221;6247.094171345129;6291.842703932392;6296.307406185316;6308.243010838729;6309.716062777186;6323.557350985433;6334.081558174039;6339.207599050119;6349.333320001426;6363.109164716897;4817.542333478349;5867.647941123546;5894.764209431661;6074.544409169286;6100.359462256443;6312;6323.936760632427;6328.272248691459;6339.29151446409;6355.586886852339;6229.875402492015;6245.469135123474;6278.6860656966965;6280.403361680633;6288.371162883766;6137.989923150242;6189.1954045974635;6215.65813880241;6224.906626400608;6270.7805226816845;6128.19758601264;6261.78083767066;6263.091851681481;6264.467520260738;6275.843103922439;6236.6258024517765;6271.937280627227;6278.717457714414;6306.1171435998995;6318.020706790455;4867.586255168279;4974.726390048582;4979.875503112451;5042.798288068436;5054.4187418447345;4473.060657262012;4478.126765280804;4518.615917646971;4521.66087543429;4549.567791059844;5941.287045554561;5997.340292567814;5999.640021598718;6000.759969601167;6032.849178770478;6186.133941248228;6219.471344935731;6243.063540468966;6253.405926436982;6294;6017.428344307371;6024.728887200086;6031.849203769853;6045.3652366501765;6096.116063170815;4606.193916064675;4961.007798440326;5368.409474957752;5516.393196748354;5579.023670857584;6250.281217659936;6267.9373206268265;6326.209223847111;6401.679916004275;6424.710888009958;6217.285012223652;6234.532410069353;6249.562530622813;6251.187345645219;6339.524535659752;5978.222831031938;6323.1463752394675;6397.4562162216735;6434.195725534309;6459.386358295955;6228.968855155696;6255.749770009149;6267.717952692231;6305.779297724557;6387.073874288295;6193.81418557444;6278.215223097113;6311.873762524816;6312.14786003902;6331.27190373113;6020.277566692025;6136.764169924414;6162.983107787344;6297.968510157603;6307.779227727191;6123.418275263843;6169.198004259597;6215;6220.657863817533;6234.407731265523;5996.610220335664;6092.512598992159;6106.992106812639;6126.601770885057;6147.877042459215;6137.499563105042;6162.691865406801;6235;6256.874862502634;6281.089242059968;6175.84560385994;6176.567640265138;6189.319174890668;6192.876142477216;6202.968985155046 19:07:08 INFO - PROCESS | 1660 | |1;ext.html;498.53138050232803;500.90733214355345;502.87930896584817;516.8707823044239;574.0356201581346;30256.697433025663;42069.158616827684;42101.57898421015;42220.57779422205;42224.78887605558;42444.575554244446;43105.78447107768;43112.92217694559;43145.13709725803;43154.784226078824;9536.332456728049;9744.902550974506;9745.31782775206;9771.21830253578;9782.657606983757;9636.518174091267;9727.1731902788;9728;9764.707058788248;9765.853512197322;9726.17327527161;9748.805023899518;9758.707238782848;9771.609135634602;9787.902120978806;9670.661526846527;9696.321257511978;9733.172680322217;9738.853917191247;9775.266854985905;9578.521073946344;9672.549117632412;9702.029797020312;9714.659986900424;9717.46553939533;39343.60656393428;39539.60460395388;39550.41798328063;39622.80188599067;39698.20603587941;2820.238535595378;3431.742619303537;3785.6971442284553;3791.241751649681;3826.8660596878976;24576.877115614487;28725.856370718222;29882.40235195284;30397.392052159055;30511.08466746001;2756.476269508787;2798.034678036074;2807.3823758773037;2850.942981140386;2905.9273518162004;432714;522806;523114;530437;530938;36076;36319.910402687965;36727.4490882637;37926.43110353347;66124.67750645056;37316.62683373186;37382.81308593494;37531.81234093867;37653.43519847204;37871.24257514889;15410.614734631725;15630.609234768994;15653.452129175424;15662.981906176063;15669.373225071098;13029.283389413673;13092.803607945889;13210;13212.867871321356;13340.466381344637;6975.581465112109;8340.833183336299;8359.205875441823;8380.622871970776;8382.580870956428;3393.423118069934;3400.3709313776535;3409.5226668266464;3412;3440.2431465077643;919.3013309884402;953.6614501851743;1033.720895358249;1069.2889228663007;1119.630521927762;23816.88091559566;29266.7073329264;29586.408271834873;29698.851505742332;30149.397012059635;1099.9560017599367;1108.3128460354471;1110.8333749937412;1132.886711328877;1211.8182272658996;15011.549653510194;15169.620759481102;15178.544643660707;15364.846351536342;15396.230188490754;683349;685292;693428;696995;702387;62018.37981620216;62047.6897615509;62352.376476235564;62451.68774156191;62468.68765656143;1469.2727100085312;1471.4114354258381;1501.7822415749656;1505.0367764630646;1507.4648499782638;27359.58960615632;28660.713392865804;28717.56923646105;29063.564046538897;29381.706182937898;52073.218901717264;52532.737336313076;52673;52704;52839;584678;604758;607562;611313;611679;26255;26856.462870742867;26904.730952691007;27173.456530869666;27655;43592.782036089615;46193.845153870076;46523.76738116288;46944.7652761734;48181.759091204316;37500.62499374971;38612.80693596514;38885;39575.60424395835;39857.60142398539;38044;38133.237335253696;38517.43608095669;39005;39396.80301598473;25621;32254.83872580716;38082.42876356912;38319.42520862134;38355.808220958716;32571.022869313958;33223.180864372946;33547.664523354455;33822.66177338195;33823.49264760981;20911.477213069797;22535.661965070864;22986.54026919486;25150.371240718396;25435.61846572266 19:07:08 INFO - PROCESS | 1660 | |2;jquery.html;393.92909276330266;395.94654721612585;397.58055251709436;400.95789942056064;418.5960548071113;14724.6318842029;16380.672386552276;21003;21262.25582104625;21414.46463838403;18092.81907180931;20913.849738264427;21022.474438139056;21025;21301.68047479285;5444.1561557958485;5842.620229685078;6181.289151747562;6248.781292654758;6293;5725.055365864628;6161.199044124258;6171.4445699887;6182.227221597301;6231.189945307104;5532.225488431623;5574.832755017335;5599.832005039855;5613.326400831906;5624.015797235488;5337.572994160497;5555.416681248492;5565.0817615093465;5583.8883222335735;5595.692236926968;5414.458554144593;5449.918251226235;5449.972750136264;5451.04606693827;5475.06923822951;12904.290264035475;13202.471901123943;13550.322483875767;13842.653933651638;13847.169169849842;2065.867520412966;2100.159936025587;2110.693949377347;2142.57148570285;2276.3171048685326;7889;10237.436940968244;10250.79498410035;10301;10453.790924181474;1721.4467732846676;1779.813119622434;1786.6516029374277;1799.3432397175068;1808.5478630342413;124458;131888;132366;132511;133651.33174334065;11529.942350288362;11784.528618855149;11795.587154449553;11800.173987820941;12009.459574319177;12828.29444380568;15311.923440382727;15459.61350966235;15504.689906202038;15555.22223888801;7339.522931009467;7958.647030004798;8150.75547733569;8919.509426981578;8984.46093234408;7468.626568671653;8628;8867.955660221658;8909.109089091038;9013.63945442188;4664.160451118763;4701.882452938704;4761.071591039783;4762.357081793983;4789.137955168034;1947.6007418479178;2006.9899650501654;2018.101944634653;2028.1645111174412;2039.4799326172028;913.388030019875;934.4486752815872;997.5959736306662;1004.1815920025156;1074.08166018055;20274.594508109756;20403.59192816165;20428.693569596468;20708.79291207099;20788;946.5078159357096;959.1799011844912;962.5235508423375;968.8740463739668;1034.4103860799273;8277.875831862528;8556.572171391406;10377.792444151226;10771.730706732395;10836;627856;628244;629168;649057;649755;140114;140150.59849401578;140351;141459;143074;1004.1414590525142;1009.0817356205818;1073.5974009746344;1082.1775450657558;1082.8429877667695;14855;18825.623487530447;19064.714029289848;19108.617827643648;19132;40158.59841401548;54446.72776636091;55651.443485564625;55963.720181398836;56063.71968140297;82158;83465.74801377863;83469;83940.74088888794;84224.57887710522;7496.287852653989;8121.75634730959;8392.538410387244;8415.663373465237;8493.447925884797;7138.0363650906465;11597.130215233723;11931.224470409396;12038.578649747216;12943.417546210254;10957.83563246536;11125.888741112809;11170.162237832357;11504.654860354203;11617.88382116168;10982.780344393228;11117.94379533943;11372.943135284271;11377.317360958368;11498;11354.659360219206;11678.883211168119;11692.240004399688;11904.821427678418;11919.284842909454;7156.821079473055;7719.92280077192;8267.83464330698;8311.501309921425;8701.564921754014;10891.891081089405;11377.032952199155;11528.193026488065;11529.827052594386;11699.88300116988 19:07:08 INFO - PROCESS | 1660 | |3;mootools.html;1613.9677206455867;1638.1072315588003;1887.4526387347669;1955.892425916574;1973.7138114973338;7977.6011199439945;8525.78685532862;8686.261667758232;9045.99999999999;9054;7512.737054203104;8747.781305467366;8786.780330491742;8843.248323892461;8879.978802437721;2405.831591788571;2442.5237078769646;2442.5969714997095;2443.5235129149823;2448.179859746987;2467.864267465289;2469;2495.413577809213;2495.987520062406;2516.7105782834933;2468.814838887082;2470.147799009339;2499.8125140614434;2500.7749302562765;2503.4116982509095;2433.3794882305074;2447.5961466358117;2469.5307891500647;2477.962830557543;2479.194261864901;2434.1237154624323;2453.251758213744;2453.374389530676;2463.4334103156375;2469.9753002469924;8777.692780752643;8926.330525210635;8951.597178127;8953.955230223808;8982.730518084469;5386.703731294777;5517.668939863621;5525.861853453655;5535.612507124503;5573.972130139324;3515.542979412686;3553.378158822222;3587.7309201809708;3691.7231207659534;3704.5554533456157;1041;1063.5905176507101;1066.1470823341374;1066.9359838409644;1086.983695244572;11525.36610486423;11599.188056836025;11601.535938562447;11607.6517704469;11646;4835.129676658235;4836.492168322346;4937.185364414831;4946.060248552763;4963.8262660806695;9996.100350968447;10007.799844003079;10010.14913732341;10078.344907580984;10093.747656308504;4099.487564054493;5107.923381149286;5394.244805727201;5497.230387745719;5502.5597952163735;4485.215087359699;4834.734089625034;4968.378952630921;4989.850304490871;5030.7233102179;3580.6598373154507;3932.2725295820364;4018.477597912253;4112.383142528644;4149.045719484536;1375.6354566040006;1381.1574939287027;1387.5559820857432;1396.015808854755;1398.5804258722342;845.4758050009034;933.2347475070512;955.0926619711302;958.0658857613771;983.5868935047297;5205.765740541685;5814.9709251454315;6047.969760151171;6059.091136329585;6066.939330606726;473.1648640150138;474.16783544879206;484.69948631847774;1014.9035841595037;1065.7548763784373;3402.42158832999;3991.702696623609;4056.411820286042;4061.0862555925273;4078.8368465261055;10275.486225688686;10310.587576496857;10613;10771.56913723458;10776.461176941279;8540.40217184791;10576.735581610368;10642;10755.354678719303;10842;8035.959820200862;8084.878726819103;8162.9591852039175;8239.134890836376;8277.172282771795;5740.885182296247;5861.560382971379;5887.911681324869;5896.469317761422;5916.615419997858;9627.518624068909;9768.804623907625;10020.799584008424;10098.939611340997;10110.747231319276;9956.054174853378;10025.147862431484;10167.339122956984;10246.743831404274;10250.333728307636;6915.827104322432;7352.264773522605;7406.481546291708;7606.125295591078;7682.347000505019;7024.754133605299;8403.747887563382;8474.110218426977;8477.872831907403;8480.61837217339;7165.7850264492145;7389.926100738924;7433.293837085468;7566.735164269224;7623.847523049619;7205.243449437734;7310.6710198040155;7325.816854578678;7328.560286382834;7411.444141689287;6923.792286231421;6994.650267486707;7012.053372794608;7016.438684905297;7018.964905175646;4834.105690447279;4928.950710492849;4940.975295123501;4995.100881841304;5147.536721694915;4914.238293064581;5025.145725226794;5032.169692000925;5082.466341034138;5238.68567885928 19:07:08 INFO - PROCESS | 1660 | |4;prototype.html;310.6303498836385;316.69438991373335;328.1124558070419;333.89315419065895;334.6101791413004;4539.273716205408;5253.369595648518;5348.331458567679;5371.221172929928;5532.585056120787;5300.840974770754;5351.411344752074;5382.515573598375;5441.59188060896;5453.945460545403;3654.9817250913707;4093.8157782899693;4096.959030409687;4117.979410102946;4158.293090174678;3621.5654121505454;3807.847686092553;4127.29835927893;4127.958720412803;4131.483564554431;3221.178599457135;3835.520559930009;3889.4749208856865;4071.246819338432;4083.673306135502;3765.587904535799;4098.8360465581345;4108.486439195116;4120.546739858613;4139.565345638694;3222.822744749038;3499.422595271777;3527.1887465882733;3534.3991521441417;3547.9822600887087;4830.420349558076;5184.403793563713;5193;5210.6613070150315;5223.608229382811;4327.112941846914;4626.144163329761;4670.416197975253;4683.086798074377;4684;4833.661643684944;5659.717014149276;5688.886222275572;5708.743106560215;5709.914351284734;3436.5634365634364;3718.5165928429133;3865.202680753435;3879.5538513071074;3883.98058009711;5177.870553236162;5716.828495145152;5853.47318741311;5900.468957793776;5904.852378690524;3377.7973321600784;3771.132639492932;3926.744761590545;3957.3866050762176;3989.840406383712;3585.6235095315137;3739.869104581381;3753.8415411138158;3762.1535154589997;3821.636944490269;3981.5421226559315;4021.8574867886123;4453.688241823042;4568.497465278817;4634.328022436728;4854.883376823351;5302.973485132549;5717.169496437397;5738.512226460714;5781;1263.943122559487;1275.7561377656896;1287.4206607026874;1290.825738525306;1307.9542216022585;843.6077224090827;843.9282111718084;871.4771137317686;877.6050777150308;886.7694399456187;4241.872743817628;4893.975530122398;4941.678790878582;4995;5003.849884503471;5172.586193104543;5527.198556209408;5527.281453411111;5566.55467562594;5583;4067.6949228807957;4322.027543802682;4852.714030781845;4936.284238785356;4974.801007959713;2667.4531720997147;2856.514392553271;2923.093840909325;2938.8971386001385;2958.940821183607;5071;5769.922827781995;5856.590038697335;5866.560007999417;5872.17789509469;5360.303160589098;5487.478689524489;5642.194497760677;5725.656460612377;5796.304443466811;5156.381234251914;5643.153526970905;5823.388544202967;5835.503982161479;5844.912326315109;5175.14610089331;5266.526012658879;5303.9204411934625;5607.18711276336;5643.35101463337;4796.784144713426;5364.892702145858;5486.972565137149;5507.504324610804;5569.972150139223;5019.511439549478;5465.797524544594;5722.198892155018;5774.624649397776;5793.827314756966;3233.2725136844024;3579.516765236764;3703.2778608171147;3729.477873097714;3734.9644443778748;5076.923846142237;5145.710158979218;5528.530074943675;5528.889422211614;5586.636868603528;4888.2178851383605;5059.974700126476;5662.773489060556;5698.4016678249845;5728.2266893970445;5047.4952504749235;5277.498637629419;5590.329160500766;5666.744996475086;5679.403662615366;4464.017916058462;4696.906061878812;4865.7323847188745;5385.057614917296;5542.501174894279;4851.927221091616;5047.596192304681;5485.75314110858;5665.498642859563;5733.512651424676;4587.885302867455;4887.975560122177;5572.888542229051;5575.330960284792;5586.776528938797 19:07:08 INFO - PROCESS | 1660 | |5;yui.html;672.2302963107242;751.0649241694092;757.5189754505891;764.1174443517739;769.2077160524658;14766.409343626256;15379.538613841603;15401.537953861389;15407.460738874128;16352;15793.052416854996;16237.675246495093;16245.187740612952;16285.185740712976;16317.184140792942;748.2928632442317;953.3993584042043;956.3353469338813;963.3641796414367;965.0687086961082;935.2611436964823;966.2125367825236;968.9127978481936;970.2868391732071;979.750163708257;987.792563561653;997.7505623594102;1007.8639383683219;1016.3089099412397;1017.5370206555999;861.1517655109712;980.1227901028575;1004.8342023566103;1005.7787286796893;1009.1573536097368;753.5365750063726;753.7625647920887;756.5725365168662;759.3507551043889;763.1643350531215;15118.319675614513;15163.469278575198;15165.090094594249;15206.46777362787;15223.695526089527;10797.244192906499;12810.16733912302;12811.167274127152;12959.805602915963;12982.35088245584;9713.174380177623;10008.749781255454;10048.196144308511;10071.24465665078;10084.294099413044;3488.843002064913;3579.49887015818;3605.693516051139;3606.1705807664384;4433.401490798766;83272.16727832705;84134;84878;86326;86729.13270867337;9552.713418597454;9754.707358779247;9765.609375625038;9910.405375677337;10226.6420675276;22179.778202218095;22725.204617838626;22779.088836446357;22852.65721014186;22913.19803806892;7498.4143156085975;7505.061867266591;7564.50830696014;7622.58075805825;7673.040869891263;4003.419504171937;4086.4074709167003;4088.468499095158;4096.283150448719;4109.260333140063;3217.2761128745847;3432.8626854925524;3597.748157628941;3708.721845861517;3714.8699795507027;646.5215740352199;649.2533586375612;651.1307404614893;668.388424591503;668.4919461209515;556.6020295488664;604.694629212245;611.126089691745;613.6011592464934;616.8126356763213;7918.168592297726;8133.308668763221;8187.26314631686;8299.170082991652;8305.875411868827;565.4260925160964;594.2364062180164;604.4227762486814;606.336814155787;616.5006344860677;3790.962090379061;3810.5046343975105;3855.248226595843;3904.1215726461887;4039.939400908989;82932.58533707293;83334.9166270848;85780.14219857722;87154.56422717846;89784.6532302016;54781;60402.69798650979;80312.59843700744;81912.18087819165;82474;67757.32242677707;71421.6428917852;72180;72877;73464.63267683628;7510.511816731895;7750.728724494616;7804.84390312202;7826.060872695313;7838.686452541834;44362.890927727065;44413.88965275894;44879.20483180636;44915;45774.77112614416;57299.71350143223;61692.69153654203;62239.688801555705;63174.68412657907;64434.67782661057;8255.463394879314;10284.691459255935;10453;10524.789504210026;10654.307470014424;10853.200750768903;11107.833382499108;11133.721656958316;11381.146414018816;11396.886031139582;10362.948185259025;10449.477526123512;10452.425116618659;10632.681019569425;10689.358638481715;10414.47927603632;10429.426381549089;10694.518746656264;10783.406912619881;10801.135909127408;9819.116279534876;10034.849477257992;10111.89888101139;10132.64535741275;10459.00639439252;6893.931060689329;8527.360447966552;8636.481811091355;8783.560821959005;8875.112488751074;7252.854942901218;7709.267619576131;8241.423100383156;8739.03870574249;9258.305627077862 19:07:08 INFO - PROCESS | 1660 | __end_tp_report 19:07:08 INFO - PROCESS | 1660 | __start_cc_report 19:07:08 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,421 19:07:08 INFO - PROCESS | 1660 | __end_cc_report 19:07:08 INFO - PROCESS | 1660 | __startTimestamp1462500428344__endTimestamp 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:07:08 INFO - PROCESS | 1660 | Number of tests: 6 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5825.13 Median:6128.20 stddev:652.38 (10.6%) stddev-sans-first:628.37 19:07:08 INFO - PROCESS | 1660 | Values: 3500.3 3502.5 3528.7 3533.9 3540.3 4788.4 4990.8 5371.6 5869.1 5918.5 5792.3 5888.9 5912.2 5964.4 6027.7 4417.9 5298.4 5322.8 5349.3 5395.0 4939.0 5424.7 5479.8 5513.2 5547.5 5258.7 5424.2 5429.2 5449.5 5480.2 5214.1 5304.9 5334.0 5353.4 5416.9 5290.7 5294.2 5308.9 5342.0 5419.1 5665.5 5676.7 5791.7 5820.9 5839.1 6247.1 6291.8 6296.3 6308.2 6309.7 6323.6 6334.1 6339.2 6349.3 6363.1 4817.5 5867.6 5894.8 6074.5 6100.4 6312.0 6323.9 6328.3 6339.3 6355.6 6229.9 6245.5 6278.7 6280.4 6288.4 6138.0 6189.2 6215.7 6224.9 6270.8 6128.2 6261.8 6263.1 6264.5 6275.8 6236.6 6271.9 6278.7 6306.1 6318.0 4867.6 4974.7 4979.9 5042.8 5054.4 4473.1 4478.1 4518.6 4521.7 4549.6 5941.3 5997.3 5999.6 6000.8 6032.8 6186.1 6219.5 6243.1 6253.4 6294.0 6017.4 6024.7 6031.8 6045.4 6096.1 4606.2 4961.0 5368.4 5516.4 5579.0 6250.3 6267.9 6326.2 6401.7 6424.7 6217.3 6234.5 6249.6 6251.2 6339.5 5978.2 6323.1 6397.5 6434.2 6459.4 6229.0 6255.7 6267.7 6305.8 6387.1 6193.8 6278.2 6311.9 6312.1 6331.3 6020.3 6136.8 6163.0 6298.0 6307.8 6123.4 6169.2 6215.0 6220.7 6234.4 5996.6 6092.5 6107.0 6126.6 6147.9 6137.5 6162.7 6235.0 6256.9 6281.1 6175.8 6176.6 6189.3 6192.9 6203.0 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71230.46 Median:38436.62 stddev:162775.45 (423.5%) stddev-sans-first:163145.45 19:07:08 INFO - PROCESS | 1660 | Values: 498.5 500.9 502.9 516.9 574.0 30256.7 42069.2 42101.6 42220.6 42224.8 42444.6 43105.8 43112.9 43145.1 43154.8 9536.3 9744.9 9745.3 9771.2 9782.7 9636.5 9727.2 9728.0 9764.7 9765.9 9726.2 9748.8 9758.7 9771.6 9787.9 9670.7 9696.3 9733.2 9738.9 9775.3 9578.5 9672.5 9702.0 9714.7 9717.5 39343.6 39539.6 39550.4 39622.8 39698.2 2820.2 3431.7 3785.7 3791.2 3826.9 24576.9 28725.9 29882.4 30397.4 30511.1 2756.5 2798.0 2807.4 2850.9 2905.9 432714.0 522806.0 523114.0 530437.0 530938.0 36076.0 36319.9 36727.4 37926.4 66124.7 37316.6 37382.8 37531.8 37653.4 37871.2 15410.6 15630.6 15653.5 15663.0 15669.4 13029.3 13092.8 13210.0 13212.9 13340.5 6975.6 8340.8 8359.2 8380.6 8382.6 3393.4 3400.4 3409.5 3412.0 3440.2 919.3 953.7 1033.7 1069.3 1119.6 23816.9 29266.7 29586.4 29698.9 30149.4 1100.0 1108.3 1110.8 1132.9 1211.8 15011.5 15169.6 15178.5 15364.8 15396.2 683349.0 685292.0 693428.0 696995.0 702387.0 62018.4 62047.7 62352.4 62451.7 62468.7 1469.3 1471.4 1501.8 1505.0 1507.5 27359.6 28660.7 28717.6 29063.6 29381.7 52073.2 52532.7 52673.0 52704.0 52839.0 584678.0 604758.0 607562.0 611313.0 611679.0 26255.0 26856.5 26904.7 27173.5 27655.0 43592.8 46193.8 46523.8 46944.8 48181.8 37500.6 38612.8 38885.0 39575.6 39857.6 38044.0 38133.2 38517.4 39005.0 39396.8 25621.0 32254.8 38082.4 38319.4 38355.8 32571.0 33223.2 33547.7 33822.7 33823.5 20911.5 22535.7 22986.5 25150.4 25435.6 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:36534.39 Median:21012.74 stddev:106729.91 (507.9%) stddev-sans-first:106994.82 19:07:08 INFO - PROCESS | 1660 | Values: 393.9 395.9 397.6 401.0 418.6 14724.6 16380.7 21003.0 21262.3 21414.5 18092.8 20913.8 21022.5 21025.0 21301.7 5444.2 5842.6 6181.3 6248.8 6293.0 5725.1 6161.2 6171.4 6182.2 6231.2 5532.2 5574.8 5599.8 5613.3 5624.0 5337.6 5555.4 5565.1 5583.9 5595.7 5414.5 5449.9 5450.0 5451.0 5475.1 12904.3 13202.5 13550.3 13842.7 13847.2 2065.9 2100.2 2110.7 2142.6 2276.3 7889.0 10237.4 10250.8 10301.0 10453.8 1721.4 1779.8 1786.7 1799.3 1808.5 124458.0 131888.0 132366.0 132511.0 133651.3 11529.9 11784.5 11795.6 11800.2 12009.5 12828.3 15311.9 15459.6 15504.7 15555.2 7339.5 7958.6 8150.8 8919.5 8984.5 7468.6 8628.0 8868.0 8909.1 9013.6 4664.2 4701.9 4761.1 4762.4 4789.1 1947.6 2007.0 2018.1 2028.2 2039.5 913.4 934.4 997.6 1004.2 1074.1 20274.6 20403.6 20428.7 20708.8 20788.0 946.5 959.2 962.5 968.9 1034.4 8277.9 8556.6 10377.8 10771.7 10836.0 627856.0 628244.0 629168.0 649057.0 649755.0 140114.0 140150.6 140351.0 141459.0 143074.0 1004.1 1009.1 1073.6 1082.2 1082.8 14855.0 18825.6 19064.7 19108.6 19132.0 40158.6 54446.7 55651.4 55963.7 56063.7 82158.0 83465.7 83469.0 83940.7 84224.6 7496.3 8121.8 8392.5 8415.7 8493.4 7138.0 11597.1 11931.2 12038.6 12943.4 10957.8 11125.9 11170.2 11504.7 11617.9 10982.8 11117.9 11372.9 11377.3 11498.0 11354.7 11678.9 11692.2 11904.8 11919.3 7156.8 7719.9 8267.8 8311.5 8701.6 10891.9 11377.0 11528.2 11529.8 11699.9 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5720.90 Median:2699.47 stddev:3152.85 (116.8%) stddev-sans-first:3146.59 19:07:08 INFO - PROCESS | 1660 | Values: 1614.0 1638.1 1887.5 1955.9 1973.7 7977.6 8525.8 8686.3 9046.0 9054.0 7512.7 8747.8 8786.8 8843.2 8880.0 2405.8 2442.5 2442.6 2443.5 2448.2 2467.9 2469.0 2495.4 2496.0 2516.7 2468.8 2470.1 2499.8 2500.8 2503.4 2433.4 2447.6 2469.5 2478.0 2479.2 2434.1 2453.3 2453.4 2463.4 2470.0 8777.7 8926.3 8951.6 8954.0 8982.7 5386.7 5517.7 5525.9 5535.6 5574.0 3515.5 3553.4 3587.7 3691.7 3704.6 1041.0 1063.6 1066.1 1066.9 1087.0 11525.4 11599.2 11601.5 11607.7 11646.0 4835.1 4836.5 4937.2 4946.1 4963.8 9996.1 10007.8 10010.1 10078.3 10093.7 4099.5 5107.9 5394.2 5497.2 5502.6 4485.2 4834.7 4968.4 4989.9 5030.7 3580.7 3932.3 4018.5 4112.4 4149.0 1375.6 1381.2 1387.6 1396.0 1398.6 845.5 933.2 955.1 958.1 983.6 5205.8 5815.0 6048.0 6059.1 6066.9 473.2 474.2 484.7 1014.9 1065.8 3402.4 3991.7 4056.4 4061.1 4078.8 10275.5 10310.6 10613.0 10771.6 10776.5 8540.4 10576.7 10642.0 10755.4 10842.0 8036.0 8084.9 8163.0 8239.1 8277.2 5740.9 5861.6 5887.9 5896.5 5916.6 9627.5 9768.8 10020.8 10098.9 10110.7 9956.1 10025.1 10167.3 10246.7 10250.3 6915.8 7352.3 7406.5 7606.1 7682.3 7024.8 8403.7 8474.1 8477.9 8480.6 7165.8 7389.9 7433.3 7566.7 7623.8 7205.2 7310.7 7325.8 7328.6 7411.4 6923.8 6994.7 7012.1 7016.4 7019.0 4834.1 4929.0 4941.0 4995.1 5147.5 4914.2 5025.1 5032.2 5082.5 5238.7 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4469.78 Median:4999.42 stddev:1372.43 (27.5%) stddev-sans-first:1340.31 19:07:08 INFO - PROCESS | 1660 | Values: 310.6 316.7 328.1 333.9 334.6 4539.3 5253.4 5348.3 5371.2 5532.6 5300.8 5351.4 5382.5 5441.6 5453.9 3655.0 4093.8 4097.0 4118.0 4158.3 3621.6 3807.8 4127.3 4128.0 4131.5 3221.2 3835.5 3889.5 4071.2 4083.7 3765.6 4098.8 4108.5 4120.5 4139.6 3222.8 3499.4 3527.2 3534.4 3548.0 4830.4 5184.4 5193.0 5210.7 5223.6 4327.1 4626.1 4670.4 4683.1 4684.0 4833.7 5659.7 5688.9 5708.7 5709.9 3436.6 3718.5 3865.2 3879.6 3884.0 5177.9 5716.8 5853.5 5900.5 5904.9 3377.8 3771.1 3926.7 3957.4 3989.8 3585.6 3739.9 3753.8 3762.2 3821.6 3981.5 4021.9 4453.7 4568.5 4634.3 4854.9 5303.0 5717.2 5738.5 5781.0 1263.9 1275.8 1287.4 1290.8 1308.0 843.6 843.9 871.5 877.6 886.8 4241.9 4894.0 4941.7 4995.0 5003.8 5172.6 5527.2 5527.3 5566.6 5583.0 4067.7 4322.0 4852.7 4936.3 4974.8 2667.5 2856.5 2923.1 2938.9 2958.9 5071.0 5769.9 5856.6 5866.6 5872.2 5360.3 5487.5 5642.2 5725.7 5796.3 5156.4 5643.2 5823.4 5835.5 5844.9 5175.1 5266.5 5303.9 5607.2 5643.4 4796.8 5364.9 5487.0 5507.5 5570.0 5019.5 5465.8 5722.2 5774.6 5793.8 3233.3 3579.5 3703.3 3729.5 3735.0 5076.9 5145.7 5528.5 5528.9 5586.6 4888.2 5060.0 5662.8 5698.4 5728.2 5047.5 5277.5 5590.3 5666.7 5679.4 4464.0 4696.9 4865.7 5385.1 5542.5 4851.9 5047.6 5485.8 5665.5 5733.5 4587.9 4888.0 5572.9 5575.3 5586.8 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17716.73 Median:30503.70 stddev:24859.02 (81.5%) stddev-sans-first:24895.81 19:07:08 INFO - PROCESS | 1660 | Values: 672.2 751.1 757.5 764.1 769.2 14766.4 15379.5 15401.5 15407.5 16352.0 15793.1 16237.7 16245.2 16285.2 16317.2 748.3 953.4 956.3 963.4 965.1 935.3 966.2 968.9 970.3 979.8 987.8 997.8 1007.9 1016.3 1017.5 861.2 980.1 1004.8 1005.8 1009.2 753.5 753.8 756.6 759.4 763.2 15118.3 15163.5 15165.1 15206.5 15223.7 10797.2 12810.2 12811.2 12959.8 12982.4 9713.2 10008.7 10048.2 10071.2 10084.3 3488.8 3579.5 3605.7 3606.2 4433.4 83272.2 84134.0 84878.0 86326.0 86729.1 9552.7 9754.7 9765.6 9910.4 10226.6 22179.8 22725.2 22779.1 22852.7 22913.2 7498.4 7505.1 7564.5 7622.6 7673.0 4003.4 4086.4 4088.5 4096.3 4109.3 3217.3 3432.9 3597.7 3708.7 3714.9 646.5 649.3 651.1 668.4 668.5 556.6 604.7 611.1 613.6 616.8 7918.2 8133.3 8187.3 8299.2 8305.9 565.4 594.2 604.4 606.3 616.5 3791.0 3810.5 3855.2 3904.1 4039.9 82932.6 83334.9 85780.1 87154.6 89784.7 54781.0 60402.7 80312.6 81912.2 82474.0 67757.3 71421.6 72180.0 72877.0 73464.6 7510.5 7750.7 7804.8 7826.1 7838.7 44362.9 44413.9 44879.2 44915.0 45774.8 57299.7 61692.7 62239.7 63174.7 64434.7 8255.5 10284.7 10453.0 10524.8 10654.3 10853.2 11107.8 11133.7 11381.1 11396.9 10362.9 10449.5 10452.4 10632.7 10689.4 10414.5 10429.4 10694.5 10783.4 10801.1 9819.1 10034.8 10111.9 10132.6 10459.0 6893.9 8527.4 8636.5 8783.6 8875.1 7252.9 7709.3 8241.4 8739.0 9258.3 19:07:08 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:07:08 INFO - PROCESS | 1660 | 19:07:08 INFO - TEST-INFO | 1660: exit 0 19:07:08 INFO - TEST-OK | dromaeo_css | took 1112978ms 19:07:08 INFO - TEST-START | kraken 19:07:08 INFO - operating with platform_type : mac_ 19:07:08 INFO - Initialising browser for kraken test... 19:07:08 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpqn7MI8/profile http://localhost:49272/getInfo.html) 19:07:11 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 19:07:11 INFO - PROCESS | 1672 | colorDepth:24 19:07:11 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 19:07:11 INFO - PROCESS | 1672 | __metrics 19:07:11 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 19:07:12 INFO - TEST-INFO | 1672: exit 0 19:07:12 INFO - Browser initialized. 19:07:12 INFO - Running cycle 1/1 for kraken test... 19:07:12 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpqn7MI8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:24 INFO - PROCESS | 1675 | RSS: Main: 249962496 19:07:24 INFO - PROCESS | 1675 | 19:07:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:28 INFO - PROCESS | 1675 | RSS: Main: 261795840 19:07:28 INFO - PROCESS | 1675 | 19:07:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 19:07:31 INFO - PROCESS | 1675 | RSS: Main: 266547200 19:07:31 INFO - PROCESS | 1675 | 19:07:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 19:07:35 INFO - PROCESS | 1675 | RSS: Main: 266964992 19:07:35 INFO - PROCESS | 1675 | 19:07:37 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 19:07:38 INFO - PROCESS | 1675 | RSS: Main: 267620352 19:07:38 INFO - PROCESS | 1675 | 19:07:40 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:41 INFO - PROCESS | 1675 | RSS: Main: 268115968 19:07:41 INFO - PROCESS | 1675 | 19:07:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:44 INFO - PROCESS | 1675 | RSS: Main: 268300288 19:07:44 INFO - PROCESS | 1675 | 19:07:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:48 INFO - PROCESS | 1675 | RSS: Main: 268365824 19:07:48 INFO - PROCESS | 1675 | 19:07:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 19:07:51 INFO - PROCESS | 1675 | RSS: Main: 268763136 19:07:51 INFO - PROCESS | 1675 | 19:07:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:53 INFO - PROCESS | 1675 | RSS: Main: 268259328 19:07:53 INFO - PROCESS | 1675 | 19:07:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:56 INFO - PROCESS | 1675 | RSS: Main: 268304384 19:07:56 INFO - PROCESS | 1675 | 19:07:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:59 INFO - PROCESS | 1675 | RSS: Main: 268333056 19:07:59 INFO - PROCESS | 1675 | 19:08:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:08:03 INFO - PROCESS | 1675 | RSS: Main: 268664832 19:08:03 INFO - PROCESS | 1675 | 19:08:05 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:08:06 INFO - PROCESS | 1675 | RSS: Main: 268816384 19:08:06 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 19:08:08 INFO - PROCESS | 1675 | RSS: Main: 270139392 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | __start_tp_report 19:08:08 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 19:08:08 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 19:08:08 INFO - PROCESS | 1675 | |i|pagename|runs| 19:08:08 INFO - PROCESS | 1675 | |0;ai-astar;112;110;87;141;148;167;152;123;111;169 19:08:08 INFO - PROCESS | 1675 | |1;audio-beat-detection;191;108;180;197;146;144;138;123;172;119 19:08:08 INFO - PROCESS | 1675 | |2;audio-dft;198;212;239;198;197;195;202;208;201;192 19:08:08 INFO - PROCESS | 1675 | |3;audio-fft;75;82;75;146;62;82;50;74;155;84 19:08:08 INFO - PROCESS | 1675 | |4;audio-oscillator;83;105;131;77;82;82;123;80;84;126 19:08:08 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;86;99;85;89;86;88;87;88;86 19:08:08 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;95;94;95;100;151;129;151;104;143 19:08:08 INFO - PROCESS | 1675 | |7;imaging-desaturate;91;96;95;91;95;90;92;93;90;90 19:08:08 INFO - PROCESS | 1675 | |8;json-parse-financial;59;62;67;96;91;88;61;76;63;61 19:08:08 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;66;69;76;78;77;73;69;69;76;106 19:08:08 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;98;101;89;87;89;91;99;113;108;113 19:08:08 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;146;157;158;149;150;134;147;157;193;142 19:08:08 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;169;170;170;172;172;174;216;170;167;169 19:08:08 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;72;69;68;68;66;70;65;68;65 19:08:08 INFO - PROCESS | 1675 | __end_tp_report 19:08:08 INFO - PROCESS | 1675 | __start_cc_report 19:08:08 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1050 19:08:08 INFO - PROCESS | 1675 | __end_cc_report 19:08:08 INFO - PROCESS | 1675 | __startTimestamp1462500488490__endTimestamp 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | ------- Summary: start ------- 19:08:08 INFO - PROCESS | 1675 | Number of tests: 14 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:132.00 Median:150.00 stddev:27.41 (18.3%) stddev-sans-first:28.10 19:08:08 INFO - PROCESS | 1675 | Values: 112.0 110.0 87.0 141.0 148.0 167.0 152.0 123.0 111.0 169.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:151.80 Median:159.00 stddev:31.43 (19.8%) stddev-sans-first:29.97 19:08:08 INFO - PROCESS | 1675 | Values: 191.0 108.0 180.0 197.0 146.0 144.0 138.0 123.0 172.0 119.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:204.20 Median:201.50 stddev:13.60 (6.7%) stddev-sans-first:14.23 19:08:08 INFO - PROCESS | 1675 | Values: 198.0 212.0 239.0 198.0 197.0 195.0 202.0 208.0 201.0 192.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:88.50 Median:75.00 stddev:34.31 (45.7%) stddev-sans-first:36.04 19:08:08 INFO - PROCESS | 1675 | Values: 75.0 82.0 75.0 146.0 62.0 82.0 50.0 74.0 155.0 84.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:81.00 stddev:21.71 (26.8%) stddev-sans-first:22.40 19:08:08 INFO - PROCESS | 1675 | Values: 83.0 105.0 131.0 77.0 82.0 82.0 123.0 80.0 84.0 126.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:88.00 stddev:3.99 (4.5%) stddev-sans-first:4.24 19:08:08 INFO - PROCESS | 1675 | Values: 88.0 86.0 99.0 85.0 89.0 86.0 88.0 87.0 88.0 86.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:122.50 stddev:24.94 (20.4%) stddev-sans-first:25.19 19:08:08 INFO - PROCESS | 1675 | Values: 94.0 95.0 94.0 95.0 100.0 151.0 129.0 151.0 104.0 143.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:92.50 stddev:2.31 (2.5%) stddev-sans-first:2.40 19:08:08 INFO - PROCESS | 1675 | Values: 91.0 96.0 95.0 91.0 95.0 90.0 92.0 93.0 90.0 90.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:72.40 Median:71.50 stddev:14.24 (19.9%) stddev-sans-first:14.25 19:08:08 INFO - PROCESS | 1675 | Values: 59.0 62.0 67.0 96.0 91.0 88.0 61.0 76.0 63.0 61.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:74.50 stddev:11.36 (15.2%) stddev-sans-first:11.47 19:08:08 INFO - PROCESS | 1675 | Values: 66.0 69.0 76.0 78.0 77.0 73.0 69.0 69.0 76.0 106.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:98.80 Median:89.00 stddev:9.92 (11.1%) stddev-sans-first:10.52 19:08:08 INFO - PROCESS | 1675 | Values: 98.0 101.0 89.0 87.0 89.0 91.0 99.0 113.0 108.0 113.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:153.50 stddev:15.80 (10.3%) stddev-sans-first:16.54 19:08:08 INFO - PROCESS | 1675 | Values: 146.0 157.0 158.0 149.0 150.0 134.0 147.0 157.0 193.0 142.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:171.00 stddev:14.57 (8.5%) stddev-sans-first:15.30 19:08:08 INFO - PROCESS | 1675 | Values: 169.0 170.0 170.0 172.0 172.0 174.0 216.0 170.0 167.0 169.0 19:08:08 INFO - PROCESS | 1675 | 19:08:08 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:2.18 (3.2%) stddev-sans-first:2.32 19:08:08 INFO - PROCESS | 1675 | Values: 68.0 72.0 69.0 68.0 68.0 66.0 70.0 65.0 68.0 65.0 19:08:08 INFO - PROCESS | 1675 | -------- Summary: end -------- 19:08:08 INFO - PROCESS | 1675 | 19:08:09 INFO - TEST-INFO | 1675: exit 0 19:08:09 INFO - TEST-OK | kraken | took 60349ms 19:08:09 INFO - SUITE-END | took 1173s 19:08:09 INFO - Completed test suite (00:19:33) 19:08:09 INFO - javascript benchmark 19:08:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3500.334936362093, 3502.474628805678, 3528.700060494858, 3533.9116522086942, 3540.274243780024, 4788.425388953322, 4990.825321113762, 5371.630234290255, 5869.090291004901, 5918.467337939595, 5792.275965504312, 5888.910551547956, 5912.231409916715, 5964.3737407572235, 6027.728752206151, 4417.933730994037, 5298.390685071208, 5322.760475778599, 5349.277847490587, 5395.001924643934, 4939.046046410799, 5424.701641409721, 5479.835604931858, 5513.2005859150395, 5547.500724934755, 5258.684478931276, 5424.240606315119, 5429.212764149216, 5449.509544141007, 5480.232767412565, 5214.113600687892, 5304.946950530484, 5334.0, 5353.384360798519, 5416.891662166774, 5290.656107353048, 5294.179402192628, 5308.893822123575, 5342.011727830325, 5419.105847535152, 5665.54675625953, 5676.716164191774, 5791.739371728282, 5820.941790582125, 5839.094940284221, 6247.094171345129, 6291.842703932392, 6296.307406185316, 6308.243010838729, 6309.716062777186, 6323.557350985433, 6334.081558174039, 6339.207599050119, 6349.333320001426, 6363.109164716897, 4817.542333478349, 5867.647941123546, 5894.764209431661, 6074.544409169286, 6100.359462256443, 6312.0, 6323.936760632427, 6328.272248691459, 6339.29151446409, 6355.586886852339, 6229.875402492015, 6245.469135123474, 6278.6860656966965, 6280.403361680633, 6288.371162883766, 6137.989923150242, 6189.1954045974635, 6215.65813880241, 6224.906626400608, 6270.7805226816845, 6128.19758601264, 6261.78083767066, 6263.091851681481, 6264.467520260738, 6275.843103922439, 6236.6258024517765, 6271.937280627227, 6278.717457714414, 6306.1171435998995, 6318.020706790455, 4867.586255168279, 4974.726390048582, 4979.875503112451, 5042.798288068436, 5054.4187418447345, 4473.060657262012, 4478.126765280804, 4518.615917646971, 4521.66087543429, 4549.567791059844, 5941.287045554561, 5997.340292567814, 5999.640021598718, 6000.759969601167, 6032.849178770478, 6186.133941248228, 6219.471344935731, 6243.063540468966, 6253.405926436982, 6294.0, 6017.428344307371, 6024.728887200086, 6031.849203769853, 6045.3652366501765, 6096.116063170815, 4606.193916064675, 4961.007798440326, 5368.409474957752, 5516.393196748354, 5579.023670857584, 6250.281217659936, 6267.9373206268265, 6326.209223847111, 6401.679916004275, 6424.710888009958, 6217.285012223652, 6234.532410069353, 6249.562530622813, 6251.187345645219, 6339.524535659752, 5978.222831031938, 6323.1463752394675, 6397.4562162216735, 6434.195725534309, 6459.386358295955, 6228.968855155696, 6255.749770009149, 6267.717952692231, 6305.779297724557, 6387.073874288295, 6193.81418557444, 6278.215223097113, 6311.873762524816, 6312.14786003902, 6331.27190373113, 6020.277566692025, 6136.764169924414, 6162.983107787344, 6297.968510157603, 6307.779227727191, 6123.418275263843, 6169.198004259597, 6215.0, 6220.657863817533, 6234.407731265523, 5996.610220335664, 6092.512598992159, 6106.992106812639, 6126.601770885057, 6147.877042459215, 6137.499563105042, 6162.691865406801, 6235.0, 6256.874862502634, 6281.089242059968, 6175.84560385994, 6176.567640265138, 6189.319174890668, 6192.876142477216, 6202.968985155046], "value": 5785.045978457387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.53138050232803, 500.90733214355345, 502.87930896584817, 516.8707823044239, 574.0356201581346, 30256.697433025663, 42069.158616827684, 42101.57898421015, 42220.57779422205, 42224.78887605558, 42444.575554244446, 43105.78447107768, 43112.92217694559, 43145.13709725803, 43154.784226078824, 9536.332456728049, 9744.902550974506, 9745.31782775206, 9771.21830253578, 9782.657606983757, 9636.518174091267, 9727.1731902788, 9728.0, 9764.707058788248, 9765.853512197322, 9726.17327527161, 9748.805023899518, 9758.707238782848, 9771.609135634602, 9787.902120978806, 9670.661526846527, 9696.321257511978, 9733.172680322217, 9738.853917191247, 9775.266854985905, 9578.521073946344, 9672.549117632412, 9702.029797020312, 9714.659986900424, 9717.46553939533, 39343.60656393428, 39539.60460395388, 39550.41798328063, 39622.80188599067, 39698.20603587941, 2820.238535595378, 3431.742619303537, 3785.6971442284553, 3791.241751649681, 3826.8660596878976, 24576.877115614487, 28725.856370718222, 29882.40235195284, 30397.392052159055, 30511.08466746001, 2756.476269508787, 2798.034678036074, 2807.3823758773037, 2850.942981140386, 2905.9273518162004, 432714.0, 522806.0, 523114.0, 530437.0, 530938.0, 36076.0, 36319.910402687965, 36727.4490882637, 37926.43110353347, 66124.67750645056, 37316.62683373186, 37382.81308593494, 37531.81234093867, 37653.43519847204, 37871.24257514889, 15410.614734631725, 15630.609234768994, 15653.452129175424, 15662.981906176063, 15669.373225071098, 13029.283389413673, 13092.803607945889, 13210.0, 13212.867871321356, 13340.466381344637, 6975.581465112109, 8340.833183336299, 8359.205875441823, 8380.622871970776, 8382.580870956428, 3393.423118069934, 3400.3709313776535, 3409.5226668266464, 3412.0, 3440.2431465077643, 919.3013309884402, 953.6614501851743, 1033.720895358249, 1069.2889228663007, 1119.630521927762, 23816.88091559566, 29266.7073329264, 29586.408271834873, 29698.851505742332, 30149.397012059635, 1099.9560017599367, 1108.3128460354471, 1110.8333749937412, 1132.886711328877, 1211.8182272658996, 15011.549653510194, 15169.620759481102, 15178.544643660707, 15364.846351536342, 15396.230188490754, 683349.0, 685292.0, 693428.0, 696995.0, 702387.0, 62018.37981620216, 62047.6897615509, 62352.376476235564, 62451.68774156191, 62468.68765656143, 1469.2727100085312, 1471.4114354258381, 1501.7822415749656, 1505.0367764630646, 1507.4648499782638, 27359.58960615632, 28660.713392865804, 28717.56923646105, 29063.564046538897, 29381.706182937898, 52073.218901717264, 52532.737336313076, 52673.0, 52704.0, 52839.0, 584678.0, 604758.0, 607562.0, 611313.0, 611679.0, 26255.0, 26856.462870742867, 26904.730952691007, 27173.456530869666, 27655.0, 43592.782036089615, 46193.845153870076, 46523.76738116288, 46944.7652761734, 48181.759091204316, 37500.62499374971, 38612.80693596514, 38885.0, 39575.60424395835, 39857.60142398539, 38044.0, 38133.237335253696, 38517.43608095669, 39005.0, 39396.80301598473, 25621.0, 32254.83872580716, 38082.42876356912, 38319.42520862134, 38355.808220958716, 32571.022869313958, 33223.180864372946, 33547.664523354455, 33822.66177338195, 33823.49264760981, 20911.477213069797, 22535.661965070864, 22986.54026919486, 25150.371240718396, 25435.61846572266], "value": 18690.604453234035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.92909276330266, 395.94654721612585, 397.58055251709436, 400.95789942056064, 418.5960548071113, 14724.6318842029, 16380.672386552276, 21003.0, 21262.25582104625, 21414.46463838403, 18092.81907180931, 20913.849738264427, 21022.474438139056, 21025.0, 21301.68047479285, 5444.1561557958485, 5842.620229685078, 6181.289151747562, 6248.781292654758, 6293.0, 5725.055365864628, 6161.199044124258, 6171.4445699887, 6182.227221597301, 6231.189945307104, 5532.225488431623, 5574.832755017335, 5599.832005039855, 5613.326400831906, 5624.015797235488, 5337.572994160497, 5555.416681248492, 5565.0817615093465, 5583.8883222335735, 5595.692236926968, 5414.458554144593, 5449.918251226235, 5449.972750136264, 5451.04606693827, 5475.06923822951, 12904.290264035475, 13202.471901123943, 13550.322483875767, 13842.653933651638, 13847.169169849842, 2065.867520412966, 2100.159936025587, 2110.693949377347, 2142.57148570285, 2276.3171048685326, 7889.0, 10237.436940968244, 10250.79498410035, 10301.0, 10453.790924181474, 1721.4467732846676, 1779.813119622434, 1786.6516029374277, 1799.3432397175068, 1808.5478630342413, 124458.0, 131888.0, 132366.0, 132511.0, 133651.33174334065, 11529.942350288362, 11784.528618855149, 11795.587154449553, 11800.173987820941, 12009.459574319177, 12828.29444380568, 15311.923440382727, 15459.61350966235, 15504.689906202038, 15555.22223888801, 7339.522931009467, 7958.647030004798, 8150.75547733569, 8919.509426981578, 8984.46093234408, 7468.626568671653, 8628.0, 8867.955660221658, 8909.109089091038, 9013.63945442188, 4664.160451118763, 4701.882452938704, 4761.071591039783, 4762.357081793983, 4789.137955168034, 1947.6007418479178, 2006.9899650501654, 2018.101944634653, 2028.1645111174412, 2039.4799326172028, 913.388030019875, 934.4486752815872, 997.5959736306662, 1004.1815920025156, 1074.08166018055, 20274.594508109756, 20403.59192816165, 20428.693569596468, 20708.79291207099, 20788.0, 946.5078159357096, 959.1799011844912, 962.5235508423375, 968.8740463739668, 1034.4103860799273, 8277.875831862528, 8556.572171391406, 10377.792444151226, 10771.730706732395, 10836.0, 627856.0, 628244.0, 629168.0, 649057.0, 649755.0, 140114.0, 140150.59849401578, 140351.0, 141459.0, 143074.0, 1004.1414590525142, 1009.0817356205818, 1073.5974009746344, 1082.1775450657558, 1082.8429877667695, 14855.0, 18825.623487530447, 19064.714029289848, 19108.617827643648, 19132.0, 40158.59841401548, 54446.72776636091, 55651.443485564625, 55963.720181398836, 56063.71968140297, 82158.0, 83465.74801377863, 83469.0, 83940.74088888794, 84224.57887710522, 7496.287852653989, 8121.75634730959, 8392.538410387244, 8415.663373465237, 8493.447925884797, 7138.0363650906465, 11597.130215233723, 11931.224470409396, 12038.578649747216, 12943.417546210254, 10957.83563246536, 11125.888741112809, 11170.162237832357, 11504.654860354203, 11617.88382116168, 10982.780344393228, 11117.94379533943, 11372.943135284271, 11377.317360958368, 11498.0, 11354.659360219206, 11678.883211168119, 11692.240004399688, 11904.821427678418, 11919.284842909454, 7156.821079473055, 7719.92280077192, 8267.83464330698, 8311.501309921425, 8701.564921754014, 10891.891081089405, 11377.032952199155, 11528.193026488065, 11529.827052594386, 11699.88300116988], "value": 9459.892170408999, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1613.9677206455867, 1638.1072315588003, 1887.4526387347669, 1955.892425916574, 1973.7138114973338, 7977.6011199439945, 8525.78685532862, 8686.261667758232, 9045.99999999999, 9054.0, 7512.737054203104, 8747.781305467366, 8786.780330491742, 8843.248323892461, 8879.978802437721, 2405.831591788571, 2442.5237078769646, 2442.5969714997095, 2443.5235129149823, 2448.179859746987, 2467.864267465289, 2469.0, 2495.413577809213, 2495.987520062406, 2516.7105782834933, 2468.814838887082, 2470.147799009339, 2499.8125140614434, 2500.7749302562765, 2503.4116982509095, 2433.3794882305074, 2447.5961466358117, 2469.5307891500647, 2477.962830557543, 2479.194261864901, 2434.1237154624323, 2453.251758213744, 2453.374389530676, 2463.4334103156375, 2469.9753002469924, 8777.692780752643, 8926.330525210635, 8951.597178127, 8953.955230223808, 8982.730518084469, 5386.703731294777, 5517.668939863621, 5525.861853453655, 5535.612507124503, 5573.972130139324, 3515.542979412686, 3553.378158822222, 3587.7309201809708, 3691.7231207659534, 3704.5554533456157, 1041.0, 1063.5905176507101, 1066.1470823341374, 1066.9359838409644, 1086.983695244572, 11525.36610486423, 11599.188056836025, 11601.535938562447, 11607.6517704469, 11646.0, 4835.129676658235, 4836.492168322346, 4937.185364414831, 4946.060248552763, 4963.8262660806695, 9996.100350968447, 10007.799844003079, 10010.14913732341, 10078.344907580984, 10093.747656308504, 4099.487564054493, 5107.923381149286, 5394.244805727201, 5497.230387745719, 5502.5597952163735, 4485.215087359699, 4834.734089625034, 4968.378952630921, 4989.850304490871, 5030.7233102179, 3580.6598373154507, 3932.2725295820364, 4018.477597912253, 4112.383142528644, 4149.045719484536, 1375.6354566040006, 1381.1574939287027, 1387.5559820857432, 1396.015808854755, 1398.5804258722342, 845.4758050009034, 933.2347475070512, 955.0926619711302, 958.0658857613771, 983.5868935047297, 5205.765740541685, 5814.9709251454315, 6047.969760151171, 6059.091136329585, 6066.939330606726, 473.1648640150138, 474.16783544879206, 484.69948631847774, 1014.9035841595037, 1065.7548763784373, 3402.42158832999, 3991.702696623609, 4056.411820286042, 4061.0862555925273, 4078.8368465261055, 10275.486225688686, 10310.587576496857, 10613.0, 10771.56913723458, 10776.461176941279, 8540.40217184791, 10576.735581610368, 10642.0, 10755.354678719303, 10842.0, 8035.959820200862, 8084.878726819103, 8162.9591852039175, 8239.134890836376, 8277.172282771795, 5740.885182296247, 5861.560382971379, 5887.911681324869, 5896.469317761422, 5916.615419997858, 9627.518624068909, 9768.804623907625, 10020.799584008424, 10098.939611340997, 10110.747231319276, 9956.054174853378, 10025.147862431484, 10167.339122956984, 10246.743831404274, 10250.333728307636, 6915.827104322432, 7352.264773522605, 7406.481546291708, 7606.125295591078, 7682.347000505019, 7024.754133605299, 8403.747887563382, 8474.110218426977, 8477.872831907403, 8480.61837217339, 7165.7850264492145, 7389.926100738924, 7433.293837085468, 7566.735164269224, 7623.847523049619, 7205.243449437734, 7310.6710198040155, 7325.816854578678, 7328.560286382834, 7411.444141689287, 6923.792286231421, 6994.650267486707, 7012.053372794608, 7016.438684905297, 7018.964905175646, 4834.105690447279, 4928.950710492849, 4940.975295123501, 4995.100881841304, 5147.536721694915, 4914.238293064581, 5025.145725226794, 5032.169692000925, 5082.466341034138, 5238.68567885928], "value": 4615.990191629278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.6303498836385, 316.69438991373335, 328.1124558070419, 333.89315419065895, 334.6101791413004, 4539.273716205408, 5253.369595648518, 5348.331458567679, 5371.221172929928, 5532.585056120787, 5300.840974770754, 5351.411344752074, 5382.515573598375, 5441.59188060896, 5453.945460545403, 3654.9817250913707, 4093.8157782899693, 4096.959030409687, 4117.979410102946, 4158.293090174678, 3621.5654121505454, 3807.847686092553, 4127.29835927893, 4127.958720412803, 4131.483564554431, 3221.178599457135, 3835.520559930009, 3889.4749208856865, 4071.246819338432, 4083.673306135502, 3765.587904535799, 4098.8360465581345, 4108.486439195116, 4120.546739858613, 4139.565345638694, 3222.822744749038, 3499.422595271777, 3527.1887465882733, 3534.3991521441417, 3547.9822600887087, 4830.420349558076, 5184.403793563713, 5193.0, 5210.6613070150315, 5223.608229382811, 4327.112941846914, 4626.144163329761, 4670.416197975253, 4683.086798074377, 4684.0, 4833.661643684944, 5659.717014149276, 5688.886222275572, 5708.743106560215, 5709.914351284734, 3436.5634365634364, 3718.5165928429133, 3865.202680753435, 3879.5538513071074, 3883.98058009711, 5177.870553236162, 5716.828495145152, 5853.47318741311, 5900.468957793776, 5904.852378690524, 3377.7973321600784, 3771.132639492932, 3926.744761590545, 3957.3866050762176, 3989.840406383712, 3585.6235095315137, 3739.869104581381, 3753.8415411138158, 3762.1535154589997, 3821.636944490269, 3981.5421226559315, 4021.8574867886123, 4453.688241823042, 4568.497465278817, 4634.328022436728, 4854.883376823351, 5302.973485132549, 5717.169496437397, 5738.512226460714, 5781.0, 1263.943122559487, 1275.7561377656896, 1287.4206607026874, 1290.825738525306, 1307.9542216022585, 843.6077224090827, 843.9282111718084, 871.4771137317686, 877.6050777150308, 886.7694399456187, 4241.872743817628, 4893.975530122398, 4941.678790878582, 4995.0, 5003.849884503471, 5172.586193104543, 5527.198556209408, 5527.281453411111, 5566.55467562594, 5583.0, 4067.6949228807957, 4322.027543802682, 4852.714030781845, 4936.284238785356, 4974.801007959713, 2667.4531720997147, 2856.514392553271, 2923.093840909325, 2938.8971386001385, 2958.940821183607, 5071.0, 5769.922827781995, 5856.590038697335, 5866.560007999417, 5872.17789509469, 5360.303160589098, 5487.478689524489, 5642.194497760677, 5725.656460612377, 5796.304443466811, 5156.381234251914, 5643.153526970905, 5823.388544202967, 5835.503982161479, 5844.912326315109, 5175.14610089331, 5266.526012658879, 5303.9204411934625, 5607.18711276336, 5643.35101463337, 4796.784144713426, 5364.892702145858, 5486.972565137149, 5507.504324610804, 5569.972150139223, 5019.511439549478, 5465.797524544594, 5722.198892155018, 5774.624649397776, 5793.827314756966, 3233.2725136844024, 3579.516765236764, 3703.2778608171147, 3729.477873097714, 3734.9644443778748, 5076.923846142237, 5145.710158979218, 5528.530074943675, 5528.889422211614, 5586.636868603528, 4888.2178851383605, 5059.974700126476, 5662.773489060556, 5698.4016678249845, 5728.2266893970445, 5047.4952504749235, 5277.498637629419, 5590.329160500766, 5666.744996475086, 5679.403662615366, 4464.017916058462, 4696.906061878812, 4865.7323847188745, 5385.057614917296, 5542.501174894279, 4851.927221091616, 5047.596192304681, 5485.75314110858, 5665.498642859563, 5733.512651424676, 4587.885302867455, 4887.975560122177, 5572.888542229051, 5575.330960284792, 5586.776528938797], "value": 4037.5258238206197, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.2302963107242, 751.0649241694092, 757.5189754505891, 764.1174443517739, 769.2077160524658, 14766.409343626256, 15379.538613841603, 15401.537953861389, 15407.460738874128, 16352.0, 15793.052416854996, 16237.675246495093, 16245.187740612952, 16285.185740712976, 16317.184140792942, 748.2928632442317, 953.3993584042043, 956.3353469338813, 963.3641796414367, 965.0687086961082, 935.2611436964823, 966.2125367825236, 968.9127978481936, 970.2868391732071, 979.750163708257, 987.792563561653, 997.7505623594102, 1007.8639383683219, 1016.3089099412397, 1017.5370206555999, 861.1517655109712, 980.1227901028575, 1004.8342023566103, 1005.7787286796893, 1009.1573536097368, 753.5365750063726, 753.7625647920887, 756.5725365168662, 759.3507551043889, 763.1643350531215, 15118.319675614513, 15163.469278575198, 15165.090094594249, 15206.46777362787, 15223.695526089527, 10797.244192906499, 12810.16733912302, 12811.167274127152, 12959.805602915963, 12982.35088245584, 9713.174380177623, 10008.749781255454, 10048.196144308511, 10071.24465665078, 10084.294099413044, 3488.843002064913, 3579.49887015818, 3605.693516051139, 3606.1705807664384, 4433.401490798766, 83272.16727832705, 84134.0, 84878.0, 86326.0, 86729.13270867337, 9552.713418597454, 9754.707358779247, 9765.609375625038, 9910.405375677337, 10226.6420675276, 22179.778202218095, 22725.204617838626, 22779.088836446357, 22852.65721014186, 22913.19803806892, 7498.4143156085975, 7505.061867266591, 7564.50830696014, 7622.58075805825, 7673.040869891263, 4003.419504171937, 4086.4074709167003, 4088.468499095158, 4096.283150448719, 4109.260333140063, 3217.2761128745847, 3432.8626854925524, 3597.748157628941, 3708.721845861517, 3714.8699795507027, 646.5215740352199, 649.2533586375612, 651.1307404614893, 668.388424591503, 668.4919461209515, 556.6020295488664, 604.694629212245, 611.126089691745, 613.6011592464934, 616.8126356763213, 7918.168592297726, 8133.308668763221, 8187.26314631686, 8299.170082991652, 8305.875411868827, 565.4260925160964, 594.2364062180164, 604.4227762486814, 606.336814155787, 616.5006344860677, 3790.962090379061, 3810.5046343975105, 3855.248226595843, 3904.1215726461887, 4039.939400908989, 82932.58533707293, 83334.9166270848, 85780.14219857722, 87154.56422717846, 89784.6532302016, 54781.0, 60402.69798650979, 80312.59843700744, 81912.18087819165, 82474.0, 67757.32242677707, 71421.6428917852, 72180.0, 72877.0, 73464.63267683628, 7510.511816731895, 7750.728724494616, 7804.84390312202, 7826.060872695313, 7838.686452541834, 44362.890927727065, 44413.88965275894, 44879.20483180636, 44915.0, 45774.77112614416, 57299.71350143223, 61692.69153654203, 62239.688801555705, 63174.68412657907, 64434.67782661057, 8255.463394879314, 10284.691459255935, 10453.0, 10524.789504210026, 10654.307470014424, 10853.200750768903, 11107.833382499108, 11133.721656958316, 11381.146414018816, 11396.886031139582, 10362.948185259025, 10449.477526123512, 10452.425116618659, 10632.681019569425, 10689.358638481715, 10414.47927603632, 10429.426381549089, 10694.518746656264, 10783.406912619881, 10801.135909127408, 9819.116279534876, 10034.849477257992, 10111.89888101139, 10132.64535741275, 10459.00639439252, 6893.931060689329, 8527.360447966552, 8636.481811091355, 8783.560821959005, 8875.112488751074, 7252.854942901218, 7709.267619576131, 8241.423100383156, 8739.03870574249, 9258.305627077862], "value": 6832.745401940237, "unit": "score"}], "name": "dromaeo_css", "value": 7119.877712741279, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 87.0, 141.0, 148.0, 167.0, 152.0, 123.0, 111.0, 169.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 108.0, 180.0, 197.0, 146.0, 144.0, 138.0, 123.0, 172.0, 119.0], "value": 151.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 212.0, 239.0, 198.0, 197.0, 195.0, 202.0, 208.0, 201.0, 192.0], "value": 204.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 75.0, 146.0, 62.0, 82.0, 50.0, 74.0, 155.0, 84.0], "value": 88.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 105.0, 131.0, 77.0, 82.0, 82.0, 123.0, 80.0, 84.0, 126.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 99.0, 85.0, 89.0, 86.0, 88.0, 87.0, 88.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 100.0, 151.0, 129.0, 151.0, 104.0, 143.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 95.0, 91.0, 95.0, 90.0, 92.0, 93.0, 90.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 67.0, 96.0, 91.0, 88.0, 61.0, 76.0, 63.0, 61.0], "value": 72.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 76.0, 78.0, 77.0, 73.0, 69.0, 69.0, 76.0, 106.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 89.0, 87.0, 89.0, 91.0, 99.0, 113.0, 108.0, 113.0], "value": 98.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 157.0, 158.0, 149.0, 150.0, 134.0, 147.0, 157.0, 193.0, 142.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 172.0, 172.0, 174.0, 216.0, 170.0, 167.0, 169.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 69.0, 68.0, 68.0, 66.0, 70.0, 65.0, 68.0, 65.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1613.1000000000001, "alertThreshold": 2.0}]} 19:08:09 INFO - Return code: 0 19:08:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:08:09 ERROR - # TBPL SUCCESS # 19:08:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:09 INFO - 2016-05-05 19:08:09.798 screenresolution[1680:10327] starting screenresolution argv=screenresolution get 19:08:09 INFO - 2016-05-05 19:08:09.800 screenresolution[1680:10327] Display 0: 1600x1200x32@60 19:08:09 INFO - 2016-05-05 19:08:09.806 screenresolution[1681:10330] starting screenresolution argv=screenresolution list 19:08:09 INFO - Available Modes on Display 0 19:08:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:10 INFO - Intel Iris: 19:08:10 INFO - Chipset Model: Intel Iris 19:08:10 INFO - Type: GPU 19:08:10 INFO - Bus: Built-In 19:08:10 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:10 INFO - Vendor: Intel (0x8086) 19:08:10 INFO - Device ID: 0x0a2e 19:08:10 INFO - Revision ID: 0x0009 19:08:10 INFO - Displays: 19:08:10 INFO - Display: 19:08:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:10 INFO - Display Serial Number: 1600x1200 60 19:08:10 INFO - Main Display: Yes 19:08:10 INFO - Mirror: Off 19:08:10 INFO - Online: Yes 19:08:10 INFO - Rotation: Supported 19:08:10 INFO - Return code: 0 19:08:10 INFO - Running post-action listener: _resource_record_post_action 19:08:10 INFO - Running post-run listener: _resource_record_post_run 19:08:11 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 36278784; Write bytes: 699261440; Read time: 376; Write time: 2032 19:08:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 390552064; Write bytes: 385603584; Read time: 28614; Write time: 729 19:08:11 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 34464256; Write bytes: 298383872; Read time: 356; Write time: 1265 19:08:11 INFO - Running post-run listener: _upload_blobber_files 19:08:11 INFO - Blob upload gear active. 19:08:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:08:11 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 19:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:08:11 INFO - (blobuploader) - INFO - Open directory for files ... 19:08:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:08:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:08:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:12 INFO - (blobuploader) - INFO - Done attempting. 19:08:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:08:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:13 INFO - (blobuploader) - INFO - Done attempting. 19:08:13 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:13 INFO - Return code: 0 19:08:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70bc227e26c63fb045a637fe1c7a884eb03f8a3267c5ac744d1fbc12c70afa51e219edf516fb417b1732a2353e7bd5a58fe32fb9372ccc82bc35e0378844dec9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b47ce8d1a58676695502c42f032ecf20c32eb92a46355304324eb64679e621b11d1ec803be992265985d0e0d457a47a9ab0fc9837a24ed8ced7a7a6422684a69"} 19:08:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:13 INFO - Contents: 19:08:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70bc227e26c63fb045a637fe1c7a884eb03f8a3267c5ac744d1fbc12c70afa51e219edf516fb417b1732a2353e7bd5a58fe32fb9372ccc82bc35e0378844dec9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b47ce8d1a58676695502c42f032ecf20c32eb92a46355304324eb64679e621b11d1ec803be992265985d0e0d457a47a9ab0fc9837a24ed8ced7a7a6422684a69"} 19:08:13 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:13 INFO - Copying logs to upload dir... 19:08:13 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.464166 ========= master_lag: 22.59 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-05 19:08:35.910696) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 19:08:35.911673) ========= 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.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70bc227e26c63fb045a637fe1c7a884eb03f8a3267c5ac744d1fbc12c70afa51e219edf516fb417b1732a2353e7bd5a58fe32fb9372ccc82bc35e0378844dec9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b47ce8d1a58676695502c42f032ecf20c32eb92a46355304324eb64679e621b11d1ec803be992265985d0e0d457a47a9ab0fc9837a24ed8ced7a7a6422684a69"} build_url:https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://queue.taskcluster.net/v1/task/dYM2Znb1TrWI3T6JZsCSLA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70bc227e26c63fb045a637fe1c7a884eb03f8a3267c5ac744d1fbc12c70afa51e219edf516fb417b1732a2353e7bd5a58fe32fb9372ccc82bc35e0378844dec9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b47ce8d1a58676695502c42f032ecf20c32eb92a46355304324eb64679e621b11d1ec803be992265985d0e0d457a47a9ab0fc9837a24ed8ced7a7a6422684a69"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 19:08:35.982098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-05 19:08:35.982416) ========= 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.xy5Vja93Te/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.qX7qWRc2or/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005176 ========= master_lag: 2.95 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-05 19:08:38.937159) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-05-05 19:08:38.937436) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-05-05 19:08:43.486511) ========= ========= Total master_lag: 25.66 =========