builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0266 starttime: 1467268544.69 results: success (0) buildid: 20160629223711 builduid: dfdc4784451f4df6b87c5320910ee133 revision: 32bb090d7e625a763a80728da2c276724a2c6761 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:44.703026) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:44.703941) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:44.704253) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:44.808843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:44.809307) ========= 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.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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-06-29 23:35:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-06-29 23:35:45 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.564299 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:45.425981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:45.426278) ========= 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.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.026864 ========= master_lag: 0.48 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:45.931864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 23:35:45.932204) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 32bb090d7e625a763a80728da2c276724a2c6761 --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 32bb090d7e625a763a80728da2c276724a2c6761 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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-06-29 23:35:46,018 truncating revision to first 12 chars 2016-06-29 23:35:46,019 Setting DEBUG logging. 2016-06-29 23:35:46,019 attempt 1/10 2016-06-29 23:35:46,019 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/32bb090d7e62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 23:35:46,719 unpacking tar archive at: fx-team-32bb090d7e62/testing/mozharness/ program finished with exit code 0 elapsedTime=0.970298 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 23:35:47.249210) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:47.249508) ========= script_repo_revision: 32bb090d7e625a763a80728da2c276724a2c6761 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:47.249887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:47.250215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:47.454396) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 23:35:47.454713) ========= /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.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:35:47 INFO - MultiFileLogger online at 20160629 23:35:47 in /builds/slave/test 23:35:47 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 23:35:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:35:47 INFO - {'append_to_log': False, 23:35:47 INFO - 'base_work_dir': '/builds/slave/test', 23:35:47 INFO - 'blob_upload_branch': 'Fx-Team', 23:35:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:35:47 INFO - 'branch': 'Fx-Team', 23:35:47 INFO - 'buildbot_json_path': 'buildprops.json', 23:35:47 INFO - 'config_files': ('talos/mac_config.py',), 23:35:47 INFO - 'default_actions': ('clobber', 23:35:47 INFO - 'read-buildbot-config', 23:35:47 INFO - 'download-and-extract', 23:35:47 INFO - 'populate-webroot', 23:35:47 INFO - 'create-virtualenv', 23:35:47 INFO - 'install', 23:35:47 INFO - 'run-tests'), 23:35:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:35:47 INFO - 'download_minidump_stackwalk': True, 23:35:47 INFO - 'download_symbols': 'ondemand', 23:35:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:35:47 INFO - 'tooltool.py': '/tools/tooltool.py', 23:35:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:35:47 INFO - '/tools/misc-python/virtualenv.py')}, 23:35:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:35:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:35:47 INFO - 'installer_path': 'installer.exe', 23:35:47 INFO - 'log_level': 'info', 23:35:47 INFO - 'log_name': 'talos', 23:35:47 INFO - 'log_to_console': True, 23:35:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:35:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:35:47 INFO - 'opt_config_files': (), 23:35:47 INFO - 'pip_index': False, 23:35:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:35:47 INFO - 'cmd': ('bash', 23:35:47 INFO - '-c', 23:35:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:35:47 INFO - 'enabled': True, 23:35:47 INFO - 'halt_on_failure': False, 23:35:47 INFO - 'name': 'check_screen_resolution'},), 23:35:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:35:47 INFO - 'cmd': ('bash', 23:35:47 INFO - '-c', 23:35:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:35:47 INFO - 'enabled': True, 23:35:47 INFO - 'halt_on_failure': False, 23:35:47 INFO - 'name': 'check_screen_resolution'},), 23:35:47 INFO - 'run_cmd_checks_enabled': True, 23:35:47 INFO - 'sps_profile': False, 23:35:47 INFO - 'sps_profile_interval': 0, 23:35:47 INFO - 'suite': 'dromaeojs', 23:35:47 INFO - 'system_bits': '32', 23:35:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:35:47 INFO - 'title': 't-yosemite-r7-0266', 23:35:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:35:47 INFO - 'use_talos_json': True, 23:35:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:35:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:35:47 INFO - 'work_dir': 'build'} 23:35:47 INFO - ##### 23:35:47 INFO - ##### Running clobber step. 23:35:47 INFO - ##### 23:35:47 INFO - Running pre-action listener: _resource_record_pre_action 23:35:47 INFO - Running main action method: clobber 23:35:47 INFO - rmtree: /builds/slave/test/build 23:35:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:35:49 INFO - Running post-action listener: _resource_record_post_action 23:35:49 INFO - ##### 23:35:49 INFO - ##### Finished clobber step (success) 23:35:49 INFO - ##### 23:35:49 INFO - ##### 23:35:49 INFO - ##### Running read-buildbot-config step. 23:35:49 INFO - ##### 23:35:49 INFO - Running pre-action listener: _resource_record_pre_action 23:35:49 INFO - Running main action method: read_buildbot_config 23:35:49 INFO - Using buildbot properties: 23:35:49 INFO - { 23:35:49 INFO - "project": "", 23:35:49 INFO - "product": "firefox", 23:35:49 INFO - "script_repo_revision": "production", 23:35:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:35:49 INFO - "repository": "", 23:35:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:35:49 INFO - "buildid": "20160629223711", 23:35:49 INFO - "pgo_build": "False", 23:35:49 INFO - "basedir": "/builds/slave/test", 23:35:49 INFO - "buildnumber": 72, 23:35:49 INFO - "slavename": "t-yosemite-r7-0266", 23:35:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 23:35:49 INFO - "platform": "yosemite_r7", 23:35:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:35:49 INFO - "branch": "fx-team", 23:35:49 INFO - "repo_path": "integration/fx-team", 23:35:49 INFO - "revision": "32bb090d7e625a763a80728da2c276724a2c6761", 23:35:49 INFO - "stage_platform": "macosx64", 23:35:49 INFO - "builduid": "dfdc4784451f4df6b87c5320910ee133", 23:35:49 INFO - "slavebuilddir": "test" 23:35:49 INFO - } 23:35:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 23:35:49 INFO - Running post-action listener: _resource_record_post_action 23:35:49 INFO - ##### 23:35:49 INFO - ##### Finished read-buildbot-config step (success) 23:35:49 INFO - ##### 23:35:49 INFO - ##### 23:35:49 INFO - ##### Running download-and-extract step. 23:35:49 INFO - ##### 23:35:49 INFO - Running pre-action listener: _resource_record_pre_action 23:35:49 INFO - Running main action method: download_and_extract 23:35:49 INFO - mkdir: /builds/slave/test/build/tests 23:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:49 INFO - https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:35:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:35:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:35:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:35:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:35:50 INFO - Downloaded 1199 bytes. 23:35:50 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:35:50 INFO - Using the following test package requirements: 23:35:50 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:35:50 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:35:50 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:35:50 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'jsshell-mac.zip'], 23:35:50 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:35:50 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:35:50 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:35:50 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:35:50 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:35:50 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:35:50 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:35:50 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:35:50 INFO - https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:35:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:35:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:35:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:35:52 INFO - Downloaded 18788466 bytes. 23:35:52 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'] 23:35:52 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 23:35:55 INFO - Return code: 0 23:35:55 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 23:35: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')]} 23:35:55 INFO - https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:35:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:35:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:35:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:35:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:36:10 INFO - Downloaded 18788466 bytes. 23:36:10 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'] 23:36:10 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 23:36:13 INFO - Return code: 0 23:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:13 INFO - https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:36:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:36:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:36:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:36:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:36:15 INFO - Downloaded 14405245 bytes. 23:36:15 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'] 23:36:15 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 23:36:15 INFO - Return code: 0 23:36:15 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')]} 23:36:15 INFO - https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:36:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:36:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:36:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UYgHH_GYTYm01n4nwiyPIA/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 23:36:25 INFO - Downloaded 112468724 bytes. 23:36:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:36:25 INFO - mkdir: /builds/slave/test/properties 23:36:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:36:25 INFO - Writing to file /builds/slave/test/properties/build_url 23:36:25 INFO - Contents: 23:36:25 INFO - build_url:https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:36:26 INFO - Running post-action listener: _resource_record_post_action 23:36:26 INFO - Running post-action listener: set_extra_try_arguments 23:36:26 INFO - ##### 23:36:26 INFO - ##### Finished download-and-extract step (success) 23:36:26 INFO - ##### 23:36:26 INFO - ##### 23:36:26 INFO - ##### Running populate-webroot step. 23:36:26 INFO - ##### 23:36:26 INFO - Running pre-action listener: _resource_record_pre_action 23:36:26 INFO - Running main action method: populate_webroot 23:36:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:36:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:36:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:36:26 INFO - '--tppagecycles', 23:36:26 INFO - '7'], 23:36:26 INFO - 'tests': ['tsvgm']}}, 23:36:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:36:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:36:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:36:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:36:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:36:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:36:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['damp', 'tps']}, 23:36:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'tests': ['damp', 'tps']}, 23:36:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['dromaeo_dom']}, 23:36:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:36:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['basic_compositor_video']}, 23:36:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:36:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'other-e10s': {'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['a11yr', 23:36:26 INFO - 'ts_paint', 23:36:26 INFO - 'tpaint', 23:36:26 INFO - 'sessionrestore', 23:36:26 INFO - 'sessionrestore_no_auto_restore', 23:36:26 INFO - 'tabpaint']}, 23:36:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['tsvgx', 23:36:26 INFO - 'tsvgr_opacity', 23:36:26 INFO - 'tart', 23:36:26 INFO - 'tscrollx', 23:36:26 INFO - 'cart']}, 23:36:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:36:26 INFO - 'tsvgr_opacity', 23:36:26 INFO - 'tart', 23:36:26 INFO - 'tscrollx', 23:36:26 INFO - 'cart']}, 23:36:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'talos_options': ['--disable-e10s'], 23:36:26 INFO - 'tests': ['tp5o']}, 23:36:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'tests': ['tp5o']}, 23:36:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'talos_options': ['--disable-e10s', 23:36:26 INFO - '--xperf_path', 23:36:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:36:26 INFO - 'tests': ['tp5n']}, 23:36:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:36:26 INFO - 'talos_options': ['--xperf_path', 23:36:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:36:26 INFO - 'tests': ['tp5n']}}, 23:36:26 INFO - 'talos.zip': {'path': '', 23:36:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:36:26 INFO - Running post-action listener: _resource_record_post_action 23:36:26 INFO - ##### 23:36:26 INFO - ##### Finished populate-webroot step (success) 23:36:26 INFO - ##### 23:36:26 INFO - ##### 23:36:26 INFO - ##### Running create-virtualenv step. 23:36:26 INFO - ##### 23:36:26 INFO - Running pre-action listener: _resource_record_pre_action 23:36:26 INFO - Running main action method: create_virtualenv 23:36:26 INFO - Creating virtualenv /builds/slave/test/build/venv 23:36:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:36:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:36:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:36:26 INFO - Using real prefix '/tools/python27' 23:36:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:36:27 INFO - Installing distribute.............................................................................................................................................................................................done. 23:36:30 INFO - Installing pip.................done. 23:36:30 INFO - Return code: 0 23:36:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:36:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:30 INFO - 'HOME': '/Users/cltbld', 23:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:30 INFO - 'LOGNAME': 'cltbld', 23:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:30 INFO - 'MOZ_NO_REMOTE': '1', 23:36:30 INFO - 'NO_EM_RESTART': '1', 23:36:30 INFO - 'PAGER': '/bin/cat', 23:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:30 INFO - 'PWD': '/builds/slave/test', 23:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:30 INFO - 'SHELL': '/bin/bash', 23:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:30 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:30 INFO - 'USER': 'cltbld', 23:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:30 INFO - 'XPC_FLAGS': '0x0', 23:36:30 INFO - 'XPC_SERVICE_NAME': '0', 23:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:36:30 INFO - Cleaning up... 23:36:30 INFO - Return code: 0 23:36:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:36:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:30 INFO - 'HOME': '/Users/cltbld', 23:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:30 INFO - 'LOGNAME': 'cltbld', 23:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:30 INFO - 'MOZ_NO_REMOTE': '1', 23:36:30 INFO - 'NO_EM_RESTART': '1', 23:36:30 INFO - 'PAGER': '/bin/cat', 23:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:30 INFO - 'PWD': '/builds/slave/test', 23:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:30 INFO - 'SHELL': '/bin/bash', 23:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:30 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:30 INFO - 'USER': 'cltbld', 23:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:30 INFO - 'XPC_FLAGS': '0x0', 23:36:30 INFO - 'XPC_SERVICE_NAME': '0', 23:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:30 INFO - Downloading/unpacking psutil>=3.1.1 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:36:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:36:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:36:34 INFO - Installing collected packages: psutil 23:36:34 INFO - Running setup.py install for psutil 23:36:34 INFO - building 'psutil._psutil_osx' extension 23:36:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:36:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:36:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:36:35 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 23:36:35 INFO - building 'psutil._psutil_posix' extension 23:36:35 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 23:36:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:36:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:36:35 INFO - ^ 23:36:35 INFO - 1 warning generated. 23:36:35 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 23:36:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:36:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:36:35 INFO - Successfully installed psutil 23:36:35 INFO - Cleaning up... 23:36:35 INFO - Return code: 0 23:36:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:36:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:35 INFO - 'HOME': '/Users/cltbld', 23:36:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:35 INFO - 'LOGNAME': 'cltbld', 23:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:35 INFO - 'MOZ_NO_REMOTE': '1', 23:36:35 INFO - 'NO_EM_RESTART': '1', 23:36:35 INFO - 'PAGER': '/bin/cat', 23:36:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:35 INFO - 'PWD': '/builds/slave/test', 23:36:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:35 INFO - 'SHELL': '/bin/bash', 23:36:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:35 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:35 INFO - 'USER': 'cltbld', 23:36:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:35 INFO - 'XPC_FLAGS': '0x0', 23:36:35 INFO - 'XPC_SERVICE_NAME': '0', 23:36:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:36: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 23:36: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 23:36: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 23:36: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 23:36: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 23:36: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 23:36:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:36:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:36:38 INFO - Installing collected packages: mozsystemmonitor 23:36:38 INFO - Running setup.py install for mozsystemmonitor 23:36:38 INFO - Successfully installed mozsystemmonitor 23:36:38 INFO - Cleaning up... 23:36:38 INFO - Return code: 0 23:36:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:36:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:36:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:38 INFO - 'HOME': '/Users/cltbld', 23:36:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:38 INFO - 'LOGNAME': 'cltbld', 23:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:38 INFO - 'MOZ_NO_REMOTE': '1', 23:36:38 INFO - 'NO_EM_RESTART': '1', 23:36:38 INFO - 'PAGER': '/bin/cat', 23:36:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:38 INFO - 'PWD': '/builds/slave/test', 23:36:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:38 INFO - 'SHELL': '/bin/bash', 23:36:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:38 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:38 INFO - 'USER': 'cltbld', 23:36:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:38 INFO - 'XPC_FLAGS': '0x0', 23:36:38 INFO - 'XPC_SERVICE_NAME': '0', 23:36:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:38 INFO - Downloading/unpacking jsonschema==2.5.1 23:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:36:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:41 INFO - Downloading functools32-3.2.3-2.tar.gz 23:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:36:41 INFO - warning: no files found matching '*.txt' 23:36:41 INFO - no previously-included directories found matching 'build' 23:36:41 INFO - no previously-included directories found matching 'dist' 23:36:41 INFO - no previously-included directories found matching '.git*' 23:36:41 INFO - Installing collected packages: jsonschema, functools32 23:36:41 INFO - Running setup.py install for functools32 23:36:41 INFO - warning: no files found matching '*.txt' 23:36:41 INFO - no previously-included directories found matching 'build' 23:36:41 INFO - no previously-included directories found matching 'dist' 23:36:41 INFO - no previously-included directories found matching '.git*' 23:36:41 INFO - Successfully installed jsonschema functools32 23:36:41 INFO - Cleaning up... 23:36:41 INFO - Return code: 0 23:36:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:41 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 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:41 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 23:36:41 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 23:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:41 INFO - 'HOME': '/Users/cltbld', 23:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:41 INFO - 'LOGNAME': 'cltbld', 23:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:41 INFO - 'MOZ_NO_REMOTE': '1', 23:36:41 INFO - 'NO_EM_RESTART': '1', 23:36:41 INFO - 'PAGER': '/bin/cat', 23:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:41 INFO - 'PWD': '/builds/slave/test', 23:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:41 INFO - 'SHELL': '/bin/bash', 23:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:41 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:41 INFO - 'USER': 'cltbld', 23:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:41 INFO - 'XPC_FLAGS': '0x0', 23:36:41 INFO - 'XPC_SERVICE_NAME': '0', 23:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:42 INFO - Downloading/unpacking blobuploader==1.2.4 23:36:42 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 23:36:42 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 23:36:42 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 23:36:42 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 23:36:42 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 23:36:42 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 23:36:44 INFO - Downloading blobuploader-1.2.4.tar.gz 23:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:36:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:36:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:36: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 23:36: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 23:36: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 23:36: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 23:36: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 23:36: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 23:36:45 INFO - Downloading docopt-0.6.1.tar.gz 23:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:36:45 INFO - Installing collected packages: blobuploader, requests, docopt 23:36:45 INFO - Running setup.py install for blobuploader 23:36:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:36:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:36:46 INFO - Running setup.py install for requests 23:36:46 INFO - Running setup.py install for docopt 23:36:46 INFO - Successfully installed blobuploader requests docopt 23:36:46 INFO - Cleaning up... 23:36:46 INFO - Return code: 0 23:36:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:46 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 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:46 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 23:36:46 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 23:36:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:46 INFO - 'HOME': '/Users/cltbld', 23:36:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:46 INFO - 'LOGNAME': 'cltbld', 23:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:46 INFO - 'MOZ_NO_REMOTE': '1', 23:36:46 INFO - 'NO_EM_RESTART': '1', 23:36:46 INFO - 'PAGER': '/bin/cat', 23:36:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:46 INFO - 'PWD': '/builds/slave/test', 23:36:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:46 INFO - 'SHELL': '/bin/bash', 23:36:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:46 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:46 INFO - 'USER': 'cltbld', 23:36:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:46 INFO - 'XPC_FLAGS': '0x0', 23:36:46 INFO - 'XPC_SERVICE_NAME': '0', 23:36:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-mp8iqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-4Nep1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Q5J2RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1rpxp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-uVtp4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-n02cwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1a69X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-4LJN7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:47 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-RHxwJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-IT2EYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-M5AiY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-aWFdMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-7IZ8T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-a7EOF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-9eCqVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jrwe8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:48 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-6ckDK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:36:48 INFO - Running setup.py install for manifestparser 23:36:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:36:48 INFO - Running setup.py install for mozcrash 23:36:49 INFO - Running setup.py install for mozdebug 23:36:49 INFO - Running setup.py install for mozdevice 23:36:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Running setup.py install for mozfile 23:36:49 INFO - Running setup.py install for mozhttpd 23:36:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Running setup.py install for mozinfo 23:36:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Running setup.py install for mozInstall 23:36:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:36:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Running setup.py install for mozleak 23:36:50 INFO - Running setup.py install for mozlog 23:36:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Running setup.py install for moznetwork 23:36:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Running setup.py install for mozprocess 23:36:50 INFO - Running setup.py install for mozprofile 23:36:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Running setup.py install for mozrunner 23:36:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:36:50 INFO - Running setup.py install for mozscreenshot 23:36:51 INFO - Running setup.py install for moztest 23:36:51 INFO - Running setup.py install for mozversion 23:36:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:36:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:36:51 INFO - Cleaning up... 23:36:51 INFO - Return code: 0 23:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36: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')]} 23:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36: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')]} 23:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:51 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 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:51 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 23:36:51 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 23:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:51 INFO - 'HOME': '/Users/cltbld', 23:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:51 INFO - 'LOGNAME': 'cltbld', 23:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:51 INFO - 'MOZ_NO_REMOTE': '1', 23:36:51 INFO - 'NO_EM_RESTART': '1', 23:36:51 INFO - 'PAGER': '/bin/cat', 23:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:51 INFO - 'PWD': '/builds/slave/test', 23:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:51 INFO - 'SHELL': '/bin/bash', 23:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:51 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:51 INFO - 'USER': 'cltbld', 23:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:51 INFO - 'XPC_FLAGS': '0x0', 23:36:51 INFO - 'XPC_SERVICE_NAME': '0', 23:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-CgytN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-2EDtlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-NYapYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-sxf5YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-rFnQTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-6hOqu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-2qdaPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:36:52 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)) 23:36:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:36:52 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-SESEk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-E8EEdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-9FJiwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-bzlnfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-6OS0Hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-d_HTXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-h1aDlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-xTLV73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Fmx23M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:36:53 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)) 23:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:36:53 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hZJ39C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:36:53 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)) 23:36:53 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)) 23:36:53 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)) 23:36:53 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)) 23:36:53 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)) 23:36:54 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)) 23:36:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:36:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:36:56 INFO - Downloading blessings-1.6.tar.gz 23:36:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:36:56 INFO - Installing collected packages: blessings 23:36:56 INFO - Running setup.py install for blessings 23:36:56 INFO - Successfully installed blessings 23:36:56 INFO - Cleaning up... 23:36:56 INFO - Return code: 0 23:36:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:36:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:57 INFO - Reading from file tmpfile_stdout 23:36:57 INFO - Current package versions: 23:36:57 INFO - blessings == 1.6 23:36:57 INFO - blobuploader == 1.2.4 23:36:57 INFO - docopt == 0.6.1 23:36:57 INFO - functools32 == 3.2.3-2 23:36:57 INFO - jsonschema == 2.5.1 23:36:57 INFO - manifestparser == 1.1 23:36:57 INFO - mozInstall == 1.12 23:36:57 INFO - mozcrash == 0.17 23:36:57 INFO - mozdebug == 0.1 23:36:57 INFO - mozdevice == 0.48 23:36:57 INFO - mozfile == 1.2 23:36:57 INFO - mozhttpd == 0.7 23:36:57 INFO - mozinfo == 0.9 23:36:57 INFO - mozleak == 0.1 23:36:57 INFO - mozlog == 3.2 23:36:57 INFO - moznetwork == 0.27 23:36:57 INFO - mozprocess == 0.22 23:36:57 INFO - mozprofile == 0.28 23:36:57 INFO - mozrunner == 6.11 23:36:57 INFO - mozscreenshot == 0.1 23:36:57 INFO - mozsystemmonitor == 0.3 23:36:57 INFO - moztest == 0.7 23:36:57 INFO - mozversion == 1.4 23:36:57 INFO - psutil == 3.1.1 23:36:57 INFO - requests == 1.2.3 23:36:57 INFO - wsgiref == 0.1.2 23:36:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:57 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 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:57 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 23:36:57 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 23:36:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:57 INFO - 'HOME': '/Users/cltbld', 23:36:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:57 INFO - 'LOGNAME': 'cltbld', 23:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:57 INFO - 'MOZ_NO_REMOTE': '1', 23:36:57 INFO - 'NO_EM_RESTART': '1', 23:36:57 INFO - 'PAGER': '/bin/cat', 23:36:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:57 INFO - 'PWD': '/builds/slave/test', 23:36:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:57 INFO - 'SHELL': '/bin/bash', 23:36:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:57 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:57 INFO - 'USER': 'cltbld', 23:36:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:57 INFO - 'XPC_FLAGS': '0x0', 23:36:57 INFO - 'XPC_SERVICE_NAME': '0', 23:36:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 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)) 23:36:57 INFO - Cleaning up... 23:36:57 INFO - Return code: 0 23:36:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:36:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:36:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:36:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102300a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102395618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd368cb1bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, '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.5I9I05KF0d/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.ghjsMFMoBo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 23:36:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:36:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:36:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:36:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:36:57 INFO - 'HOME': '/Users/cltbld', 23:36:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:36:57 INFO - 'LOGNAME': 'cltbld', 23:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:36:57 INFO - 'MOZ_NO_REMOTE': '1', 23:36:57 INFO - 'NO_EM_RESTART': '1', 23:36:57 INFO - 'PAGER': '/bin/cat', 23:36:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:36:57 INFO - 'PWD': '/builds/slave/test', 23:36:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:36:57 INFO - 'SHELL': '/bin/bash', 23:36:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:36:57 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:36:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:36:57 INFO - 'USER': 'cltbld', 23:36:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:36:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:36:57 INFO - 'XPC_FLAGS': '0x0', 23:36:57 INFO - 'XPC_SERVICE_NAME': '0', 23:36:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:36:57 INFO - Cleaning up... 23:36:57 INFO - Return code: 0 23:36:57 INFO - Running post-action listener: _resource_record_post_action 23:36:57 INFO - Running post-action listener: _start_resource_monitoring 23:36:57 INFO - Starting resource monitoring. 23:36:57 INFO - ##### 23:36:57 INFO - ##### Finished create-virtualenv step (success) 23:36:57 INFO - ##### 23:36:57 INFO - ##### 23:36:57 INFO - ##### Running install step. 23:36:57 INFO - ##### 23:36:57 INFO - Running pre-action listener: _resource_record_pre_action 23:36:57 INFO - Running main action method: install 23:36:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:36:57 INFO - Reading from file tmpfile_stdout 23:36:57 INFO - Detecting whether we're running mozinstall >=1.0... 23:36:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:36:57 INFO - Reading from file tmpfile_stdout 23:36:57 INFO - Output received: 23:36:57 INFO - Usage: mozinstall [options] installer 23:36:57 INFO - Options: 23:36:57 INFO - -h, --help show this help message and exit 23:36:57 INFO - -d DEST, --destination=DEST 23:36:57 INFO - Directory to install application into. [default: 23:36:57 INFO - "/builds/slave/test"] 23:36:57 INFO - --app=APP Application being installed. [default: firefox] 23:36:57 INFO - mkdir: /builds/slave/test/build/application 23:36:57 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'] 23:36:57 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 23:37:32 INFO - Reading from file tmpfile_stdout 23:37:32 INFO - Output received: 23:37:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:37:32 INFO - Running post-action listener: _resource_record_post_action 23:37:32 INFO - ##### 23:37:32 INFO - ##### Finished install step (success) 23:37:32 INFO - ##### 23:37:32 INFO - ##### 23:37:32 INFO - ##### Running run-tests step. 23:37:32 INFO - ##### 23:37:32 INFO - Running pre-action listener: _resource_record_pre_action 23:37:32 INFO - Running main action method: run_tests 23:37:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:37:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:37:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:37:32 INFO - 2016-06-29 23:37:32.958 screenresolution[1645:9642] starting screenresolution argv=screenresolution get 23:37:32 INFO - 2016-06-29 23:37:32.961 screenresolution[1645:9642] Display 0: 1600x1200x32@60 23:37:32 INFO - 2016-06-29 23:37:32.966 screenresolution[1646:9644] starting screenresolution argv=screenresolution list 23:37:32 INFO - Available Modes on Display 0 23:37:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:37:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:37:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:37:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:37:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:37:33 INFO - Intel Iris: 23:37:33 INFO - Chipset Model: Intel Iris 23:37:33 INFO - Type: GPU 23:37:33 INFO - Bus: Built-In 23:37:33 INFO - VRAM (Dynamic, Max): 1536 MB 23:37:33 INFO - Vendor: Intel (0x8086) 23:37:33 INFO - Device ID: 0x0a2e 23:37:33 INFO - Revision ID: 0x0009 23:37:33 INFO - Displays: 23:37:33 INFO - Display: 23:37:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:37:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:37:33 INFO - Display Serial Number: 1600x1200 60 23:37:33 INFO - Main Display: Yes 23:37:33 INFO - Mirror: Off 23:37:33 INFO - Online: Yes 23:37:33 INFO - Rotation: Supported 23:37:33 INFO - Return code: 0 23:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:37:33 INFO - Python 2.7.3 23:37:33 INFO - Return code: 0 23:37:33 INFO - grabbing minidump binary from tooltool 23:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:37:33 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 0x102264d30>, '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 0x101fc9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10225cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:37:33 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 23:37:33 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 23:37:33 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 23:37:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:37:33 INFO - Return code: 0 23:37:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:37:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:37:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:37:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:37:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:37:33 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/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 23:37:33 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/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 23:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5I9I05KF0d/Render', 23:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:37:33 INFO - 'HOME': '/Users/cltbld', 23:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:37:33 INFO - 'LOGNAME': 'cltbld', 23:37:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:37:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:37:33 INFO - 'MOZ_NO_REMOTE': '1', 23:37:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:37:33 INFO - 'NO_EM_RESTART': '1', 23:37:33 INFO - 'PAGER': '/bin/cat', 23:37:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:37:33 INFO - 'PWD': '/builds/slave/test', 23:37:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:37:33 INFO - 'SHELL': '/bin/bash', 23:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ghjsMFMoBo/Listeners', 23:37:33 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 23:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:37:33 INFO - 'USER': 'cltbld', 23:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:37:33 INFO - 'XPC_FLAGS': '0x0', 23:37:33 INFO - 'XPC_SERVICE_NAME': '0', 23:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:37:33 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/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 23:37:33 INFO - mozversion application_buildid: 20160629223711 23:37:33 INFO - mozversion application_changeset: 32bb090d7e625a763a80728da2c276724a2c6761 23:37:33 INFO - mozversion application_display_name: Nightly 23:37:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:37:33 INFO - mozversion application_name: Firefox 23:37:33 INFO - mozversion application_remotingname: firefox 23:37:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:37:33 INFO - mozversion application_vendor: Mozilla 23:37:33 INFO - mozversion application_version: 50.0a1 23:37:33 INFO - mozversion platform_buildid: 20160629223711 23:37:33 INFO - mozversion platform_changeset: 32bb090d7e625a763a80728da2c276724a2c6761 23:37:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:37:33 INFO - mozversion platform_version: 50.0a1 23:37:33 INFO - using testdate: 1467268653 23:37:33 INFO - actual date: 1467268653 23:37:33 INFO - starting webserver on 'localhost:49271' 23:37:33 INFO - SUITE-START | Running 2 tests 23:37:33 INFO - TEST-START | dromaeo_css 23:37:33 INFO - operating with platform_type : mac_ 23:37:33 INFO - Initialising browser for dromaeo_css test... 23:37:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpmO1gbi/profile http://localhost:49271/getInfo.html) 23:37:35 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 23:37:35 INFO - PROCESS | 1656 | colorDepth:24 23:37:35 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 23:37:35 INFO - PROCESS | 1656 | __metrics 23:37:35 INFO - PROCESS | 1656 | 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" 23:37:35 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:37:36 INFO - PROCESS | 1656 | ************************* 23:37:36 INFO - PROCESS | 1656 | A coding exception was thrown in a Promise resolution callback. 23:37:36 INFO - PROCESS | 1656 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 23:37:36 INFO - PROCESS | 1656 | 23:37:36 ERROR - PROCESS | 1656 | Full message: TypeError: win is null 23:37:36 INFO - PROCESS | 1656 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 23:37:36 INFO - PROCESS | 1656 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 23:37:36 INFO - PROCESS | 1656 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 23:37:36 INFO - PROCESS | 1656 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 23:37:36 INFO - PROCESS | 1656 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 23:37:36 INFO - PROCESS | 1656 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 23:37:36 INFO - PROCESS | 1656 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 23:37:36 INFO - PROCESS | 1656 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 23:37:36 INFO - PROCESS | 1656 | 23:37:36 INFO - PROCESS | 1656 | ************************* 23:37:36 INFO - TEST-INFO | 1656: exit 0 23:37:36 INFO - Browser initialized. 23:37:36 INFO - Running cycle 1/1 for dromaeo_css test... 23:37:36 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpmO1gbi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:37:48 INFO - PROCESS | 1659 | RSS: Main: 263348224 23:37:48 INFO - PROCESS | 1659 | 23:38:39 INFO - PROCESS | 1659 | 1467268719558 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] 23:40:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 23:40:52 INFO - PROCESS | 1659 | RSS: Main: 350826496 23:40:52 INFO - PROCESS | 1659 | 23:43:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 23:43:56 INFO - PROCESS | 1659 | RSS: Main: 401596416 23:43:56 INFO - PROCESS | 1659 | 23:46:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 23:46:59 INFO - PROCESS | 1659 | RSS: Main: 390340608 23:46:59 INFO - PROCESS | 1659 | 23:50:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 23:50:02 INFO - PROCESS | 1659 | RSS: Main: 386334720 23:50:02 INFO - PROCESS | 1659 | 23:53:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 23:53:06 INFO - PROCESS | 1659 | RSS: Main: 356429824 23:53:06 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 23:56:09 INFO - PROCESS | 1659 | RSS: Main: 411525120 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | __start_tp_report 23:56:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 23:56:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 23:56:09 INFO - PROCESS | 1659 | |i|pagename|runs| 23:56:09 INFO - PROCESS | 1659 | |0;dojo.html;3352.865885364585;3409.6020631541073;3416.6241713411528;3430.9485357719655;3462.9134271643206;4647.5352464753505;5712.685802280873;5744.655320680752;5765.567582431318;5809.5642826788;5705.74324155413;5776.364599894008;5778.508826749731;5787.98710225711;5814.970925145379;5160.587153027749;5188.299579556769;5216.400113986883;5255.553277971377;5283.8150664726745;5316.175992721134;5319.015982043315;5322.787088516455;5330.466953304658;5333.679979201261;5221.686698798066;5226.137687281594;5228.686278823283;5237.2144178373355;5325.387580428262;5317.41508434072;5336.786528538853;5343.545798607123;5344.732763361855;5377.381601115882;5127.230915362688;5138.486151384832;5156.845294641167;5270.39390470097;5270.736463176864;5714.685692286923;5719.6854173020465;5752.108423194411;5753.453421924911;5776.566757493163;6284.622922624611;6319.304876463585;6323.494120470351;6327.209098862642;6337.87324253517;5373.865653358658;6111.9461670930195;6292.119103325538;6311.652859092748;6331.778387756406;5891.351951285355;5896.380880007623;5931.822045338604;5932.525397968152;5941.673207973517;5946.72145488722;5955.13650520672;6335.841603959938;6349.904751428732;6350.364963503705;6097.8407220865065;6112.724927378279;6154.446099851035;6163.19878415803;6234.594751341148;6317.810465686037;6328.430441260309;6336.17629708135;6351.650659213695;6358;6210.875782484325;6277.278113016971;6278.183836101278;6278.780242691575;6288.5598008139905;6143.341297849555;6215.440610345091;6222.875542489125;6282.340354262828;6291.96854015727;5001.524855138756;5046.142155833444;5050.0657378384385;5051.494850514992;5103.8468845934685;4521.0279789845345;4527.275635898241;4536.3422303765765;4590.472095708969;4596.655250856133;5667.149927510906;5699.658020518782;5718.313802343745;5719.227904232958;5770.682612456271;6372.4583410409705;6380.55336126476;6387.073874288202;6394.328595497499;6398.744050238031;5719.542436605062;5758.309002919593;5766.152158192671;5773.39379365169;5793.420657934173;5364.91952620711;5387.191921211849;5423.267858839085;5490.8078217263;5493.011257973523;6241.937580624226;6264.248290205204;6276.623402595859;6302.779402720883;6344.777932772331;6369.8726025480155;6373.426391624776;6377.170967774346;6378;6401.263854656774;6243.96878015607;6278.560500765086;6334.081558173947;6375.298717141203;6392.648404337806;6182.505399567934;6305.274893387319;6310.684465776784;6312.7159277833525;6325.240971083499;6350.936490635035;6361.777337793155;6373.87252254943;6373.968130159319;6387.265464471459;5937.139114828239;5985.132155837467;6108.159949115189;6121.47967422774;6185.165002724754;6149.292831324276;6165.229346331708;6185.659788711664;6186.226721659793;6206.627602343874;5824.330202026821;6012.579119461596;6016.699165041818;6069.180660610761;6073.605215660968;6010.609310394811;6115.696722738907;6128.50971922254;6152;6175.073738939195;6023.819285421444;6039;6061.484773794277;6065.727042283196;6098.146259523582 23:56:09 INFO - PROCESS | 1659 | |1;ext.html;510.0818526652025;521.1687358662932;547.6960287040691;548.7558036673677;548.8024311247951;25304.114355997543;31219.531707024413;40545.7972710136;41004.4895061543;41118.794406027926;42356;42501.7874910625;42673;42787;42948.92627684309;9634.036596340362;9752.073553012453;9819.607215711363;9826.901730982707;9842.753931151707;8810.38327317088;9052.683156089539;11840;12116.515339386458;12123.393830308494;12143.149979501395;12208.267503949746;12209.938950305235;12215.755684886297;12238.265704057785;8802.207801297915;11861.46623401949;11964.2821430714;12114.878851211462;12130.150889437742;11807.94096029523;12127.818082728767;12135.029197664166;12144.878551214551;12169.330686812222;40611;40903.72770178408;40980;41098.794506027574;41124;3429.0570093224314;3486.3550243205086;3884.7280690351404;4198.181354635847;4285.528591854893;31379.84310078458;32426.513602295985;32828.6717132828;32847.85032523874;32934.3413131736;3277.655846136145;3393.52490651309;3399.3371292597953;3400.4389275769468;3441.535392722001;454019.7299013517;459025.4097459016;461827;462085;462925;59777;59778.10332845011;60212.39787602068;60220;60229.09656355158;51215.7439212809;61627;62259.37740622536;62561.68719156375;62733.372666273666;16201.59496012609;16495.422660207074;16498.917505412635;16511.17444127789;16522.917385413235;11732.648020559396;12003.1597788154;12385.690357740948;12429.689257768447;12667.873321266854;16342.101184434732;16610.252538635792;16648.916755416147;16689.91655041717;16784.916075419787;5566.415526369754;5596.468335508121;6565.934340656628;6585.80242592723;6639.804835129723;888.2139306713605;925.3383830561181;972.3096601413076;1001.7095042437758;1075.5052675769077;28393;29900.85049574738;30087.699123008926;30273.848630756704;30900.53649195264;1009.1573536097441;1029.130384824822;1035.7514196592915;1050.5902697947806;1078.164422572497;15338;15535.76696349533;15559.377624895104;15606.29771660278;15993;637497.8125109344;644899;645184;646088;650872;57177.71411143001;58593.70703146542;58852.70573647104;58869.116963245586;58980;1237.7648246833253;1272.7200015996466;1273.19152338264;1365.3514580574147;1390.9026368154132;22741;23224.76775232226;30835.84582077075;31620;31732.524012139376;48448.27327590019;48812;49338.75330623324;49363.753181233864;49525.257121143935;533574;542664;545186;545346;550649;34854.651453486156;39670.80987570377;40516;41707.123179456394;41745.37381939334;39536;46900.82747931329;47593.048139036335;47984.520154798;48041.75979120082;39403.40894886637;39627.80186099166;39724.20551588894;40056;40600.59399405968;38935;39076;39449;40144.59855401408;40149.799251003555;37559.81220093882;37707;38014.42978355272;38345.42481862719;38391.6160838388;33791.3241735159;33836.66163338335;34634;34913.12717182091;34969.125771855906;20982.580348392643;24351.634725478776;25391.068688599637;25662.61506077373;25743.227703168934 23:56:09 INFO - PROCESS | 1659 | |2;jquery.html;324.76616835878167;352.3569485688618;352.8923678278124;383.25456986161925;409.3409610527051;15693.215339233035;17191.22639481223;20477;20642.690359644617;20742.96285185742;16667.41664041759;18802.683812133157;20654.793452065474;21369.67945480819;21831.890840545813;5768.740406681688;6004.42957918997;6226.40849119333;6274.341194174614;6278.215223097113;6938;7083.929160708404;7100.431965442778;7104.4671649626225;7143.499955003152;6704.631245281507;6953.235144134142;6971.3028697130385;6983.092198014277;7055.082839230893;5868.853278668067;6603.636799976;6872.759453419131;6917.7578784742545;6924.030635711005;5890.646561206341;5902.6458412495385;5954.017587098124;5956.315023772278;5975.462208401264;26619.467610647873;26894.731052689418;27015.054473093543;27280.727192728216;27379.31551711203;2641.511320405731;2650.125458598658;2690.8429375368582;2853.9714602853915;3123.890663826778;15557;16251.318532442132;16381.426650067191;16396;16558.83441165597;1842.97235541467;1867.5237814357251;1907.542189874434;1959.12818795636;1970.162680860643;113577;118704.21943670852;120716.79283207143;121892;123284;9370.437773733462;10788.298760580537;11076.5569377224;11339.659810205707;11379.43102844871;13936.95472839541;14068.226247556482;14089.647758806112;14100.929495352662;14133.222672753098;6687.832804179837;8855.158759917798;8855.73432797017;8932.955335223282;8955.328350373748;7611.706009978317;8851.867221991675;8876.556172191365;9009.459432433943;9022.233110185707;6511.348865113546;6642.701078451481;6790.830229244309;6802.8299292517095;6819.624920629314;1791.435697755203;1819.3905041811142;1819.5360183153475;1831.716084006981;1868.5515476285866;763.9465237433437;841.6465084664334;868.7784614923154;890.875277461156;982.8967958364269;22124.557508850055;22757;22757.08971641149;22801.543969120525;22831.885840571023;938.9061093890693;953.4946478366338;953.68051702679;972.023116767644;1030.644427672452;10265.74335641615;10994.175436842268;11259.831102533468;12056;12259.570915017932;592986;594557;603749;605864;606575;90544.9303219606;109746.35380469299;138094.3095284517;142220;144857;950.2825366848124;964.4936408385654;991.499292857117;995.930284880066;1007.6926537406162;15316.82356740099;19388.612227755082;19581.412557623295;19891.932584158505;19961.52450451631;41456.79271603622;53495;55929.161062584906;56115.71942140263;57158.428415716975;74484.2551574499;78757;82225.58887205526;87277.56361218153;89547;8198.836023279382;8326.833463330579;8414.032386275412;8466.91533084686;8671;13072.934635327143;13271.469141234242;13307.334633268492;13622.38699258516;13653.658658533615;11423;11682.12384071181;11707.590234341757;11746.353950532803;11783.175177737476;9513.809723805347;9672.419654820733;11525.769484610093;11543.249688770204;11606.12954028468;11575.305481671126;11753.588624398106;11870.465829037541;12090.93954530257;12100.092493062879;7019.403350715246;7265.709371625076;7700.152983171959;7980.880286795818;8424.873626895724;9609;11512.19414640967;11763.941180294043;11857.940710296394;11887.346195959306 23:56:09 INFO - PROCESS | 1659 | |3;mootools.html;1492.246415560142;1542.3599206329375;1712.4520153550868;1882.0589705147427;1894.81999210075;8512.744617661472;8776.42953208041;8958.328125390597;8966.23786978107;8968;7823.960880195591;8048.074471435787;8050.597470126485;8151.8777218341775;8750.256228220609;2126.9361919142452;2160.686700428437;2296.5062511560013;2368.8815559222057;2381.4641705616186;2376.2989917974237;2389.2115601851433;2390.032037025008;2391.151141344824;2393.8563686178886;2326.906923723049;2377.8811059447044;2381.4641705616186;2382.940426489334;2383.416063064543;1991.701244813275;2097.758757742864;2298.5402919416183;2374.643803429482;2375.5011447596025;2375.916842910507;2388.4028992751814;2413.613821788523;2415.9154429595055;2415.9154429595055;8901.599428025755;8976.820463590691;8981.775455613662;8981.955090224572;8993.235574976135;5250.868728281824;5334.413214546396;5401;5405.999890020359;5424.186372044185;3529.6293889141525;3584.4623306503972;3655.7623754455876;3660.0300920256154;3673.430618254175;1060.151878497199;1080.1143062688598;1080.400377790326;1109.389835590422;1110.655696734015;11761.882381176165;11834.940825295904;11852.644420667393;11854.585089521912;11864.169508134433;4536.138133754576;4739.573438390493;4815.927761083586;4848.975755121272;4856.708597484162;9922;9979.251556133318;9992.65025724096;9999.200063994864;10000.849987250198;5355.143177091647;5357.812476563302;5360.222767698662;5372.704501252469;5374.596905232123;4754.191787396151;4932.21084626458;4933.9506604933495;4938.75306234694;4971.080350135237;3151.921201969969;3533.7703049301713;3671.8714844980295;3804.3722785740597;3869.6517313441364;1046.9790604188026;1110.5058249079082;1164.545827127421;1175.8236264560214;1176.4294317256124;896.1844721303713;899.5861903524452;913.3652111782247;947.0576776107816;947.545178314413;5878.853028674232;6003.0695242237525;6004.609700369462;6152.821078906627;6244.344343843831;818.5947955761936;919.3013309884536;925.4632313258294;953.5327689432129;982.6216906491053;3815.523059617548;4013.959860401417;4030.133521292936;4089.734167279117;4117.300058989979;10612.363258204532;10889.128869690407;10954.397508137128;10967.396793176293;10969.067629251444;10892.945535272273;10908.836367454494;10920.962508561674;10934.726631834108;10980.505877235544;7953;8243.299319557906;8298;8309.459885107448;8420.200080992296;4985.975070124626;6035.1249068885645;6083.878322433615;6180.227471566054;6352.523560732871;10087.243456740627;10253;10319.845202322122;10341.37951722899;10352.792944141225;8791.252743516872;10039.749006274902;10145.54345054489;10531.368117912949;10540.15678745714;7085.468589855885;7657.770266891779;7789;7802.375809935305;7811.648475818715;7912.84174316522;8279.917200827915;8509.787255318666;8530.70142545008;8627.35294852876;7561.621918903923;7599.848003040019;7660.080790305199;7739.41954353437;7789.64946577394;7446.478746487694;7530.359919406693;7534.736284230025;7548.3961283098315;7617.5048621839405;7061.929380706127;7068.717251310096;7131.714731410893;7135.036770035978;7148.749793757193;4928.926066108939;4942.604591632589;5130.97434512825;5159.716215608178;5174.404943431403;5122.129238029469;5146.76839542214;5196.948030519647;5212.791488340424;5284.339457567804 23:56:09 INFO - PROCESS | 1659 | |4;prototype.html;300.96388433387995;305.41055762378613;308.63118573304905;311.9298157914468;315.048553368826;4549.545045495449;4860.58685011774;5323.653962492435;5387.19192121182;5393.898508357691;4612.469565999911;5252.765600083977;5292.803486553076;5310.04419204544;5338.412774594792;3831.616838316174;3870.0518372998513;3999.320115580358;4099.713020088595;4133.697885166178;3329.833508324586;3447.5690538682666;4043.029672878503;4052.7568345899194;4099.3031184698675;4033.334499807528;4054.776987265699;4059.3505039193733;4065.8576949806616;4087.7751723655188;3410.36908171989;3830.233953209355;3985.80070996449;4083.1629515949157;4084.550699423061;3316.8722634304454;3498.5801703795705;3535.5403797506415;3541.698955588778;3558.0430763080203;4000.9399859002137;4339.587739164775;4677.158111539921;5151.948480515184;5340.652857564285;3721.5348081489815;4052.824680842552;4647.86056418308;4669.182892993713;4705.6470764692785;5303.923724239931;5587.8603034924045;5618.381977982419;5621.100623900197;5634.746436410372;3344.6989770920736;3747.707041070826;3829.5596006459336;3850.7497012694084;3852.4413959975927;5131.640785145079;5559.331520869965;5862.648241105548;5868.2664666916635;5920.733566989496;3634.23681026982;3797.810109494514;3844.9231015380096;3845.576986531479;3858.459815625815;2977.3896351248377;3631.2555926035097;3928.3518219493462;3934.626210509997;3940.8817735467983;3779.9244015119543;3855.9614403856162;3874.380099184117;4539.5460453955;4617.238155704338;4991.675541089818;5334.839954801362;5616.522595579421;5650.180723795027;5665.3768085510565;1169.4796764206496;1256.7737807194792;1294.1911305434103;1334.2861569060506;1336.432016393029;668.5387082912774;783.7021931666045;799.4044436894551;807.9798005049921;827.6606591297659;5069.9746501267255;5129.820456284012;5250.7374631268285;5263.763130659129;5284.630075894727;4864.683795553277;5760.337561180434;5820.126980952891;5835.44563266489;5858.681796595732;4637.884052898705;4930.975345123252;5217.843464696065;5263.894722105613;5275.683458992396;3000.354923691417;3014.623172103487;3038.650555186182;3046.8628911699025;3067.263856674382;5338.626296159309;5496.7526461309335;5694.181380216736;5909.763609455659;5962.403759624003;4558.703684260512;5496.477834605706;5890.970545147246;5917.852053698692;6004.309504406961;4924.359833221658;6012.879742405127;6047.2182795913495;6049.243844519435;6053.4854537363935;5463.590230732791;5669.598241055333;5862.501687356623;5896.9115463268945;5933.753911678596;5711.457411545896;5771.1920331154515;5772.826815195551;5806.506446952057;5870.53035757147;5072.315237443046;6105.755769769159;6106.267642577039;6114.97308832131;6137.508999279958;2945.617069780915;3685.70514358856;3856.9421458678703;3889.5414219667623;3895.006773272797;5444.346678398618;5790.507806836466;5796.188533605215;5808.361080281081;5863.882722345615;5714.79998200061;5726.74229659658;5734.770609175753;5739.2252045973255;5742.5118864895285;4908.803647854046;5356.276902618097;5645.596434780878;5674.404187560247;5686.943130568641;4948.282499037691;5203.323567936295;5482.23248745184;5580.218769372373;5590.071425358245;4811.975940120277;5102.591792656504;5290.841274761763;5548.306461692288;5566.777328906798;4617.822455273884;4673.228917228619;5467.808626698046;5527.668339899619;5568.554515638658 23:56:09 INFO - PROCESS | 1659 | |5;yui.html;686.0292685849523;702.8207807009218;715.2561336210341;770.2990278846247;824.4599787139422;14630;15976.281067351969;15985.84014159858;16004.839951600496;16039.919800400996;12456.626301210956;14025.509107181251;14300.998930074898;14521.20133392663;14528.709425811478;647.1780838335311;674.6120980436269;803.0844836885925;813.3046245460121;839.3830534557096;719.5035425556349;832.76682528892;839.3243439031577;845.0239972831359;845.8858054162702;896.3053633434108;899.4378513429107;899.4872922434215;900.158351940934;900.4912224593116;683.3166833166833;829.9253067223918;886.0519244408421;888.3159966825508;897.0804924951943;570.7403131575123;658.8254112660148;659.0641289369107;660.7390080918059;661.6394065234459;14670.559883203414;14689.192094434802;14710.411583536647;14719.852801471956;14728.18994955277;13695.86304136956;13928.93035534826;13941.093828901088;13951.372188251553;14095;12643.873561264361;12665.810012849815;12753.489860405572;12925.12585675072;12959;4578.427696537933;4586.3579098926175;4649.674522783406;4690.319903613957;4748.976255118702;106762.46618766857;109574;109654;109847;110024.34963475555;10853.782924341469;10921.508532116073;10923.726906827393;11310.94344528272;11398.430078496041;21663.35009949704;21717.45706357322;21723.239686610894;21727.565448691254;21728.674069888963;7861.764147075597;7865.567393793282;7896.09194942589;8072.3138533224155;8110.797230069295;4334.263175260214;4391.802368893407;4392.275274579659;4444.666650001198;4573.428321459817;3424.5719285089363;3530.858765649345;3532.1346270163463;3538.292341531704;3546.982265088658;605.8000859716283;647.695583075963;653.6764301670703;655.1875674164097;656.0454538646259;434.71960585422227;491.987700307488;562.3224015061841;599.6462087368386;654.8297442664846;7662.667831889997;9077.183053525214;9208.309376796633;9644.807103857884;9781.386071298299;551.5973339462215;563.8505795964073;659.7591878964143;669.8392385827366;675.9695813688396;3365.9495107573407;3758.755680880734;3768.3028639701743;3920.0591857953887;3981.3828856527284;124584;140128.50525476833;142581;145476.27261863623;147222;109855.45072274747;112457.87542124474;113199.43400282945;114877;115769.10577235637;101073;109260.25917926706;111023.44488277669;118772.40613796876;119880.8309839448;7944.721934732488;9865.564837270565;9914.504274786375;10146.289759716748;10177.898221017695;47636;59298.814023720144;67032;67222;68513;99605.47737238024;102822.69060474621;103066.48466757618;104696.47651761693;106025;13332.40004199794;13415;13512.332250598594;13537.864621354054;13553.796693049415;15060;15148.621284467976;15221.923890380478;15413.383464661289;16054.036757794569;12014.699632509257;12254.75490490203;12303.630891073282;12470.1270911039;12495;14008.789868151782;14113.647158821112;14379.928100359431;14499.202543859767;14794.6301342463;13757.573055526373;14010.78983815264;14011.369488373251;14342.713145736818;14459.349329279998;11459.255148415326;11635.592754253561;11649.708757281136;11672.649820505398;11864.347460889734;10744.247902647052;11488.253263537843;11542.711432214262;11580.594679186188;11765.823512647146 23:56:09 INFO - PROCESS | 1659 | __end_tp_report 23:56:09 INFO - PROCESS | 1659 | __start_cc_report 23:56:09 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1314 23:56:09 INFO - PROCESS | 1659 | __end_cc_report 23:56:09 INFO - PROCESS | 1659 | __startTimestamp1467269769710__endTimestamp 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | ------- Summary: start ------- 23:56:09 INFO - PROCESS | 1659 | Number of tests: 6 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5795.82 Median:6012.58 stddev:638.98 (10.6%) stddev-sans-first:611.53 23:56:09 INFO - PROCESS | 1659 | Values: 3352.9 3409.6 3416.6 3430.9 3462.9 4647.5 5712.7 5744.7 5765.6 5809.6 5705.7 5776.4 5778.5 5788.0 5815.0 5160.6 5188.3 5216.4 5255.6 5283.8 5316.2 5319.0 5322.8 5330.5 5333.7 5221.7 5226.1 5228.7 5237.2 5325.4 5317.4 5336.8 5343.5 5344.7 5377.4 5127.2 5138.5 5156.8 5270.4 5270.7 5714.7 5719.7 5752.1 5753.5 5776.6 6284.6 6319.3 6323.5 6327.2 6337.9 5373.9 6111.9 6292.1 6311.7 6331.8 5891.4 5896.4 5931.8 5932.5 5941.7 5946.7 5955.1 6335.8 6349.9 6350.4 6097.8 6112.7 6154.4 6163.2 6234.6 6317.8 6328.4 6336.2 6351.7 6358.0 6210.9 6277.3 6278.2 6278.8 6288.6 6143.3 6215.4 6222.9 6282.3 6292.0 5001.5 5046.1 5050.1 5051.5 5103.8 4521.0 4527.3 4536.3 4590.5 4596.7 5667.1 5699.7 5718.3 5719.2 5770.7 6372.5 6380.6 6387.1 6394.3 6398.7 5719.5 5758.3 5766.2 5773.4 5793.4 5364.9 5387.2 5423.3 5490.8 5493.0 6241.9 6264.2 6276.6 6302.8 6344.8 6369.9 6373.4 6377.2 6378.0 6401.3 6244.0 6278.6 6334.1 6375.3 6392.6 6182.5 6305.3 6310.7 6312.7 6325.2 6350.9 6361.8 6373.9 6374.0 6387.3 5937.1 5985.1 6108.2 6121.5 6185.2 6149.3 6165.2 6185.7 6186.2 6206.6 5824.3 6012.6 6016.7 6069.2 6073.6 6010.6 6115.7 6128.5 6152.0 6175.1 6023.8 6039.0 6061.5 6065.7 6098.1 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:68954.52 Median:39005.50 stddev:147997.56 (379.4%) stddev-sans-first:148323.52 23:56:09 INFO - PROCESS | 1659 | Values: 510.1 521.2 547.7 548.8 548.8 25304.1 31219.5 40545.8 41004.5 41118.8 42356.0 42501.8 42673.0 42787.0 42948.9 9634.0 9752.1 9819.6 9826.9 9842.8 8810.4 9052.7 11840.0 12116.5 12123.4 12143.1 12208.3 12209.9 12215.8 12238.3 8802.2 11861.5 11964.3 12114.9 12130.2 11807.9 12127.8 12135.0 12144.9 12169.3 40611.0 40903.7 40980.0 41098.8 41124.0 3429.1 3486.4 3884.7 4198.2 4285.5 31379.8 32426.5 32828.7 32847.9 32934.3 3277.7 3393.5 3399.3 3400.4 3441.5 454019.7 459025.4 461827.0 462085.0 462925.0 59777.0 59778.1 60212.4 60220.0 60229.1 51215.7 61627.0 62259.4 62561.7 62733.4 16201.6 16495.4 16498.9 16511.2 16522.9 11732.6 12003.2 12385.7 12429.7 12667.9 16342.1 16610.3 16648.9 16689.9 16784.9 5566.4 5596.5 6565.9 6585.8 6639.8 888.2 925.3 972.3 1001.7 1075.5 28393.0 29900.9 30087.7 30273.8 30900.5 1009.2 1029.1 1035.8 1050.6 1078.2 15338.0 15535.8 15559.4 15606.3 15993.0 637497.8 644899.0 645184.0 646088.0 650872.0 57177.7 58593.7 58852.7 58869.1 58980.0 1237.8 1272.7 1273.2 1365.4 1390.9 22741.0 23224.8 30835.8 31620.0 31732.5 48448.3 48812.0 49338.8 49363.8 49525.3 533574.0 542664.0 545186.0 545346.0 550649.0 34854.7 39670.8 40516.0 41707.1 41745.4 39536.0 46900.8 47593.0 47984.5 48041.8 39403.4 39627.8 39724.2 40056.0 40600.6 38935.0 39076.0 39449.0 40144.6 40149.8 37559.8 37707.0 38014.4 38345.4 38391.6 33791.3 33836.7 34634.0 34913.1 34969.1 20982.6 24351.6 25391.1 25662.6 25743.2 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35609.02 Median:2988.93 stddev:100236.36 (3353.6%) stddev-sans-first:100482.53 23:56:09 INFO - PROCESS | 1659 | Values: 324.8 352.4 352.9 383.3 409.3 15693.2 17191.2 20477.0 20642.7 20743.0 16667.4 18802.7 20654.8 21369.7 21831.9 5768.7 6004.4 6226.4 6274.3 6278.2 6938.0 7083.9 7100.4 7104.5 7143.5 6704.6 6953.2 6971.3 6983.1 7055.1 5868.9 6603.6 6872.8 6917.8 6924.0 5890.6 5902.6 5954.0 5956.3 5975.5 26619.5 26894.7 27015.1 27280.7 27379.3 2641.5 2650.1 2690.8 2854.0 3123.9 15557.0 16251.3 16381.4 16396.0 16558.8 1843.0 1867.5 1907.5 1959.1 1970.2 113577.0 118704.2 120716.8 121892.0 123284.0 9370.4 10788.3 11076.6 11339.7 11379.4 13937.0 14068.2 14089.6 14100.9 14133.2 6687.8 8855.2 8855.7 8933.0 8955.3 7611.7 8851.9 8876.6 9009.5 9022.2 6511.3 6642.7 6790.8 6802.8 6819.6 1791.4 1819.4 1819.5 1831.7 1868.6 763.9 841.6 868.8 890.9 982.9 22124.6 22757.0 22757.1 22801.5 22831.9 938.9 953.5 953.7 972.0 1030.6 10265.7 10994.2 11259.8 12056.0 12259.6 592986.0 594557.0 603749.0 605864.0 606575.0 90544.9 109746.4 138094.3 142220.0 144857.0 950.3 964.5 991.5 995.9 1007.7 15316.8 19388.6 19581.4 19891.9 19961.5 41456.8 53495.0 55929.2 56115.7 57158.4 74484.3 78757.0 82225.6 87277.6 89547.0 8198.8 8326.8 8414.0 8466.9 8671.0 13072.9 13271.5 13307.3 13622.4 13653.7 11423.0 11682.1 11707.6 11746.4 11783.2 9513.8 9672.4 11525.8 11543.2 11606.1 11575.3 11753.6 11870.5 12090.9 12100.1 7019.4 7265.7 7700.2 7980.9 8424.9 9609.0 11512.2 11763.9 11857.9 11887.3 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5770.26 Median:4956.84 stddev:3251.04 (65.6%) stddev-sans-first:3244.26 23:56:09 INFO - PROCESS | 1659 | Values: 1492.2 1542.4 1712.5 1882.1 1894.8 8512.7 8776.4 8958.3 8966.2 8968.0 7824.0 8048.1 8050.6 8151.9 8750.3 2126.9 2160.7 2296.5 2368.9 2381.5 2376.3 2389.2 2390.0 2391.2 2393.9 2326.9 2377.9 2381.5 2382.9 2383.4 1991.7 2097.8 2298.5 2374.6 2375.5 2375.9 2388.4 2413.6 2415.9 2415.9 8901.6 8976.8 8981.8 8982.0 8993.2 5250.9 5334.4 5401.0 5406.0 5424.2 3529.6 3584.5 3655.8 3660.0 3673.4 1060.2 1080.1 1080.4 1109.4 1110.7 11761.9 11834.9 11852.6 11854.6 11864.2 4536.1 4739.6 4815.9 4849.0 4856.7 9922.0 9979.3 9992.7 9999.2 10000.8 5355.1 5357.8 5360.2 5372.7 5374.6 4754.2 4932.2 4934.0 4938.8 4971.1 3151.9 3533.8 3671.9 3804.4 3869.7 1047.0 1110.5 1164.5 1175.8 1176.4 896.2 899.6 913.4 947.1 947.5 5878.9 6003.1 6004.6 6152.8 6244.3 818.6 919.3 925.5 953.5 982.6 3815.5 4014.0 4030.1 4089.7 4117.3 10612.4 10889.1 10954.4 10967.4 10969.1 10892.9 10908.8 10921.0 10934.7 10980.5 7953.0 8243.3 8298.0 8309.5 8420.2 4986.0 6035.1 6083.9 6180.2 6352.5 10087.2 10253.0 10319.8 10341.4 10352.8 8791.3 10039.7 10145.5 10531.4 10540.2 7085.5 7657.8 7789.0 7802.4 7811.6 7912.8 8279.9 8509.8 8530.7 8627.4 7561.6 7599.8 7660.1 7739.4 7789.6 7446.5 7530.4 7534.7 7548.4 7617.5 7061.9 7068.7 7131.7 7135.0 7148.7 4928.9 4942.6 5131.0 5159.7 5174.4 5122.1 5146.8 5196.9 5212.8 5284.3 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4520.70 Median:5130.73 stddev:1431.20 (27.9%) stddev-sans-first:1399.73 23:56:09 INFO - PROCESS | 1659 | Values: 301.0 305.4 308.6 311.9 315.0 4549.5 4860.6 5323.7 5387.2 5393.9 4612.5 5252.8 5292.8 5310.0 5338.4 3831.6 3870.1 3999.3 4099.7 4133.7 3329.8 3447.6 4043.0 4052.8 4099.3 4033.3 4054.8 4059.4 4065.9 4087.8 3410.4 3830.2 3985.8 4083.2 4084.6 3316.9 3498.6 3535.5 3541.7 3558.0 4000.9 4339.6 4677.2 5151.9 5340.7 3721.5 4052.8 4647.9 4669.2 4705.6 5303.9 5587.9 5618.4 5621.1 5634.7 3344.7 3747.7 3829.6 3850.7 3852.4 5131.6 5559.3 5862.6 5868.3 5920.7 3634.2 3797.8 3844.9 3845.6 3858.5 2977.4 3631.3 3928.4 3934.6 3940.9 3779.9 3856.0 3874.4 4539.5 4617.2 4991.7 5334.8 5616.5 5650.2 5665.4 1169.5 1256.8 1294.2 1334.3 1336.4 668.5 783.7 799.4 808.0 827.7 5070.0 5129.8 5250.7 5263.8 5284.6 4864.7 5760.3 5820.1 5835.4 5858.7 4637.9 4931.0 5217.8 5263.9 5275.7 3000.4 3014.6 3038.7 3046.9 3067.3 5338.6 5496.8 5694.2 5909.8 5962.4 4558.7 5496.5 5891.0 5917.9 6004.3 4924.4 6012.9 6047.2 6049.2 6053.5 5463.6 5669.6 5862.5 5896.9 5933.8 5711.5 5771.2 5772.8 5806.5 5870.5 5072.3 6105.8 6106.3 6115.0 6137.5 2945.6 3685.7 3856.9 3889.5 3895.0 5444.3 5790.5 5796.2 5808.4 5863.9 5714.8 5726.7 5734.8 5739.2 5742.5 4908.8 5356.3 5645.6 5674.4 5686.9 4948.3 5203.3 5482.2 5580.2 5590.1 4812.0 5102.6 5290.8 5548.3 5566.8 4617.8 4673.2 5467.8 5527.7 5568.6 23:56:09 INFO - PROCESS | 1659 | 23:56:09 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24603.03 Median:18858.69 stddev:38454.13 (203.9%) stddev-sans-first:38520.07 23:56:09 INFO - PROCESS | 1659 | Values: 686.0 702.8 715.3 770.3 824.5 14630.0 15976.3 15985.8 16004.8 16039.9 12456.6 14025.5 14301.0 14521.2 14528.7 647.2 674.6 803.1 813.3 839.4 719.5 832.8 839.3 845.0 845.9 896.3 899.4 899.5 900.2 900.5 683.3 829.9 886.1 888.3 897.1 570.7 658.8 659.1 660.7 661.6 14670.6 14689.2 14710.4 14719.9 14728.2 13695.9 13928.9 13941.1 13951.4 14095.0 12643.9 12665.8 12753.5 12925.1 12959.0 4578.4 4586.4 4649.7 4690.3 4749.0 106762.5 109574.0 109654.0 109847.0 110024.3 10853.8 10921.5 10923.7 11310.9 11398.4 21663.4 21717.5 21723.2 21727.6 21728.7 7861.8 7865.6 7896.1 8072.3 8110.8 4334.3 4391.8 4392.3 4444.7 4573.4 3424.6 3530.9 3532.1 3538.3 3547.0 605.8 647.7 653.7 655.2 656.0 434.7 492.0 562.3 599.6 654.8 7662.7 9077.2 9208.3 9644.8 9781.4 551.6 563.9 659.8 669.8 676.0 3365.9 3758.8 3768.3 3920.1 3981.4 124584.0 140128.5 142581.0 145476.3 147222.0 109855.5 112457.9 113199.4 114877.0 115769.1 101073.0 109260.3 111023.4 118772.4 119880.8 7944.7 9865.6 9914.5 10146.3 10177.9 47636.0 59298.8 67032.0 67222.0 68513.0 99605.5 102822.7 103066.5 104696.5 106025.0 13332.4 13415.0 13512.3 13537.9 13553.8 15060.0 15148.6 15221.9 15413.4 16054.0 12014.7 12254.8 12303.6 12470.1 12495.0 14008.8 14113.6 14379.9 14499.2 14794.6 13757.6 14010.8 14011.4 14342.7 14459.3 11459.3 11635.6 11649.7 11672.6 11864.3 10744.2 11488.3 11542.7 11580.6 11765.8 23:56:09 INFO - PROCESS | 1659 | -------- Summary: end -------- 23:56:09 INFO - PROCESS | 1659 | 23:56:10 INFO - TEST-INFO | 1659: exit 0 23:56:10 INFO - TEST-OK | dromaeo_css | took 1116772ms 23:56:10 INFO - TEST-START | kraken 23:56:10 INFO - operating with platform_type : mac_ 23:56:10 INFO - Initialising browser for kraken test... 23:56:10 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpiMw23C/profile http://localhost:49271/getInfo.html) 23:56:12 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 23:56:12 INFO - PROCESS | 1664 | colorDepth:24 23:56:12 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 23:56:12 INFO - PROCESS | 1664 | __metrics 23:56:12 INFO - PROCESS | 1664 | 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" 23:56:12 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:56:12 INFO - PROCESS | 1664 | ************************* 23:56:12 INFO - PROCESS | 1664 | A coding exception was thrown in a Promise resolution callback. 23:56:12 INFO - PROCESS | 1664 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 23:56:12 INFO - PROCESS | 1664 | 23:56:12 ERROR - PROCESS | 1664 | Full message: TypeError: win is null 23:56:12 INFO - PROCESS | 1664 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 23:56:12 INFO - PROCESS | 1664 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 23:56:12 INFO - PROCESS | 1664 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 23:56:12 INFO - PROCESS | 1664 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 23:56:12 INFO - PROCESS | 1664 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 23:56:12 INFO - PROCESS | 1664 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 23:56:12 INFO - PROCESS | 1664 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 23:56:12 INFO - PROCESS | 1664 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 23:56:12 INFO - PROCESS | 1664 | 23:56:12 INFO - PROCESS | 1664 | ************************* 23:56:13 INFO - TEST-INFO | 1664: exit 0 23:56:13 INFO - Browser initialized. 23:56:13 INFO - Running cycle 1/1 for kraken test... 23:56:13 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpiMw23C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:56:25 INFO - PROCESS | 1665 | RSS: Main: 261312512 23:56:25 INFO - PROCESS | 1665 | 23:56:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 23:56:28 INFO - PROCESS | 1665 | RSS: Main: 308174848 23:56:28 INFO - PROCESS | 1665 | 23:56:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 23:56:31 INFO - PROCESS | 1665 | RSS: Main: 318300160 23:56:31 INFO - PROCESS | 1665 | 23:56:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 23:56:36 INFO - PROCESS | 1665 | RSS: Main: 402665472 23:56:36 INFO - PROCESS | 1665 | 23:56:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 23:56:38 INFO - PROCESS | 1665 | RSS: Main: 302911488 23:56:38 INFO - PROCESS | 1665 | 23:56:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:56:41 INFO - PROCESS | 1665 | RSS: Main: 307224576 23:56:41 INFO - PROCESS | 1665 | 23:56:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 23:56:44 INFO - PROCESS | 1665 | RSS: Main: 343580672 23:56:44 INFO - PROCESS | 1665 | 23:56:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 23:56:48 INFO - PROCESS | 1665 | RSS: Main: 346316800 23:56:48 INFO - PROCESS | 1665 | 23:56:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 23:56:51 INFO - PROCESS | 1665 | RSS: Main: 343609344 23:56:51 INFO - PROCESS | 1665 | 23:56:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:56:54 INFO - PROCESS | 1665 | RSS: Main: 325926912 23:56:54 INFO - PROCESS | 1665 | 23:56:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:56:56 INFO - PROCESS | 1665 | RSS: Main: 332849152 23:56:56 INFO - PROCESS | 1665 | 23:56:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:56:59 INFO - PROCESS | 1665 | RSS: Main: 329916416 23:56:59 INFO - PROCESS | 1665 | 23:57:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:57:03 INFO - PROCESS | 1665 | RSS: Main: 339087360 23:57:03 INFO - PROCESS | 1665 | 23:57:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:57:06 INFO - PROCESS | 1665 | RSS: Main: 340058112 23:57:06 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 23:57:08 INFO - PROCESS | 1665 | RSS: Main: 383328256 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | __start_tp_report 23:57:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 23:57:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 23:57:08 INFO - PROCESS | 1665 | |i|pagename|runs| 23:57:08 INFO - PROCESS | 1665 | |0;ai-astar;103;113;113;113;113;113;114;114;112;112 23:57:08 INFO - PROCESS | 1665 | |1;audio-beat-detection;144;109;109;159;109;108;130;103;134;109 23:57:08 INFO - PROCESS | 1665 | |2;audio-dft;187;183;184;184;182;186;180;183;183;183 23:57:08 INFO - PROCESS | 1665 | |3;audio-fft;80;75;58;107;57;57;62;82;85;70 23:57:08 INFO - PROCESS | 1665 | |4;audio-oscillator;84;89;93;71;68;72;121;102;99;101 23:57:08 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;91;87;90;87;91;86;90;87;89;86 23:57:08 INFO - PROCESS | 1665 | |6;imaging-darkroom;94;95;96;96;95;95;96;95;94;94 23:57:08 INFO - PROCESS | 1665 | |7;imaging-desaturate;84;85;84;83;83;87;99;133;142;83 23:57:08 INFO - PROCESS | 1665 | |8;json-parse-financial;56;55;53;54;54;56;53;53;54;54 23:57:08 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;64;73;79;75;71;70;71;68;69;75 23:57:08 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;82;81;93;101;99;101;84;82;81;85 23:57:08 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;137;137;138;136;136;136;135;136;174;138 23:57:08 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;171;169;171;171;169;167;174;169;169;167 23:57:08 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;68;69;68;68;68;68;68;68;67 23:57:08 INFO - PROCESS | 1665 | __end_tp_report 23:57:08 INFO - PROCESS | 1665 | __start_cc_report 23:57:08 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2021 23:57:08 INFO - PROCESS | 1665 | __end_cc_report 23:57:08 INFO - PROCESS | 1665 | __startTimestamp1467269828479__endTimestamp 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | ------- Summary: start ------- 23:57:08 INFO - PROCESS | 1665 | Number of tests: 14 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.23 (2.9%) stddev-sans-first:0.71 23:57:08 INFO - PROCESS | 1665 | Values: 103.0 113.0 113.0 113.0 113.0 113.0 114.0 114.0 112.0 112.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:119.50 stddev:19.12 (16.0%) stddev-sans-first:18.45 23:57:08 INFO - PROCESS | 1665 | Values: 144.0 109.0 109.0 159.0 109.0 108.0 130.0 103.0 134.0 109.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.96 (1.1%) stddev-sans-first:1.62 23:57:08 INFO - PROCESS | 1665 | Values: 187.0 183.0 184.0 184.0 182.0 186.0 180.0 183.0 183.0 183.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:73.30 Median:72.50 stddev:15.99 (22.1%) stddev-sans-first:16.77 23:57:08 INFO - PROCESS | 1665 | Values: 80.0 75.0 58.0 107.0 57.0 57.0 62.0 82.0 85.0 70.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:78.00 stddev:16.74 (21.5%) stddev-sans-first:17.61 23:57:08 INFO - PROCESS | 1665 | Values: 84.0 89.0 93.0 71.0 68.0 72.0 121.0 102.0 99.0 101.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.50 stddev:2.01 (2.2%) stddev-sans-first:1.90 23:57:08 INFO - PROCESS | 1665 | Values: 91.0 87.0 90.0 87.0 91.0 86.0 90.0 87.0 89.0 86.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 23:57:08 INFO - PROCESS | 1665 | Values: 94.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 94.0 94.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:84.00 stddev:22.34 (26.6%) stddev-sans-first:23.24 23:57:08 INFO - PROCESS | 1665 | Values: 84.0 85.0 84.0 83.0 83.0 87.0 99.0 133.0 142.0 83.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:1.00 23:57:08 INFO - PROCESS | 1665 | Values: 56.0 55.0 53.0 54.0 54.0 56.0 53.0 53.0 54.0 54.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.00 stddev:4.22 (5.9%) stddev-sans-first:3.50 23:57:08 INFO - PROCESS | 1665 | Values: 64.0 73.0 79.0 75.0 71.0 70.0 71.0 68.0 69.0 75.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:83.00 stddev:8.63 (10.4%) stddev-sans-first:8.79 23:57:08 INFO - PROCESS | 1665 | Values: 82.0 81.0 93.0 101.0 99.0 101.0 84.0 82.0 81.0 85.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:137.00 stddev:11.88 (8.7%) stddev-sans-first:12.54 23:57:08 INFO - PROCESS | 1665 | Values: 137.0 137.0 138.0 136.0 136.0 136.0 135.0 136.0 174.0 138.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:2.11 (1.2%) stddev-sans-first:2.19 23:57:08 INFO - PROCESS | 1665 | Values: 171.0 169.0 171.0 171.0 169.0 167.0 174.0 169.0 169.0 167.0 23:57:08 INFO - PROCESS | 1665 | 23:57:08 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 23:57:08 INFO - PROCESS | 1665 | Values: 68.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 68.0 67.0 23:57:08 INFO - PROCESS | 1665 | -------- Summary: end -------- 23:57:08 INFO - PROCESS | 1665 | 23:57:09 INFO - TEST-INFO | 1665: exit 0 23:57:09 INFO - TEST-OK | kraken | took 58974ms 23:57:09 INFO - SUITE-END | took 1175s 23:57:09 INFO - Completed test suite (00:19:36) 23:57:09 INFO - javascript benchmark 23:57:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.865885364585, 3409.6020631541073, 3416.6241713411528, 3430.9485357719655, 3462.9134271643206, 4647.5352464753505, 5712.685802280873, 5744.655320680752, 5765.567582431318, 5809.5642826788, 5705.74324155413, 5776.364599894008, 5778.508826749731, 5787.98710225711, 5814.970925145379, 5160.587153027749, 5188.299579556769, 5216.400113986883, 5255.553277971377, 5283.8150664726745, 5316.175992721134, 5319.015982043315, 5322.787088516455, 5330.466953304658, 5333.679979201261, 5221.686698798066, 5226.137687281594, 5228.686278823283, 5237.2144178373355, 5325.387580428262, 5317.41508434072, 5336.786528538853, 5343.545798607123, 5344.732763361855, 5377.381601115882, 5127.230915362688, 5138.486151384832, 5156.845294641167, 5270.39390470097, 5270.736463176864, 5714.685692286923, 5719.6854173020465, 5752.108423194411, 5753.453421924911, 5776.566757493163, 6284.622922624611, 6319.304876463585, 6323.494120470351, 6327.209098862642, 6337.87324253517, 5373.865653358658, 6111.9461670930195, 6292.119103325538, 6311.652859092748, 6331.778387756406, 5891.351951285355, 5896.380880007623, 5931.822045338604, 5932.525397968152, 5941.673207973517, 5946.72145488722, 5955.13650520672, 6335.841603959938, 6349.904751428732, 6350.364963503705, 6097.8407220865065, 6112.724927378279, 6154.446099851035, 6163.19878415803, 6234.594751341148, 6317.810465686037, 6328.430441260309, 6336.17629708135, 6351.650659213695, 6358.0, 6210.875782484325, 6277.278113016971, 6278.183836101278, 6278.780242691575, 6288.5598008139905, 6143.341297849555, 6215.440610345091, 6222.875542489125, 6282.340354262828, 6291.96854015727, 5001.524855138756, 5046.142155833444, 5050.0657378384385, 5051.494850514992, 5103.8468845934685, 4521.0279789845345, 4527.275635898241, 4536.3422303765765, 4590.472095708969, 4596.655250856133, 5667.149927510906, 5699.658020518782, 5718.313802343745, 5719.227904232958, 5770.682612456271, 6372.4583410409705, 6380.55336126476, 6387.073874288202, 6394.328595497499, 6398.744050238031, 5719.542436605062, 5758.309002919593, 5766.152158192671, 5773.39379365169, 5793.420657934173, 5364.91952620711, 5387.191921211849, 5423.267858839085, 5490.8078217263, 5493.011257973523, 6241.937580624226, 6264.248290205204, 6276.623402595859, 6302.779402720883, 6344.777932772331, 6369.8726025480155, 6373.426391624776, 6377.170967774346, 6378.0, 6401.263854656774, 6243.96878015607, 6278.560500765086, 6334.081558173947, 6375.298717141203, 6392.648404337806, 6182.505399567934, 6305.274893387319, 6310.684465776784, 6312.7159277833525, 6325.240971083499, 6350.936490635035, 6361.777337793155, 6373.87252254943, 6373.968130159319, 6387.265464471459, 5937.139114828239, 5985.132155837467, 6108.159949115189, 6121.47967422774, 6185.165002724754, 6149.292831324276, 6165.229346331708, 6185.659788711664, 6186.226721659793, 6206.627602343874, 5824.330202026821, 6012.579119461596, 6016.699165041818, 6069.180660610761, 6073.605215660968, 6010.609310394811, 6115.696722738907, 6128.50971922254, 6152.0, 6175.073738939195, 6023.819285421444, 6039.0, 6061.484773794277, 6065.727042283196, 6098.146259523582], "value": 5755.519184433289, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.0818526652025, 521.1687358662932, 547.6960287040691, 548.7558036673677, 548.8024311247951, 25304.114355997543, 31219.531707024413, 40545.7972710136, 41004.4895061543, 41118.794406027926, 42356.0, 42501.7874910625, 42673.0, 42787.0, 42948.92627684309, 9634.036596340362, 9752.073553012453, 9819.607215711363, 9826.901730982707, 9842.753931151707, 8810.38327317088, 9052.683156089539, 11840.0, 12116.515339386458, 12123.393830308494, 12143.149979501395, 12208.267503949746, 12209.938950305235, 12215.755684886297, 12238.265704057785, 8802.207801297915, 11861.46623401949, 11964.2821430714, 12114.878851211462, 12130.150889437742, 11807.94096029523, 12127.818082728767, 12135.029197664166, 12144.878551214551, 12169.330686812222, 40611.0, 40903.72770178408, 40980.0, 41098.794506027574, 41124.0, 3429.0570093224314, 3486.3550243205086, 3884.7280690351404, 4198.181354635847, 4285.528591854893, 31379.84310078458, 32426.513602295985, 32828.6717132828, 32847.85032523874, 32934.3413131736, 3277.655846136145, 3393.52490651309, 3399.3371292597953, 3400.4389275769468, 3441.535392722001, 454019.7299013517, 459025.4097459016, 461827.0, 462085.0, 462925.0, 59777.0, 59778.10332845011, 60212.39787602068, 60220.0, 60229.09656355158, 51215.7439212809, 61627.0, 62259.37740622536, 62561.68719156375, 62733.372666273666, 16201.59496012609, 16495.422660207074, 16498.917505412635, 16511.17444127789, 16522.917385413235, 11732.648020559396, 12003.1597788154, 12385.690357740948, 12429.689257768447, 12667.873321266854, 16342.101184434732, 16610.252538635792, 16648.916755416147, 16689.91655041717, 16784.916075419787, 5566.415526369754, 5596.468335508121, 6565.934340656628, 6585.80242592723, 6639.804835129723, 888.2139306713605, 925.3383830561181, 972.3096601413076, 1001.7095042437758, 1075.5052675769077, 28393.0, 29900.85049574738, 30087.699123008926, 30273.848630756704, 30900.53649195264, 1009.1573536097441, 1029.130384824822, 1035.7514196592915, 1050.5902697947806, 1078.164422572497, 15338.0, 15535.76696349533, 15559.377624895104, 15606.29771660278, 15993.0, 637497.8125109344, 644899.0, 645184.0, 646088.0, 650872.0, 57177.71411143001, 58593.70703146542, 58852.70573647104, 58869.116963245586, 58980.0, 1237.7648246833253, 1272.7200015996466, 1273.19152338264, 1365.3514580574147, 1390.9026368154132, 22741.0, 23224.76775232226, 30835.84582077075, 31620.0, 31732.524012139376, 48448.27327590019, 48812.0, 49338.75330623324, 49363.753181233864, 49525.257121143935, 533574.0, 542664.0, 545186.0, 545346.0, 550649.0, 34854.651453486156, 39670.80987570377, 40516.0, 41707.123179456394, 41745.37381939334, 39536.0, 46900.82747931329, 47593.048139036335, 47984.520154798, 48041.75979120082, 39403.40894886637, 39627.80186099166, 39724.20551588894, 40056.0, 40600.59399405968, 38935.0, 39076.0, 39449.0, 40144.59855401408, 40149.799251003555, 37559.81220093882, 37707.0, 38014.42978355272, 38345.42481862719, 38391.6160838388, 33791.3241735159, 33836.66163338335, 34634.0, 34913.12717182091, 34969.125771855906, 20982.580348392643, 24351.634725478776, 25391.068688599637, 25662.61506077373, 25743.227703168934], "value": 20395.849755996456, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.76616835878167, 352.3569485688618, 352.8923678278124, 383.25456986161925, 409.3409610527051, 15693.215339233035, 17191.22639481223, 20477.0, 20642.690359644617, 20742.96285185742, 16667.41664041759, 18802.683812133157, 20654.793452065474, 21369.67945480819, 21831.890840545813, 5768.740406681688, 6004.42957918997, 6226.40849119333, 6274.341194174614, 6278.215223097113, 6938.0, 7083.929160708404, 7100.431965442778, 7104.4671649626225, 7143.499955003152, 6704.631245281507, 6953.235144134142, 6971.3028697130385, 6983.092198014277, 7055.082839230893, 5868.853278668067, 6603.636799976, 6872.759453419131, 6917.7578784742545, 6924.030635711005, 5890.646561206341, 5902.6458412495385, 5954.017587098124, 5956.315023772278, 5975.462208401264, 26619.467610647873, 26894.731052689418, 27015.054473093543, 27280.727192728216, 27379.31551711203, 2641.511320405731, 2650.125458598658, 2690.8429375368582, 2853.9714602853915, 3123.890663826778, 15557.0, 16251.318532442132, 16381.426650067191, 16396.0, 16558.83441165597, 1842.97235541467, 1867.5237814357251, 1907.542189874434, 1959.12818795636, 1970.162680860643, 113577.0, 118704.21943670852, 120716.79283207143, 121892.0, 123284.0, 9370.437773733462, 10788.298760580537, 11076.5569377224, 11339.659810205707, 11379.43102844871, 13936.95472839541, 14068.226247556482, 14089.647758806112, 14100.929495352662, 14133.222672753098, 6687.832804179837, 8855.158759917798, 8855.73432797017, 8932.955335223282, 8955.328350373748, 7611.706009978317, 8851.867221991675, 8876.556172191365, 9009.459432433943, 9022.233110185707, 6511.348865113546, 6642.701078451481, 6790.830229244309, 6802.8299292517095, 6819.624920629314, 1791.435697755203, 1819.3905041811142, 1819.5360183153475, 1831.716084006981, 1868.5515476285866, 763.9465237433437, 841.6465084664334, 868.7784614923154, 890.875277461156, 982.8967958364269, 22124.557508850055, 22757.0, 22757.08971641149, 22801.543969120525, 22831.885840571023, 938.9061093890693, 953.4946478366338, 953.68051702679, 972.023116767644, 1030.644427672452, 10265.74335641615, 10994.175436842268, 11259.831102533468, 12056.0, 12259.570915017932, 592986.0, 594557.0, 603749.0, 605864.0, 606575.0, 90544.9303219606, 109746.35380469299, 138094.3095284517, 142220.0, 144857.0, 950.2825366848124, 964.4936408385654, 991.499292857117, 995.930284880066, 1007.6926537406162, 15316.82356740099, 19388.612227755082, 19581.412557623295, 19891.932584158505, 19961.52450451631, 41456.79271603622, 53495.0, 55929.161062584906, 56115.71942140263, 57158.428415716975, 74484.2551574499, 78757.0, 82225.58887205526, 87277.56361218153, 89547.0, 8198.836023279382, 8326.833463330579, 8414.032386275412, 8466.91533084686, 8671.0, 13072.934635327143, 13271.469141234242, 13307.334633268492, 13622.38699258516, 13653.658658533615, 11423.0, 11682.12384071181, 11707.590234341757, 11746.353950532803, 11783.175177737476, 9513.809723805347, 9672.419654820733, 11525.769484610093, 11543.249688770204, 11606.12954028468, 11575.305481671126, 11753.588624398106, 11870.465829037541, 12090.93954530257, 12100.092493062879, 7019.403350715246, 7265.709371625076, 7700.152983171959, 7980.880286795818, 8424.873626895724, 9609.0, 11512.19414640967, 11763.941180294043, 11857.940710296394, 11887.346195959306], "value": 10050.197088350995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.246415560142, 1542.3599206329375, 1712.4520153550868, 1882.0589705147427, 1894.81999210075, 8512.744617661472, 8776.42953208041, 8958.328125390597, 8966.23786978107, 8968.0, 7823.960880195591, 8048.074471435787, 8050.597470126485, 8151.8777218341775, 8750.256228220609, 2126.9361919142452, 2160.686700428437, 2296.5062511560013, 2368.8815559222057, 2381.4641705616186, 2376.2989917974237, 2389.2115601851433, 2390.032037025008, 2391.151141344824, 2393.8563686178886, 2326.906923723049, 2377.8811059447044, 2381.4641705616186, 2382.940426489334, 2383.416063064543, 1991.701244813275, 2097.758757742864, 2298.5402919416183, 2374.643803429482, 2375.5011447596025, 2375.916842910507, 2388.4028992751814, 2413.613821788523, 2415.9154429595055, 2415.9154429595055, 8901.599428025755, 8976.820463590691, 8981.775455613662, 8981.955090224572, 8993.235574976135, 5250.868728281824, 5334.413214546396, 5401.0, 5405.999890020359, 5424.186372044185, 3529.6293889141525, 3584.4623306503972, 3655.7623754455876, 3660.0300920256154, 3673.430618254175, 1060.151878497199, 1080.1143062688598, 1080.400377790326, 1109.389835590422, 1110.655696734015, 11761.882381176165, 11834.940825295904, 11852.644420667393, 11854.585089521912, 11864.169508134433, 4536.138133754576, 4739.573438390493, 4815.927761083586, 4848.975755121272, 4856.708597484162, 9922.0, 9979.251556133318, 9992.65025724096, 9999.200063994864, 10000.849987250198, 5355.143177091647, 5357.812476563302, 5360.222767698662, 5372.704501252469, 5374.596905232123, 4754.191787396151, 4932.21084626458, 4933.9506604933495, 4938.75306234694, 4971.080350135237, 3151.921201969969, 3533.7703049301713, 3671.8714844980295, 3804.3722785740597, 3869.6517313441364, 1046.9790604188026, 1110.5058249079082, 1164.545827127421, 1175.8236264560214, 1176.4294317256124, 896.1844721303713, 899.5861903524452, 913.3652111782247, 947.0576776107816, 947.545178314413, 5878.853028674232, 6003.0695242237525, 6004.609700369462, 6152.821078906627, 6244.344343843831, 818.5947955761936, 919.3013309884536, 925.4632313258294, 953.5327689432129, 982.6216906491053, 3815.523059617548, 4013.959860401417, 4030.133521292936, 4089.734167279117, 4117.300058989979, 10612.363258204532, 10889.128869690407, 10954.397508137128, 10967.396793176293, 10969.067629251444, 10892.945535272273, 10908.836367454494, 10920.962508561674, 10934.726631834108, 10980.505877235544, 7953.0, 8243.299319557906, 8298.0, 8309.459885107448, 8420.200080992296, 4985.975070124626, 6035.1249068885645, 6083.878322433615, 6180.227471566054, 6352.523560732871, 10087.243456740627, 10253.0, 10319.845202322122, 10341.37951722899, 10352.792944141225, 8791.252743516872, 10039.749006274902, 10145.54345054489, 10531.368117912949, 10540.15678745714, 7085.468589855885, 7657.770266891779, 7789.0, 7802.375809935305, 7811.648475818715, 7912.84174316522, 8279.917200827915, 8509.787255318666, 8530.70142545008, 8627.35294852876, 7561.621918903923, 7599.848003040019, 7660.080790305199, 7739.41954353437, 7789.64946577394, 7446.478746487694, 7530.359919406693, 7534.736284230025, 7548.3961283098315, 7617.5048621839405, 7061.929380706127, 7068.717251310096, 7131.714731410893, 7135.036770035978, 7148.749793757193, 4928.926066108939, 4942.604591632589, 5130.97434512825, 5159.716215608178, 5174.404943431403, 5122.129238029469, 5146.76839542214, 5196.948030519647, 5212.791488340424, 5284.339457567804], "value": 4616.852168889744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.96388433387995, 305.41055762378613, 308.63118573304905, 311.9298157914468, 315.048553368826, 4549.545045495449, 4860.58685011774, 5323.653962492435, 5387.19192121182, 5393.898508357691, 4612.469565999911, 5252.765600083977, 5292.803486553076, 5310.04419204544, 5338.412774594792, 3831.616838316174, 3870.0518372998513, 3999.320115580358, 4099.713020088595, 4133.697885166178, 3329.833508324586, 3447.5690538682666, 4043.029672878503, 4052.7568345899194, 4099.3031184698675, 4033.334499807528, 4054.776987265699, 4059.3505039193733, 4065.8576949806616, 4087.7751723655188, 3410.36908171989, 3830.233953209355, 3985.80070996449, 4083.1629515949157, 4084.550699423061, 3316.8722634304454, 3498.5801703795705, 3535.5403797506415, 3541.698955588778, 3558.0430763080203, 4000.9399859002137, 4339.587739164775, 4677.158111539921, 5151.948480515184, 5340.652857564285, 3721.5348081489815, 4052.824680842552, 4647.86056418308, 4669.182892993713, 4705.6470764692785, 5303.923724239931, 5587.8603034924045, 5618.381977982419, 5621.100623900197, 5634.746436410372, 3344.6989770920736, 3747.707041070826, 3829.5596006459336, 3850.7497012694084, 3852.4413959975927, 5131.640785145079, 5559.331520869965, 5862.648241105548, 5868.2664666916635, 5920.733566989496, 3634.23681026982, 3797.810109494514, 3844.9231015380096, 3845.576986531479, 3858.459815625815, 2977.3896351248377, 3631.2555926035097, 3928.3518219493462, 3934.626210509997, 3940.8817735467983, 3779.9244015119543, 3855.9614403856162, 3874.380099184117, 4539.5460453955, 4617.238155704338, 4991.675541089818, 5334.839954801362, 5616.522595579421, 5650.180723795027, 5665.3768085510565, 1169.4796764206496, 1256.7737807194792, 1294.1911305434103, 1334.2861569060506, 1336.432016393029, 668.5387082912774, 783.7021931666045, 799.4044436894551, 807.9798005049921, 827.6606591297659, 5069.9746501267255, 5129.820456284012, 5250.7374631268285, 5263.763130659129, 5284.630075894727, 4864.683795553277, 5760.337561180434, 5820.126980952891, 5835.44563266489, 5858.681796595732, 4637.884052898705, 4930.975345123252, 5217.843464696065, 5263.894722105613, 5275.683458992396, 3000.354923691417, 3014.623172103487, 3038.650555186182, 3046.8628911699025, 3067.263856674382, 5338.626296159309, 5496.7526461309335, 5694.181380216736, 5909.763609455659, 5962.403759624003, 4558.703684260512, 5496.477834605706, 5890.970545147246, 5917.852053698692, 6004.309504406961, 4924.359833221658, 6012.879742405127, 6047.2182795913495, 6049.243844519435, 6053.4854537363935, 5463.590230732791, 5669.598241055333, 5862.501687356623, 5896.9115463268945, 5933.753911678596, 5711.457411545896, 5771.1920331154515, 5772.826815195551, 5806.506446952057, 5870.53035757147, 5072.315237443046, 6105.755769769159, 6106.267642577039, 6114.97308832131, 6137.508999279958, 2945.617069780915, 3685.70514358856, 3856.9421458678703, 3889.5414219667623, 3895.006773272797, 5444.346678398618, 5790.507806836466, 5796.188533605215, 5808.361080281081, 5863.882722345615, 5714.79998200061, 5726.74229659658, 5734.770609175753, 5739.2252045973255, 5742.5118864895285, 4908.803647854046, 5356.276902618097, 5645.596434780878, 5674.404187560247, 5686.943130568641, 4948.282499037691, 5203.323567936295, 5482.23248745184, 5580.218769372373, 5590.071425358245, 4811.975940120277, 5102.591792656504, 5290.841274761763, 5548.306461692288, 5566.777328906798, 4617.822455273884, 4673.228917228619, 5467.808626698046, 5527.668339899619, 5568.554515638658], "value": 4058.5288977410996, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.0292685849523, 702.8207807009218, 715.2561336210341, 770.2990278846247, 824.4599787139422, 14630.0, 15976.281067351969, 15985.84014159858, 16004.839951600496, 16039.919800400996, 12456.626301210956, 14025.509107181251, 14300.998930074898, 14521.20133392663, 14528.709425811478, 647.1780838335311, 674.6120980436269, 803.0844836885925, 813.3046245460121, 839.3830534557096, 719.5035425556349, 832.76682528892, 839.3243439031577, 845.0239972831359, 845.8858054162702, 896.3053633434108, 899.4378513429107, 899.4872922434215, 900.158351940934, 900.4912224593116, 683.3166833166833, 829.9253067223918, 886.0519244408421, 888.3159966825508, 897.0804924951943, 570.7403131575123, 658.8254112660148, 659.0641289369107, 660.7390080918059, 661.6394065234459, 14670.559883203414, 14689.192094434802, 14710.411583536647, 14719.852801471956, 14728.18994955277, 13695.86304136956, 13928.93035534826, 13941.093828901088, 13951.372188251553, 14095.0, 12643.873561264361, 12665.810012849815, 12753.489860405572, 12925.12585675072, 12959.0, 4578.427696537933, 4586.3579098926175, 4649.674522783406, 4690.319903613957, 4748.976255118702, 106762.46618766857, 109574.0, 109654.0, 109847.0, 110024.34963475555, 10853.782924341469, 10921.508532116073, 10923.726906827393, 11310.94344528272, 11398.430078496041, 21663.35009949704, 21717.45706357322, 21723.239686610894, 21727.565448691254, 21728.674069888963, 7861.764147075597, 7865.567393793282, 7896.09194942589, 8072.3138533224155, 8110.797230069295, 4334.263175260214, 4391.802368893407, 4392.275274579659, 4444.666650001198, 4573.428321459817, 3424.5719285089363, 3530.858765649345, 3532.1346270163463, 3538.292341531704, 3546.982265088658, 605.8000859716283, 647.695583075963, 653.6764301670703, 655.1875674164097, 656.0454538646259, 434.71960585422227, 491.987700307488, 562.3224015061841, 599.6462087368386, 654.8297442664846, 7662.667831889997, 9077.183053525214, 9208.309376796633, 9644.807103857884, 9781.386071298299, 551.5973339462215, 563.8505795964073, 659.7591878964143, 669.8392385827366, 675.9695813688396, 3365.9495107573407, 3758.755680880734, 3768.3028639701743, 3920.0591857953887, 3981.3828856527284, 124584.0, 140128.50525476833, 142581.0, 145476.27261863623, 147222.0, 109855.45072274747, 112457.87542124474, 113199.43400282945, 114877.0, 115769.10577235637, 101073.0, 109260.25917926706, 111023.44488277669, 118772.40613796876, 119880.8309839448, 7944.721934732488, 9865.564837270565, 9914.504274786375, 10146.289759716748, 10177.898221017695, 47636.0, 59298.814023720144, 67032.0, 67222.0, 68513.0, 99605.47737238024, 102822.69060474621, 103066.48466757618, 104696.47651761693, 106025.0, 13332.40004199794, 13415.0, 13512.332250598594, 13537.864621354054, 13553.796693049415, 15060.0, 15148.621284467976, 15221.923890380478, 15413.383464661289, 16054.036757794569, 12014.699632509257, 12254.75490490203, 12303.630891073282, 12470.1270911039, 12495.0, 14008.789868151782, 14113.647158821112, 14379.928100359431, 14499.202543859767, 14794.6301342463, 13757.573055526373, 14010.78983815264, 14011.369488373251, 14342.713145736818, 14459.349329279998, 11459.255148415326, 11635.592754253561, 11649.708757281136, 11672.649820505398, 11864.347460889734, 10744.247902647052, 11488.253263537843, 11542.711432214262, 11580.594679186188, 11765.823512647146], "value": 7762.26686367595, "unit": "score"}], "name": "dromaeo_css", "value": 7454.61551503152, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 109.0, 159.0, 109.0, 108.0, 130.0, 103.0, 134.0, 109.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 184.0, 182.0, 186.0, 180.0, 183.0, 183.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 107.0, 57.0, 57.0, 62.0, 82.0, 85.0, 70.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 93.0, 71.0, 68.0, 72.0, 121.0, 102.0, 99.0, 101.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 87.0, 91.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 83.0, 83.0, 87.0, 99.0, 133.0, 142.0, 83.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 54.0, 54.0, 56.0, 53.0, 53.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 79.0, 75.0, 71.0, 70.0, 71.0, 68.0, 69.0, 75.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 93.0, 101.0, 99.0, 101.0, 84.0, 82.0, 81.0, 85.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 138.0, 136.0, 136.0, 136.0, 135.0, 136.0, 174.0, 138.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 171.0, 171.0, 169.0, 167.0, 174.0, 169.0, 169.0, 167.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1452.5, "alertThreshold": 2.0}]} 23:57:09 INFO - Return code: 0 23:57:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:57:09 ERROR - # TBPL SUCCESS # 23:57:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:57:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:57:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:57:09 INFO - 2016-06-29 23:57:09.767 screenresolution[1669:11634] starting screenresolution argv=screenresolution get 23:57:09 INFO - 2016-06-29 23:57:09.769 screenresolution[1669:11634] Display 0: 1600x1200x32@60 23:57:09 INFO - 2016-06-29 23:57:09.775 screenresolution[1670:11637] starting screenresolution argv=screenresolution list 23:57:09 INFO - Available Modes on Display 0 23:57:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:57:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:57:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:57:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:57:10 INFO - Intel Iris: 23:57:10 INFO - Chipset Model: Intel Iris 23:57:10 INFO - Type: GPU 23:57:10 INFO - Bus: Built-In 23:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 23:57:10 INFO - Vendor: Intel (0x8086) 23:57:10 INFO - Device ID: 0x0a2e 23:57:10 INFO - Revision ID: 0x0009 23:57:10 INFO - Displays: 23:57:10 INFO - Display: 23:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:57:10 INFO - Display Serial Number: 1600x1200 60 23:57:10 INFO - Main Display: Yes 23:57:10 INFO - Mirror: Off 23:57:10 INFO - Online: Yes 23:57:10 INFO - Rotation: Supported 23:57:10 INFO - Return code: 0 23:57:10 INFO - Running post-action listener: _resource_record_post_action 23:57:10 INFO - ##### 23:57:10 INFO - ##### Finished run-tests step (success) 23:57:10 INFO - ##### 23:57:10 INFO - Running post-run listener: _resource_record_post_run 23:57:10 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31346176; Write bytes: 704543744; Read time: 199; Write time: 1936 23:57:10 INFO - TinderboxPrint: CPU usage
53.2% 23:57:10 INFO - TinderboxPrint: I/O read bytes / time
31,346,176 / 199 23:57:10 INFO - TinderboxPrint: I/O write bytes / time
704,543,744 / 1,936 23:57:10 INFO - TinderboxPrint: CPU idle
2,266.6 (46.8%) 23:57:10 INFO - TinderboxPrint: CPU system
961.7 (19.8%) 23:57:10 INFO - TinderboxPrint: CPU user
1,617.4 (33.4%) 23:57:10 INFO - TinderboxPrint: Swap in / out
434,208,768 / 0 23:57:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 402711040; Write bytes: 397674496; Read time: 30316; Write time: 803 23:57:10 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29277696; Write bytes: 293045248; Read time: 180; Write time: 1094 23:57:10 INFO - Running post-run listener: _upload_blobber_files 23:57:10 INFO - Blob upload gear active. 23:57:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:57:10 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 23:57:10 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'] 23:57:10 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 23:57:10 INFO - (blobuploader) - INFO - Open directory for files ... 23:57:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:57:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:57:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:11 INFO - (blobuploader) - INFO - Done attempting. 23:57:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:57:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:57:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:12 INFO - (blobuploader) - INFO - Done attempting. 23:57:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:57:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:57:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:57:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:57:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:57:15 INFO - (blobuploader) - INFO - Done attempting. 23:57:15 INFO - (blobuploader) - INFO - Iteration through files over. 23:57:15 INFO - Return code: 0 23:57:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:57:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:57:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/368fea18ac8fa5bc07c4d02eb9ebbcbb6a3f5a61d34310f95523feb12574feec9567d8fa2274efcea9b997c2fd3426d879963fe23badab9a7c19e521e62137eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9813e344f1d0c8d84b6dc1868f5cdfd34b8840dd547add1a33214eb845134fd832224bdc7be83a15080543d13c4b8a980448b178c7dade47e5bbc8cd0b436fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e3d6794683274e52b2fef607f1ae55b48f5b4f2f5dc39d15d6b2847a24e9a0aa8ef4014ca29d136eac4d5192736e91c3dc51bf4e34019e19036a30383984cb0"} 23:57:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:57:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:57:15 INFO - Contents: 23:57:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/368fea18ac8fa5bc07c4d02eb9ebbcbb6a3f5a61d34310f95523feb12574feec9567d8fa2274efcea9b997c2fd3426d879963fe23badab9a7c19e521e62137eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9813e344f1d0c8d84b6dc1868f5cdfd34b8840dd547add1a33214eb845134fd832224bdc7be83a15080543d13c4b8a980448b178c7dade47e5bbc8cd0b436fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e3d6794683274e52b2fef607f1ae55b48f5b4f2f5dc39d15d6b2847a24e9a0aa8ef4014ca29d136eac4d5192736e91c3dc51bf4e34019e19036a30383984cb0"} 23:57:15 INFO - Running post-run listener: copy_logs_to_upload_dir 23:57:15 INFO - Copying logs to upload dir... 23:57:15 INFO - mkdir: /builds/slave/test/build/upload/logs 23:57:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.842336 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 23:57:15.335314) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 23:57:15.338679) ========= 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.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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/368fea18ac8fa5bc07c4d02eb9ebbcbb6a3f5a61d34310f95523feb12574feec9567d8fa2274efcea9b997c2fd3426d879963fe23badab9a7c19e521e62137eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9813e344f1d0c8d84b6dc1868f5cdfd34b8840dd547add1a33214eb845134fd832224bdc7be83a15080543d13c4b8a980448b178c7dade47e5bbc8cd0b436fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e3d6794683274e52b2fef607f1ae55b48f5b4f2f5dc39d15d6b2847a24e9a0aa8ef4014ca29d136eac4d5192736e91c3dc51bf4e34019e19036a30383984cb0"} build_url:https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/UYgHH_GYTYm01n4nwiyPIA/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/368fea18ac8fa5bc07c4d02eb9ebbcbb6a3f5a61d34310f95523feb12574feec9567d8fa2274efcea9b997c2fd3426d879963fe23badab9a7c19e521e62137eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9813e344f1d0c8d84b6dc1868f5cdfd34b8840dd547add1a33214eb845134fd832224bdc7be83a15080543d13c4b8a980448b178c7dade47e5bbc8cd0b436fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e3d6794683274e52b2fef607f1ae55b48f5b4f2f5dc39d15d6b2847a24e9a0aa8ef4014ca29d136eac4d5192736e91c3dc51bf4e34019e19036a30383984cb0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 23:57:15.371870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:57:15.372203) ========= 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.5I9I05KF0d/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.ghjsMFMoBo/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005009 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:57:15.421589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 23:57:15.421900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 23:57:15.422257) ========= ========= Total master_lag: 0.98 =========