builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1467933482.71 results: success (0) buildid: 20160707141926 builduid: 2ec022409c754ff0a2e20e32d6ed5d5c revision: 8e07e417a5bb24c48486e560ec5823f17dbe3403 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.711621) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.712064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.712340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.743773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.744213) ========= 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.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-07 16:18:02-- 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% 61.6M=0s 2016-07-07 16:18:02 (61.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106561 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.876546) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.876865) ========= 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.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.028024 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:02.925893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 16:18:02.929318) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8e07e417a5bb24c48486e560ec5823f17dbe3403 --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 8e07e417a5bb24c48486e560ec5823f17dbe3403 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-07 16:18:03,003 truncating revision to first 12 chars 2016-07-07 16:18:03,003 Setting DEBUG logging. 2016-07-07 16:18:03,003 attempt 1/10 2016-07-07 16:18:03,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8e07e417a5bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 16:18:04,729 unpacking tar archive at: fx-team-8e07e417a5bb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.976737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 16:18:04.927468) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:04.927784) ========= script_repo_revision: 8e07e417a5bb24c48486e560ec5823f17dbe3403 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:04.928190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:04.928454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 16:18:04.950412) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-07 16:18:04.950754) ========= /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.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:05 INFO - MultiFileLogger online at 20160707 16:18:05 in /builds/slave/test 16:18:05 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 16:18:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:05 INFO - {'append_to_log': False, 16:18:05 INFO - 'base_work_dir': '/builds/slave/test', 16:18:05 INFO - 'blob_upload_branch': 'Fx-Team', 16:18:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:05 INFO - 'branch': 'Fx-Team', 16:18:05 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:05 INFO - 'config_files': ('talos/mac_config.py',), 16:18:05 INFO - 'default_actions': ('clobber', 16:18:05 INFO - 'read-buildbot-config', 16:18:05 INFO - 'download-and-extract', 16:18:05 INFO - 'populate-webroot', 16:18:05 INFO - 'create-virtualenv', 16:18:05 INFO - 'install', 16:18:05 INFO - 'run-tests'), 16:18:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:05 INFO - 'download_minidump_stackwalk': True, 16:18:05 INFO - 'download_symbols': 'ondemand', 16:18:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:05 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:05 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:05 INFO - 'installer_path': 'installer.exe', 16:18:05 INFO - 'log_level': 'info', 16:18:05 INFO - 'log_name': 'talos', 16:18:05 INFO - 'log_to_console': True, 16:18:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:18:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:18:05 INFO - 'opt_config_files': (), 16:18:05 INFO - 'pip_index': False, 16:18:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:05 INFO - 'cmd': ('bash', 16:18:05 INFO - '-c', 16:18:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:05 INFO - 'enabled': True, 16:18:05 INFO - 'halt_on_failure': False, 16:18:05 INFO - 'name': 'check_screen_resolution'},), 16:18:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:05 INFO - 'cmd': ('bash', 16:18:05 INFO - '-c', 16:18:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:05 INFO - 'enabled': True, 16:18:05 INFO - 'halt_on_failure': False, 16:18:05 INFO - 'name': 'check_screen_resolution'},), 16:18:05 INFO - 'run_cmd_checks_enabled': True, 16:18:05 INFO - 'sps_profile': False, 16:18:05 INFO - 'sps_profile_interval': 0, 16:18:05 INFO - 'suite': 'dromaeojs-e10s', 16:18:05 INFO - 'system_bits': '32', 16:18:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:05 INFO - 'title': 't-yosemite-r7-0111', 16:18:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:05 INFO - 'use_talos_json': True, 16:18:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:05 INFO - 'work_dir': 'build'} 16:18:05 INFO - ##### 16:18:05 INFO - ##### Running clobber step. 16:18:05 INFO - ##### 16:18:05 INFO - Running pre-action listener: _resource_record_pre_action 16:18:05 INFO - Running main action method: clobber 16:18:05 INFO - rmtree: /builds/slave/test/build 16:18:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:07 INFO - Running post-action listener: _resource_record_post_action 16:18:07 INFO - ##### 16:18:07 INFO - ##### Finished clobber step (success) 16:18:07 INFO - ##### 16:18:07 INFO - ##### 16:18:07 INFO - ##### Running read-buildbot-config step. 16:18:07 INFO - ##### 16:18:07 INFO - Running pre-action listener: _resource_record_pre_action 16:18:07 INFO - Running main action method: read_buildbot_config 16:18:07 INFO - Using buildbot properties: 16:18:07 INFO - { 16:18:07 INFO - "project": "", 16:18:07 INFO - "product": "firefox", 16:18:07 INFO - "script_repo_revision": "production", 16:18:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:18:07 INFO - "repository": "", 16:18:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:18:07 INFO - "buildid": "20160707141926", 16:18:07 INFO - "pgo_build": "False", 16:18:07 INFO - "basedir": "/builds/slave/test", 16:18:07 INFO - "buildnumber": 725, 16:18:07 INFO - "slavename": "t-yosemite-r7-0111", 16:18:07 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:18:07 INFO - "platform": "yosemite_r7", 16:18:07 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 16:18:07 INFO - "branch": "fx-team", 16:18:07 INFO - "repo_path": "integration/fx-team", 16:18:07 INFO - "revision": "8e07e417a5bb24c48486e560ec5823f17dbe3403", 16:18:07 INFO - "stage_platform": "macosx64", 16:18:07 INFO - "builduid": "2ec022409c754ff0a2e20e32d6ed5d5c", 16:18:07 INFO - "slavebuilddir": "test" 16:18:07 INFO - } 16:18:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:18:07 INFO - Running post-action listener: _resource_record_post_action 16:18:07 INFO - ##### 16:18:07 INFO - ##### Finished read-buildbot-config step (success) 16:18:07 INFO - ##### 16:18:07 INFO - ##### 16:18:07 INFO - ##### Running download-and-extract step. 16:18:07 INFO - ##### 16:18:07 INFO - Running pre-action listener: _resource_record_pre_action 16:18:07 INFO - Running main action method: download_and_extract 16:18:07 INFO - mkdir: /builds/slave/test/build/tests 16:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:07 INFO - https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:18:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:18:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:18:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:18:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:18:08 INFO - Downloaded 1199 bytes. 16:18:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:18:08 INFO - Using the following test package requirements: 16:18:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:18:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:18:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:18:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'jsshell-mac.zip'], 16:18:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:18:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:18:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:18:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:18:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:18:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:18:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:18:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:18: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')]} 16:18:08 INFO - https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:09 INFO - Downloaded 18806290 bytes. 16:18:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:18:12 INFO - Return code: 0 16:18:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:12 INFO - https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:13 INFO - Downloaded 18806290 bytes. 16:18:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:18:17 INFO - Return code: 0 16:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:17 INFO - https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:18:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:18:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:18:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:18:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:18:18 INFO - Downloaded 14403333 bytes. 16:18:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:18:18 INFO - Return code: 0 16:18: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')]} 16:18:18 INFO - https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:18:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:18:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:18:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:18:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:18:21 INFO - Downloaded 112424055 bytes. 16:18:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:18:21 INFO - mkdir: /builds/slave/test/properties 16:18:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:21 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:21 INFO - Contents: 16:18:21 INFO - build_url:https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:18:22 INFO - Running post-action listener: _resource_record_post_action 16:18:22 INFO - Running post-action listener: set_extra_try_arguments 16:18:22 INFO - ##### 16:18:22 INFO - ##### Finished download-and-extract step (success) 16:18:22 INFO - ##### 16:18:22 INFO - ##### 16:18:22 INFO - ##### Running populate-webroot step. 16:18:22 INFO - ##### 16:18:22 INFO - Running pre-action listener: _resource_record_pre_action 16:18:22 INFO - Running main action method: populate_webroot 16:18:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:22 INFO - '--tppagecycles', 16:18:22 INFO - '7'], 16:18:22 INFO - 'tests': ['tsvgm']}}, 16:18:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['damp', 'tps']}, 16:18:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'tests': ['damp', 'tps']}, 16:18:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['dromaeo_dom']}, 16:18:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['basic_compositor_video']}, 16:18:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:18:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['a11yr', 16:18:22 INFO - 'ts_paint', 16:18:22 INFO - 'tpaint', 16:18:22 INFO - 'sessionrestore', 16:18:22 INFO - 'sessionrestore_no_auto_restore', 16:18:22 INFO - 'tabpaint']}, 16:18:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['tsvgx', 16:18:22 INFO - 'tsvgr_opacity', 16:18:22 INFO - 'tart', 16:18:22 INFO - 'tscrollx', 16:18:22 INFO - 'cart']}, 16:18:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:22 INFO - 'tsvgr_opacity', 16:18:22 INFO - 'tart', 16:18:22 INFO - 'tscrollx', 16:18:22 INFO - 'cart']}, 16:18:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'talos_options': ['--disable-e10s'], 16:18:22 INFO - 'tests': ['tp5o']}, 16:18:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'tests': ['tp5o']}, 16:18:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'talos_options': ['--disable-e10s', 16:18:22 INFO - '--xperf_path', 16:18:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:22 INFO - 'tests': ['tp5n']}, 16:18:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:22 INFO - 'talos_options': ['--xperf_path', 16:18:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:22 INFO - 'tests': ['tp5n']}}, 16:18:22 INFO - 'talos.zip': {'path': '', 16:18:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:18:22 INFO - Running post-action listener: _resource_record_post_action 16:18:22 INFO - ##### 16:18:22 INFO - ##### Finished populate-webroot step (success) 16:18:22 INFO - ##### 16:18:22 INFO - ##### 16:18:22 INFO - ##### Running create-virtualenv step. 16:18:22 INFO - ##### 16:18:22 INFO - Running pre-action listener: _resource_record_pre_action 16:18:22 INFO - Running main action method: create_virtualenv 16:18:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:22 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 16:18:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:18:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:22 INFO - Using real prefix '/tools/python27' 16:18:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:26 INFO - Installing pip.................done. 16:18:26 INFO - Return code: 0 16:18:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:26 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')]} 16:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:26 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')]} 16:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:26 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 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:26 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 16:18:26 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 16:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:26 INFO - 'HOME': '/Users/cltbld', 16:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:26 INFO - 'LOGNAME': 'cltbld', 16:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:26 INFO - 'MOZ_NO_REMOTE': '1', 16:18:26 INFO - 'NO_EM_RESTART': '1', 16:18:26 INFO - 'PAGER': '/bin/cat', 16:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:26 INFO - 'PWD': '/builds/slave/test', 16:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:26 INFO - 'SHELL': '/bin/bash', 16:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:26 INFO - 'USER': 'cltbld', 16:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:26 INFO - 'XPC_FLAGS': '0x0', 16:18:26 INFO - 'XPC_SERVICE_NAME': '0', 16:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:26 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 16:18:26 INFO - Cleaning up... 16:18:26 INFO - Return code: 0 16:18:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:26 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')]} 16:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:26 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')]} 16:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:26 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:26 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>=3.1.1'] in /builds/slave/test/build 16:18:26 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>=3.1.1 16:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:26 INFO - 'HOME': '/Users/cltbld', 16:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:26 INFO - 'LOGNAME': 'cltbld', 16:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:26 INFO - 'MOZ_NO_REMOTE': '1', 16:18:26 INFO - 'NO_EM_RESTART': '1', 16:18:26 INFO - 'PAGER': '/bin/cat', 16:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:26 INFO - 'PWD': '/builds/slave/test', 16:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:26 INFO - 'SHELL': '/bin/bash', 16:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:26 INFO - 'USER': 'cltbld', 16:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:26 INFO - 'XPC_FLAGS': '0x0', 16:18:26 INFO - 'XPC_SERVICE_NAME': '0', 16:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:26 INFO - Downloading/unpacking psutil>=3.1.1 16:18:26 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 16:18:26 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 16:18:26 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 16:18:26 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 16:18:26 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 16:18:26 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 16:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:29 INFO - Installing collected packages: psutil 16:18:29 INFO - Running setup.py install for psutil 16:18:29 INFO - building 'psutil._psutil_osx' extension 16:18:29 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 16:18:29 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 16:18:30 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 16:18:30 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 16:18:30 INFO - building 'psutil._psutil_posix' extension 16:18:30 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 16:18:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:18:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:18:30 INFO - ^ 16:18:30 INFO - 1 warning generated. 16:18:30 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 16:18:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:30 INFO - Successfully installed psutil 16:18:30 INFO - Cleaning up... 16:18:30 INFO - Return code: 0 16:18:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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')]} 16:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:18: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 mozsystemmonitor==0.3 16:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:30 INFO - 'HOME': '/Users/cltbld', 16:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:30 INFO - 'LOGNAME': 'cltbld', 16:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:30 INFO - 'MOZ_NO_REMOTE': '1', 16:18:30 INFO - 'NO_EM_RESTART': '1', 16:18:30 INFO - 'PAGER': '/bin/cat', 16:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:30 INFO - 'PWD': '/builds/slave/test', 16:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:30 INFO - 'SHELL': '/bin/bash', 16:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:30 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:30 INFO - 'USER': 'cltbld', 16:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:30 INFO - 'XPC_FLAGS': '0x0', 16:18:30 INFO - 'XPC_SERVICE_NAME': '0', 16:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:33 INFO - Installing collected packages: mozsystemmonitor 16:18:33 INFO - Running setup.py install for mozsystemmonitor 16:18:33 INFO - Successfully installed mozsystemmonitor 16:18:33 INFO - Cleaning up... 16:18:33 INFO - Return code: 0 16:18:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:33 INFO - 'HOME': '/Users/cltbld', 16:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:33 INFO - 'LOGNAME': 'cltbld', 16:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:33 INFO - 'MOZ_NO_REMOTE': '1', 16:18:33 INFO - 'NO_EM_RESTART': '1', 16:18:33 INFO - 'PAGER': '/bin/cat', 16:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:33 INFO - 'PWD': '/builds/slave/test', 16:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:33 INFO - 'SHELL': '/bin/bash', 16:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:33 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:33 INFO - 'USER': 'cltbld', 16:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:33 INFO - 'XPC_FLAGS': '0x0', 16:18:33 INFO - 'XPC_SERVICE_NAME': '0', 16:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:36 INFO - warning: no files found matching '*.txt' 16:18:36 INFO - no previously-included directories found matching 'build' 16:18:36 INFO - no previously-included directories found matching 'dist' 16:18:36 INFO - no previously-included directories found matching '.git*' 16:18:36 INFO - Installing collected packages: jsonschema, functools32 16:18:36 INFO - Running setup.py install for functools32 16:18:36 INFO - warning: no files found matching '*.txt' 16:18:36 INFO - no previously-included directories found matching 'build' 16:18:36 INFO - no previously-included directories found matching 'dist' 16:18:36 INFO - no previously-included directories found matching '.git*' 16:18:36 INFO - Successfully installed jsonschema functools32 16:18:36 INFO - Cleaning up... 16:18:36 INFO - Return code: 0 16:18:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:36 INFO - 'HOME': '/Users/cltbld', 16:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:36 INFO - 'LOGNAME': 'cltbld', 16:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:36 INFO - 'MOZ_NO_REMOTE': '1', 16:18:36 INFO - 'NO_EM_RESTART': '1', 16:18:36 INFO - 'PAGER': '/bin/cat', 16:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:36 INFO - 'PWD': '/builds/slave/test', 16:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:36 INFO - 'SHELL': '/bin/bash', 16:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:36 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:36 INFO - 'USER': 'cltbld', 16:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:36 INFO - 'XPC_FLAGS': '0x0', 16:18:36 INFO - 'XPC_SERVICE_NAME': '0', 16:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:37 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:39 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18:40 INFO - Downloading docopt-0.6.1.tar.gz 16:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:40 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:40 INFO - Running setup.py install for blobuploader 16:18:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:41 INFO - Running setup.py install for requests 16:18:41 INFO - Running setup.py install for docopt 16:18:41 INFO - Successfully installed blobuploader requests docopt 16:18:41 INFO - Cleaning up... 16:18:41 INFO - Return code: 0 16:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:41 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')]} 16:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 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')]} 16:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:41 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 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:41 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 16:18:41 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 16:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:41 INFO - 'HOME': '/Users/cltbld', 16:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:41 INFO - 'LOGNAME': 'cltbld', 16:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:41 INFO - 'MOZ_NO_REMOTE': '1', 16:18:41 INFO - 'NO_EM_RESTART': '1', 16:18:41 INFO - 'PAGER': '/bin/cat', 16:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:41 INFO - 'PWD': '/builds/slave/test', 16:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:41 INFO - 'SHELL': '/bin/bash', 16:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:41 INFO - 'USER': 'cltbld', 16:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:41 INFO - 'XPC_FLAGS': '0x0', 16:18:41 INFO - 'XPC_SERVICE_NAME': '0', 16:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1ovbIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-gkJXFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xssiHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ufyQqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-JM2OzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-kVONW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-OnF1YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-9IALVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:42 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Utc23a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-uU1S7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WAZ9hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tMAPea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sz5ILU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DkTYEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dLCV2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wrlCDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:43 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eDF3GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:43 INFO - Running setup.py install for manifestparser 16:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:44 INFO - Running setup.py install for mozcrash 16:18:44 INFO - Running setup.py install for mozdebug 16:18:44 INFO - Running setup.py install for mozdevice 16:18:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:44 INFO - Running setup.py install for mozfile 16:18:44 INFO - Running setup.py install for mozhttpd 16:18:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:44 INFO - Running setup.py install for mozinfo 16:18:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:44 INFO - Running setup.py install for mozInstall 16:18:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozleak 16:18:45 INFO - Running setup.py install for mozlog 16:18:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for moznetwork 16:18:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozprocess 16:18:45 INFO - Running setup.py install for mozprofile 16:18:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozrunner 16:18:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:45 INFO - Running setup.py install for mozscreenshot 16:18:46 INFO - Running setup.py install for moztest 16:18:46 INFO - Running setup.py install for mozversion 16:18:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:46 INFO - Cleaning up... 16:18:46 INFO - Return code: 0 16:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:46 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')]} 16:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:46 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')]} 16:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:46 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 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:46 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 16:18:46 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 16:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:46 INFO - 'HOME': '/Users/cltbld', 16:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:46 INFO - 'LOGNAME': 'cltbld', 16:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:46 INFO - 'MOZ_NO_REMOTE': '1', 16:18:46 INFO - 'NO_EM_RESTART': '1', 16:18:46 INFO - 'PAGER': '/bin/cat', 16:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:46 INFO - 'PWD': '/builds/slave/test', 16:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:46 INFO - 'SHELL': '/bin/bash', 16:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:46 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:46 INFO - 'USER': 'cltbld', 16:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:46 INFO - 'XPC_FLAGS': '0x0', 16:18:46 INFO - 'XPC_SERVICE_NAME': '0', 16:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:46 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4tMWot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:46 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)) 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:46 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Sq0jB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:46 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)) 16:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:46 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-v_H2pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-cixJVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ScxNFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8KKHlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eeHbny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-hnpsMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fFKG7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Kdkjz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wUN6Xq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:47 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:47 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VNMhbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:18:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3TBmUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xWzRHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0rziKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FSqdyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:48 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)) 16:18:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:48 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1JAI4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:48 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)) 16:18:48 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)) 16:18:48 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)) 16:18:48 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)) 16:18:48 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)) 16:18:48 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)) 16:18:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:48 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 16:18:48 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 16:18:48 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 16:18:48 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 16:18:48 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 16:18:48 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 16:18:50 INFO - Downloading blessings-1.6.tar.gz 16:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:50 INFO - Installing collected packages: blessings 16:18:50 INFO - Running setup.py install for blessings 16:18:51 INFO - Successfully installed blessings 16:18:51 INFO - Cleaning up... 16:18:51 INFO - Return code: 0 16:18:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:51 INFO - Reading from file tmpfile_stdout 16:18:51 INFO - Current package versions: 16:18:51 INFO - blessings == 1.6 16:18:51 INFO - blobuploader == 1.2.4 16:18:51 INFO - docopt == 0.6.1 16:18:51 INFO - functools32 == 3.2.3-2 16:18:51 INFO - jsonschema == 2.5.1 16:18:51 INFO - manifestparser == 1.1 16:18:51 INFO - mozInstall == 1.12 16:18:51 INFO - mozcrash == 0.17 16:18:51 INFO - mozdebug == 0.1 16:18:51 INFO - mozdevice == 0.48 16:18:51 INFO - mozfile == 1.2 16:18:51 INFO - mozhttpd == 0.7 16:18:51 INFO - mozinfo == 0.9 16:18:51 INFO - mozleak == 0.1 16:18:51 INFO - mozlog == 3.2 16:18:51 INFO - moznetwork == 0.27 16:18:51 INFO - mozprocess == 0.23 16:18:51 INFO - mozprofile == 0.28 16:18:51 INFO - mozrunner == 6.12 16:18:51 INFO - mozscreenshot == 0.1 16:18:51 INFO - mozsystemmonitor == 0.3 16:18:51 INFO - moztest == 0.7 16:18:51 INFO - mozversion == 1.4 16:18:51 INFO - psutil == 3.1.1 16:18:51 INFO - requests == 1.2.3 16:18:51 INFO - wsgiref == 0.1.2 16:18:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 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 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:51 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 16:18:51 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 16:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:51 INFO - 'HOME': '/Users/cltbld', 16:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:51 INFO - 'LOGNAME': 'cltbld', 16:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:51 INFO - 'MOZ_NO_REMOTE': '1', 16:18:51 INFO - 'NO_EM_RESTART': '1', 16:18:51 INFO - 'PAGER': '/bin/cat', 16:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:51 INFO - 'PWD': '/builds/slave/test', 16:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:51 INFO - 'SHELL': '/bin/bash', 16:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:51 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:51 INFO - 'USER': 'cltbld', 16:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:51 INFO - 'XPC_FLAGS': '0x0', 16:18:51 INFO - 'XPC_SERVICE_NAME': '0', 16:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 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)) 16:18:51 INFO - Cleaning up... 16:18:51 INFO - Return code: 0 16:18:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a70a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b05618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd020ca2860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, '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.p6j9nTD9oD/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.cPVnbze6SU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:51 INFO - 'HOME': '/Users/cltbld', 16:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:51 INFO - 'LOGNAME': 'cltbld', 16:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:51 INFO - 'MOZ_NO_REMOTE': '1', 16:18:51 INFO - 'NO_EM_RESTART': '1', 16:18:51 INFO - 'PAGER': '/bin/cat', 16:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:51 INFO - 'PWD': '/builds/slave/test', 16:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:51 INFO - 'SHELL': '/bin/bash', 16:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:18:51 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:51 INFO - 'USER': 'cltbld', 16:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:51 INFO - 'XPC_FLAGS': '0x0', 16:18:51 INFO - 'XPC_SERVICE_NAME': '0', 16:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:52 INFO - Cleaning up... 16:18:52 INFO - Return code: 0 16:18:52 INFO - Running post-action listener: _resource_record_post_action 16:18:52 INFO - Running post-action listener: _start_resource_monitoring 16:18:52 INFO - Starting resource monitoring. 16:18:52 INFO - ##### 16:18:52 INFO - ##### Finished create-virtualenv step (success) 16:18:52 INFO - ##### 16:18:52 INFO - ##### 16:18:52 INFO - ##### Running install step. 16:18:52 INFO - ##### 16:18:52 INFO - Running pre-action listener: _resource_record_pre_action 16:18:52 INFO - Running main action method: install 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:52 INFO - Reading from file tmpfile_stdout 16:18:52 INFO - Output received: 16:18:52 INFO - Usage: mozinstall [options] installer 16:18:52 INFO - Options: 16:18:52 INFO - -h, --help show this help message and exit 16:18:52 INFO - -d DEST, --destination=DEST 16:18:52 INFO - Directory to install application into. [default: 16:18:52 INFO - "/builds/slave/test"] 16:18:52 INFO - --app=APP Application being installed. [default: firefox] 16:18:52 INFO - mkdir: /builds/slave/test/build/application 16:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:19:27 INFO - Reading from file tmpfile_stdout 16:19:27 INFO - Output received: 16:19:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:19:27 INFO - Running post-action listener: _resource_record_post_action 16:19:27 INFO - ##### 16:19:27 INFO - ##### Finished install step (success) 16:19:27 INFO - ##### 16:19:27 INFO - ##### 16:19:27 INFO - ##### Running run-tests step. 16:19:27 INFO - ##### 16:19:27 INFO - Running pre-action listener: _resource_record_pre_action 16:19:27 INFO - Running main action method: run_tests 16:19:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:19:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:19:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:19:27 INFO - 2016-07-07 16:19:27.282 screenresolution[1635:7496] starting screenresolution argv=screenresolution get 16:19:27 INFO - 2016-07-07 16:19:27.285 screenresolution[1635:7496] Display 0: 1600x1200x32@60 16:19:27 INFO - 2016-07-07 16:19:27.290 screenresolution[1636:7498] starting screenresolution argv=screenresolution list 16:19:27 INFO - Available Modes on Display 0 16:19:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:19:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:19:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:19:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:19:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:19:27 INFO - Intel Iris: 16:19:27 INFO - Chipset Model: Intel Iris 16:19:27 INFO - Type: GPU 16:19:27 INFO - Bus: Built-In 16:19:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:19:27 INFO - Vendor: Intel (0x8086) 16:19:27 INFO - Device ID: 0x0a2e 16:19:27 INFO - Revision ID: 0x0009 16:19:27 INFO - Displays: 16:19:27 INFO - Display: 16:19:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:19:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:19:27 INFO - Display Serial Number: 1600x1200 60 16:19:27 INFO - Main Display: Yes 16:19:27 INFO - Mirror: Off 16:19:27 INFO - Online: Yes 16:19:27 INFO - Rotation: Supported 16:19:27 INFO - Return code: 0 16:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:27 INFO - Python 2.7.3 16:19:27 INFO - Return code: 0 16:19:27 INFO - grabbing minidump binary from tooltool 16:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:27 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d4d30>, '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 0x106739db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:27 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 16:19:27 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 16:19:27 INFO - Calling ['/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'] with output_timeout 600 16:19:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:27 INFO - Return code: 0 16:19:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:19:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:19:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:27 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/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 16:19:27 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/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 16:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p6j9nTD9oD/Render', 16:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:27 INFO - 'HOME': '/Users/cltbld', 16:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:27 INFO - 'LOGNAME': 'cltbld', 16:19:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:27 INFO - 'MOZ_NO_REMOTE': '1', 16:19:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:27 INFO - 'NO_EM_RESTART': '1', 16:19:27 INFO - 'PAGER': '/bin/cat', 16:19:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:27 INFO - 'PWD': '/builds/slave/test', 16:19:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:27 INFO - 'SHELL': '/bin/bash', 16:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cPVnbze6SU/Listeners', 16:19:27 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:27 INFO - 'USER': 'cltbld', 16:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:27 INFO - 'XPC_FLAGS': '0x0', 16:19:27 INFO - 'XPC_SERVICE_NAME': '0', 16:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:27 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/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 16:19:27 INFO - mozversion application_buildid: 20160707141926 16:19:27 INFO - mozversion application_changeset: 8e07e417a5bb24c48486e560ec5823f17dbe3403 16:19:27 INFO - mozversion application_display_name: Nightly 16:19:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:27 INFO - mozversion application_name: Firefox 16:19:27 INFO - mozversion application_remotingname: firefox 16:19:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:19:27 INFO - mozversion application_vendor: Mozilla 16:19:27 INFO - mozversion application_version: 50.0a1 16:19:27 INFO - mozversion platform_buildid: 20160707141926 16:19:27 INFO - mozversion platform_changeset: 8e07e417a5bb24c48486e560ec5823f17dbe3403 16:19:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:19:27 INFO - mozversion platform_version: 50.0a1 16:19:27 INFO - using testdate: 1467933567 16:19:27 INFO - actual date: 1467933567 16:19:27 INFO - starting webserver on 'localhost:49266' 16:19:27 INFO - SUITE-START | Running 2 tests 16:19:27 INFO - TEST-START | dromaeo_css 16:19:27 INFO - operating with platform_type : mac_ 16:19:27 INFO - Initialising browser for dromaeo_css test... 16:19:27 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpHQNV_h/profile http://localhost:49266/getInfo.html) 16:19:30 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 16:19:30 INFO - PROCESS | 1646 | colorDepth:24 16:19:30 INFO - PROCESS | 1646 | Browser inner width/height: 1024/663 16:19:30 INFO - PROCESS | 1646 | __metrics 16:19:30 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 16:19:31 INFO - TEST-INFO | 1646: exit 0 16:19:31 INFO - Browser initialized. 16:19:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:31 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpHQNV_h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:43 INFO - PROCESS | 1649 | RSS: Main: 261599232 16:19:43 INFO - PROCESS | 1649 | 16:20:33 INFO - PROCESS | 1649 | 1467933633120 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] 16:22:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 16:22:46 INFO - PROCESS | 1649 | RSS: Main: 273489920 16:22:46 INFO - PROCESS | 1649 | 16:25:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 16:25:49 INFO - PROCESS | 1649 | RSS: Main: 282075136 16:25:49 INFO - PROCESS | 1649 | 16:28:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 16:28:51 INFO - PROCESS | 1649 | RSS: Main: 286638080 16:28:51 INFO - PROCESS | 1649 | 16:31:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 16:31:54 INFO - PROCESS | 1649 | RSS: Main: 286547968 16:31:54 INFO - PROCESS | 1649 | 16:34:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 16:34:57 INFO - PROCESS | 1649 | RSS: Main: 289390592 16:34:57 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 16:38:00 INFO - PROCESS | 1649 | RSS: Main: 289570816 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | __start_tp_report 16:38:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 16:38:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 16:38:00 INFO - PROCESS | 1649 | |i|pagename|runs| 16:38:00 INFO - PROCESS | 1649 | |0;dojo.html;3413.5562376891003;3415.573053368329;3443;3443.9311213775727;3444.190615205428;4620.907581848361;5810.86688095821;5892.32238292596;5909.231799866016;5920.9407905920925;5661.094224924013;5753.597248192628;5800.564957628184;5814.389489103647;5900.911486327698;5213.504717051874;5290.418054014055;5293.364796224459;5376.78492860287;5430.864228394283;5448.809291674773;5476.890462190754;5492.258545096401;5508.669479831203;5545;4388.166248412823;4485.416895803524;4982.202847544376;5346.893062138774;5366.275552800381;5308.867278318035;5329.786808527654;5337.599680024014;5349;5352.839414817562;5253.527182553588;5296.682199068068;5325.760340784635;5337.2527846101575;5411.56707463402;5686.630369026;5688.857778555528;5792.507636850873;5816.680082595455;5819.388964158787;5206.505381988743;6217.067439884008;6226.501879849601;6288.025356069771;6292;6159.692015399257;6275.6548389838545;6286.214223222098;6295.842603934892;6304.779332723333;5836.6789826559525;5891.263592050994;5901.7639294427745;5921.526277897758;5934.37689042653;6289.7169627366875;6326.746930122744;6340.048992651047;6349.841253968688;6381.840453988688;6106.236720409949;6139.062858969336;6263.592866463665;6272.059191121369;6307.590006649553;6231.719572619152;6238.656873871981;6309.274433440213;6320.241571011508;6321.494280457552;6124.203853499017;6126.3955342902;6157.599756015845;6244.438000579969;6261.624302541862;6295.244570651551;6296.874062518724;6297.96851015742;6305.621662700252;6320.083587879823;4141.798878325252;4903.87740306488;5065.569426598707;5096;5111.463296353903;3770.962290377116;4513.187626227245;4516.796744146522;4520.547945205519;4546.818127274872;5735.512481439125;5904.261967254093;5962.493188078975;5996.340402555716;5998.640081595118;6230.844228894223;6232.034034724625;6265.154204182377;6293.1504246927;6300.338464461257;5887.882242355129;5899.587028907935;5903.35063143054;5905.704714764244;5961.910571341434;4555.453345598549;5428.864278393072;5533.446655334515;5541.722913854291;5544.8355845272945;6252.218472690914;6368.586041907446;6374.617522948638;6380.234371875405;6382.266039405527;6260.812175634738;6283.937160628335;6290;6312.589681670676;6322.620642761449;5946.791862284972;6137.9286238596105;6268.749250029948;6368.9044664330995;6371.426571608577;6231.2522497300615;6271.184745982993;6274.247090349188;6330.651814150266;6339.270983836734;5308;6035;6133.258990800019;6241.875162496815;6247.9062814056915;5164;5945.10823376497;6050.7277172526465;6126.6017708848785;6173.166622505904;6098.242253746034;6108.7670647404;6145.9692701538;6172;6214.5028397727165;4902.485239049991;6091.722750294852;6091.994820854509;6102.694865256808;6109.382479655331;5340.75966581497;5958.897603943285;6033.547483938634;6099.94541213825;6209.285932117684;6046.9720147574135;6061.212042434456;6082.2093127893095;6087.239095113111;6105.358937311518 16:38:00 INFO - PROCESS | 1649 | |1;ext.html;456.98629041128765;483.70735704898533;525.8869343091237;541.3314556522694;564.5145175149373;34358.656413435856;38371.819804326406;41723.37414938778;41730.791346043225;41739.37390939138;34119;41634.16731665365;41752.79123604386;42256.78871605638;42522.936926576855;9629.374090684101;9712.27157963152;9818.656347027856;9822.705318840446;9837.803243935117;9690.806183876319;9779.75550611237;9839.16367108793;9858.802823943517;9862.358946668444;9785.315027948047;9839.704808855746;9840.163586095156;9846.113849753521;9879.506024698772;9784.510774461283;9837.262205334628;9846;9857.211423086208;9860.802783944317;9677.564509597014;9745.074217949283;9766.609335626566;9790.118889299998;9797.461139637317;41289.38065929014;41510.79244603788;41599.58400416018;41677.37483937744;41696.74909752712;3370.1911541230174;3439.3809114359406;3453.4647129694936;3458.39478091333;3489.7731647442833;25880.352991175183;32656.346873062404;33364.6663533364;33411.83294083514;33435;3186.1078897908624;3354.2117602363423;3382.7632065755406;3405.267867408519;3414.2830005698834;514198;518719;519228;520395.39802300744;520881;62524.687376563736;62561.68719156375;62940.685296573225;62979;63152.05271920925;65700;65977.71144875568;66588.6670566644;67127.3287267121;67179.32820671829;16514.58713532171;16741.6651666966;16744.497665070066;16755.748663770053;16806.915965420096;12895.613131606067;12921.676958076123;12927.806082908575;12945.54690585825;12976.02679799019;17345.959242445242;17769.378071767427;17816.287348505914;17848.91075544614;17959.371422000168;6121.4490695837585;6340.778072767431;6352;6462.903056454157;6467.32093130224;780.5667854340895;824.0235321144344;959.9856002159974;978.6721448314752;981.239539357004;28829.711702883124;30856.228594284872;30861.84569077185;31300.68699313023;31661.20846978844;882.761654353321;1042.2547878266923;1062.5431064642123;1137.852079229695;1169.5847973969119;15856.52430427089;16204.351825927066;16269.186540673156;16289.185540722916;16611.750823737653;674286;696425;697922;699245;699498;59496.70251648714;61419.07871381933;61449.69275153596;61487.6925615378;61775.69112154411;1228.6498347970964;1319.85172899578;1371.1567386057502;1381.4888491258298;1403.8806701430492;29812.552811707406;33757.83121084379;33997.49003764896;34335.484967725;35028.64971350254;59909.70045149746;60533.39466605277;61459;63139.6843015782;64313.67843160754;528402;593373.0331348316;630967;648718;657818;46987.765061174476;47096;48298.27552586644;48434.75782621064;49104.263436047775;51162.6280639185;51453;51681.7415912918;52712.20931685951;52955.73522132519;42133.78933105315;42137.789311053246;42605.78697106495;43088;43731;35847.46228806622;38284.425733614575;41783;42621.573784263004;43442.565574343855;39574;40864.18271634491;41679.7916010418;41697.79151104225;43100.92247693833;34695.091769952895;37061;37195.88412347634;38078.61921380862;38148;22423;27198.048068317516;27288.04491842776;27422.86288568624;27693.538307711537 16:38:00 INFO - PROCESS | 1649 | |2;jquery.html;373.30192539950286;377.6544461817437;380.10106099075676;390.9120447899225;396.9563348031718;20079.799202007976;20565.074571644276;21561.892190539027;21604.675929861045;21920.013599388047;20625.896870515662;20819.959002049876;21045.789542104576;22111.778882211212;22473.438164045867;5095.312132862054;5977.790777322794;6548.214214294291;6564.770233041844;6641.667916604175;3807.8667246646373;6343.71453284601;6531.24890637578;6650.268470468244;6667.933130699089;6568.277489476154;6656.434203092743;6657.667116644173;6675.732970681167;6677.1987361516685;6578.013298005338;6668.732940741268;6669.199696036482;6703.296153903819;6779.796606101775;5800.825975220708;5806.883862322772;5890.676012819294;5920.585559010871;5926.407359264039;25830.225093247234;25944.221673349828;26147.346316342053;26685.46629067427;26779.33051673704;2369.680093187415;2388.641703744435;2944.381679847235;2991.8504074796174;3016.547517872315;9969.40183588987;10066.345687530287;10211.693649190462;10320.638777642822;10472.214583906161;1827.8172182781748;1866.5426970392205;1882.2565086790642;1924.5765931495048;1949.083930552638;119444;125491.3725431367;127944.36027819895;129805.35097324547;130413.34793325972;11405.714857128638;11556.479958401893;11715.88284117165;11779.469923853447;11881.227720198332;14513.709725805425;14602.85397146015;14607.904407169504;14608.05047671898;14610.853891461162;8529.275011623957;8567.100454452193;8592.699255526026;8628.309735221168;8641.611127499278;8569.228769410784;8584.8712269316;8587.656493740305;8706.303495720327;8726.171013753683;4535.795889184995;4564.863054108382;4572;4573.908521829545;4621.838235661736;1600.7514138971621;1674.4055860169465;1679.8320167983104;1684.2084220416384;1743.7035703930362;350.28709610007087;394.58174335204774;400.3174587328598;773.7407968330672;938.4603852784676;9981;11981.46083426248;12169.391530423443;12295.262284262973;12305.753884922251;344.674282802754;363.38587786640494;402.76840816530614;403.9175010970627;428.5992596921888;5816.41835816415;6039.909401358983;6104.3590423005835;6134.478569321568;6151.907721384183;427967;428928;429332;432111;441472;127386;137065;137188;137478;141719.29140354233;432.6473923752132;439.6372992281381;440.9184300904343;441.20142541999087;443.6362183009901;11297.548098076313;11551.826722599;11611.361375124112;11687.824682629598;11693.76612467763;54492.455075448735;57770.4222957765;57977;58240;58528.4147158523;83909.16090839013;84016.57991710003;84207.5789621048;84784.57607711921;85097.57451212704;8189.22202390772;8421.789455263668;8447.155284471504;8681.131886811268;8763.561821908752;10098.444585547866;12129.75740485203;13381.330933453482;13463.865361346261;13876.58370248894;11878.93089621938;11905.52377904874;11908.166428349932;11923.463444145207;12105.455254513392;10209.897901021192;11752.294862308288;11758.647240582795;11805.350705710925;11856.822147667965;12020.699482512657;12038.638840834788;12114.21257618252;12180.57367992116;12195.75608487843;8060.153683863345;8341;8414.957925210334;8604.483730975911;8696.565171741515;9177.908220917705;10848.077913377452;11392.145589080686;11678.124140689313;11998.58004969822 16:38:00 INFO - PROCESS | 1649 | |3;mootools.html;1310.9803352949705;1417.9007469477137;1451.920144392058;1747.33601231532;1905.8380037696795;8067.35461163107;8510.234078932895;8555.230029297361;8977.596008179631;8982.461052336865;8653.697120600764;8679.349048821332;8752.256058235042;8762.649494020248;8778.56107194641;2329.499157681098;2356.552255071531;2359.2922123362923;2359.528094381122;2365.5150694107665;1979.604079184169;1989.6816509358505;2333.6616190652344;2377.3105799318178;2382.940426489334;2360.3155085025323;2366.680498132756;2366.7869891709743;2379.428937055103;2394.3774618599205;2317.953640927189;2319.9884000579973;2325.360525855386;2332.4868528923694;2360.5986982213067;2334.7781960713705;2337.1118974789642;2350.8707021113833;2354.21133920137;2354.835161538693;8799.208071273617;8857.601407936658;8894.911050889537;8909.020007799201;8915.063918288552;5217.452167522393;5295.4704529547125;5341.866453338659;5363.06146424378;5373.650712703662;3491.6508349165133;3574.3566158091794;3609.530761001053;3632.400653892105;3670;1059.828889077566;1066.0991462214417;1083.544911137324;1099.230538622959;1104.7956128116243;11345.432728363632;11494.597689080842;11503.309801411859;11540;11544.595939142173;4025.5169379674626;4518.615917647036;4687.6249900007915;4695.0375173090115;4726.26742854858;9821.116099550933;9829.262805289489;9852.014798520091;9868.30921835479;9868.80262394748;5268.130758424817;5272.736363181825;5332.173513105397;5336.0661884170895;5339.919901201485;4332.1985432695055;4700.083483720641;4829.517048295143;4859.878503037452;4886.438059623118;3918.6669133124005;3945.980270098631;4002.8398864045694;4034.8991275218355;4047.433359329696;1187.625897842177;1211.3882489343002;1283.403217503865;1296.4490091710986;1298.2340419152745;834.9749507514663;941.5668792355456;962.5620342744035;962.8170647576937;991.9169743538655;5867.501262392744;6019.693937493183;6060.969695151584;6062.969685151635;6100.450959413674;425.9224162867949;426.6949131371023;435.2209544914567;437.1235672476664;439.87243699326837;3599.4960705501253;3807.7715337079862;4087.5912408758886;4144.109016561393;4147.108371700099;10137.695869123938;10518.105960993249;10548.261621686408;10616.363018218777;10645.254832161674;10315.587376504856;10599.470026498799;10600.469976501145;10621.681349559525;10626.256162068736;6886.655667216619;8113.675452981933;8182.18178182189;8195.057568379594;8222.38332125093;5751.626144300607;5870.17817505541;5944.524438044859;5948.643081415129;6012.218411606463;9775.902240977499;9836.016398360107;9855;9903.257255705706;9931.702048938543;9669.661561845302;9942.801143976936;10014.890743498217;10113.848292275768;10163.54264058134;6958.408535274559;7266.273372662691;7458.664360103699;7459.36595389398;7548.245175482408;7357.926420735724;8247.917520824714;8364.6235919385;8364.707235246737;8410.957945210235;7270.382017528569;7314.7074117036855;7470.775876723707;7546.886796698163;7614.200508946481;7364.66858991336;7457.589832559044;7487.925120748723;7594.31651151399;7610.9238907610215;5976.671283079472;7018.508704390643;7063.046488724162;7065.434765218873;7072.9646351767915;4866.756662166948;5000;5055.241713742968;5091.669041512438;5107.693538387709;4266.381374700713;4964.230544265644;5071.18860982241;5075.416327122428;5255.816046438356 16:38:00 INFO - PROCESS | 1649 | |4;prototype.html;302.73359443689566;308.93357928045475;309.2130527806732;316.8669158953241;316.9587953566036;5448.594628357277;5662.915056274154;5670.291213598301;5802.883942321151;5846.766129354826;4514.210013247677;5210;5523.171524271362;5666.688332141731;5704.857378565538;4345.543717909613;4346.434963454746;4350.97824510877;4357.891052723676;4394.582514661102;3593.4969104325414;4151.294279972397;4371.715838470505;4379.671524635649;4385.232584297751;3717.3866312058476;4089.8548406446243;4294.935575966363;4303.870883873489;4394.198378664748;4320.379857553402;4324.762138082404;4353.216421044211;4362.236608593484;4364.258076127066;3194.952075718866;3688.17016171362;3725.012871589031;3734.271816995687;3742.719296052807;5015.974920125376;5108.948910510884;5459.972700136514;5519.868426972461;5526.861828454281;4478.305862591304;4828.130936431441;4858.368412106403;4865.5134486551415;4882.829100981484;5875.471207591338;6026.770095064854;6062.484688801457;6075.939240607626;6089.250384699941;3491.965080349215;3504.7021003214504;3967.1668949520345;3993.9600603994168;4017.31705610047;5456.653638935256;6269.122322874801;6278.30938596754;6306.684665766693;6319.8104056878365;3378.8648454061563;3812;3918.1575961168487;4050.959490405058;4062.898427539335;3655.670989610948;3888.747231429948;4013.6387725104883;4051.493563304587;4113.177364527107;4293.720908140961;4425.782909699794;4749.833755818529;4793.784279707422;4817.8313759018265;5283.52448279649;5643.858903527363;5915.349311575723;5963.4931030862;6036.366181550874;1235.3761350518112;1384.2940100548792;1384.349355802771;1391.1931079973792;1396.427464739452;758.317514237191;822.9711960081488;830.6552780596106;839.2195258409615;853.2875049333876;3838.8272527736317;3902.551206611219;4142.700801055383;4181.205570941512;4474.679969409032;4403.911921761547;5057.418396884332;5064.215046667772;5093.363329583802;5180.456052114474;3693.778373297611;4129.483814523184;4636.678546614266;4636.797521177414;4664.067186562701;2219.922302719397;2229.6209644360174;2287.6159923246196;2484.3416494628937;2616.2282126772648;4509.842155524541;4693.389859318267;4889.386502454182;5151.5621172200035;5371.328583927009;4811.807527698923;4946.332245146931;5171.388448833315;5195.6622819516615;5221.772883372404;4348.347747837786;4505.5269196734525;4774.517251029514;4872.829450969199;5255.448177941337;4150.481189851269;4651.86044418668;4768.76156192182;5115.335006449138;5172.767225474788;4688.523115218695;4714.581928102099;4944.629152813482;5014.824481143142;5059.394411877813;4013.8996525087105;4860.975695121501;4892.706437613754;5376.274202982548;5376.973115134399;3153.9879415158925;3305.0910999474377;3512.8946131616094;3514.8752399232;3519.6832285094465;4359.9346009810515;5007.599392048554;5094.962015193804;5154.922676159936;5867.765289388377;4785.9042819144115;5715.514181294637;5925.970370148294;6019.548533860066;6021.397860213943;4878.756062196947;5279.287296215115;5974.372690867396;5974.522038237017;6016.42843929826;4738.815296175956;5433.891322173456;5749.361431991862;5825.50483208915;5884.411558844081;5918.7928422505;5984.521238300838;6002.279726432856;6024.9397506026135;6028.5177185825905;4771.451283102488;5569.443055694398;5907.438793314628;5928.170056192051;5941 16:38:00 INFO - PROCESS | 1649 | |5;yui.html;727.8871774874893;734.8567029429263;771.4599780153894;825.178946947787;873.4366333714756;15134.243287835605;15666.82498812588;15808.051516908965;15877.523674289776;15904;12119.939400303008;14099.436022559112;14225.217613031306;14360.353784079716;14422.78365824511;683.1051322767191;843.9704610338608;852.901916279628;863.9092895246002;872.4285592936604;855.9058503564603;865.5196366016858;866.6706651472432;874.9125087491263;877.1754550722333;931.6599441203946;934.1032608695626;937.2455173585261;938.052566907425;938.558877327655;931.0224264522225;931.2363861633463;931.3015238571072;932.3566738950136;932.8880534335923;678.575890068707;684.4353408438009;685.0306815855558;692.4114502672738;693.3413257405434;14631.561053168421;14914.70170596582;14923.552293431107;14939.103653780809;14941.775873361908;14679.926600367036;14681.192534410604;14732.18972956487;14733.410663573444;14734.410623575044;10363.37819730811;11955.88044119565;12234.938825305817;12453.937730311382;12547.3098979557;3824.6749026332795;3845.730798844082;4037.1723796621623;4054.0472988847596;4057.5739547347694;95977;96433;96821.51589242008;97192;102607.48696256471;11120.555177792798;11197.832032519518;11210.260473407976;11389.943050284695;11463.025642820454;22702.432439189153;22874.771252287595;22879.7712022881;22891.542169156855;22928.541429171324;6613.3717296857685;8089.595520223966;8187.222213889671;8237.50574965504;8366.874496882552;4434.224010798098;4493.213687604722;4521.95416224189;4532.456105267389;4706.623470122382;3519.1202199450136;3619.819009049537;3625.564932208099;3629.2378600494194;3631.654992775682;589.8613825750945;618.9566730328834;621.610700085309;640.078287266335;667.8630880669451;538.5449295345505;544.0995153021755;613.6103574230307;631.958922670025;645.7578408096964;8761.036286008533;9207.723768286962;9386.342955993152;9603.663871764455;9625.133737963617;556.7278768015066;624.0701354981046;656.1011414362259;656.4420242793587;670.4167374384317;3840.9231815363537;4642.3500709900645;4687.95312046882;4699.154152252559;4709.599684026827;93157.06842931619;121743.39128304302;147724;149695.2515237417;154439;133084.33457832842;136659.31670341585;136815.31592341975;136898.3155084238;140111;90125.64811527963;95079.0492095084;108871;110779.44610276897;117503;8868.64525418976;8911.15344042315;8962.417442866194;8964.14840590143;9255.907440925504;44652.55347446484;62356;62462.68768656128;64755.02867457086;67572.98640520494;68956;85651;96578;97718;102672.48663756634;13270.336483175995;13324.866751332362;13758.65603359924;13770.518031868836;14049.22729249901;15443.6139096519;15544.145072020696;15556.922215388851;15618;16356.509304720877;12311.322877241479;14153.787693184817;14216;14394.784078239045;14836.62908427298;13280.734385312433;13384.665383365495;13595.592132236048;13597.660058498617;14057.437702491785;12252.816207756712;12427.316497592357;12777.105602607728;12823.871761282267;12839.358032098171;10014.849777253201;10220.540075696463;11160.776784464428;11313.038391736794;11440.48517816717;9714.562844671866;10295.691129266133;11028.841971592867;11411.771764564828;11618 16:38:00 INFO - PROCESS | 1649 | __end_tp_report 16:38:00 INFO - PROCESS | 1649 | __start_cc_report 16:38:00 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,449 16:38:00 INFO - PROCESS | 1649 | __end_cc_report 16:38:00 INFO - PROCESS | 1649 | __startTimestamp1467934680480__endTimestamp 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | ------- Summary: start ------- 16:38:00 INFO - PROCESS | 1649 | Number of tests: 6 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5764.78 Median:5998.64 stddev:671.34 (11.2%) stddev-sans-first:647.57 16:38:00 INFO - PROCESS | 1649 | Values: 3413.6 3415.6 3443.0 3443.9 3444.2 4620.9 5810.9 5892.3 5909.2 5920.9 5661.1 5753.6 5800.6 5814.4 5900.9 5213.5 5290.4 5293.4 5376.8 5430.9 5448.8 5476.9 5492.3 5508.7 5545.0 4388.2 4485.4 4982.2 5346.9 5366.3 5308.9 5329.8 5337.6 5349.0 5352.8 5253.5 5296.7 5325.8 5337.3 5411.6 5686.6 5688.9 5792.5 5816.7 5819.4 5206.5 6217.1 6226.5 6288.0 6292.0 6159.7 6275.7 6286.2 6295.8 6304.8 5836.7 5891.3 5901.8 5921.5 5934.4 6289.7 6326.7 6340.0 6349.8 6381.8 6106.2 6139.1 6263.6 6272.1 6307.6 6231.7 6238.7 6309.3 6320.2 6321.5 6124.2 6126.4 6157.6 6244.4 6261.6 6295.2 6296.9 6298.0 6305.6 6320.1 4141.8 4903.9 5065.6 5096.0 5111.5 3771.0 4513.2 4516.8 4520.5 4546.8 5735.5 5904.3 5962.5 5996.3 5998.6 6230.8 6232.0 6265.2 6293.2 6300.3 5887.9 5899.6 5903.4 5905.7 5961.9 4555.5 5428.9 5533.4 5541.7 5544.8 6252.2 6368.6 6374.6 6380.2 6382.3 6260.8 6283.9 6290.0 6312.6 6322.6 5946.8 6137.9 6268.7 6368.9 6371.4 6231.3 6271.2 6274.2 6330.7 6339.3 5308.0 6035.0 6133.3 6241.9 6247.9 5164.0 5945.1 6050.7 6126.6 6173.2 6098.2 6108.8 6146.0 6172.0 6214.5 4902.5 6091.7 6092.0 6102.7 6109.4 5340.8 5958.9 6033.5 6099.9 6209.3 6047.0 6061.2 6082.2 6087.2 6105.4 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:75314.71 Median:43094.46 stddev:163774.98 (380.0%) stddev-sans-first:164137.97 16:38:00 INFO - PROCESS | 1649 | Values: 457.0 483.7 525.9 541.3 564.5 34358.7 38371.8 41723.4 41730.8 41739.4 34119.0 41634.2 41752.8 42256.8 42522.9 9629.4 9712.3 9818.7 9822.7 9837.8 9690.8 9779.8 9839.2 9858.8 9862.4 9785.3 9839.7 9840.2 9846.1 9879.5 9784.5 9837.3 9846.0 9857.2 9860.8 9677.6 9745.1 9766.6 9790.1 9797.5 41289.4 41510.8 41599.6 41677.4 41696.7 3370.2 3439.4 3453.5 3458.4 3489.8 25880.4 32656.3 33364.7 33411.8 33435.0 3186.1 3354.2 3382.8 3405.3 3414.3 514198.0 518719.0 519228.0 520395.4 520881.0 62524.7 62561.7 62940.7 62979.0 63152.1 65700.0 65977.7 66588.7 67127.3 67179.3 16514.6 16741.7 16744.5 16755.7 16806.9 12895.6 12921.7 12927.8 12945.5 12976.0 17346.0 17769.4 17816.3 17848.9 17959.4 6121.4 6340.8 6352.0 6462.9 6467.3 780.6 824.0 960.0 978.7 981.2 28829.7 30856.2 30861.8 31300.7 31661.2 882.8 1042.3 1062.5 1137.9 1169.6 15856.5 16204.4 16269.2 16289.2 16611.8 674286.0 696425.0 697922.0 699245.0 699498.0 59496.7 61419.1 61449.7 61487.7 61775.7 1228.6 1319.9 1371.2 1381.5 1403.9 29812.6 33757.8 33997.5 34335.5 35028.6 59909.7 60533.4 61459.0 63139.7 64313.7 528402.0 593373.0 630967.0 648718.0 657818.0 46987.8 47096.0 48298.3 48434.8 49104.3 51162.6 51453.0 51681.7 52712.2 52955.7 42133.8 42137.8 42605.8 43088.0 43731.0 35847.5 38284.4 41783.0 42621.6 43442.6 39574.0 40864.2 41679.8 41697.8 43100.9 34695.1 37061.0 37195.9 38078.6 38148.0 22423.0 27198.0 27288.0 27422.9 27693.5 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:30792.78 Median:375.48 stddev:74949.62 (19961.1%) stddev-sans-first:75125.08 16:38:00 INFO - PROCESS | 1649 | Values: 373.3 377.7 380.1 390.9 397.0 20079.8 20565.1 21561.9 21604.7 21920.0 20625.9 20820.0 21045.8 22111.8 22473.4 5095.3 5977.8 6548.2 6564.8 6641.7 3807.9 6343.7 6531.2 6650.3 6667.9 6568.3 6656.4 6657.7 6675.7 6677.2 6578.0 6668.7 6669.2 6703.3 6779.8 5800.8 5806.9 5890.7 5920.6 5926.4 25830.2 25944.2 26147.3 26685.5 26779.3 2369.7 2388.6 2944.4 2991.9 3016.5 9969.4 10066.3 10211.7 10320.6 10472.2 1827.8 1866.5 1882.3 1924.6 1949.1 119444.0 125491.4 127944.4 129805.4 130413.3 11405.7 11556.5 11715.9 11779.5 11881.2 14513.7 14602.9 14607.9 14608.1 14610.9 8529.3 8567.1 8592.7 8628.3 8641.6 8569.2 8584.9 8587.7 8706.3 8726.2 4535.8 4564.9 4572.0 4573.9 4621.8 1600.8 1674.4 1679.8 1684.2 1743.7 350.3 394.6 400.3 773.7 938.5 9981.0 11981.5 12169.4 12295.3 12305.8 344.7 363.4 402.8 403.9 428.6 5816.4 6039.9 6104.4 6134.5 6151.9 427967.0 428928.0 429332.0 432111.0 441472.0 127386.0 137065.0 137188.0 137478.0 141719.3 432.6 439.6 440.9 441.2 443.6 11297.5 11551.8 11611.4 11687.8 11693.8 54492.5 57770.4 57977.0 58240.0 58528.4 83909.2 84016.6 84207.6 84784.6 85097.6 8189.2 8421.8 8447.2 8681.1 8763.6 10098.4 12129.8 13381.3 13463.9 13876.6 11878.9 11905.5 11908.2 11923.5 12105.5 10209.9 11752.3 11758.6 11805.4 11856.8 12020.7 12038.6 12114.2 12180.6 12195.8 8060.2 8341.0 8415.0 8604.5 8696.6 9177.9 10848.1 11392.1 11678.1 11998.6 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5661.92 Median:4982.12 stddev:3183.52 (63.9%) stddev-sans-first:3175.66 16:38:00 INFO - PROCESS | 1649 | Values: 1311.0 1417.9 1451.9 1747.3 1905.8 8067.4 8510.2 8555.2 8977.6 8982.5 8653.7 8679.3 8752.3 8762.6 8778.6 2329.5 2356.6 2359.3 2359.5 2365.5 1979.6 1989.7 2333.7 2377.3 2382.9 2360.3 2366.7 2366.8 2379.4 2394.4 2318.0 2320.0 2325.4 2332.5 2360.6 2334.8 2337.1 2350.9 2354.2 2354.8 8799.2 8857.6 8894.9 8909.0 8915.1 5217.5 5295.5 5341.9 5363.1 5373.7 3491.7 3574.4 3609.5 3632.4 3670.0 1059.8 1066.1 1083.5 1099.2 1104.8 11345.4 11494.6 11503.3 11540.0 11544.6 4025.5 4518.6 4687.6 4695.0 4726.3 9821.1 9829.3 9852.0 9868.3 9868.8 5268.1 5272.7 5332.2 5336.1 5339.9 4332.2 4700.1 4829.5 4859.9 4886.4 3918.7 3946.0 4002.8 4034.9 4047.4 1187.6 1211.4 1283.4 1296.4 1298.2 835.0 941.6 962.6 962.8 991.9 5867.5 6019.7 6061.0 6063.0 6100.5 425.9 426.7 435.2 437.1 439.9 3599.5 3807.8 4087.6 4144.1 4147.1 10137.7 10518.1 10548.3 10616.4 10645.3 10315.6 10599.5 10600.5 10621.7 10626.3 6886.7 8113.7 8182.2 8195.1 8222.4 5751.6 5870.2 5944.5 5948.6 6012.2 9775.9 9836.0 9855.0 9903.3 9931.7 9669.7 9942.8 10014.9 10113.8 10163.5 6958.4 7266.3 7458.7 7459.4 7548.2 7357.9 8247.9 8364.6 8364.7 8411.0 7270.4 7314.7 7470.8 7546.9 7614.2 7364.7 7457.6 7487.9 7594.3 7610.9 5976.7 7018.5 7063.0 7065.4 7073.0 4866.8 5000.0 5055.2 5091.7 5107.7 4266.4 4964.2 5071.2 5075.4 5255.8 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4456.60 Median:4789.84 stddev:1399.95 (29.2%) stddev-sans-first:1368.72 16:38:00 INFO - PROCESS | 1649 | Values: 302.7 308.9 309.2 316.9 317.0 5448.6 5662.9 5670.3 5802.9 5846.8 4514.2 5210.0 5523.2 5666.7 5704.9 4345.5 4346.4 4351.0 4357.9 4394.6 3593.5 4151.3 4371.7 4379.7 4385.2 3717.4 4089.9 4294.9 4303.9 4394.2 4320.4 4324.8 4353.2 4362.2 4364.3 3195.0 3688.2 3725.0 3734.3 3742.7 5016.0 5108.9 5460.0 5519.9 5526.9 4478.3 4828.1 4858.4 4865.5 4882.8 5875.5 6026.8 6062.5 6075.9 6089.3 3492.0 3504.7 3967.2 3994.0 4017.3 5456.7 6269.1 6278.3 6306.7 6319.8 3378.9 3812.0 3918.2 4051.0 4062.9 3655.7 3888.7 4013.6 4051.5 4113.2 4293.7 4425.8 4749.8 4793.8 4817.8 5283.5 5643.9 5915.3 5963.5 6036.4 1235.4 1384.3 1384.3 1391.2 1396.4 758.3 823.0 830.7 839.2 853.3 3838.8 3902.6 4142.7 4181.2 4474.7 4403.9 5057.4 5064.2 5093.4 5180.5 3693.8 4129.5 4636.7 4636.8 4664.1 2219.9 2229.6 2287.6 2484.3 2616.2 4509.8 4693.4 4889.4 5151.6 5371.3 4811.8 4946.3 5171.4 5195.7 5221.8 4348.3 4505.5 4774.5 4872.8 5255.4 4150.5 4651.9 4768.8 5115.3 5172.8 4688.5 4714.6 4944.6 5014.8 5059.4 4013.9 4861.0 4892.7 5376.3 5377.0 3154.0 3305.1 3512.9 3514.9 3519.7 4359.9 5007.6 5095.0 5154.9 5867.8 4785.9 5715.5 5926.0 6019.5 6021.4 4878.8 5279.3 5974.4 5974.5 6016.4 4738.8 5433.9 5749.4 5825.5 5884.4 5918.8 5984.5 6002.3 6024.9 6028.5 4771.5 5569.4 5907.4 5928.2 5941.0 16:38:00 INFO - PROCESS | 1649 | 16:38:00 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24140.48 Median:3941.45 stddev:38044.26 (965.2%) stddev-sans-first:38110.37 16:38:00 INFO - PROCESS | 1649 | Values: 727.9 734.9 771.5 825.2 873.4 15134.2 15666.8 15808.1 15877.5 15904.0 12119.9 14099.4 14225.2 14360.4 14422.8 683.1 844.0 852.9 863.9 872.4 855.9 865.5 866.7 874.9 877.2 931.7 934.1 937.2 938.1 938.6 931.0 931.2 931.3 932.4 932.9 678.6 684.4 685.0 692.4 693.3 14631.6 14914.7 14923.6 14939.1 14941.8 14679.9 14681.2 14732.2 14733.4 14734.4 10363.4 11955.9 12234.9 12453.9 12547.3 3824.7 3845.7 4037.2 4054.0 4057.6 95977.0 96433.0 96821.5 97192.0 102607.5 11120.6 11197.8 11210.3 11389.9 11463.0 22702.4 22874.8 22879.8 22891.5 22928.5 6613.4 8089.6 8187.2 8237.5 8366.9 4434.2 4493.2 4522.0 4532.5 4706.6 3519.1 3619.8 3625.6 3629.2 3631.7 589.9 619.0 621.6 640.1 667.9 538.5 544.1 613.6 632.0 645.8 8761.0 9207.7 9386.3 9603.7 9625.1 556.7 624.1 656.1 656.4 670.4 3840.9 4642.4 4688.0 4699.2 4709.6 93157.1 121743.4 147724.0 149695.3 154439.0 133084.3 136659.3 136815.3 136898.3 140111.0 90125.6 95079.0 108871.0 110779.4 117503.0 8868.6 8911.2 8962.4 8964.1 9255.9 44652.6 62356.0 62462.7 64755.0 67573.0 68956.0 85651.0 96578.0 97718.0 102672.5 13270.3 13324.9 13758.7 13770.5 14049.2 15443.6 15544.1 15556.9 15618.0 16356.5 12311.3 14153.8 14216.0 14394.8 14836.6 13280.7 13384.7 13595.6 13597.7 14057.4 12252.8 12427.3 12777.1 12823.9 12839.4 10014.8 10220.5 11160.8 11313.0 11440.5 9714.6 10295.7 11028.8 11411.8 11618.0 16:38:00 INFO - PROCESS | 1649 | -------- Summary: end -------- 16:38:00 INFO - PROCESS | 1649 | 16:38:01 INFO - TEST-INFO | 1649: exit 0 16:38:01 INFO - TEST-OK | dromaeo_css | took 1113260ms 16:38:01 INFO - TEST-START | kraken 16:38:01 INFO - operating with platform_type : mac_ 16:38:01 INFO - Initialising browser for kraken test... 16:38:01 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpqgIRy_/profile http://localhost:49266/getInfo.html) 16:38:04 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 16:38:04 INFO - PROCESS | 1662 | colorDepth:24 16:38:04 INFO - PROCESS | 1662 | Browser inner width/height: 1024/663 16:38:04 INFO - PROCESS | 1662 | __metrics 16:38:04 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 16:38:04 INFO - TEST-INFO | 1662: exit 0 16:38:04 INFO - Browser initialized. 16:38:04 INFO - Running cycle 1/1 for kraken test... 16:38:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpqgIRy_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:16 INFO - PROCESS | 1664 | RSS: Main: 267403264 16:38:16 INFO - PROCESS | 1664 | 16:38:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:20 INFO - PROCESS | 1664 | RSS: Main: 278151168 16:38:20 INFO - PROCESS | 1664 | 16:38:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 16:38:23 INFO - PROCESS | 1664 | RSS: Main: 282267648 16:38:23 INFO - PROCESS | 1664 | 16:38:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 16:38:27 INFO - PROCESS | 1664 | RSS: Main: 282636288 16:38:27 INFO - PROCESS | 1664 | 16:38:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 16:38:30 INFO - PROCESS | 1664 | RSS: Main: 283627520 16:38:30 INFO - PROCESS | 1664 | 16:38:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:33 INFO - PROCESS | 1664 | RSS: Main: 283992064 16:38:33 INFO - PROCESS | 1664 | 16:38:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:36 INFO - PROCESS | 1664 | RSS: Main: 284839936 16:38:36 INFO - PROCESS | 1664 | 16:38:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:40 INFO - PROCESS | 1664 | RSS: Main: 285130752 16:38:40 INFO - PROCESS | 1664 | 16:38:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 16:38:44 INFO - PROCESS | 1664 | RSS: Main: 285208576 16:38:44 INFO - PROCESS | 1664 | 16:38:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:46 INFO - PROCESS | 1664 | RSS: Main: 284725248 16:38:46 INFO - PROCESS | 1664 | 16:38:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:49 INFO - PROCESS | 1664 | RSS: Main: 284917760 16:38:49 INFO - PROCESS | 1664 | 16:38:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:51 INFO - PROCESS | 1664 | RSS: Main: 284950528 16:38:51 INFO - PROCESS | 1664 | 16:38:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:55 INFO - PROCESS | 1664 | RSS: Main: 284921856 16:38:55 INFO - PROCESS | 1664 | 16:38:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:58 INFO - PROCESS | 1664 | RSS: Main: 284827648 16:38:58 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 16:39:00 INFO - PROCESS | 1664 | RSS: Main: 286035968 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | __start_tp_report 16:39:00 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:39:00 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:39:00 INFO - PROCESS | 1664 | |i|pagename|runs| 16:39:00 INFO - PROCESS | 1664 | |0;ai-astar;102;114;108;130;171;120;133;165;120;115 16:39:00 INFO - PROCESS | 1664 | |1;audio-beat-detection;146;114;151;151;111;133;132;111;154;108 16:39:00 INFO - PROCESS | 1664 | |2;audio-dft;196;199;191;264;275;202;196;202;194;198 16:39:00 INFO - PROCESS | 1664 | |3;audio-fft;71;88;100;180;58;60;62;60;132;58 16:39:00 INFO - PROCESS | 1664 | |4;audio-oscillator;67;86;119;94;101;63;122;96;99;131 16:39:00 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;135;75;90;127;78;131;119;84;135;129 16:39:00 INFO - PROCESS | 1664 | |6;imaging-darkroom;140;98;101;158;119;96;137;148;98;91 16:39:00 INFO - PROCESS | 1664 | |7;imaging-desaturate;91;92;90;91;93;96;99;140;89;138 16:39:00 INFO - PROCESS | 1664 | |8;json-parse-financial;56;57;54;58;59;56;58;56;56;56 16:39:00 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;81;73;72;69;75;74;74;68;67;66 16:39:00 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;88;84;84;84;84;87;84;87;90;86 16:39:00 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;145;144;147;152;146;147;139;125;237;145 16:39:00 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;165;166;163;183;221;167;166;166;162;167 16:39:00 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;67;64;69;66;68;73;76;84;65 16:39:00 INFO - PROCESS | 1664 | __end_tp_report 16:39:00 INFO - PROCESS | 1664 | __start_cc_report 16:39:00 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1115 16:39:00 INFO - PROCESS | 1664 | __end_cc_report 16:39:00 INFO - PROCESS | 1664 | __startTimestamp1467934740938__endTimestamp 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:39:00 INFO - PROCESS | 1664 | Number of tests: 14 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:127.80 Median:125.00 stddev:23.13 (18.5%) stddev-sans-first:22.57 16:39:00 INFO - PROCESS | 1664 | Values: 102.0 114.0 108.0 130.0 171.0 120.0 133.0 165.0 120.0 115.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:139.50 stddev:18.79 (13.5%) stddev-sans-first:19.14 16:39:00 INFO - PROCESS | 1664 | Values: 146.0 114.0 151.0 151.0 111.0 133.0 132.0 111.0 154.0 108.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:211.70 Median:200.50 stddev:30.76 (15.3%) stddev-sans-first:32.09 16:39:00 INFO - PROCESS | 1664 | Values: 196.0 199.0 191.0 264.0 275.0 202.0 196.0 202.0 194.0 198.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:86.90 Median:60.00 stddev:40.64 (67.7%) stddev-sans-first:42.70 16:39:00 INFO - PROCESS | 1664 | Values: 71.0 88.0 100.0 180.0 58.0 60.0 62.0 60.0 132.0 58.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:76.50 stddev:22.28 (29.1%) stddev-sans-first:20.65 16:39:00 INFO - PROCESS | 1664 | Values: 67.0 86.0 119.0 94.0 101.0 63.0 122.0 96.0 99.0 131.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:105.00 stddev:25.27 (24.1%) stddev-sans-first:25.17 16:39:00 INFO - PROCESS | 1664 | Values: 135.0 75.0 90.0 127.0 78.0 131.0 119.0 84.0 135.0 129.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:118.60 Median:124.50 stddev:25.04 (20.1%) stddev-sans-first:25.34 16:39:00 INFO - PROCESS | 1664 | Values: 140.0 98.0 101.0 158.0 119.0 96.0 137.0 148.0 98.0 91.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:91.50 stddev:19.78 (21.6%) stddev-sans-first:20.58 16:39:00 INFO - PROCESS | 1664 | Values: 91.0 92.0 90.0 91.0 93.0 96.0 99.0 140.0 89.0 138.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.50 stddev:1.43 (2.5%) stddev-sans-first:1.50 16:39:00 INFO - PROCESS | 1664 | Values: 56.0 57.0 54.0 58.0 59.0 56.0 58.0 56.0 56.0 56.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.50 stddev:4.53 (6.2%) stddev-sans-first:3.41 16:39:00 INFO - PROCESS | 1664 | Values: 81.0 73.0 72.0 69.0 75.0 74.0 74.0 68.0 67.0 66.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.50 stddev:2.15 (2.5%) stddev-sans-first:2.13 16:39:00 INFO - PROCESS | 1664 | Values: 88.0 84.0 84.0 84.0 84.0 87.0 84.0 87.0 90.0 86.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:146.50 stddev:30.49 (20.8%) stddev-sans-first:32.21 16:39:00 INFO - PROCESS | 1664 | Values: 145.0 144.0 147.0 152.0 146.0 147.0 139.0 125.0 237.0 145.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:166.50 stddev:17.97 (10.8%) stddev-sans-first:18.85 16:39:00 INFO - PROCESS | 1664 | Values: 165.0 166.0 163.0 183.0 221.0 167.0 166.0 166.0 162.0 167.0 16:39:00 INFO - PROCESS | 1664 | 16:39:00 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:68.50 stddev:6.15 (9.0%) stddev-sans-first:6.44 16:39:00 INFO - PROCESS | 1664 | Values: 67.0 67.0 64.0 69.0 66.0 68.0 73.0 76.0 84.0 65.0 16:39:00 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:39:00 INFO - PROCESS | 1664 | 16:39:01 INFO - TEST-INFO | 1664: exit 0 16:39:01 INFO - TEST-OK | kraken | took 60592ms 16:39:01 INFO - SUITE-END | took 1173s 16:39:02 INFO - Completed test suite (00:19:34) 16:39:02 INFO - javascript benchmark 16:39:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7275.248073200973, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.5562376891003, 3415.573053368329, 3443.0, 3443.9311213775727, 3444.190615205428, 4620.907581848361, 5810.86688095821, 5892.32238292596, 5909.231799866016, 5920.9407905920925, 5661.094224924013, 5753.597248192628, 5800.564957628184, 5814.389489103647, 5900.911486327698, 5213.504717051874, 5290.418054014055, 5293.364796224459, 5376.78492860287, 5430.864228394283, 5448.809291674773, 5476.890462190754, 5492.258545096401, 5508.669479831203, 5545.0, 4388.166248412823, 4485.416895803524, 4982.202847544376, 5346.893062138774, 5366.275552800381, 5308.867278318035, 5329.786808527654, 5337.599680024014, 5349.0, 5352.839414817562, 5253.527182553588, 5296.682199068068, 5325.760340784635, 5337.2527846101575, 5411.56707463402, 5686.630369026, 5688.857778555528, 5792.507636850873, 5816.680082595455, 5819.388964158787, 5206.505381988743, 6217.067439884008, 6226.501879849601, 6288.025356069771, 6292.0, 6159.692015399257, 6275.6548389838545, 6286.214223222098, 6295.842603934892, 6304.779332723333, 5836.6789826559525, 5891.263592050994, 5901.7639294427745, 5921.526277897758, 5934.37689042653, 6289.7169627366875, 6326.746930122744, 6340.048992651047, 6349.841253968688, 6381.840453988688, 6106.236720409949, 6139.062858969336, 6263.592866463665, 6272.059191121369, 6307.590006649553, 6231.719572619152, 6238.656873871981, 6309.274433440213, 6320.241571011508, 6321.494280457552, 6124.203853499017, 6126.3955342902, 6157.599756015845, 6244.438000579969, 6261.624302541862, 6295.244570651551, 6296.874062518724, 6297.96851015742, 6305.621662700252, 6320.083587879823, 4141.798878325252, 4903.87740306488, 5065.569426598707, 5096.0, 5111.463296353903, 3770.962290377116, 4513.187626227245, 4516.796744146522, 4520.547945205519, 4546.818127274872, 5735.512481439125, 5904.261967254093, 5962.493188078975, 5996.340402555716, 5998.640081595118, 6230.844228894223, 6232.034034724625, 6265.154204182377, 6293.1504246927, 6300.338464461257, 5887.882242355129, 5899.587028907935, 5903.35063143054, 5905.704714764244, 5961.910571341434, 4555.453345598549, 5428.864278393072, 5533.446655334515, 5541.722913854291, 5544.8355845272945, 6252.218472690914, 6368.586041907446, 6374.617522948638, 6380.234371875405, 6382.266039405527, 6260.812175634738, 6283.937160628335, 6290.0, 6312.589681670676, 6322.620642761449, 5946.791862284972, 6137.9286238596105, 6268.749250029948, 6368.9044664330995, 6371.426571608577, 6231.2522497300615, 6271.184745982993, 6274.247090349188, 6330.651814150266, 6339.270983836734, 5308.0, 6035.0, 6133.258990800019, 6241.875162496815, 6247.9062814056915, 5164.0, 5945.10823376497, 6050.7277172526465, 6126.6017708848785, 6173.166622505904, 6098.242253746034, 6108.7670647404, 6145.9692701538, 6172.0, 6214.5028397727165, 4902.485239049991, 6091.722750294852, 6091.994820854509, 6102.694865256808, 6109.382479655331, 5340.75966581497, 5958.897603943285, 6033.547483938634, 6099.94541213825, 6209.285932117684, 6046.9720147574135, 6061.212042434456, 6082.2093127893095, 6087.239095113111, 6105.358937311518], "value": 5723.683588374535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.98629041128765, 483.70735704898533, 525.8869343091237, 541.3314556522694, 564.5145175149373, 34358.656413435856, 38371.819804326406, 41723.37414938778, 41730.791346043225, 41739.37390939138, 34119.0, 41634.16731665365, 41752.79123604386, 42256.78871605638, 42522.936926576855, 9629.374090684101, 9712.27157963152, 9818.656347027856, 9822.705318840446, 9837.803243935117, 9690.806183876319, 9779.75550611237, 9839.16367108793, 9858.802823943517, 9862.358946668444, 9785.315027948047, 9839.704808855746, 9840.163586095156, 9846.113849753521, 9879.506024698772, 9784.510774461283, 9837.262205334628, 9846.0, 9857.211423086208, 9860.802783944317, 9677.564509597014, 9745.074217949283, 9766.609335626566, 9790.118889299998, 9797.461139637317, 41289.38065929014, 41510.79244603788, 41599.58400416018, 41677.37483937744, 41696.74909752712, 3370.1911541230174, 3439.3809114359406, 3453.4647129694936, 3458.39478091333, 3489.7731647442833, 25880.352991175183, 32656.346873062404, 33364.6663533364, 33411.83294083514, 33435.0, 3186.1078897908624, 3354.2117602363423, 3382.7632065755406, 3405.267867408519, 3414.2830005698834, 514198.0, 518719.0, 519228.0, 520395.39802300744, 520881.0, 62524.687376563736, 62561.68719156375, 62940.685296573225, 62979.0, 63152.05271920925, 65700.0, 65977.71144875568, 66588.6670566644, 67127.3287267121, 67179.32820671829, 16514.58713532171, 16741.6651666966, 16744.497665070066, 16755.748663770053, 16806.915965420096, 12895.613131606067, 12921.676958076123, 12927.806082908575, 12945.54690585825, 12976.02679799019, 17345.959242445242, 17769.378071767427, 17816.287348505914, 17848.91075544614, 17959.371422000168, 6121.4490695837585, 6340.778072767431, 6352.0, 6462.903056454157, 6467.32093130224, 780.5667854340895, 824.0235321144344, 959.9856002159974, 978.6721448314752, 981.239539357004, 28829.711702883124, 30856.228594284872, 30861.84569077185, 31300.68699313023, 31661.20846978844, 882.761654353321, 1042.2547878266923, 1062.5431064642123, 1137.852079229695, 1169.5847973969119, 15856.52430427089, 16204.351825927066, 16269.186540673156, 16289.185540722916, 16611.750823737653, 674286.0, 696425.0, 697922.0, 699245.0, 699498.0, 59496.70251648714, 61419.07871381933, 61449.69275153596, 61487.6925615378, 61775.69112154411, 1228.6498347970964, 1319.85172899578, 1371.1567386057502, 1381.4888491258298, 1403.8806701430492, 29812.552811707406, 33757.83121084379, 33997.49003764896, 34335.484967725, 35028.64971350254, 59909.70045149746, 60533.39466605277, 61459.0, 63139.6843015782, 64313.67843160754, 528402.0, 593373.0331348316, 630967.0, 648718.0, 657818.0, 46987.765061174476, 47096.0, 48298.27552586644, 48434.75782621064, 49104.263436047775, 51162.6280639185, 51453.0, 51681.7415912918, 52712.20931685951, 52955.73522132519, 42133.78933105315, 42137.789311053246, 42605.78697106495, 43088.0, 43731.0, 35847.46228806622, 38284.425733614575, 41783.0, 42621.573784263004, 43442.565574343855, 39574.0, 40864.18271634491, 41679.7916010418, 41697.79151104225, 43100.92247693833, 34695.091769952895, 37061.0, 37195.88412347634, 38078.61921380862, 38148.0, 22423.0, 27198.048068317516, 27288.04491842776, 27422.86288568624, 27693.538307711537], "value": 20941.13455475787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.30192539950286, 377.6544461817437, 380.10106099075676, 390.9120447899225, 396.9563348031718, 20079.799202007976, 20565.074571644276, 21561.892190539027, 21604.675929861045, 21920.013599388047, 20625.896870515662, 20819.959002049876, 21045.789542104576, 22111.778882211212, 22473.438164045867, 5095.312132862054, 5977.790777322794, 6548.214214294291, 6564.770233041844, 6641.667916604175, 3807.8667246646373, 6343.71453284601, 6531.24890637578, 6650.268470468244, 6667.933130699089, 6568.277489476154, 6656.434203092743, 6657.667116644173, 6675.732970681167, 6677.1987361516685, 6578.013298005338, 6668.732940741268, 6669.199696036482, 6703.296153903819, 6779.796606101775, 5800.825975220708, 5806.883862322772, 5890.676012819294, 5920.585559010871, 5926.407359264039, 25830.225093247234, 25944.221673349828, 26147.346316342053, 26685.46629067427, 26779.33051673704, 2369.680093187415, 2388.641703744435, 2944.381679847235, 2991.8504074796174, 3016.547517872315, 9969.40183588987, 10066.345687530287, 10211.693649190462, 10320.638777642822, 10472.214583906161, 1827.8172182781748, 1866.5426970392205, 1882.2565086790642, 1924.5765931495048, 1949.083930552638, 119444.0, 125491.3725431367, 127944.36027819895, 129805.35097324547, 130413.34793325972, 11405.714857128638, 11556.479958401893, 11715.88284117165, 11779.469923853447, 11881.227720198332, 14513.709725805425, 14602.85397146015, 14607.904407169504, 14608.05047671898, 14610.853891461162, 8529.275011623957, 8567.100454452193, 8592.699255526026, 8628.309735221168, 8641.611127499278, 8569.228769410784, 8584.8712269316, 8587.656493740305, 8706.303495720327, 8726.171013753683, 4535.795889184995, 4564.863054108382, 4572.0, 4573.908521829545, 4621.838235661736, 1600.7514138971621, 1674.4055860169465, 1679.8320167983104, 1684.2084220416384, 1743.7035703930362, 350.28709610007087, 394.58174335204774, 400.3174587328598, 773.7407968330672, 938.4603852784676, 9981.0, 11981.46083426248, 12169.391530423443, 12295.262284262973, 12305.753884922251, 344.674282802754, 363.38587786640494, 402.76840816530614, 403.9175010970627, 428.5992596921888, 5816.41835816415, 6039.909401358983, 6104.3590423005835, 6134.478569321568, 6151.907721384183, 427967.0, 428928.0, 429332.0, 432111.0, 441472.0, 127386.0, 137065.0, 137188.0, 137478.0, 141719.29140354233, 432.6473923752132, 439.6372992281381, 440.9184300904343, 441.20142541999087, 443.6362183009901, 11297.548098076313, 11551.826722599, 11611.361375124112, 11687.824682629598, 11693.76612467763, 54492.455075448735, 57770.4222957765, 57977.0, 58240.0, 58528.4147158523, 83909.16090839013, 84016.57991710003, 84207.5789621048, 84784.57607711921, 85097.57451212704, 8189.22202390772, 8421.789455263668, 8447.155284471504, 8681.131886811268, 8763.561821908752, 10098.444585547866, 12129.75740485203, 13381.330933453482, 13463.865361346261, 13876.58370248894, 11878.93089621938, 11905.52377904874, 11908.166428349932, 11923.463444145207, 12105.455254513392, 10209.897901021192, 11752.294862308288, 11758.647240582795, 11805.350705710925, 11856.822147667965, 12020.699482512657, 12038.638840834788, 12114.21257618252, 12180.57367992116, 12195.75608487843, 8060.153683863345, 8341.0, 8414.957925210334, 8604.483730975911, 8696.565171741515, 9177.908220917705, 10848.077913377452, 11392.145589080686, 11678.124140689313, 11998.58004969822], "value": 8819.724724267913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.9803352949705, 1417.9007469477137, 1451.920144392058, 1747.33601231532, 1905.8380037696795, 8067.35461163107, 8510.234078932895, 8555.230029297361, 8977.596008179631, 8982.461052336865, 8653.697120600764, 8679.349048821332, 8752.256058235042, 8762.649494020248, 8778.56107194641, 2329.499157681098, 2356.552255071531, 2359.2922123362923, 2359.528094381122, 2365.5150694107665, 1979.604079184169, 1989.6816509358505, 2333.6616190652344, 2377.3105799318178, 2382.940426489334, 2360.3155085025323, 2366.680498132756, 2366.7869891709743, 2379.428937055103, 2394.3774618599205, 2317.953640927189, 2319.9884000579973, 2325.360525855386, 2332.4868528923694, 2360.5986982213067, 2334.7781960713705, 2337.1118974789642, 2350.8707021113833, 2354.21133920137, 2354.835161538693, 8799.208071273617, 8857.601407936658, 8894.911050889537, 8909.020007799201, 8915.063918288552, 5217.452167522393, 5295.4704529547125, 5341.866453338659, 5363.06146424378, 5373.650712703662, 3491.6508349165133, 3574.3566158091794, 3609.530761001053, 3632.400653892105, 3670.0, 1059.828889077566, 1066.0991462214417, 1083.544911137324, 1099.230538622959, 1104.7956128116243, 11345.432728363632, 11494.597689080842, 11503.309801411859, 11540.0, 11544.595939142173, 4025.5169379674626, 4518.615917647036, 4687.6249900007915, 4695.0375173090115, 4726.26742854858, 9821.116099550933, 9829.262805289489, 9852.014798520091, 9868.30921835479, 9868.80262394748, 5268.130758424817, 5272.736363181825, 5332.173513105397, 5336.0661884170895, 5339.919901201485, 4332.1985432695055, 4700.083483720641, 4829.517048295143, 4859.878503037452, 4886.438059623118, 3918.6669133124005, 3945.980270098631, 4002.8398864045694, 4034.8991275218355, 4047.433359329696, 1187.625897842177, 1211.3882489343002, 1283.403217503865, 1296.4490091710986, 1298.2340419152745, 834.9749507514663, 941.5668792355456, 962.5620342744035, 962.8170647576937, 991.9169743538655, 5867.501262392744, 6019.693937493183, 6060.969695151584, 6062.969685151635, 6100.450959413674, 425.9224162867949, 426.6949131371023, 435.2209544914567, 437.1235672476664, 439.87243699326837, 3599.4960705501253, 3807.7715337079862, 4087.5912408758886, 4144.109016561393, 4147.108371700099, 10137.695869123938, 10518.105960993249, 10548.261621686408, 10616.363018218777, 10645.254832161674, 10315.587376504856, 10599.470026498799, 10600.469976501145, 10621.681349559525, 10626.256162068736, 6886.655667216619, 8113.675452981933, 8182.18178182189, 8195.057568379594, 8222.38332125093, 5751.626144300607, 5870.17817505541, 5944.524438044859, 5948.643081415129, 6012.218411606463, 9775.902240977499, 9836.016398360107, 9855.0, 9903.257255705706, 9931.702048938543, 9669.661561845302, 9942.801143976936, 10014.890743498217, 10113.848292275768, 10163.54264058134, 6958.408535274559, 7266.273372662691, 7458.664360103699, 7459.36595389398, 7548.245175482408, 7357.926420735724, 8247.917520824714, 8364.6235919385, 8364.707235246737, 8410.957945210235, 7270.382017528569, 7314.7074117036855, 7470.775876723707, 7546.886796698163, 7614.200508946481, 7364.66858991336, 7457.589832559044, 7487.925120748723, 7594.31651151399, 7610.9238907610215, 5976.671283079472, 7018.508704390643, 7063.046488724162, 7065.434765218873, 7072.9646351767915, 4866.756662166948, 5000.0, 5055.241713742968, 5091.669041512438, 5107.693538387709, 4266.381374700713, 4964.230544265644, 5071.18860982241, 5075.416327122428, 5255.816046438356], "value": 4473.02964905727, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.73359443689566, 308.93357928045475, 309.2130527806732, 316.8669158953241, 316.9587953566036, 5448.594628357277, 5662.915056274154, 5670.291213598301, 5802.883942321151, 5846.766129354826, 4514.210013247677, 5210.0, 5523.171524271362, 5666.688332141731, 5704.857378565538, 4345.543717909613, 4346.434963454746, 4350.97824510877, 4357.891052723676, 4394.582514661102, 3593.4969104325414, 4151.294279972397, 4371.715838470505, 4379.671524635649, 4385.232584297751, 3717.3866312058476, 4089.8548406446243, 4294.935575966363, 4303.870883873489, 4394.198378664748, 4320.379857553402, 4324.762138082404, 4353.216421044211, 4362.236608593484, 4364.258076127066, 3194.952075718866, 3688.17016171362, 3725.012871589031, 3734.271816995687, 3742.719296052807, 5015.974920125376, 5108.948910510884, 5459.972700136514, 5519.868426972461, 5526.861828454281, 4478.305862591304, 4828.130936431441, 4858.368412106403, 4865.5134486551415, 4882.829100981484, 5875.471207591338, 6026.770095064854, 6062.484688801457, 6075.939240607626, 6089.250384699941, 3491.965080349215, 3504.7021003214504, 3967.1668949520345, 3993.9600603994168, 4017.31705610047, 5456.653638935256, 6269.122322874801, 6278.30938596754, 6306.684665766693, 6319.8104056878365, 3378.8648454061563, 3812.0, 3918.1575961168487, 4050.959490405058, 4062.898427539335, 3655.670989610948, 3888.747231429948, 4013.6387725104883, 4051.493563304587, 4113.177364527107, 4293.720908140961, 4425.782909699794, 4749.833755818529, 4793.784279707422, 4817.8313759018265, 5283.52448279649, 5643.858903527363, 5915.349311575723, 5963.4931030862, 6036.366181550874, 1235.3761350518112, 1384.2940100548792, 1384.349355802771, 1391.1931079973792, 1396.427464739452, 758.317514237191, 822.9711960081488, 830.6552780596106, 839.2195258409615, 853.2875049333876, 3838.8272527736317, 3902.551206611219, 4142.700801055383, 4181.205570941512, 4474.679969409032, 4403.911921761547, 5057.418396884332, 5064.215046667772, 5093.363329583802, 5180.456052114474, 3693.778373297611, 4129.483814523184, 4636.678546614266, 4636.797521177414, 4664.067186562701, 2219.922302719397, 2229.6209644360174, 2287.6159923246196, 2484.3416494628937, 2616.2282126772648, 4509.842155524541, 4693.389859318267, 4889.386502454182, 5151.5621172200035, 5371.328583927009, 4811.807527698923, 4946.332245146931, 5171.388448833315, 5195.6622819516615, 5221.772883372404, 4348.347747837786, 4505.5269196734525, 4774.517251029514, 4872.829450969199, 5255.448177941337, 4150.481189851269, 4651.86044418668, 4768.76156192182, 5115.335006449138, 5172.767225474788, 4688.523115218695, 4714.581928102099, 4944.629152813482, 5014.824481143142, 5059.394411877813, 4013.8996525087105, 4860.975695121501, 4892.706437613754, 5376.274202982548, 5376.973115134399, 3153.9879415158925, 3305.0910999474377, 3512.8946131616094, 3514.8752399232, 3519.6832285094465, 4359.9346009810515, 5007.599392048554, 5094.962015193804, 5154.922676159936, 5867.765289388377, 4785.9042819144115, 5715.514181294637, 5925.970370148294, 6019.548533860066, 6021.397860213943, 4878.756062196947, 5279.287296215115, 5974.372690867396, 5974.522038237017, 6016.42843929826, 4738.815296175956, 5433.891322173456, 5749.361431991862, 5825.50483208915, 5884.411558844081, 5918.7928422505, 5984.521238300838, 6002.279726432856, 6024.9397506026135, 6028.5177185825905, 4771.451283102488, 5569.443055694398, 5907.438793314628, 5928.170056192051, 5941.0], "value": 4013.1439152959942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.8871774874893, 734.8567029429263, 771.4599780153894, 825.178946947787, 873.4366333714756, 15134.243287835605, 15666.82498812588, 15808.051516908965, 15877.523674289776, 15904.0, 12119.939400303008, 14099.436022559112, 14225.217613031306, 14360.353784079716, 14422.78365824511, 683.1051322767191, 843.9704610338608, 852.901916279628, 863.9092895246002, 872.4285592936604, 855.9058503564603, 865.5196366016858, 866.6706651472432, 874.9125087491263, 877.1754550722333, 931.6599441203946, 934.1032608695626, 937.2455173585261, 938.052566907425, 938.558877327655, 931.0224264522225, 931.2363861633463, 931.3015238571072, 932.3566738950136, 932.8880534335923, 678.575890068707, 684.4353408438009, 685.0306815855558, 692.4114502672738, 693.3413257405434, 14631.561053168421, 14914.70170596582, 14923.552293431107, 14939.103653780809, 14941.775873361908, 14679.926600367036, 14681.192534410604, 14732.18972956487, 14733.410663573444, 14734.410623575044, 10363.37819730811, 11955.88044119565, 12234.938825305817, 12453.937730311382, 12547.3098979557, 3824.6749026332795, 3845.730798844082, 4037.1723796621623, 4054.0472988847596, 4057.5739547347694, 95977.0, 96433.0, 96821.51589242008, 97192.0, 102607.48696256471, 11120.555177792798, 11197.832032519518, 11210.260473407976, 11389.943050284695, 11463.025642820454, 22702.432439189153, 22874.771252287595, 22879.7712022881, 22891.542169156855, 22928.541429171324, 6613.3717296857685, 8089.595520223966, 8187.222213889671, 8237.50574965504, 8366.874496882552, 4434.224010798098, 4493.213687604722, 4521.95416224189, 4532.456105267389, 4706.623470122382, 3519.1202199450136, 3619.819009049537, 3625.564932208099, 3629.2378600494194, 3631.654992775682, 589.8613825750945, 618.9566730328834, 621.610700085309, 640.078287266335, 667.8630880669451, 538.5449295345505, 544.0995153021755, 613.6103574230307, 631.958922670025, 645.7578408096964, 8761.036286008533, 9207.723768286962, 9386.342955993152, 9603.663871764455, 9625.133737963617, 556.7278768015066, 624.0701354981046, 656.1011414362259, 656.4420242793587, 670.4167374384317, 3840.9231815363537, 4642.3500709900645, 4687.95312046882, 4699.154152252559, 4709.599684026827, 93157.06842931619, 121743.39128304302, 147724.0, 149695.2515237417, 154439.0, 133084.33457832842, 136659.31670341585, 136815.31592341975, 136898.3155084238, 140111.0, 90125.64811527963, 95079.0492095084, 108871.0, 110779.44610276897, 117503.0, 8868.64525418976, 8911.15344042315, 8962.417442866194, 8964.14840590143, 9255.907440925504, 44652.55347446484, 62356.0, 62462.68768656128, 64755.02867457086, 67572.98640520494, 68956.0, 85651.0, 96578.0, 97718.0, 102672.48663756634, 13270.336483175995, 13324.866751332362, 13758.65603359924, 13770.518031868836, 14049.22729249901, 15443.6139096519, 15544.145072020696, 15556.922215388851, 15618.0, 16356.509304720877, 12311.322877241479, 14153.787693184817, 14216.0, 14394.784078239045, 14836.62908427298, 13280.734385312433, 13384.665383365495, 13595.592132236048, 13597.660058498617, 14057.437702491785, 12252.816207756712, 12427.316497592357, 12777.105602607728, 12823.871761282267, 12839.358032098171, 10014.849777253201, 10220.540075696463, 11160.776784464428, 11313.038391736794, 11440.48517816717, 9714.562844671866, 10295.691129266133, 11028.841971592867, 11411.771764564828, 11618.0], "value": 7813.055383273894, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 108.0, 130.0, 171.0, 120.0, 133.0, 165.0, 120.0, 115.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 151.0, 151.0, 111.0, 133.0, 132.0, 111.0, 154.0, 108.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 191.0, 264.0, 275.0, 202.0, 196.0, 202.0, 194.0, 198.0], "value": 211.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 88.0, 100.0, 180.0, 58.0, 60.0, 62.0, 60.0, 132.0, 58.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 86.0, 119.0, 94.0, 101.0, 63.0, 122.0, 96.0, 99.0, 131.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 75.0, 90.0, 127.0, 78.0, 131.0, 119.0, 84.0, 135.0, 129.0], "value": 110.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 98.0, 101.0, 158.0, 119.0, 96.0, 137.0, 148.0, 98.0, 91.0], "value": 118.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 91.0, 93.0, 96.0, 99.0, 140.0, 89.0, 138.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 58.0, 59.0, 56.0, 58.0, 56.0, 56.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 72.0, 69.0, 75.0, 74.0, 74.0, 68.0, 67.0, 66.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 84.0, 84.0, 84.0, 87.0, 84.0, 87.0, 90.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 147.0, 152.0, 146.0, 147.0, 139.0, 125.0, 237.0, 145.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 163.0, 183.0, 221.0, 167.0, 166.0, 166.0, 162.0, 167.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 64.0, 69.0, 66.0, 68.0, 73.0, 76.0, 84.0, 65.0], "value": 69.9, "unit": "score"}]}]} 16:39:02 INFO - Return code: 0 16:39:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:39:02 ERROR - # TBPL SUCCESS # 16:39:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:39:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:39:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:39:02 INFO - 2016-07-07 16:39:02.251 screenresolution[1669:10564] starting screenresolution argv=screenresolution get 16:39:02 INFO - 2016-07-07 16:39:02.254 screenresolution[1669:10564] Display 0: 1600x1200x32@60 16:39:02 INFO - 2016-07-07 16:39:02.260 screenresolution[1670:10567] starting screenresolution argv=screenresolution list 16:39:02 INFO - Available Modes on Display 0 16:39:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:39:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:39:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:39:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:39:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:39:02 INFO - Intel Iris: 16:39:02 INFO - Chipset Model: Intel Iris 16:39:02 INFO - Type: GPU 16:39:02 INFO - Bus: Built-In 16:39:02 INFO - VRAM (Dynamic, Max): 1536 MB 16:39:02 INFO - Vendor: Intel (0x8086) 16:39:02 INFO - Device ID: 0x0a2e 16:39:02 INFO - Revision ID: 0x0009 16:39:02 INFO - Displays: 16:39:02 INFO - Display: 16:39:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:39:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:39:02 INFO - Display Serial Number: 1600x1200 60 16:39:02 INFO - Main Display: Yes 16:39:02 INFO - Mirror: Off 16:39:02 INFO - Online: Yes 16:39:02 INFO - Rotation: Supported 16:39:02 INFO - Return code: 0 16:39:02 INFO - Running post-action listener: _resource_record_post_action 16:39:02 INFO - ##### 16:39:02 INFO - ##### Finished run-tests step (success) 16:39:02 INFO - ##### 16:39:02 INFO - Running post-run listener: _resource_record_post_run 16:39:02 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 36935168; Write bytes: 707087360; Read time: 386; Write time: 2119 16:39:02 INFO - TinderboxPrint: CPU usage
54.3% 16:39:02 INFO - TinderboxPrint: I/O read bytes / time
36,935,168 / 386 16:39:02 INFO - TinderboxPrint: I/O write bytes / time
707,087,360 / 2,119 16:39:02 INFO - TinderboxPrint: CPU idle
2,212.3 (45.7%) 16:39:02 INFO - TinderboxPrint: CPU system
973.1 (20.1%) 16:39:02 INFO - TinderboxPrint: CPU user
1,652.2 (34.2%) 16:39:02 INFO - TinderboxPrint: Swap in / out
444,186,624 / 0 16:39:02 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 407185920; Write bytes: 402159616; Read time: 30900; Write time: 735 16:39:02 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 34629120; Write bytes: 295310336; Read time: 358; Write time: 1357 16:39:02 INFO - Running post-run listener: _upload_blobber_files 16:39:02 INFO - Blob upload gear active. 16:39:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:02 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 16:39:02 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'] 16:39:02 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 16:39:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:39:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:04 INFO - (blobuploader) - INFO - Done attempting. 16:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:04 INFO - (blobuploader) - INFO - Done attempting. 16:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:07 INFO - (blobuploader) - INFO - Done attempting. 16:39:07 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:07 INFO - Return code: 0 16:39:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533dfea6a6f5a25605c1c088feae3e50fb3a4dc4ce050e40bb7a362d7d65d426e1814c52d3d1a2f92c1e43e8cace87b9a0c158169303a836e353459717bc48a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c4f5389be0011ecf21a48a1113b02345af46254077b3024dfbecc949e4ab0a682ca669bbace0b7652e887963a0b7b23a9cd3d504a887e6c3de7d5c81ca2cc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c31ca768380d0de96f9f6faea746cb14b9ece653e8099a136990e08005af3c9168aab8bf0991caec034a37c3531d68b94d13e3045f3e82c64b04dbca6fb177c"} 16:39:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:07 INFO - Contents: 16:39:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533dfea6a6f5a25605c1c088feae3e50fb3a4dc4ce050e40bb7a362d7d65d426e1814c52d3d1a2f92c1e43e8cace87b9a0c158169303a836e353459717bc48a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c4f5389be0011ecf21a48a1113b02345af46254077b3024dfbecc949e4ab0a682ca669bbace0b7652e887963a0b7b23a9cd3d504a887e6c3de7d5c81ca2cc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c31ca768380d0de96f9f6faea746cb14b9ece653e8099a136990e08005af3c9168aab8bf0991caec034a37c3531d68b94d13e3045f3e82c64b04dbca6fb177c"} 16:39:07 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:07 INFO - Copying logs to upload dir... 16:39:07 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.464760 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-07 16:39:07.463802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 16:39:07.467721) ========= 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.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/533dfea6a6f5a25605c1c088feae3e50fb3a4dc4ce050e40bb7a362d7d65d426e1814c52d3d1a2f92c1e43e8cace87b9a0c158169303a836e353459717bc48a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c4f5389be0011ecf21a48a1113b02345af46254077b3024dfbecc949e4ab0a682ca669bbace0b7652e887963a0b7b23a9cd3d504a887e6c3de7d5c81ca2cc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c31ca768380d0de96f9f6faea746cb14b9ece653e8099a136990e08005af3c9168aab8bf0991caec034a37c3531d68b94d13e3045f3e82c64b04dbca6fb177c"} build_url:https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/UmPxd6kkRf2SmSvsGRrsDg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/533dfea6a6f5a25605c1c088feae3e50fb3a4dc4ce050e40bb7a362d7d65d426e1814c52d3d1a2f92c1e43e8cace87b9a0c158169303a836e353459717bc48a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72c4f5389be0011ecf21a48a1113b02345af46254077b3024dfbecc949e4ab0a682ca669bbace0b7652e887963a0b7b23a9cd3d504a887e6c3de7d5c81ca2cc2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c31ca768380d0de96f9f6faea746cb14b9ece653e8099a136990e08005af3c9168aab8bf0991caec034a37c3531d68b94d13e3045f3e82c64b04dbca6fb177c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 16:39:07.504762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:39:07.505105) ========= 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.p6j9nTD9oD/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.cPVnbze6SU/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.004330 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:39:07.553765) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 16:39:07.554080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 16:39:07.554393) ========= ========= Total master_lag: 0.19 =========