builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1468915981.26 results: success (0) buildid: 20160718204020 builduid: 573806672b044ad5bc87218f0f33440f revision: e2ddf53209916302a81ca331f88b4df8df0ca7c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.258118) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.258643) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.258935) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.284847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.285232) ========= 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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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-19 01:13:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.71M=0.001s 2016-07-19 01:13:01 (9.71 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110178 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.448969) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.449247) ========= 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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.030050 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.499871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:01.500205) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e2ddf53209916302a81ca331f88b4df8df0ca7c7 --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 e2ddf53209916302a81ca331f88b4df8df0ca7c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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-19 01:13:01,737 truncating revision to first 12 chars 2016-07-19 01:13:01,738 Setting DEBUG logging. 2016-07-19 01:13:01,738 attempt 1/10 2016-07-19 01:13:01,738 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e2ddf5320991?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 01:13:02,393 unpacking tar archive at: fx-team-e2ddf5320991/testing/mozharness/ program finished with exit code 0 elapsedTime=0.905253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:02.427206) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:02.427561) ========= script_repo_revision: e2ddf53209916302a81ca331f88b4df8df0ca7c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:02.428026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:02.428307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 01:13:02.447817) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-19 01:13:02.448219) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:02 INFO - MultiFileLogger online at 20160719 01:13:02 in /builds/slave/test 01:13:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:13:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:13:02 INFO - {'append_to_log': False, 01:13:02 INFO - 'base_work_dir': '/builds/slave/test', 01:13:02 INFO - 'blob_upload_branch': 'Fx-Team', 01:13:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:13:02 INFO - 'branch': 'Fx-Team', 01:13:02 INFO - 'buildbot_json_path': 'buildprops.json', 01:13:02 INFO - 'config_files': ('talos/mac_config.py',), 01:13:02 INFO - 'default_actions': ('clobber', 01:13:02 INFO - 'read-buildbot-config', 01:13:02 INFO - 'download-and-extract', 01:13:02 INFO - 'populate-webroot', 01:13:02 INFO - 'create-virtualenv', 01:13:02 INFO - 'install', 01:13:02 INFO - 'run-tests'), 01:13:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:13:02 INFO - 'download_minidump_stackwalk': True, 01:13:02 INFO - 'download_symbols': 'ondemand', 01:13:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:13:02 INFO - 'tooltool.py': '/tools/tooltool.py', 01:13:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:13:02 INFO - '/tools/misc-python/virtualenv.py')}, 01:13:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:13:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:13:02 INFO - 'installer_path': 'installer.exe', 01:13:02 INFO - 'log_level': 'info', 01:13:02 INFO - 'log_name': 'talos', 01:13:02 INFO - 'log_to_console': True, 01:13:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:13:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:13:02 INFO - 'opt_config_files': (), 01:13:02 INFO - 'pip_index': False, 01:13:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:13:02 INFO - 'cmd': ('bash', 01:13:02 INFO - '-c', 01:13:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:13:02 INFO - 'enabled': True, 01:13:02 INFO - 'halt_on_failure': False, 01:13:02 INFO - 'name': 'check_screen_resolution'},), 01:13:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:13:02 INFO - 'cmd': ('bash', 01:13:02 INFO - '-c', 01:13:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:13:02 INFO - 'enabled': True, 01:13:02 INFO - 'halt_on_failure': False, 01:13:02 INFO - 'name': 'check_screen_resolution'},), 01:13:02 INFO - 'run_cmd_checks_enabled': True, 01:13:02 INFO - 'sps_profile': False, 01:13:02 INFO - 'sps_profile_interval': 0, 01:13:02 INFO - 'suite': 'dromaeojs', 01:13:02 INFO - 'system_bits': '32', 01:13:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:13:02 INFO - 'title': 't-yosemite-r7-0018', 01:13:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:13:02 INFO - 'use_talos_json': True, 01:13:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:13:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:13:02 INFO - 'work_dir': 'build'} 01:13:02 INFO - ##### 01:13:02 INFO - ##### Running clobber step. 01:13:02 INFO - ##### 01:13:02 INFO - Running pre-action listener: _resource_record_pre_action 01:13:02 INFO - Running main action method: clobber 01:13:02 INFO - rmtree: /builds/slave/test/build 01:13:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:13:04 INFO - Running post-action listener: _resource_record_post_action 01:13:04 INFO - ##### 01:13:04 INFO - ##### Finished clobber step (success) 01:13:04 INFO - ##### 01:13:04 INFO - ##### 01:13:04 INFO - ##### Running read-buildbot-config step. 01:13:04 INFO - ##### 01:13:04 INFO - Running pre-action listener: _resource_record_pre_action 01:13:04 INFO - Running main action method: read_buildbot_config 01:13:04 INFO - Using buildbot properties: 01:13:04 INFO - { 01:13:04 INFO - "project": "", 01:13:04 INFO - "product": "firefox", 01:13:04 INFO - "script_repo_revision": "production", 01:13:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:13:04 INFO - "repository": "", 01:13:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:13:04 INFO - "buildid": "20160718204020", 01:13:04 INFO - "pgo_build": "False", 01:13:04 INFO - "basedir": "/builds/slave/test", 01:13:04 INFO - "buildnumber": 71, 01:13:04 INFO - "slavename": "t-yosemite-r7-0018", 01:13:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 01:13:04 INFO - "platform": "yosemite_r7", 01:13:04 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 01:13:04 INFO - "branch": "fx-team", 01:13:04 INFO - "repo_path": "integration/fx-team", 01:13:04 INFO - "revision": "e2ddf53209916302a81ca331f88b4df8df0ca7c7", 01:13:04 INFO - "stage_platform": "macosx64", 01:13:04 INFO - "builduid": "573806672b044ad5bc87218f0f33440f", 01:13:04 INFO - "slavebuilddir": "test" 01:13:04 INFO - } 01:13:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 01:13:04 INFO - Running post-action listener: _resource_record_post_action 01:13:04 INFO - ##### 01:13:04 INFO - ##### Finished read-buildbot-config step (success) 01:13:04 INFO - ##### 01:13:04 INFO - ##### 01:13:04 INFO - ##### Running download-and-extract step. 01:13:04 INFO - ##### 01:13:04 INFO - Running pre-action listener: _resource_record_pre_action 01:13:04 INFO - Running main action method: download_and_extract 01:13:04 INFO - mkdir: /builds/slave/test/build/tests 01:13:04 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')]} 01:13:04 INFO - https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:13:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:13:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:13:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:12 INFO - Downloaded 1199 bytes. 01:13:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:13:12 INFO - Using the following test package requirements: 01:13:12 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:13:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 01:13:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 01:13:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'jsshell-mac.zip'], 01:13:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 01:13:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:13:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 01:13:12 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 01:13:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 01:13:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:13:12 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 01:13:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:13: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')]} 01:13:12 INFO - https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:13:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:13:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:13:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:14 INFO - Downloaded 18835424 bytes. 01:13:14 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'] 01:13:14 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 01:13:17 INFO - Return code: 0 01:13:17 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 01:13: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')]} 01:13:17 INFO - https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:13:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:13:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:13:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:19 INFO - Downloaded 18835424 bytes. 01:13:19 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'] 01:13:19 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 01:13:23 INFO - Return code: 0 01:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:23 INFO - https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:13:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:13:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:13:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:24 INFO - Downloaded 14403317 bytes. 01:13:24 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'] 01:13:24 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 01:13:25 INFO - Return code: 0 01:13:25 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')]} 01:13:25 INFO - https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:13:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:13:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:13:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 01:13:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FRvoX-n_TaetQKVGfrvtew/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 01:13:30 INFO - Downloaded 112870874 bytes. 01:13:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:13:30 INFO - mkdir: /builds/slave/test/properties 01:13:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:13:30 INFO - Writing to file /builds/slave/test/properties/build_url 01:13:30 INFO - Contents: 01:13:30 INFO - build_url:https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:13:30 INFO - Running post-action listener: _resource_record_post_action 01:13:30 INFO - Running post-action listener: set_extra_try_arguments 01:13:30 INFO - ##### 01:13:30 INFO - ##### Finished download-and-extract step (success) 01:13:30 INFO - ##### 01:13:30 INFO - ##### 01:13:30 INFO - ##### Running populate-webroot step. 01:13:30 INFO - ##### 01:13:30 INFO - Running pre-action listener: _resource_record_pre_action 01:13:30 INFO - Running main action method: populate_webroot 01:13:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:13:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:13:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:13:30 INFO - '--tppagecycles', 01:13:30 INFO - '7'], 01:13:30 INFO - 'tests': ['tsvgm']}}, 01:13:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:13:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:13:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:13:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:13:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:13:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:13:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['damp', 'tps']}, 01:13:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'tests': ['damp', 'tps']}, 01:13:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['dromaeo_dom']}, 01:13:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:13:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['basic_compositor_video']}, 01:13:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:13:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'other-e10s': {'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['a11yr', 01:13:30 INFO - 'ts_paint', 01:13:30 INFO - 'tpaint', 01:13:30 INFO - 'sessionrestore', 01:13:30 INFO - 'sessionrestore_no_auto_restore', 01:13:30 INFO - 'tabpaint']}, 01:13:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['tsvgx', 01:13:30 INFO - 'tsvgr_opacity', 01:13:30 INFO - 'tart', 01:13:30 INFO - 'tscrollx', 01:13:30 INFO - 'cart']}, 01:13:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:13:30 INFO - 'tsvgr_opacity', 01:13:30 INFO - 'tart', 01:13:30 INFO - 'tscrollx', 01:13:30 INFO - 'cart']}, 01:13:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'talos_options': ['--disable-e10s'], 01:13:30 INFO - 'tests': ['tp5o']}, 01:13:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'tests': ['tp5o']}, 01:13:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'talos_options': ['--disable-e10s', 01:13:30 INFO - '--xperf_path', 01:13:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:13:30 INFO - 'tests': ['tp5n']}, 01:13:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:30 INFO - 'talos_options': ['--xperf_path', 01:13:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:13:30 INFO - 'tests': ['tp5n']}}, 01:13:30 INFO - 'talos.zip': {'path': '', 01:13:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:13:30 INFO - Running post-action listener: _resource_record_post_action 01:13:30 INFO - ##### 01:13:30 INFO - ##### Finished populate-webroot step (success) 01:13:30 INFO - ##### 01:13:30 INFO - ##### 01:13:30 INFO - ##### Running create-virtualenv step. 01:13:30 INFO - ##### 01:13:30 INFO - Running pre-action listener: _resource_record_pre_action 01:13:30 INFO - Running main action method: create_virtualenv 01:13:30 INFO - Creating virtualenv /builds/slave/test/build/venv 01:13:30 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 01:13:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:13:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:13:31 INFO - Using real prefix '/tools/python27' 01:13:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:13:31 INFO - Installing distribute.............................................................................................................................................................................................done. 01:13:34 INFO - Installing pip.................done. 01:13:34 INFO - Return code: 0 01:13:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:34 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:34 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 01:13:34 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 01:13:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:34 INFO - 'HOME': '/Users/cltbld', 01:13:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:34 INFO - 'LOGNAME': 'cltbld', 01:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:34 INFO - 'MOZ_NO_REMOTE': '1', 01:13:34 INFO - 'NO_EM_RESTART': '1', 01:13:34 INFO - 'PAGER': '/bin/cat', 01:13:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:34 INFO - 'PWD': '/builds/slave/test', 01:13:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:34 INFO - 'SHELL': '/bin/bash', 01:13:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:34 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:34 INFO - 'USER': 'cltbld', 01:13:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:34 INFO - 'XPC_FLAGS': '0x0', 01:13:34 INFO - 'XPC_SERVICE_NAME': '0', 01:13:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:35 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 01:13:35 INFO - Cleaning up... 01:13:35 INFO - Return code: 0 01:13:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:13:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:35 INFO - 'HOME': '/Users/cltbld', 01:13:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:35 INFO - 'LOGNAME': 'cltbld', 01:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:35 INFO - 'MOZ_NO_REMOTE': '1', 01:13:35 INFO - 'NO_EM_RESTART': '1', 01:13:35 INFO - 'PAGER': '/bin/cat', 01:13:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:35 INFO - 'PWD': '/builds/slave/test', 01:13:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:35 INFO - 'SHELL': '/bin/bash', 01:13:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:35 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:35 INFO - 'USER': 'cltbld', 01:13:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:35 INFO - 'XPC_FLAGS': '0x0', 01:13:35 INFO - 'XPC_SERVICE_NAME': '0', 01:13:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:35 INFO - Downloading/unpacking psutil>=3.1.1 01:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:13:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:13:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:13:38 INFO - Installing collected packages: psutil 01:13:38 INFO - Running setup.py install for psutil 01:13:38 INFO - building 'psutil._psutil_osx' extension 01:13:38 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 01:13:39 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 01:13:39 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 01:13:39 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 01:13:39 INFO - building 'psutil._psutil_posix' extension 01:13:39 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 01:13:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:13:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:13:39 INFO - ^ 01:13:39 INFO - 1 warning generated. 01:13:39 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 01:13:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:13:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:13:39 INFO - Successfully installed psutil 01:13:39 INFO - Cleaning up... 01:13:39 INFO - Return code: 0 01:13:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:39 INFO - 'HOME': '/Users/cltbld', 01:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:39 INFO - 'LOGNAME': 'cltbld', 01:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:39 INFO - 'MOZ_NO_REMOTE': '1', 01:13:39 INFO - 'NO_EM_RESTART': '1', 01:13:39 INFO - 'PAGER': '/bin/cat', 01:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:39 INFO - 'PWD': '/builds/slave/test', 01:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:39 INFO - 'SHELL': '/bin/bash', 01:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:39 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:39 INFO - 'USER': 'cltbld', 01:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:39 INFO - 'XPC_FLAGS': '0x0', 01:13:39 INFO - 'XPC_SERVICE_NAME': '0', 01:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:13: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 01:13: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 01:13: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 01:13: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 01:13: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 01:13: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 01:13:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:13:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:13:42 INFO - Installing collected packages: mozsystemmonitor 01:13:42 INFO - Running setup.py install for mozsystemmonitor 01:13:42 INFO - Successfully installed mozsystemmonitor 01:13:42 INFO - Cleaning up... 01:13:42 INFO - Return code: 0 01:13:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:42 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:42 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 01:13:42 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 01:13:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:42 INFO - 'HOME': '/Users/cltbld', 01:13:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:42 INFO - 'LOGNAME': 'cltbld', 01:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:42 INFO - 'MOZ_NO_REMOTE': '1', 01:13:42 INFO - 'NO_EM_RESTART': '1', 01:13:42 INFO - 'PAGER': '/bin/cat', 01:13:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:42 INFO - 'PWD': '/builds/slave/test', 01:13:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:42 INFO - 'SHELL': '/bin/bash', 01:13:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:42 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:42 INFO - 'USER': 'cltbld', 01:13:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:42 INFO - 'XPC_FLAGS': '0x0', 01:13:42 INFO - 'XPC_SERVICE_NAME': '0', 01:13:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:43 INFO - Downloading/unpacking jsonschema==2.5.1 01:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:13:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:13:45 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 01:13:45 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 01:13:45 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 01:13:45 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 01:13:45 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 01:13:45 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 01:13:45 INFO - Downloading functools32-3.2.3-2.tar.gz 01:13:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:13:45 INFO - warning: no files found matching '*.txt' 01:13:45 INFO - no previously-included directories found matching 'build' 01:13:45 INFO - no previously-included directories found matching 'dist' 01:13:45 INFO - no previously-included directories found matching '.git*' 01:13:45 INFO - Installing collected packages: jsonschema, functools32 01:13:46 INFO - Running setup.py install for functools32 01:13:46 INFO - warning: no files found matching '*.txt' 01:13:46 INFO - no previously-included directories found matching 'build' 01:13:46 INFO - no previously-included directories found matching 'dist' 01:13:46 INFO - no previously-included directories found matching '.git*' 01:13:46 INFO - Successfully installed jsonschema functools32 01:13:46 INFO - Cleaning up... 01:13:46 INFO - Return code: 0 01:13:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:13: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')]} 01:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13: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')]} 01:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:46 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:46 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 01:13:46 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 01:13:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:46 INFO - 'HOME': '/Users/cltbld', 01:13:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:46 INFO - 'LOGNAME': 'cltbld', 01:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:46 INFO - 'MOZ_NO_REMOTE': '1', 01:13:46 INFO - 'NO_EM_RESTART': '1', 01:13:46 INFO - 'PAGER': '/bin/cat', 01:13:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:46 INFO - 'PWD': '/builds/slave/test', 01:13:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:46 INFO - 'SHELL': '/bin/bash', 01:13:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:46 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:46 INFO - 'USER': 'cltbld', 01:13:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:46 INFO - 'XPC_FLAGS': '0x0', 01:13:46 INFO - 'XPC_SERVICE_NAME': '0', 01:13:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:46 INFO - Downloading/unpacking blobuploader==1.2.4 01:13:46 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 01:13:46 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 01:13:46 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 01:13:46 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 01:13:46 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 01:13:46 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 01:13:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:13:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:13:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:50 INFO - Downloading docopt-0.6.1.tar.gz 01:13:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:13:50 INFO - Installing collected packages: blobuploader, requests, docopt 01:13:50 INFO - Running setup.py install for blobuploader 01:13:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:13:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:13:50 INFO - Running setup.py install for requests 01:13:50 INFO - Running setup.py install for docopt 01:13:51 INFO - Successfully installed blobuploader requests docopt 01:13:51 INFO - Cleaning up... 01:13:51 INFO - Return code: 0 01:13:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:13: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')]} 01:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13: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')]} 01:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:51 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:51 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 01:13:51 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 01:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:51 INFO - 'HOME': '/Users/cltbld', 01:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:51 INFO - 'LOGNAME': 'cltbld', 01:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:51 INFO - 'MOZ_NO_REMOTE': '1', 01:13:51 INFO - 'NO_EM_RESTART': '1', 01:13:51 INFO - 'PAGER': '/bin/cat', 01:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:51 INFO - 'PWD': '/builds/slave/test', 01:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:51 INFO - 'SHELL': '/bin/bash', 01:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:51 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:51 INFO - 'USER': 'cltbld', 01:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:51 INFO - 'XPC_FLAGS': '0x0', 01:13:51 INFO - 'XPC_SERVICE_NAME': '0', 01:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-O71oAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-lVGS2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-I4F7rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-xj3vwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9YZnt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:13:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:13:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-0gGGAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IFExpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bQV9ZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-0MTIBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-wBT3Fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-owmuCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-NgtEZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-QyXF8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Ll2e3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IATTG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:13:53 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-MUuceP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:13:53 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-INqvPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:13:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:13:53 INFO - Running setup.py install for manifestparser 01:13:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:13:53 INFO - Running setup.py install for mozcrash 01:13:53 INFO - Running setup.py install for mozdebug 01:13:53 INFO - Running setup.py install for mozdevice 01:13:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:13:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:13:53 INFO - Running setup.py install for mozfile 01:13:53 INFO - Running setup.py install for mozhttpd 01:13:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Running setup.py install for mozinfo 01:13:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Running setup.py install for mozInstall 01:13:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Running setup.py install for mozleak 01:13:54 INFO - Running setup.py install for mozlog 01:13:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Running setup.py install for moznetwork 01:13:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:13:54 INFO - Running setup.py install for mozprocess 01:13:55 INFO - Running setup.py install for mozprofile 01:13:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:13:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:13:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:13:55 INFO - Running setup.py install for mozrunner 01:13:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:13:55 INFO - Running setup.py install for mozscreenshot 01:13:55 INFO - Running setup.py install for moztest 01:13:55 INFO - Running setup.py install for mozversion 01:13:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:13:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:13:55 INFO - Cleaning up... 01:13:55 INFO - Return code: 0 01:13:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:55 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:13:55 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 01:13:55 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 01:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:55 INFO - 'HOME': '/Users/cltbld', 01:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:55 INFO - 'LOGNAME': 'cltbld', 01:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:55 INFO - 'MOZ_NO_REMOTE': '1', 01:13:55 INFO - 'NO_EM_RESTART': '1', 01:13:55 INFO - 'PAGER': '/bin/cat', 01:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:55 INFO - 'PWD': '/builds/slave/test', 01:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:55 INFO - 'SHELL': '/bin/bash', 01:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:13:55 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:55 INFO - 'USER': 'cltbld', 01:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:55 INFO - 'XPC_FLAGS': '0x0', 01:13:55 INFO - 'XPC_SERVICE_NAME': '0', 01:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eaQxmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-uTk6bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-4IG8j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-XYoHUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-F3uM2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-TWmq9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-iotvDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-4zhCmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:13:56 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)) 01:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:13:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-Usishd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-hLSVAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-6MxNBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-7QI8if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-0XBb8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-xJKbEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IfwNSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-EpDWNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:13:57 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)) 01:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:13:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-urK6j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:13:57 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)) 01:13:57 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)) 01:13:57 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)) 01:13:57 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)) 01:13:57 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)) 01:13:57 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)) 01:13:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:14:00 INFO - Downloading blessings-1.6.tar.gz 01:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:14:00 INFO - Installing collected packages: blessings 01:14:00 INFO - Running setup.py install for blessings 01:14:00 INFO - Successfully installed blessings 01:14:00 INFO - Cleaning up... 01:14:00 INFO - Return code: 0 01:14:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:14:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:14:01 INFO - Reading from file tmpfile_stdout 01:14:01 INFO - Current package versions: 01:14:01 INFO - blessings == 1.6 01:14:01 INFO - blobuploader == 1.2.4 01:14:01 INFO - docopt == 0.6.1 01:14:01 INFO - functools32 == 3.2.3-2 01:14:01 INFO - jsonschema == 2.5.1 01:14:01 INFO - manifestparser == 1.1 01:14:01 INFO - mozInstall == 1.12 01:14:01 INFO - mozcrash == 0.17 01:14:01 INFO - mozdebug == 0.1 01:14:01 INFO - mozdevice == 0.48 01:14:01 INFO - mozfile == 1.2 01:14:01 INFO - mozhttpd == 0.7 01:14:01 INFO - mozinfo == 0.9 01:14:01 INFO - mozleak == 0.1 01:14:01 INFO - mozlog == 3.2 01:14:01 INFO - moznetwork == 0.27 01:14:01 INFO - mozprocess == 0.23 01:14:01 INFO - mozprofile == 0.28 01:14:01 INFO - mozrunner == 6.12 01:14:01 INFO - mozscreenshot == 0.1 01:14:01 INFO - mozsystemmonitor == 0.3 01:14:01 INFO - moztest == 0.7 01:14:01 INFO - mozversion == 1.4 01:14:01 INFO - psutil == 3.1.1 01:14:01 INFO - requests == 1.2.3 01:14:01 INFO - wsgiref == 0.1.2 01:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:14:01 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')]} 01:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:14:01 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')]} 01:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:14:01 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:14:01 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 01:14:01 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 01:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:14:01 INFO - 'HOME': '/Users/cltbld', 01:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:14:01 INFO - 'LOGNAME': 'cltbld', 01:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:14:01 INFO - 'MOZ_NO_REMOTE': '1', 01:14:01 INFO - 'NO_EM_RESTART': '1', 01:14:01 INFO - 'PAGER': '/bin/cat', 01:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:01 INFO - 'PWD': '/builds/slave/test', 01:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:14:01 INFO - 'SHELL': '/bin/bash', 01:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:14:01 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:14:01 INFO - 'USER': 'cltbld', 01:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:14:01 INFO - 'XPC_FLAGS': '0x0', 01:14:01 INFO - 'XPC_SERVICE_NAME': '0', 01:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 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)) 01:14:01 INFO - Cleaning up... 01:14:01 INFO - Return code: 0 01:14:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:14:01 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')]} 01:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:14:01 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')]} 01:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:14:01 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 0x10e578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e60d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb983492510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, '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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 01:14:01 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 01:14:01 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 01:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:14:01 INFO - 'HOME': '/Users/cltbld', 01:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:14:01 INFO - 'LOGNAME': 'cltbld', 01:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:14:01 INFO - 'MOZ_NO_REMOTE': '1', 01:14:01 INFO - 'NO_EM_RESTART': '1', 01:14:01 INFO - 'PAGER': '/bin/cat', 01:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:01 INFO - 'PWD': '/builds/slave/test', 01:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:14:01 INFO - 'SHELL': '/bin/bash', 01:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:14:01 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:14:01 INFO - 'USER': 'cltbld', 01:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:14:01 INFO - 'XPC_FLAGS': '0x0', 01:14:01 INFO - 'XPC_SERVICE_NAME': '0', 01:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:14:01 INFO - Cleaning up... 01:14:01 INFO - Return code: 0 01:14:01 INFO - Running post-action listener: _resource_record_post_action 01:14:01 INFO - Running post-action listener: _start_resource_monitoring 01:14:01 INFO - Starting resource monitoring. 01:14:01 INFO - ##### 01:14:01 INFO - ##### Finished create-virtualenv step (success) 01:14:01 INFO - ##### 01:14:01 INFO - ##### 01:14:01 INFO - ##### Running install step. 01:14:01 INFO - ##### 01:14:01 INFO - Running pre-action listener: _resource_record_pre_action 01:14:01 INFO - Running main action method: install 01:14:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:14:01 INFO - Reading from file tmpfile_stdout 01:14:01 INFO - Detecting whether we're running mozinstall >=1.0... 01:14:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:14:02 INFO - Reading from file tmpfile_stdout 01:14:02 INFO - Output received: 01:14:02 INFO - Usage: mozinstall [options] installer 01:14:02 INFO - Options: 01:14:02 INFO - -h, --help show this help message and exit 01:14:02 INFO - -d DEST, --destination=DEST 01:14:02 INFO - Directory to install application into. [default: 01:14:02 INFO - "/builds/slave/test"] 01:14:02 INFO - --app=APP Application being installed. [default: firefox] 01:14:02 INFO - mkdir: /builds/slave/test/build/application 01:14:02 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'] 01:14:02 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 01:14:37 INFO - Reading from file tmpfile_stdout 01:14:37 INFO - Output received: 01:14:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:14:37 INFO - Running post-action listener: _resource_record_post_action 01:14:37 INFO - ##### 01:14:37 INFO - ##### Finished install step (success) 01:14:37 INFO - ##### 01:14:37 INFO - ##### 01:14:37 INFO - ##### Running run-tests step. 01:14:37 INFO - ##### 01:14:37 INFO - Running pre-action listener: _resource_record_pre_action 01:14:37 INFO - Running main action method: run_tests 01:14:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:14:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:14:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:14:37 INFO - 2016-07-19 01:14:37.634 screenresolution[1642:7956] starting screenresolution argv=screenresolution get 01:14:37 INFO - 2016-07-19 01:14:37.636 screenresolution[1642:7956] Display 0: 1600x1200x32@60 01:14:37 INFO - 2016-07-19 01:14:37.642 screenresolution[1643:7958] starting screenresolution argv=screenresolution list 01:14:37 INFO - Available Modes on Display 0 01:14:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:14:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:14:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:14:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:14:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:14:37 INFO - Intel Iris: 01:14:37 INFO - Chipset Model: Intel Iris 01:14:37 INFO - Type: GPU 01:14:37 INFO - Bus: Built-In 01:14:37 INFO - VRAM (Dynamic, Max): 1536 MB 01:14:37 INFO - Vendor: Intel (0x8086) 01:14:37 INFO - Device ID: 0x0a2e 01:14:37 INFO - Revision ID: 0x0009 01:14:37 INFO - Displays: 01:14:37 INFO - Display: 01:14:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:14:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:14:37 INFO - Display Serial Number: 1600x1200 60 01:14:37 INFO - Main Display: Yes 01:14:37 INFO - Mirror: Off 01:14:37 INFO - Online: Yes 01:14:37 INFO - Rotation: Supported 01:14:37 INFO - Return code: 0 01:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:14:37 INFO - Python 2.7.3 01:14:37 INFO - Return code: 0 01:14:37 INFO - grabbing minidump binary from tooltool 01:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:14:37 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 0x10e4dcd30>, '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 0x10e241db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4d4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:14:37 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 01:14:37 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 01:14:37 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 01:14:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:14:37 INFO - Return code: 0 01:14:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:14:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:14:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:14:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:14:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0BIU6DMcwt/Render', 01:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:14:38 INFO - 'HOME': '/Users/cltbld', 01:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:14:38 INFO - 'LOGNAME': 'cltbld', 01:14:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:14:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:14:38 INFO - 'MOZ_NO_REMOTE': '1', 01:14:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:14:38 INFO - 'NO_EM_RESTART': '1', 01:14:38 INFO - 'PAGER': '/bin/cat', 01:14:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:38 INFO - 'PWD': '/builds/slave/test', 01:14:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:14:38 INFO - 'SHELL': '/bin/bash', 01:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y7QN0JsFm2/Listeners', 01:14:38 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 01:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:14:38 INFO - 'USER': 'cltbld', 01:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:14:38 INFO - 'XPC_FLAGS': '0x0', 01:14:38 INFO - 'XPC_SERVICE_NAME': '0', 01:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:14:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:14:38 INFO - mozversion application_buildid: 20160718204020 01:14:38 INFO - mozversion application_changeset: e2ddf53209916302a81ca331f88b4df8df0ca7c7 01:14:38 INFO - mozversion application_display_name: Nightly 01:14:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:14:38 INFO - mozversion application_name: Firefox 01:14:38 INFO - mozversion application_remotingname: firefox 01:14:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:14:38 INFO - mozversion application_vendor: Mozilla 01:14:38 INFO - mozversion application_version: 50.0a1 01:14:38 INFO - mozversion platform_buildid: 20160718204020 01:14:38 INFO - mozversion platform_changeset: e2ddf53209916302a81ca331f88b4df8df0ca7c7 01:14:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:14:38 INFO - mozversion platform_version: 50.0a1 01:14:38 INFO - using testdate: 1468916078 01:14:38 INFO - actual date: 1468916078 01:14:38 INFO - starting webserver on 'localhost:49265' 01:14:38 INFO - SUITE-START | Running 2 tests 01:14:38 INFO - TEST-START | dromaeo_css 01:14:38 INFO - operating with platform_type : mac_ 01:14:38 INFO - Initialising browser for dromaeo_css test... 01:14:38 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpju6TDW/profile http://localhost:49265/getInfo.html) 01:14:40 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 01:14:40 INFO - PROCESS | 1653 | colorDepth:24 01:14:40 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 01:14:40 INFO - PROCESS | 1653 | __metrics 01:14:40 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:14:40 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:14:41 INFO - TEST-INFO | 1653: exit 0 01:14:41 INFO - Browser initialized. 01:14:41 INFO - Running cycle 1/1 for dromaeo_css test... 01:14:41 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpju6TDW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:14:53 INFO - PROCESS | 1656 | RSS: Main: 245092352 01:14:53 INFO - PROCESS | 1656 | 01:15:44 INFO - PROCESS | 1656 | 1468916144637 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] 01:17:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 01:17:57 INFO - PROCESS | 1656 | RSS: Main: 342302720 01:17:57 INFO - PROCESS | 1656 | 01:21:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 01:21:00 INFO - PROCESS | 1656 | RSS: Main: 375668736 01:21:00 INFO - PROCESS | 1656 | 01:24:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 01:24:03 INFO - PROCESS | 1656 | RSS: Main: 369532928 01:24:03 INFO - PROCESS | 1656 | 01:27:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 01:27:06 INFO - PROCESS | 1656 | RSS: Main: 371347456 01:27:06 INFO - PROCESS | 1656 | 01:30:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 01:30:10 INFO - PROCESS | 1656 | RSS: Main: 345133056 01:30:10 INFO - PROCESS | 1656 | 01:33:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 01:33:14 INFO - PROCESS | 1656 | RSS: Main: 419688448 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | __start_tp_report 01:33:14 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 01:33:14 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 01:33:14 INFO - PROCESS | 1656 | |i|pagename|runs| 01:33:14 INFO - PROCESS | 1656 | |0;dojo.html;3371.2246183377806;3378.1892345837;3392.321535692859;3399.3881101401744;3447.1209841490418;5724;5758.251427314453;5805.564582656307;5823.5341172706185;5857.619254748443;5872.70636468176;5873.823785286449;5981.1028345748055;6009.9399006010035;6023.337432882379;5196.662216955886;5217.243499692542;5274.498922602346;5286.4449232830575;5433.891322173554;5391.892162156754;5430.402655707889;5433.945660543383;5437.374701909292;5474.671519708811;5089.160288552403;5105.285260063594;5134.794608215667;5197.66215196011;5293.417724050351;5165.199394093884;5182.378114626231;5218.269442278046;5306.177542480922;5336.226247194174;5252.212168174766;5260.210968354778;5267.736613169364;5267.841964741064;5289.68261904287;5945.940540594582;6030.728617212236;6038.486728628071;6043.60716553427;6072.483838873701;6130.938690613081;6161.7227224775;6183.845403864894;6195.256569211723;6201.255849298113;5671.837273358951;6302.243730752293;6309.3690630937;6323.30443651198;6336.176297081396;6055.12200730896;6065.361294711772;6069.721115537848;6076.118962750376;6122.663253521099;6154.938450615526;6213.316535181127;6285.245770507477;6326.588771729915;6345.587536810092;6178.845528861814;6249.468795152463;6260.937390626035;6266.530010249158;6280.434760871452;6276.623402595859;6291.779787707408;6294.874102517924;6301.40136687014;6305.905411418832;6150.200473938449;6156.476699480594;6215.316315205324;6228.065790131426;6242.282137554149;6149.723262453201;6198.690065496708;6203.503719702413;6205.81382558524;6213.689315534205;4993.101241776515;4996.250562415594;4999.10016197088;4999.875003124878;5016.5485106339875;4378.846740364135;4412.757298348557;4455.420795296655;4461.687681862303;4513.977430112828;4965.652404331662;5954.76180952757;5954.791582294599;5968.194293770285;6024.216851809324;6151.384861513813;6231.158793562901;6248.375162483715;6262.5616206866;6303.9684801575695;5815.912761308584;5871.647701137946;5874.970625146932;5996.850078747979;6055.909161362583;5486.725663716798;5501.752421141058;5508;5511.889762204733;5514.007478653881;6466.482681385572;6476;6483.902741458787;6487.059376390397;6489.5781774184525;6096.79883341448;6143.389059649542;6196.690165491797;6198.72105755233;6217.96891015542;6269.373062693694;6337.6197428154455;6345.111684364285;6408.61548307103;6423.29343772194;6159.630422174684;6219.253689557282;6239;6251.71867265965;6256.280527739368;6155.415235552616;6158.661273629993;6239.563230573816;6247.875042499216;6257.843553911189;6113.537117903944;6137.039921714347;6185.165002724754;6215.129881816459;6232;5966.433188847052;6190.907136393048;6218.720157592828;6229.408206220402;6232.158658581034;5969.970150149222;6002.909956350571;6094.299155596986;6140.3552626975315;6144.385561443999;5143.819966301162;6138.6366248995355;6173.135760993554;6203.2556093269095;6211.3167551570195;5097.515736005073;5132.820351287687;6133.294671112879;6148.200733904564;6160.876782464416 01:33:14 INFO - PROCESS | 1656 | |1;ext.html;506.8418663354234;515.4664921805931;518.548862489634;529.5498825997901;546.2570903571143;30248.243793905134;30421.391572168544;41470.79264603677;42289;42339.78830105853;44072.55927440725;44351;44409.001594928224;44412;44544;9639.56621952012;9825.115739583436;9842.458664773434;9844.114029737322;9883.90116098837;8761.123887611251;9007.54962251888;9032.14194651507;9175.49534775587;9189.989101198897;11703.765924681502;12029.157958942833;12060;12244.755104897897;12245.510179592806;12112.333821639806;12183.32991685457;12207.206531575464;12209.755804883853;12221.816672749916;8739.475631462132;11626.360550169737;11903.226290291102;12004;12034.398280086049;41642.37536436956;42018.94952626209;42047.159056819;42073.368899466535;42173;3484.163800687842;3530.046887340404;3531.9117022074397;3563.1804684922354;3660.2679464107287;31154.688453115406;31838.363232735446;32144.5178322323;32744;33313;2846.7722582193373;3177.539256807773;3206.6312374077047;3230.628477725045;3293.061477478931;506672.46663766814;508784;513589;514355.4282228602;517608;62194.68902655458;62781;62824;62900;63026;65708.34291657117;65754.0136897947;65855.01217481741;65866.67066664636;65926.67036664786;16318.75521867173;16855.157242137844;16864.156792160342;16865.747013794804;16875.24061417239;12932.159409638343;13056;13075.411606477732;13080.672983175307;13118.737625247442;14831.11013339203;15461.304241309168;16907.32370705158;17224.846894138234;17332.04673742931;6119.96940015306;7232.566046037255;7249.891251631229;7310.96344518274;7365.963170184222;956.9425834449955;986.9753256168653;1099.9945000274947;1112.8775834658181;1128.373752567333;30706.078817635505;30718;30944;31237.68762312348;31624.841875790473;1111.4220605285207;1169.6491052684162;1171.4318555500574;1218.0742635596832;1243.030436259719;15494.225288735519;15896.841031589767;16016.119113448873;16136;16272.51182464528;562818;626878;634026;637799;640613;51546.484535154166;59629.403705963254;60431.79136417247;60657;60667.69666151641;1413.9952600723464;1431.806706094664;1441.2001339256813;1442.8629280218363;1444.9711005779825;31501.684983149877;32089.67910320867;32407.51388729124;32776.60338225989;32993.67006330002;42458;52745;53478;53498;53511.732441337546;434714;446624.7668761765;532103.3394833001;584461;596811;39446.40830387604;39920.60079399169;40289;40297.597024030554;40762;46556;47564.52435475739;47564.762176188895;47596.76201619108;48187.518124819704;35246.82376588203;39484;39503;39576.60423395729;39643.60356396399;36763.26473470569;38003.42994855024;39150.80424597859;39529.407058893565;39881.800590996856;37714.622853771114;38280.04299892525;38535.6146438532;38576.80711596536;38594.80702596469;29177;33335.83324583628;34197.48703769396;35079;35218.94343169709;25528.23415297544;25528.617070744323;25862.870685646452;26031.21906342813;26131.08541201049 01:33:14 INFO - PROCESS | 1656 | |2;jquery.html;356.9857205711772;381.9293430715317;386.5651142464727;390.62109753539073;410.47664228109176;15570.688586228283;15699.293531791069;21213.68179477307;21214.151433942625;21709.891450542764;21233.68149477759;21813.67279490805;22196.0011799469;22258.443538911535;22283.55432891341;5205.297284866551;5554.694491802949;6013.909791353133;6033.064874944391;6045;4208.284591619432;4446.466424029121;5940.495057920082;6400.615963042209;6699.631520266384;6396.840078998016;6690.966545167267;6719.697613607399;6723.058771771955;6725.798226053226;6516.120323756303;6550.574212676161;6564.803055908283;6576.0793488911595;6576.605403675795;5068.543831055186;5388.245645609618;5390.083685773388;5771.942280577183;5778.913316300259;13392.665183370396;13494.122882012638;13605.251711155883;13635.863641363558;13701;2179.7820217978233;2241.9887900560557;2307.734610519795;2392.174699728591;2932.809367391134;10412.739681507946;14401.78397324041;15200.543983680507;15586.220688965506;15997.200139992954;1605.5263697209352;1861.5439217391827;1878.7275845002398;1924.6631839428044;1925.075963537496;111657;123107.76892231053;124178.37910810388;124338;126560;9791.363561368487;11163.330200187853;11339.886601133883;11349.94325028386;11425.771484570429;14473.348699308557;14586.4894728684;14611.415543378145;14617.976741628197;14651.560453186208;8482.872756908651;8605.569721514024;8667.17661822126;8776.648934042567;8781.692640757543;8639.049704532623;8645.740627781177;8651.783705407415;8752.824943501222;8770.122987701307;3884.1649045454797;4207.6633869291;4473.910521789611;4580.587747102777;4660.207764680013;1599.5441299229665;1834.2112891456534;1836.1645451319619;1838.7977322494514;1839.8068202838508;818.5620692929255;886.3529623374973;909.4997751236793;990.3661656539821;1006.642641862143;17217.311307547807;20384.592308153755;21072.630279031815;21760.455988600093;21861.234856779938;959.491469521155;968.9612415503301;981;992.1120597065586;1042.5412818359898;9320.953395233117;10319.380837149794;10774.084202842689;11402.258853174517;11457.026152777107;452110;575855.1207243938;583896;595759;603063;109242.81514369669;129817.8674695218;130943;132725;134654;888.2094935507404;991.4794732765356;994.6767300627325;1011.9848202276971;1017.7557386227401;16369;16387.672246554765;18711.625767484846;18785.872847629187;20363.389098327072;52283.477165229386;54971;56206.71896640491;57005.14492282702;57586;58979;77650.2234977643;79520.60239698764;80478;80901;8031.357491400557;8212.343012558862;8254.215849494289;8258.504489730636;8280.461769984931;12137.21108127969;12774.425150868048;12781.488740450277;12980.935095324463;13017.609471715863;11108.50011749457;11160.218784684994;11279.492422840827;11306.152038596974;11330.320180789178;10935.453227338443;10972.945135274273;11227.438628068727;11238.943805281247;11280.548778048786;10994.175436842428;11299.322040677256;11351.886481135083;11641.767164656829;11675.76648467009;8287.668493260202;8316.792080198044;8326.750197494084;8327.37544684139;8553.101924297858;11084.778304434027;11133.165012623924;11236.325820450798;11327.773444531229;11559.30644161353 01:33:14 INFO - PROCESS | 1656 | |3;mootools.html;1425.7148570285942;1563.0543521169695;1720.7762990811193;1856.368834596237;1895.5924476237606;8374.204450577194;8656.350773691982;8802.119788021195;8818.250448711859;8823.735287941365;7732.845343093135;7894.684212631502;8331.16688331168;8506.106858779833;8641.092685268035;2286.4626812699003;2327.5344931013865;2329.056732023533;2329.848559843613;2339.040993192791;2305.2853615379286;2306.3772781349026;2350.3654013416367;2351.47091904321;2352.9764702353013;2090.508730448351;2165.5344101018277;2167.9891600542055;2168.3928500019942;2211.07135003299;2119.459537817854;2280.977190228093;2343.6601692754625;2344.4959333743327;2357.858528488296;2323.7327707313707;2332.7433982261937;2338.0243694078476;2342.5314937012495;2342.941426464352;8967.551622418852;9033.277337813024;9041.050689677555;9043.819123617557;9044.186023257938;5179.249008893727;5298.841034768963;5343.251944727703;5356.035913535562;5373;3464.064702530328;3563.2695297464215;3634.963650363489;3642.3079614873354;3645.763025403367;1059.6450189186633;1082.5561519776854;1087.0488322717622;1088.2056099047738;1095.0746619106922;11857.5849845255;11863.822042669366;11872.643820685393;11889.702757431134;11891.643250702493;4476.597106260452;4527.886802829889;4726.8109275628585;4729.030548737501;4732.455767586772;10014.198864090855;10017.599296028224;10018.098371146632;10028.997100289913;10059;5330.520253177233;5342.9732851336275;5344.652597581145;5353.544948679406;5354.759035843396;4304.80628371724;4352.020795321027;4416.381706561084;4558.384618076518;4897.191963326011;3960.2871483133313;3971;3988.3618621020496;4036.253293140778;4080.367543030835;1227.9079069069676;1230.5385480444834;1267.8034904589804;1282.7498637765545;1286.0740267007739;905.35719639057;928.3176865004217;931.4923366765129;947.3747326764302;964.0889359555154;5799.449052340021;5816.709164541756;5878.2064421302575;6030.547708921761;6056.788012419544;430.8922769307673;449.2587231068763;459.63688685937734;463.0807846424844;936.6768464879606;3781.621837816196;4006.8797936061965;4018.1963607278662;4074.5925407459017;4087.1825634873144;10228.437435941094;10504.737381565523;10552.208584356204;10578.841317380091;10583.84124238137;10537.63118290856;10559.788804223872;10568.577256909792;10574.947125264325;10578.735531611772;7840;8214.548199849185;8220;8347.332213422911;8357.874631880526;5972.283326000907;6022;6053.243344581927;6082.239720034996;6138;9921.801563968824;10056.29605927578;10166.74583135398;10185.69442916684;10186.643467478603;8807.295416366547;9106.317026222927;9956.65585146025;10420.531076101744;10426.843597346198;6443.548951573345;7494.100707915085;7505.512141710771;7570.977917981002;7575;6934.687939042654;8114;8173.019237691515;8193.877091843508;8217;7393.112826460859;7439.702411903463;7527.134379546423;7558.735444259424;7613;7183.8563228736175;7284.562926224444;7339.706411743684;7348.559086454829;7421.8144546386775;6858.3827455529245;6983.266756990442;7042.823929401601;7056.223815380406;7059.5058345915295;5015.122353588181;5025.673331233458;5059.544640982329;5137.8972420550635;5149.279100925798;4449.354843547732;4683.274092515529;5061.670991385548;5215.322008138917;5245.554127899171 01:33:14 INFO - PROCESS | 1656 | |4;prototype.html;300.1445879244154;305.3374178033667;306.77758624996875;315.50623274575287;315.8657570532526;5491.720935811639;5511.283533140691;5521.806736764204;5538.833834984946;5557.499825015748;4764.404449443819;5034.993001399716;5045.672031317962;5436.483534064258;5469.371022332433;3527.3650742866275;3920.353141731618;3927.8821635350987;4144.901601075718;4167.770507700236;3816.740475643045;4069.8168582413864;4145.7719825409595;4152.37714342848;4169;4103.876883693494;4106.733062350952;4111.362738775495;4126.174765047002;4130.2978493656;3879.8571400690926;3886.591907849663;3904;4064.8862591452576;4119.382092686091;2946.720061594145;3520.5775306963073;3525.294941011808;3551.3430015447225;3594.19130695594;4373.322135069037;5319.893602127974;5362.410134885159;5371.167469042305;5381.434949330303;4274.5939135782055;4511.871243435364;4530.932036019462;4745.406824146981;4768.73771942543;5000.67495612784;5652.321721393418;5656.575756818255;5721.056025755745;5772.913406298909;3334.89854435375;3991.900202494932;3993.1614360983936;3995.5005624296964;4002.239574480839;5173.982146962688;5452.427495113036;5600.751564092817;5785.797497087582;6080.908786368208;3280.7703460757994;3529.6117427083;3967.940480892789;3972.106276087857;3974.841006359771;4048.1701251243426;4056.6957478188665;4072.572379900127;4076.8596486844644;4099.257815428431;4114.650254728322;4325.396627606161;4726.905461890812;4728.22691490691;4786.635808794477;4410.977945110254;5226.869328266824;5550.419055139379;5710.514606258432;5773.422657734277;1365.3342921635788;1387.8681525255085;1390.242317936727;1394.7907813828008;1395.741787769266;696.4289282788167;856.195176534056;856.6145234644434;857.6869442653511;874.8294082653821;4704.788284527204;4992.675476094042;5064.290999260033;5213.244079608511;5371.785128594813;5170.741462926839;5707.258056452634;5894.557908156819;5910.261217347832;5930.161649888549;4724.456687480915;4972.627052971042;4978.680649627851;5134.845954621367;5421.346489320795;2831.0515977147475;2862.8711707973193;2867.913962581126;3051.5727798108283;3121.219695076231;4915.754212289371;5027.053762796188;5631.436856314418;5953.1070339449425;6142.600730952563;5297.894042119213;5407.648502847303;5422.75597598109;5533.063427800238;6154.476869466056;5368.973155134199;6145.985912324416;6152.446734605178;6164.815055548341;6196.907046394398;5682.5453963681975;5940.6435613863305;5953.821385358447;5969.52243820502;5975.342712301556;5330.401978664464;5837.7157025454335;5919.289685237799;5986.521078313812;5987.191729116514;5171.689698618095;5580.051391263414;6004.039353703386;6223.502119830493;6251.937480625135;3225.70968612826;3935.3864915384556;3956.8219430125137;3995.4206640037614;4014.116894283253;5169.327987361619;5877;5931.377205393372;5936.673482958306;5941.376155503783;4912.017596480647;5813.215215945962;5828.970855145867;5833.212516310414;5843.561732870136;3967.5635680075748;5172.224166375074;5474.09677403224;5762.567807414377;5764.7405866736935;4961.593627090786;5147.845564633067;5321.6540924839765;5647.265855438931;5656.37779844225;5490.423505531861;5585.146249088116;5695.829125126253;5744.195812586325;5791.971040144941;5586.581006424453;5615.108219266999;5617.320654971;5617.88764224705;5630.4369563043365 01:33:14 INFO - PROCESS | 1656 | |5;yui.html;743.6988019851959;826.2241556088674;845.3701992015949;853.5689476814208;859.8022454835387;14943.701125977488;16331.34674613014;16339.591510212236;16406.343746250164;17294.481165565052;16323.265453054611;17181.484555463325;17205;17225.397111101112;17501.474955751317;695.4436450839333;878.3236907581158;897.3718397122008;910.8087301666658;910.8360495110877;703.9507234493587;871.2594294849391;899.4513346858411;900.7838118851462;901.3735453859571;924.4915296586884;926.9212116970066;927.0544045074021;929.4980710416401;929.6327950459564;722.8120688620944;924.306769922558;924.3298608508811;924.5885580916495;926.0970553710146;550.1307933465115;675.5203805298244;695.0824911117327;695.2803848017328;696.8919817428307;15524.767128493082;15531.534053978286;15582.766258506132;15615.92192039044;15618.219089045502;13675.247861367621;13937.303134843218;13952.441902323895;13968.93015534926;14047.367868445945;13123.934380328134;13204.009699272592;13462.124961877447;13614.795778063337;13834;4501.909961800746;4577.473590537097;4605.746683932382;4605.838795642137;4788.233882578806;111191;111559.44220278847;111956;112191.87808121897;113109.86890131075;11010.889891100986;11087.944560277147;11103.44482775858;11112.611058612909;11538.711532211593;21362.679559806616;21474.463138421354;21535.56928861414;21555.89222053911;21674.891625542088;7883.645235964395;7895.7236496722335;7985.680572777024;8077.7576672699915;8118.715844945516;4406.691531592822;4584.839530616412;4608.170529304757;4750.121227572841;4789.544993225638;3422.418188907892;3558.448440491728;3558.5729712434672;3563.376409128392;3564.2515071835205;642.065794269337;660.8909529927604;669.1568623534382;669.9062131301622;671.6540981394586;577.5495113811234;664.9035889795953;677.928817474168;692.1002696494537;698.7798843364426;9446.38598491096;9517.476538790434;9546.713598592054;9628.614855405845;9658.082482164184;664.4252721395958;688.8117996456173;693.6254422611734;698.8113209433425;710.0662628643317;4443.311286750559;4548.31775233711;4696.459907110658;4703.106409782131;4763.904721905543;92241.53879230561;122188.38905805592;147081.26459367634;149382.25308873385;155429;108976;112071;123127.38436307761;126046.36976815057;127961;75059;87967.12032879717;89102;106163.46918265669;108374;8716.607752651273;9075.047120052299;9210.585523651585;9266.259475160568;9351.672691456039;63510;64142.67928660327;65480.34519654742;65940.68118637697;66488.66755666191;86597.56701216454;93437;99531.00468995217;100593;103189;11645.47595358194;11756.018752539901;11772.411379430823;11958.521659133537;12067.57763478274;13327.623296948621;13453.596392108253;13523.459061637424;13705.246211458465;13762.105463144864;12193.573224937085;12539.37303134858;12894.097413180987;12913.935430322788;12961.546345877849;11883.287002779862;12398.265990674385;12401;12531.122821402414;12986.35068246565;11898.643040708792;12121.212121212093;12375.69060773488;12648.620541383772;12676.302803345536;9358.486814306556;9977.917479011589;10100.747481313027;10122.79754404893;10534.051935325857;9004.729858104267;9548.952255238679;10571.682849514527;10670.46647667629;10674.679759607225 01:33:14 INFO - PROCESS | 1656 | __end_tp_report 01:33:14 INFO - PROCESS | 1656 | __start_cc_report 01:33:14 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1140 01:33:14 INFO - PROCESS | 1656 | __end_cc_report 01:33:14 INFO - PROCESS | 1656 | __startTimestamp1468917194090__endTimestamp 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | ------- Summary: start ------- 01:33:14 INFO - PROCESS | 1656 | Number of tests: 6 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5807.67 Median:6096.80 stddev:647.27 (10.6%) stddev-sans-first:620.40 01:33:14 INFO - PROCESS | 1656 | Values: 3371.2 3378.2 3392.3 3399.4 3447.1 5724.0 5758.3 5805.6 5823.5 5857.6 5872.7 5873.8 5981.1 6009.9 6023.3 5196.7 5217.2 5274.5 5286.4 5433.9 5391.9 5430.4 5433.9 5437.4 5474.7 5089.2 5105.3 5134.8 5197.7 5293.4 5165.2 5182.4 5218.3 5306.2 5336.2 5252.2 5260.2 5267.7 5267.8 5289.7 5945.9 6030.7 6038.5 6043.6 6072.5 6130.9 6161.7 6183.8 6195.3 6201.3 5671.8 6302.2 6309.4 6323.3 6336.2 6055.1 6065.4 6069.7 6076.1 6122.7 6154.9 6213.3 6285.2 6326.6 6345.6 6178.8 6249.5 6260.9 6266.5 6280.4 6276.6 6291.8 6294.9 6301.4 6305.9 6150.2 6156.5 6215.3 6228.1 6242.3 6149.7 6198.7 6203.5 6205.8 6213.7 4993.1 4996.3 4999.1 4999.9 5016.5 4378.8 4412.8 4455.4 4461.7 4514.0 4965.7 5954.8 5954.8 5968.2 6024.2 6151.4 6231.2 6248.4 6262.6 6304.0 5815.9 5871.6 5875.0 5996.9 6055.9 5486.7 5501.8 5508.0 5511.9 5514.0 6466.5 6476.0 6483.9 6487.1 6489.6 6096.8 6143.4 6196.7 6198.7 6218.0 6269.4 6337.6 6345.1 6408.6 6423.3 6159.6 6219.3 6239.0 6251.7 6256.3 6155.4 6158.7 6239.6 6247.9 6257.8 6113.5 6137.0 6185.2 6215.1 6232.0 5966.4 6190.9 6218.7 6229.4 6232.2 5970.0 6002.9 6094.3 6140.4 6144.4 5143.8 6138.6 6173.1 6203.3 6211.3 5097.5 5132.8 6133.3 6148.2 6160.9 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:69616.21 Median:38585.81 stddev:147784.36 (383.0%) stddev-sans-first:148107.85 01:33:14 INFO - PROCESS | 1656 | Values: 506.8 515.5 518.5 529.5 546.3 30248.2 30421.4 41470.8 42289.0 42339.8 44072.6 44351.0 44409.0 44412.0 44544.0 9639.6 9825.1 9842.5 9844.1 9883.9 8761.1 9007.5 9032.1 9175.5 9190.0 11703.8 12029.2 12060.0 12244.8 12245.5 12112.3 12183.3 12207.2 12209.8 12221.8 8739.5 11626.4 11903.2 12004.0 12034.4 41642.4 42018.9 42047.2 42073.4 42173.0 3484.2 3530.0 3531.9 3563.2 3660.3 31154.7 31838.4 32144.5 32744.0 33313.0 2846.8 3177.5 3206.6 3230.6 3293.1 506672.5 508784.0 513589.0 514355.4 517608.0 62194.7 62781.0 62824.0 62900.0 63026.0 65708.3 65754.0 65855.0 65866.7 65926.7 16318.8 16855.2 16864.2 16865.7 16875.2 12932.2 13056.0 13075.4 13080.7 13118.7 14831.1 15461.3 16907.3 17224.8 17332.0 6120.0 7232.6 7249.9 7311.0 7366.0 956.9 987.0 1100.0 1112.9 1128.4 30706.1 30718.0 30944.0 31237.7 31624.8 1111.4 1169.6 1171.4 1218.1 1243.0 15494.2 15896.8 16016.1 16136.0 16272.5 562818.0 626878.0 634026.0 637799.0 640613.0 51546.5 59629.4 60431.8 60657.0 60667.7 1414.0 1431.8 1441.2 1442.9 1445.0 31501.7 32089.7 32407.5 32776.6 32993.7 42458.0 52745.0 53478.0 53498.0 53511.7 434714.0 446624.8 532103.3 584461.0 596811.0 39446.4 39920.6 40289.0 40297.6 40762.0 46556.0 47564.5 47564.8 47596.8 48187.5 35246.8 39484.0 39503.0 39576.6 39643.6 36763.3 38003.4 39150.8 39529.4 39881.8 37714.6 38280.0 38535.6 38576.8 38594.8 29177.0 33335.8 34197.5 35079.0 35218.9 25528.2 25528.6 25862.9 26031.2 26131.1 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33939.68 Median:22227.22 stddev:94746.57 (426.3%) stddev-sans-first:94978.80 01:33:14 INFO - PROCESS | 1656 | Values: 357.0 381.9 386.6 390.6 410.5 15570.7 15699.3 21213.7 21214.2 21709.9 21233.7 21813.7 22196.0 22258.4 22283.6 5205.3 5554.7 6013.9 6033.1 6045.0 4208.3 4446.5 5940.5 6400.6 6699.6 6396.8 6691.0 6719.7 6723.1 6725.8 6516.1 6550.6 6564.8 6576.1 6576.6 5068.5 5388.2 5390.1 5771.9 5778.9 13392.7 13494.1 13605.3 13635.9 13701.0 2179.8 2242.0 2307.7 2392.2 2932.8 10412.7 14401.8 15200.5 15586.2 15997.2 1605.5 1861.5 1878.7 1924.7 1925.1 111657.0 123107.8 124178.4 124338.0 126560.0 9791.4 11163.3 11339.9 11349.9 11425.8 14473.3 14586.5 14611.4 14618.0 14651.6 8482.9 8605.6 8667.2 8776.6 8781.7 8639.0 8645.7 8651.8 8752.8 8770.1 3884.2 4207.7 4473.9 4580.6 4660.2 1599.5 1834.2 1836.2 1838.8 1839.8 818.6 886.4 909.5 990.4 1006.6 17217.3 20384.6 21072.6 21760.5 21861.2 959.5 969.0 981.0 992.1 1042.5 9321.0 10319.4 10774.1 11402.3 11457.0 452110.0 575855.1 583896.0 595759.0 603063.0 109242.8 129817.9 130943.0 132725.0 134654.0 888.2 991.5 994.7 1012.0 1017.8 16369.0 16387.7 18711.6 18785.9 20363.4 52283.5 54971.0 56206.7 57005.1 57586.0 58979.0 77650.2 79520.6 80478.0 80901.0 8031.4 8212.3 8254.2 8258.5 8280.5 12137.2 12774.4 12781.5 12980.9 13017.6 11108.5 11160.2 11279.5 11306.2 11330.3 10935.5 10972.9 11227.4 11238.9 11280.5 10994.2 11299.3 11351.9 11641.8 11675.8 8287.7 8316.8 8326.8 8327.4 8553.1 11084.8 11133.2 11236.3 11327.8 11559.3 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5675.99 Median:461.36 stddev:3202.30 (694.1%) stddev-sans-first:3195.35 01:33:14 INFO - PROCESS | 1656 | Values: 1425.7 1563.1 1720.8 1856.4 1895.6 8374.2 8656.4 8802.1 8818.3 8823.7 7732.8 7894.7 8331.2 8506.1 8641.1 2286.5 2327.5 2329.1 2329.8 2339.0 2305.3 2306.4 2350.4 2351.5 2353.0 2090.5 2165.5 2168.0 2168.4 2211.1 2119.5 2281.0 2343.7 2344.5 2357.9 2323.7 2332.7 2338.0 2342.5 2342.9 8967.6 9033.3 9041.1 9043.8 9044.2 5179.2 5298.8 5343.3 5356.0 5373.0 3464.1 3563.3 3635.0 3642.3 3645.8 1059.6 1082.6 1087.0 1088.2 1095.1 11857.6 11863.8 11872.6 11889.7 11891.6 4476.6 4527.9 4726.8 4729.0 4732.5 10014.2 10017.6 10018.1 10029.0 10059.0 5330.5 5343.0 5344.7 5353.5 5354.8 4304.8 4352.0 4416.4 4558.4 4897.2 3960.3 3971.0 3988.4 4036.3 4080.4 1227.9 1230.5 1267.8 1282.7 1286.1 905.4 928.3 931.5 947.4 964.1 5799.4 5816.7 5878.2 6030.5 6056.8 430.9 449.3 459.6 463.1 936.7 3781.6 4006.9 4018.2 4074.6 4087.2 10228.4 10504.7 10552.2 10578.8 10583.8 10537.6 10559.8 10568.6 10574.9 10578.7 7840.0 8214.5 8220.0 8347.3 8357.9 5972.3 6022.0 6053.2 6082.2 6138.0 9921.8 10056.3 10166.7 10185.7 10186.6 8807.3 9106.3 9956.7 10420.5 10426.8 6443.5 7494.1 7505.5 7571.0 7575.0 6934.7 8114.0 8173.0 8193.9 8217.0 7393.1 7439.7 7527.1 7558.7 7613.0 7183.9 7284.6 7339.7 7348.6 7421.8 6858.4 6983.3 7042.8 7056.2 7059.5 5015.1 5025.7 5059.5 5137.9 5149.3 4449.4 4683.3 5061.7 5215.3 5245.6 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4603.41 Median:5171.22 stddev:1428.78 (27.6%) stddev-sans-first:1395.81 01:33:14 INFO - PROCESS | 1656 | Values: 300.1 305.3 306.8 315.5 315.9 5491.7 5511.3 5521.8 5538.8 5557.5 4764.4 5035.0 5045.7 5436.5 5469.4 3527.4 3920.4 3927.9 4144.9 4167.8 3816.7 4069.8 4145.8 4152.4 4169.0 4103.9 4106.7 4111.4 4126.2 4130.3 3879.9 3886.6 3904.0 4064.9 4119.4 2946.7 3520.6 3525.3 3551.3 3594.2 4373.3 5319.9 5362.4 5371.2 5381.4 4274.6 4511.9 4530.9 4745.4 4768.7 5000.7 5652.3 5656.6 5721.1 5772.9 3334.9 3991.9 3993.2 3995.5 4002.2 5174.0 5452.4 5600.8 5785.8 6080.9 3280.8 3529.6 3967.9 3972.1 3974.8 4048.2 4056.7 4072.6 4076.9 4099.3 4114.7 4325.4 4726.9 4728.2 4786.6 4411.0 5226.9 5550.4 5710.5 5773.4 1365.3 1387.9 1390.2 1394.8 1395.7 696.4 856.2 856.6 857.7 874.8 4704.8 4992.7 5064.3 5213.2 5371.8 5170.7 5707.3 5894.6 5910.3 5930.2 4724.5 4972.6 4978.7 5134.8 5421.3 2831.1 2862.9 2867.9 3051.6 3121.2 4915.8 5027.1 5631.4 5953.1 6142.6 5297.9 5407.6 5422.8 5533.1 6154.5 5369.0 6146.0 6152.4 6164.8 6196.9 5682.5 5940.6 5953.8 5969.5 5975.3 5330.4 5837.7 5919.3 5986.5 5987.2 5171.7 5580.1 6004.0 6223.5 6251.9 3225.7 3935.4 3956.8 3995.4 4014.1 5169.3 5877.0 5931.4 5936.7 5941.4 4912.0 5813.2 5829.0 5833.2 5843.6 3967.6 5172.2 5474.1 5762.6 5764.7 4961.6 5147.8 5321.7 5647.3 5656.4 5490.4 5585.1 5695.8 5744.2 5792.0 5586.6 5615.1 5617.3 5617.9 5630.4 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:23975.12 Median:4472.61 stddev:37408.31 (836.4%) stddev-sans-first:37472.58 01:33:14 INFO - PROCESS | 1656 | Values: 743.7 826.2 845.4 853.6 859.8 14943.7 16331.3 16339.6 16406.3 17294.5 16323.3 17181.5 17205.0 17225.4 17501.5 695.4 878.3 897.4 910.8 910.8 704.0 871.3 899.5 900.8 901.4 924.5 926.9 927.1 929.5 929.6 722.8 924.3 924.3 924.6 926.1 550.1 675.5 695.1 695.3 696.9 15524.8 15531.5 15582.8 15615.9 15618.2 13675.2 13937.3 13952.4 13968.9 14047.4 13123.9 13204.0 13462.1 13614.8 13834.0 4501.9 4577.5 4605.7 4605.8 4788.2 111191.0 111559.4 111956.0 112191.9 113109.9 11010.9 11087.9 11103.4 11112.6 11538.7 21362.7 21474.5 21535.6 21555.9 21674.9 7883.6 7895.7 7985.7 8077.8 8118.7 4406.7 4584.8 4608.2 4750.1 4789.5 3422.4 3558.4 3558.6 3563.4 3564.3 642.1 660.9 669.2 669.9 671.7 577.5 664.9 677.9 692.1 698.8 9446.4 9517.5 9546.7 9628.6 9658.1 664.4 688.8 693.6 698.8 710.1 4443.3 4548.3 4696.5 4703.1 4763.9 92241.5 122188.4 147081.3 149382.3 155429.0 108976.0 112071.0 123127.4 126046.4 127961.0 75059.0 87967.1 89102.0 106163.5 108374.0 8716.6 9075.0 9210.6 9266.3 9351.7 63510.0 64142.7 65480.3 65940.7 66488.7 86597.6 93437.0 99531.0 100593.0 103189.0 11645.5 11756.0 11772.4 11958.5 12067.6 13327.6 13453.6 13523.5 13705.2 13762.1 12193.6 12539.4 12894.1 12913.9 12961.5 11883.3 12398.3 12401.0 12531.1 12986.4 11898.6 12121.2 12375.7 12648.6 12676.3 9358.5 9977.9 10100.7 10122.8 10534.1 9004.7 9549.0 10571.7 10670.5 10674.7 01:33:14 INFO - PROCESS | 1656 | -------- Summary: end -------- 01:33:14 INFO - PROCESS | 1656 | 01:33:14 INFO - TEST-INFO | 1656: exit 0 01:33:14 INFO - TEST-OK | dromaeo_css | took 1116513ms 01:33:14 INFO - TEST-START | kraken 01:33:14 INFO - operating with platform_type : mac_ 01:33:14 INFO - Initialising browser for kraken test... 01:33:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmp5YfXPz/profile http://localhost:49265/getInfo.html) 01:33:16 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 01:33:16 INFO - PROCESS | 1662 | colorDepth:24 01:33:16 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 01:33:16 INFO - PROCESS | 1662 | __metrics 01:33:17 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:33:17 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:33:17 INFO - TEST-INFO | 1662: exit 0 01:33:17 INFO - Browser initialized. 01:33:17 INFO - Running cycle 1/1 for kraken test... 01:33:17 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmp5YfXPz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:33:29 INFO - PROCESS | 1663 | RSS: Main: 245096448 01:33:29 INFO - PROCESS | 1663 | 01:33:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 01:33:33 INFO - PROCESS | 1663 | RSS: Main: 294273024 01:33:33 INFO - PROCESS | 1663 | 01:33:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 01:33:36 INFO - PROCESS | 1663 | RSS: Main: 304971776 01:33:36 INFO - PROCESS | 1663 | 01:33:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 01:33:41 INFO - PROCESS | 1663 | RSS: Main: 387620864 01:33:41 INFO - PROCESS | 1663 | 01:33:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 01:33:43 INFO - PROCESS | 1663 | RSS: Main: 292388864 01:33:43 INFO - PROCESS | 1663 | 01:33:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:33:46 INFO - PROCESS | 1663 | RSS: Main: 296116224 01:33:46 INFO - PROCESS | 1663 | 01:33:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 01:33:50 INFO - PROCESS | 1663 | RSS: Main: 333996032 01:33:50 INFO - PROCESS | 1663 | 01:33:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 01:33:53 INFO - PROCESS | 1663 | RSS: Main: 335073280 01:33:53 INFO - PROCESS | 1663 | 01:33:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 01:33:56 INFO - PROCESS | 1663 | RSS: Main: 333443072 01:33:56 INFO - PROCESS | 1663 | 01:33:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:33:59 INFO - PROCESS | 1663 | RSS: Main: 311934976 01:33:59 INFO - PROCESS | 1663 | 01:34:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:34:01 INFO - PROCESS | 1663 | RSS: Main: 318865408 01:34:01 INFO - PROCESS | 1663 | 01:34:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:34:04 INFO - PROCESS | 1663 | RSS: Main: 318390272 01:34:04 INFO - PROCESS | 1663 | 01:34:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:34:07 INFO - PROCESS | 1663 | RSS: Main: 326176768 01:34:07 INFO - PROCESS | 1663 | 01:34:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:34:11 INFO - PROCESS | 1663 | RSS: Main: 328769536 01:34:11 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 01:34:13 INFO - PROCESS | 1663 | RSS: Main: 371875840 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | __start_tp_report 01:34:13 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 01:34:13 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 01:34:13 INFO - PROCESS | 1663 | |i|pagename|runs| 01:34:13 INFO - PROCESS | 1663 | |0;ai-astar;98;112;113;114;113;113;112;111;113;113 01:34:13 INFO - PROCESS | 1663 | |1;audio-beat-detection;143;113;106;154;108;136;157;110;161;107 01:34:13 INFO - PROCESS | 1663 | |2;audio-dft;185;186;182;183;184;181;268;245;182;181 01:34:13 INFO - PROCESS | 1663 | |3;audio-fft;73;80;57;86;57;57;65;63;83;74 01:34:13 INFO - PROCESS | 1663 | |4;audio-oscillator;84;91;92;72;71;68;97;70;68;101 01:34:13 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;91;88;92;88;92;88;91;90;90;88 01:34:13 INFO - PROCESS | 1663 | |6;imaging-darkroom;97;100;96;96;97;96;98;97;96;96 01:34:13 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;86;86;84;84;86;83;83;84;84 01:34:13 INFO - PROCESS | 1663 | |8;json-parse-financial;56;54;55;54;56;54;54;54;55;52 01:34:13 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;69;80;78;74;73;70;69;72;71 01:34:13 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;82;84;85;83;83;80;82;85;85;85 01:34:13 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;137;134;129;123;152;123;144;143;241;134 01:34:13 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;164;165;164;164;164;163;164;162;215;201 01:34:13 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;59;65;82;68;65;68;65;66;65;70 01:34:13 INFO - PROCESS | 1663 | __end_tp_report 01:34:13 INFO - PROCESS | 1663 | __start_cc_report 01:34:13 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2060 01:34:13 INFO - PROCESS | 1663 | __end_cc_report 01:34:13 INFO - PROCESS | 1663 | __startTimestamp1468917253452__endTimestamp 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | ------- Summary: start ------- 01:34:13 INFO - PROCESS | 1663 | Number of tests: 14 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:4.71 (4.2%) stddev-sans-first:0.87 01:34:13 INFO - PROCESS | 1663 | Values: 98.0 112.0 113.0 114.0 113.0 113.0 112.0 111.0 113.0 113.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:139.50 stddev:22.96 (16.5%) stddev-sans-first:23.83 01:34:13 INFO - PROCESS | 1663 | Values: 143.0 113.0 106.0 154.0 108.0 136.0 157.0 110.0 161.0 107.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:197.70 Median:184.50 stddev:31.50 (17.1%) stddev-sans-first:33.08 01:34:13 INFO - PROCESS | 1663 | Values: 185.0 186.0 182.0 183.0 184.0 181.0 268.0 245.0 182.0 181.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:69.50 Median:73.50 stddev:11.20 (15.2%) stddev-sans-first:11.81 01:34:13 INFO - PROCESS | 1663 | Values: 73.0 80.0 57.0 86.0 57.0 57.0 65.0 63.0 83.0 74.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:78.00 stddev:13.01 (16.7%) stddev-sans-first:13.77 01:34:13 INFO - PROCESS | 1663 | Values: 84.0 91.0 92.0 72.0 71.0 68.0 97.0 70.0 68.0 101.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:90.50 stddev:1.69 (1.9%) stddev-sans-first:1.73 01:34:13 INFO - PROCESS | 1663 | Values: 91.0 88.0 92.0 88.0 92.0 88.0 91.0 90.0 90.0 88.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.50 stddev:1.29 (1.3%) stddev-sans-first:1.36 01:34:13 INFO - PROCESS | 1663 | Values: 97.0 100.0 96.0 96.0 97.0 96.0 98.0 97.0 96.0 96.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.24 01:34:13 INFO - PROCESS | 1663 | Values: 86.0 86.0 86.0 84.0 84.0 86.0 83.0 83.0 84.0 84.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.17 (2.2%) stddev-sans-first:1.09 01:34:13 INFO - PROCESS | 1663 | Values: 56.0 54.0 55.0 54.0 56.0 54.0 54.0 54.0 55.0 52.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:3.98 (5.5%) stddev-sans-first:3.89 01:34:13 INFO - PROCESS | 1663 | Values: 68.0 69.0 80.0 78.0 74.0 73.0 70.0 69.0 72.0 71.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.50 stddev:1.71 (2.0%) stddev-sans-first:1.74 01:34:13 INFO - PROCESS | 1663 | Values: 82.0 84.0 85.0 83.0 83.0 80.0 82.0 85.0 85.0 85.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:140.00 stddev:34.62 (24.7%) stddev-sans-first:36.57 01:34:13 INFO - PROCESS | 1663 | Values: 137.0 134.0 129.0 123.0 152.0 123.0 144.0 143.0 241.0 134.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:164.00 stddev:18.96 (11.6%) stddev-sans-first:19.86 01:34:13 INFO - PROCESS | 1663 | Values: 164.0 165.0 164.0 164.0 164.0 163.0 164.0 162.0 215.0 201.0 01:34:13 INFO - PROCESS | 1663 | 01:34:13 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:5.93 (8.8%) stddev-sans-first:5.47 01:34:13 INFO - PROCESS | 1663 | Values: 59.0 65.0 82.0 68.0 65.0 68.0 65.0 66.0 65.0 70.0 01:34:13 INFO - PROCESS | 1663 | -------- Summary: end -------- 01:34:13 INFO - PROCESS | 1663 | 01:34:14 INFO - TEST-INFO | 1663: exit 0 01:34:14 INFO - TEST-OK | kraken | took 59530ms 01:34:14 INFO - SUITE-END | took 1176s 01:34:14 INFO - Completed test suite (00:19:36) 01:34:14 INFO - javascript benchmark 01:34:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3371.2246183377806, 3378.1892345837, 3392.321535692859, 3399.3881101401744, 3447.1209841490418, 5724.0, 5758.251427314453, 5805.564582656307, 5823.5341172706185, 5857.619254748443, 5872.70636468176, 5873.823785286449, 5981.1028345748055, 6009.9399006010035, 6023.337432882379, 5196.662216955886, 5217.243499692542, 5274.498922602346, 5286.4449232830575, 5433.891322173554, 5391.892162156754, 5430.402655707889, 5433.945660543383, 5437.374701909292, 5474.671519708811, 5089.160288552403, 5105.285260063594, 5134.794608215667, 5197.66215196011, 5293.417724050351, 5165.199394093884, 5182.378114626231, 5218.269442278046, 5306.177542480922, 5336.226247194174, 5252.212168174766, 5260.210968354778, 5267.736613169364, 5267.841964741064, 5289.68261904287, 5945.940540594582, 6030.728617212236, 6038.486728628071, 6043.60716553427, 6072.483838873701, 6130.938690613081, 6161.7227224775, 6183.845403864894, 6195.256569211723, 6201.255849298113, 5671.837273358951, 6302.243730752293, 6309.3690630937, 6323.30443651198, 6336.176297081396, 6055.12200730896, 6065.361294711772, 6069.721115537848, 6076.118962750376, 6122.663253521099, 6154.938450615526, 6213.316535181127, 6285.245770507477, 6326.588771729915, 6345.587536810092, 6178.845528861814, 6249.468795152463, 6260.937390626035, 6266.530010249158, 6280.434760871452, 6276.623402595859, 6291.779787707408, 6294.874102517924, 6301.40136687014, 6305.905411418832, 6150.200473938449, 6156.476699480594, 6215.316315205324, 6228.065790131426, 6242.282137554149, 6149.723262453201, 6198.690065496708, 6203.503719702413, 6205.81382558524, 6213.689315534205, 4993.101241776515, 4996.250562415594, 4999.10016197088, 4999.875003124878, 5016.5485106339875, 4378.846740364135, 4412.757298348557, 4455.420795296655, 4461.687681862303, 4513.977430112828, 4965.652404331662, 5954.76180952757, 5954.791582294599, 5968.194293770285, 6024.216851809324, 6151.384861513813, 6231.158793562901, 6248.375162483715, 6262.5616206866, 6303.9684801575695, 5815.912761308584, 5871.647701137946, 5874.970625146932, 5996.850078747979, 6055.909161362583, 5486.725663716798, 5501.752421141058, 5508.0, 5511.889762204733, 5514.007478653881, 6466.482681385572, 6476.0, 6483.902741458787, 6487.059376390397, 6489.5781774184525, 6096.79883341448, 6143.389059649542, 6196.690165491797, 6198.72105755233, 6217.96891015542, 6269.373062693694, 6337.6197428154455, 6345.111684364285, 6408.61548307103, 6423.29343772194, 6159.630422174684, 6219.253689557282, 6239.0, 6251.71867265965, 6256.280527739368, 6155.415235552616, 6158.661273629993, 6239.563230573816, 6247.875042499216, 6257.843553911189, 6113.537117903944, 6137.039921714347, 6185.165002724754, 6215.129881816459, 6232.0, 5966.433188847052, 6190.907136393048, 6218.720157592828, 6229.408206220402, 6232.158658581034, 5969.970150149222, 6002.909956350571, 6094.299155596986, 6140.3552626975315, 6144.385561443999, 5143.819966301162, 6138.6366248995355, 6173.135760993554, 6203.2556093269095, 6211.3167551570195, 5097.515736005073, 5132.820351287687, 6133.294671112879, 6148.200733904564, 6160.876782464416], "value": 5766.754343026674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.8418663354234, 515.4664921805931, 518.548862489634, 529.5498825997901, 546.2570903571143, 30248.243793905134, 30421.391572168544, 41470.79264603677, 42289.0, 42339.78830105853, 44072.55927440725, 44351.0, 44409.001594928224, 44412.0, 44544.0, 9639.56621952012, 9825.115739583436, 9842.458664773434, 9844.114029737322, 9883.90116098837, 8761.123887611251, 9007.54962251888, 9032.14194651507, 9175.49534775587, 9189.989101198897, 11703.765924681502, 12029.157958942833, 12060.0, 12244.755104897897, 12245.510179592806, 12112.333821639806, 12183.32991685457, 12207.206531575464, 12209.755804883853, 12221.816672749916, 8739.475631462132, 11626.360550169737, 11903.226290291102, 12004.0, 12034.398280086049, 41642.37536436956, 42018.94952626209, 42047.159056819, 42073.368899466535, 42173.0, 3484.163800687842, 3530.046887340404, 3531.9117022074397, 3563.1804684922354, 3660.2679464107287, 31154.688453115406, 31838.363232735446, 32144.5178322323, 32744.0, 33313.0, 2846.7722582193373, 3177.539256807773, 3206.6312374077047, 3230.628477725045, 3293.061477478931, 506672.46663766814, 508784.0, 513589.0, 514355.4282228602, 517608.0, 62194.68902655458, 62781.0, 62824.0, 62900.0, 63026.0, 65708.34291657117, 65754.0136897947, 65855.01217481741, 65866.67066664636, 65926.67036664786, 16318.75521867173, 16855.157242137844, 16864.156792160342, 16865.747013794804, 16875.24061417239, 12932.159409638343, 13056.0, 13075.411606477732, 13080.672983175307, 13118.737625247442, 14831.11013339203, 15461.304241309168, 16907.32370705158, 17224.846894138234, 17332.04673742931, 6119.96940015306, 7232.566046037255, 7249.891251631229, 7310.96344518274, 7365.963170184222, 956.9425834449955, 986.9753256168653, 1099.9945000274947, 1112.8775834658181, 1128.373752567333, 30706.078817635505, 30718.0, 30944.0, 31237.68762312348, 31624.841875790473, 1111.4220605285207, 1169.6491052684162, 1171.4318555500574, 1218.0742635596832, 1243.030436259719, 15494.225288735519, 15896.841031589767, 16016.119113448873, 16136.0, 16272.51182464528, 562818.0, 626878.0, 634026.0, 637799.0, 640613.0, 51546.484535154166, 59629.403705963254, 60431.79136417247, 60657.0, 60667.69666151641, 1413.9952600723464, 1431.806706094664, 1441.2001339256813, 1442.8629280218363, 1444.9711005779825, 31501.684983149877, 32089.67910320867, 32407.51388729124, 32776.60338225989, 32993.67006330002, 42458.0, 52745.0, 53478.0, 53498.0, 53511.732441337546, 434714.0, 446624.7668761765, 532103.3394833001, 584461.0, 596811.0, 39446.40830387604, 39920.60079399169, 40289.0, 40297.597024030554, 40762.0, 46556.0, 47564.52435475739, 47564.762176188895, 47596.76201619108, 48187.518124819704, 35246.82376588203, 39484.0, 39503.0, 39576.60423395729, 39643.60356396399, 36763.26473470569, 38003.42994855024, 39150.80424597859, 39529.407058893565, 39881.800590996856, 37714.622853771114, 38280.04299892525, 38535.6146438532, 38576.80711596536, 38594.80702596469, 29177.0, 33335.83324583628, 34197.48703769396, 35079.0, 35218.94343169709, 25528.23415297544, 25528.617070744323, 25862.870685646452, 26031.21906342813, 26131.08541201049], "value": 20775.59120329519, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.9857205711772, 381.9293430715317, 386.5651142464727, 390.62109753539073, 410.47664228109176, 15570.688586228283, 15699.293531791069, 21213.68179477307, 21214.151433942625, 21709.891450542764, 21233.68149477759, 21813.67279490805, 22196.0011799469, 22258.443538911535, 22283.55432891341, 5205.297284866551, 5554.694491802949, 6013.909791353133, 6033.064874944391, 6045.0, 4208.284591619432, 4446.466424029121, 5940.495057920082, 6400.615963042209, 6699.631520266384, 6396.840078998016, 6690.966545167267, 6719.697613607399, 6723.058771771955, 6725.798226053226, 6516.120323756303, 6550.574212676161, 6564.803055908283, 6576.0793488911595, 6576.605403675795, 5068.543831055186, 5388.245645609618, 5390.083685773388, 5771.942280577183, 5778.913316300259, 13392.665183370396, 13494.122882012638, 13605.251711155883, 13635.863641363558, 13701.0, 2179.7820217978233, 2241.9887900560557, 2307.734610519795, 2392.174699728591, 2932.809367391134, 10412.739681507946, 14401.78397324041, 15200.543983680507, 15586.220688965506, 15997.200139992954, 1605.5263697209352, 1861.5439217391827, 1878.7275845002398, 1924.6631839428044, 1925.075963537496, 111657.0, 123107.76892231053, 124178.37910810388, 124338.0, 126560.0, 9791.363561368487, 11163.330200187853, 11339.886601133883, 11349.94325028386, 11425.771484570429, 14473.348699308557, 14586.4894728684, 14611.415543378145, 14617.976741628197, 14651.560453186208, 8482.872756908651, 8605.569721514024, 8667.17661822126, 8776.648934042567, 8781.692640757543, 8639.049704532623, 8645.740627781177, 8651.783705407415, 8752.824943501222, 8770.122987701307, 3884.1649045454797, 4207.6633869291, 4473.910521789611, 4580.587747102777, 4660.207764680013, 1599.5441299229665, 1834.2112891456534, 1836.1645451319619, 1838.7977322494514, 1839.8068202838508, 818.5620692929255, 886.3529623374973, 909.4997751236793, 990.3661656539821, 1006.642641862143, 17217.311307547807, 20384.592308153755, 21072.630279031815, 21760.455988600093, 21861.234856779938, 959.491469521155, 968.9612415503301, 981.0, 992.1120597065586, 1042.5412818359898, 9320.953395233117, 10319.380837149794, 10774.084202842689, 11402.258853174517, 11457.026152777107, 452110.0, 575855.1207243938, 583896.0, 595759.0, 603063.0, 109242.81514369669, 129817.8674695218, 130943.0, 132725.0, 134654.0, 888.2094935507404, 991.4794732765356, 994.6767300627325, 1011.9848202276971, 1017.7557386227401, 16369.0, 16387.672246554765, 18711.625767484846, 18785.872847629187, 20363.389098327072, 52283.477165229386, 54971.0, 56206.71896640491, 57005.14492282702, 57586.0, 58979.0, 77650.2234977643, 79520.60239698764, 80478.0, 80901.0, 8031.357491400557, 8212.343012558862, 8254.215849494289, 8258.504489730636, 8280.461769984931, 12137.21108127969, 12774.425150868048, 12781.488740450277, 12980.935095324463, 13017.609471715863, 11108.50011749457, 11160.218784684994, 11279.492422840827, 11306.152038596974, 11330.320180789178, 10935.453227338443, 10972.945135274273, 11227.438628068727, 11238.943805281247, 11280.548778048786, 10994.175436842428, 11299.322040677256, 11351.886481135083, 11641.767164656829, 11675.76648467009, 8287.668493260202, 8316.792080198044, 8326.750197494084, 8327.37544684139, 8553.101924297858, 11084.778304434027, 11133.165012623924, 11236.325820450798, 11327.773444531229, 11559.30644161353], "value": 9540.233594728561, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.7148570285942, 1563.0543521169695, 1720.7762990811193, 1856.368834596237, 1895.5924476237606, 8374.204450577194, 8656.350773691982, 8802.119788021195, 8818.250448711859, 8823.735287941365, 7732.845343093135, 7894.684212631502, 8331.16688331168, 8506.106858779833, 8641.092685268035, 2286.4626812699003, 2327.5344931013865, 2329.056732023533, 2329.848559843613, 2339.040993192791, 2305.2853615379286, 2306.3772781349026, 2350.3654013416367, 2351.47091904321, 2352.9764702353013, 2090.508730448351, 2165.5344101018277, 2167.9891600542055, 2168.3928500019942, 2211.07135003299, 2119.459537817854, 2280.977190228093, 2343.6601692754625, 2344.4959333743327, 2357.858528488296, 2323.7327707313707, 2332.7433982261937, 2338.0243694078476, 2342.5314937012495, 2342.941426464352, 8967.551622418852, 9033.277337813024, 9041.050689677555, 9043.819123617557, 9044.186023257938, 5179.249008893727, 5298.841034768963, 5343.251944727703, 5356.035913535562, 5373.0, 3464.064702530328, 3563.2695297464215, 3634.963650363489, 3642.3079614873354, 3645.763025403367, 1059.6450189186633, 1082.5561519776854, 1087.0488322717622, 1088.2056099047738, 1095.0746619106922, 11857.5849845255, 11863.822042669366, 11872.643820685393, 11889.702757431134, 11891.643250702493, 4476.597106260452, 4527.886802829889, 4726.8109275628585, 4729.030548737501, 4732.455767586772, 10014.198864090855, 10017.599296028224, 10018.098371146632, 10028.997100289913, 10059.0, 5330.520253177233, 5342.9732851336275, 5344.652597581145, 5353.544948679406, 5354.759035843396, 4304.80628371724, 4352.020795321027, 4416.381706561084, 4558.384618076518, 4897.191963326011, 3960.2871483133313, 3971.0, 3988.3618621020496, 4036.253293140778, 4080.367543030835, 1227.9079069069676, 1230.5385480444834, 1267.8034904589804, 1282.7498637765545, 1286.0740267007739, 905.35719639057, 928.3176865004217, 931.4923366765129, 947.3747326764302, 964.0889359555154, 5799.449052340021, 5816.709164541756, 5878.2064421302575, 6030.547708921761, 6056.788012419544, 430.8922769307673, 449.2587231068763, 459.63688685937734, 463.0807846424844, 936.6768464879606, 3781.621837816196, 4006.8797936061965, 4018.1963607278662, 4074.5925407459017, 4087.1825634873144, 10228.437435941094, 10504.737381565523, 10552.208584356204, 10578.841317380091, 10583.84124238137, 10537.63118290856, 10559.788804223872, 10568.577256909792, 10574.947125264325, 10578.735531611772, 7840.0, 8214.548199849185, 8220.0, 8347.332213422911, 8357.874631880526, 5972.283326000907, 6022.0, 6053.243344581927, 6082.239720034996, 6138.0, 9921.801563968824, 10056.29605927578, 10166.74583135398, 10185.69442916684, 10186.643467478603, 8807.295416366547, 9106.317026222927, 9956.65585146025, 10420.531076101744, 10426.843597346198, 6443.548951573345, 7494.100707915085, 7505.512141710771, 7570.977917981002, 7575.0, 6934.687939042654, 8114.0, 8173.019237691515, 8193.877091843508, 8217.0, 7393.112826460859, 7439.702411903463, 7527.134379546423, 7558.735444259424, 7613.0, 7183.8563228736175, 7284.562926224444, 7339.706411743684, 7348.559086454829, 7421.8144546386775, 6858.3827455529245, 6983.266756990442, 7042.823929401601, 7056.223815380406, 7059.5058345915295, 5015.122353588181, 5025.673331233458, 5059.544640982329, 5137.8972420550635, 5149.279100925798, 4449.354843547732, 4683.274092515529, 5061.670991385548, 5215.322008138917, 5245.554127899171], "value": 4507.337931830083, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1445879244154, 305.3374178033667, 306.77758624996875, 315.50623274575287, 315.8657570532526, 5491.720935811639, 5511.283533140691, 5521.806736764204, 5538.833834984946, 5557.499825015748, 4764.404449443819, 5034.993001399716, 5045.672031317962, 5436.483534064258, 5469.371022332433, 3527.3650742866275, 3920.353141731618, 3927.8821635350987, 4144.901601075718, 4167.770507700236, 3816.740475643045, 4069.8168582413864, 4145.7719825409595, 4152.37714342848, 4169.0, 4103.876883693494, 4106.733062350952, 4111.362738775495, 4126.174765047002, 4130.2978493656, 3879.8571400690926, 3886.591907849663, 3904.0, 4064.8862591452576, 4119.382092686091, 2946.720061594145, 3520.5775306963073, 3525.294941011808, 3551.3430015447225, 3594.19130695594, 4373.322135069037, 5319.893602127974, 5362.410134885159, 5371.167469042305, 5381.434949330303, 4274.5939135782055, 4511.871243435364, 4530.932036019462, 4745.406824146981, 4768.73771942543, 5000.67495612784, 5652.321721393418, 5656.575756818255, 5721.056025755745, 5772.913406298909, 3334.89854435375, 3991.900202494932, 3993.1614360983936, 3995.5005624296964, 4002.239574480839, 5173.982146962688, 5452.427495113036, 5600.751564092817, 5785.797497087582, 6080.908786368208, 3280.7703460757994, 3529.6117427083, 3967.940480892789, 3972.106276087857, 3974.841006359771, 4048.1701251243426, 4056.6957478188665, 4072.572379900127, 4076.8596486844644, 4099.257815428431, 4114.650254728322, 4325.396627606161, 4726.905461890812, 4728.22691490691, 4786.635808794477, 4410.977945110254, 5226.869328266824, 5550.419055139379, 5710.514606258432, 5773.422657734277, 1365.3342921635788, 1387.8681525255085, 1390.242317936727, 1394.7907813828008, 1395.741787769266, 696.4289282788167, 856.195176534056, 856.6145234644434, 857.6869442653511, 874.8294082653821, 4704.788284527204, 4992.675476094042, 5064.290999260033, 5213.244079608511, 5371.785128594813, 5170.741462926839, 5707.258056452634, 5894.557908156819, 5910.261217347832, 5930.161649888549, 4724.456687480915, 4972.627052971042, 4978.680649627851, 5134.845954621367, 5421.346489320795, 2831.0515977147475, 2862.8711707973193, 2867.913962581126, 3051.5727798108283, 3121.219695076231, 4915.754212289371, 5027.053762796188, 5631.436856314418, 5953.1070339449425, 6142.600730952563, 5297.894042119213, 5407.648502847303, 5422.75597598109, 5533.063427800238, 6154.476869466056, 5368.973155134199, 6145.985912324416, 6152.446734605178, 6164.815055548341, 6196.907046394398, 5682.5453963681975, 5940.6435613863305, 5953.821385358447, 5969.52243820502, 5975.342712301556, 5330.401978664464, 5837.7157025454335, 5919.289685237799, 5986.521078313812, 5987.191729116514, 5171.689698618095, 5580.051391263414, 6004.039353703386, 6223.502119830493, 6251.937480625135, 3225.70968612826, 3935.3864915384556, 3956.8219430125137, 3995.4206640037614, 4014.116894283253, 5169.327987361619, 5877.0, 5931.377205393372, 5936.673482958306, 5941.376155503783, 4912.017596480647, 5813.215215945962, 5828.970855145867, 5833.212516310414, 5843.561732870136, 3967.5635680075748, 5172.224166375074, 5474.09677403224, 5762.567807414377, 5764.7405866736935, 4961.593627090786, 5147.845564633067, 5321.6540924839765, 5647.265855438931, 5656.37779844225, 5490.423505531861, 5585.146249088116, 5695.829125126253, 5744.195812586325, 5791.971040144941, 5586.581006424453, 5615.108219266999, 5617.320654971, 5617.88764224705, 5630.4369563043365], "value": 4144.286626858739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.6988019851959, 826.2241556088674, 845.3701992015949, 853.5689476814208, 859.8022454835387, 14943.701125977488, 16331.34674613014, 16339.591510212236, 16406.343746250164, 17294.481165565052, 16323.265453054611, 17181.484555463325, 17205.0, 17225.397111101112, 17501.474955751317, 695.4436450839333, 878.3236907581158, 897.3718397122008, 910.8087301666658, 910.8360495110877, 703.9507234493587, 871.2594294849391, 899.4513346858411, 900.7838118851462, 901.3735453859571, 924.4915296586884, 926.9212116970066, 927.0544045074021, 929.4980710416401, 929.6327950459564, 722.8120688620944, 924.306769922558, 924.3298608508811, 924.5885580916495, 926.0970553710146, 550.1307933465115, 675.5203805298244, 695.0824911117327, 695.2803848017328, 696.8919817428307, 15524.767128493082, 15531.534053978286, 15582.766258506132, 15615.92192039044, 15618.219089045502, 13675.247861367621, 13937.303134843218, 13952.441902323895, 13968.93015534926, 14047.367868445945, 13123.934380328134, 13204.009699272592, 13462.124961877447, 13614.795778063337, 13834.0, 4501.909961800746, 4577.473590537097, 4605.746683932382, 4605.838795642137, 4788.233882578806, 111191.0, 111559.44220278847, 111956.0, 112191.87808121897, 113109.86890131075, 11010.889891100986, 11087.944560277147, 11103.44482775858, 11112.611058612909, 11538.711532211593, 21362.679559806616, 21474.463138421354, 21535.56928861414, 21555.89222053911, 21674.891625542088, 7883.645235964395, 7895.7236496722335, 7985.680572777024, 8077.7576672699915, 8118.715844945516, 4406.691531592822, 4584.839530616412, 4608.170529304757, 4750.121227572841, 4789.544993225638, 3422.418188907892, 3558.448440491728, 3558.5729712434672, 3563.376409128392, 3564.2515071835205, 642.065794269337, 660.8909529927604, 669.1568623534382, 669.9062131301622, 671.6540981394586, 577.5495113811234, 664.9035889795953, 677.928817474168, 692.1002696494537, 698.7798843364426, 9446.38598491096, 9517.476538790434, 9546.713598592054, 9628.614855405845, 9658.082482164184, 664.4252721395958, 688.8117996456173, 693.6254422611734, 698.8113209433425, 710.0662628643317, 4443.311286750559, 4548.31775233711, 4696.459907110658, 4703.106409782131, 4763.904721905543, 92241.53879230561, 122188.38905805592, 147081.26459367634, 149382.25308873385, 155429.0, 108976.0, 112071.0, 123127.38436307761, 126046.36976815057, 127961.0, 75059.0, 87967.12032879717, 89102.0, 106163.46918265669, 108374.0, 8716.607752651273, 9075.047120052299, 9210.585523651585, 9266.259475160568, 9351.672691456039, 63510.0, 64142.67928660327, 65480.34519654742, 65940.68118637697, 66488.66755666191, 86597.56701216454, 93437.0, 99531.00468995217, 100593.0, 103189.0, 11645.47595358194, 11756.018752539901, 11772.411379430823, 11958.521659133537, 12067.57763478274, 13327.623296948621, 13453.596392108253, 13523.459061637424, 13705.246211458465, 13762.105463144864, 12193.573224937085, 12539.37303134858, 12894.097413180987, 12913.935430322788, 12961.546345877849, 11883.287002779862, 12398.265990674385, 12401.0, 12531.122821402414, 12986.35068246565, 11898.643040708792, 12121.212121212093, 12375.69060773488, 12648.620541383772, 12676.302803345536, 9358.486814306556, 9977.917479011589, 10100.747481313027, 10122.79754404893, 10534.051935325857, 9004.729858104267, 9548.952255238679, 10571.682849514527, 10670.46647667629, 10674.679759607225], "value": 7843.052555440393, "unit": "score"}], "name": "dromaeo_css", "value": 7424.3372471289185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 112.0, 113.0, 114.0, 113.0, 113.0, 112.0, 111.0, 113.0, 113.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 106.0, 154.0, 108.0, 136.0, 157.0, 110.0, 161.0, 107.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 182.0, 183.0, 184.0, 181.0, 268.0, 245.0, 182.0, 181.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 57.0, 86.0, 57.0, 57.0, 65.0, 63.0, 83.0, 74.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 92.0, 72.0, 71.0, 68.0, 97.0, 70.0, 68.0, 101.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 88.0, 92.0, 88.0, 91.0, 90.0, 90.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 100.0, 96.0, 96.0, 97.0, 96.0, 98.0, 97.0, 96.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 84.0, 86.0, 83.0, 83.0, 84.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 56.0, 54.0, 54.0, 54.0, 55.0, 52.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 80.0, 78.0, 74.0, 73.0, 70.0, 69.0, 72.0, 71.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 85.0, 83.0, 83.0, 80.0, 82.0, 85.0, 85.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 129.0, 123.0, 152.0, 123.0, 144.0, 143.0, 241.0, 134.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 164.0, 164.0, 164.0, 163.0, 164.0, 162.0, 215.0, 201.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 82.0, 68.0, 65.0, 68.0, 65.0, 66.0, 65.0, 70.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1456.6999999999998, "alertThreshold": 2.0}]} 01:34:14 INFO - Return code: 0 01:34:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:34:14 INFO - # TBPL SUCCESS # 01:34:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:34:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:34:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:34:14 INFO - 2016-07-19 01:34:14.770 screenresolution[1667:9920] starting screenresolution argv=screenresolution get 01:34:14 INFO - 2016-07-19 01:34:14.772 screenresolution[1667:9920] Display 0: 1600x1200x32@60 01:34:14 INFO - 2016-07-19 01:34:14.778 screenresolution[1668:9923] starting screenresolution argv=screenresolution list 01:34:14 INFO - Available Modes on Display 0 01:34:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:34:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:34:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:34:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:34:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:34:15 INFO - Intel Iris: 01:34:15 INFO - Chipset Model: Intel Iris 01:34:15 INFO - Type: GPU 01:34:15 INFO - Bus: Built-In 01:34:15 INFO - VRAM (Dynamic, Max): 1536 MB 01:34:15 INFO - Vendor: Intel (0x8086) 01:34:15 INFO - Device ID: 0x0a2e 01:34:15 INFO - Revision ID: 0x0009 01:34:15 INFO - Displays: 01:34:15 INFO - Display: 01:34:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:34:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:34:15 INFO - Display Serial Number: 1600x1200 60 01:34:15 INFO - Main Display: Yes 01:34:15 INFO - Mirror: Off 01:34:15 INFO - Online: Yes 01:34:15 INFO - Rotation: Supported 01:34:15 INFO - Return code: 0 01:34:15 INFO - Running post-action listener: _resource_record_post_action 01:34:15 INFO - ##### 01:34:15 INFO - ##### Finished run-tests step (success) 01:34:15 INFO - ##### 01:34:15 INFO - Running post-run listener: _resource_record_post_run 01:34:15 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32706048; Write bytes: 704010240; Read time: 317; Write time: 1797 01:34:15 INFO - TinderboxPrint: CPU usage
53.2% 01:34:15 INFO - TinderboxPrint: I/O read bytes / time
32,706,048 / 317 01:34:15 INFO - TinderboxPrint: I/O write bytes / time
704,010,240 / 1,797 01:34:15 INFO - TinderboxPrint: CPU idle
2,267.7 (46.8%) 01:34:15 INFO - TinderboxPrint: CPU system
964.4 (19.9%) 01:34:15 INFO - TinderboxPrint: CPU user
1,617.6 (33.4%) 01:34:15 INFO - TinderboxPrint: Swap in / out
436,240,384 / 0 01:34:15 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 388827648; Write bytes: 382957056; Read time: 30019; Write time: 681 01:34:15 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31784448; Write bytes: 293777920; Read time: 304; Write time: 1067 01:34:15 INFO - Running post-run listener: _upload_blobber_files 01:34:15 INFO - Blob upload gear active. 01:34:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:34:15 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 01:34:15 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'] 01:34:15 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 01:34:15 INFO - (blobuploader) - INFO - Open directory for files ... 01:34:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:34:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:34:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:34:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:34:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:34:17 INFO - (blobuploader) - INFO - Done attempting. 01:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:34:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:34:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:34:18 INFO - (blobuploader) - INFO - Done attempting. 01:34:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:34:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:34:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:34:20 INFO - (blobuploader) - INFO - Done attempting. 01:34:20 INFO - (blobuploader) - INFO - Iteration through files over. 01:34:20 INFO - Return code: 0 01:34:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:34:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:34:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eda78ccd7f1962e0fb84fbaa7836119cf180dd42eda338c15a930702f77a24eb24c3d854a6a60011a0710c5625c41a7fb9e6aa36bcb28e39b893a76fdf270ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf90b69b9d687b4ccc641aa302298e43163610640461960b837c2b716a6b804863b94127e49f9aea30272abe9543cea1614b6927b61efca1e1e5d311fb442ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54cb4bf9de91edd6ac5bc3e04d8491ed2cfb498e0fd79c4beb1a902c911ce1ce4df34e4bd0ea02076018be96f70838ec58092b6701805dc97d79a7f9207361c0"} 01:34:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:34:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:34:20 INFO - Contents: 01:34:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eda78ccd7f1962e0fb84fbaa7836119cf180dd42eda338c15a930702f77a24eb24c3d854a6a60011a0710c5625c41a7fb9e6aa36bcb28e39b893a76fdf270ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf90b69b9d687b4ccc641aa302298e43163610640461960b837c2b716a6b804863b94127e49f9aea30272abe9543cea1614b6927b61efca1e1e5d311fb442ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54cb4bf9de91edd6ac5bc3e04d8491ed2cfb498e0fd79c4beb1a902c911ce1ce4df34e4bd0ea02076018be96f70838ec58092b6701805dc97d79a7f9207361c0"} 01:34:20 INFO - Running post-run listener: copy_logs_to_upload_dir 01:34:20 INFO - Copying logs to upload dir... 01:34:20 INFO - mkdir: /builds/slave/test/build/upload/logs 01:34:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.344584 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-19 01:34:20.804165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 01:34:20.808616) ========= 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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eda78ccd7f1962e0fb84fbaa7836119cf180dd42eda338c15a930702f77a24eb24c3d854a6a60011a0710c5625c41a7fb9e6aa36bcb28e39b893a76fdf270ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf90b69b9d687b4ccc641aa302298e43163610640461960b837c2b716a6b804863b94127e49f9aea30272abe9543cea1614b6927b61efca1e1e5d311fb442ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54cb4bf9de91edd6ac5bc3e04d8491ed2cfb498e0fd79c4beb1a902c911ce1ce4df34e4bd0ea02076018be96f70838ec58092b6701805dc97d79a7f9207361c0"} build_url:https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/FRvoX-n_TaetQKVGfrvtew/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eda78ccd7f1962e0fb84fbaa7836119cf180dd42eda338c15a930702f77a24eb24c3d854a6a60011a0710c5625c41a7fb9e6aa36bcb28e39b893a76fdf270ed6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf90b69b9d687b4ccc641aa302298e43163610640461960b837c2b716a6b804863b94127e49f9aea30272abe9543cea1614b6927b61efca1e1e5d311fb442ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54cb4bf9de91edd6ac5bc3e04d8491ed2cfb498e0fd79c4beb1a902c911ce1ce4df34e4bd0ea02076018be96f70838ec58092b6701805dc97d79a7f9207361c0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 01:34:20.845927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:34:20.846285) ========= 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.0BIU6DMcwt/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.Y7QN0JsFm2/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.005346 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 01:34:20.894650) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 01:34:20.894975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 01:34:20.895304) ========= ========= Total master_lag: 0.18 =========