builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1468330718.78 results: success (0) buildid: 20160712054127 builduid: 4e015d0ed2844457a1140ade2c50af73 revision: d9c1917d74d588280f47213716cd6012b5a0902e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:38.783616) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:38.784285) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:38.784910) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:38.895811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:38.896263) ========= 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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-12 06:38:38-- 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% 8.22M=0.001s 2016-07-12 06:38:39 (8.22 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098140 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:39.028491) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:39.028877) ========= 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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.020717 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:39.079072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:39.079478) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d9c1917d74d588280f47213716cd6012b5a0902e --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 d9c1917d74d588280f47213716cd6012b5a0902e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-12 06:38:39,159 truncating revision to first 12 chars 2016-07-12 06:38:39,159 Setting DEBUG logging. 2016-07-12 06:38:39,159 attempt 1/10 2016-07-12 06:38:39,159 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d9c1917d74d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 06:38:39,828 unpacking tar archive at: fx-team-d9c1917d74d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.940890 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:40.046889) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:40.047522) ========= script_repo_revision: d9c1917d74d588280f47213716cd6012b5a0902e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:40.047975) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:40.048285) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 06:38:40.066646) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-12 06:38:40.067189) ========= /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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:38:40 INFO - MultiFileLogger online at 20160712 06:38:40 in /builds/slave/test 06:38:40 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 06:38:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:38:40 INFO - {'append_to_log': False, 06:38:40 INFO - 'base_work_dir': '/builds/slave/test', 06:38:40 INFO - 'blob_upload_branch': 'Fx-Team', 06:38:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:38:40 INFO - 'branch': 'Fx-Team', 06:38:40 INFO - 'buildbot_json_path': 'buildprops.json', 06:38:40 INFO - 'config_files': ('talos/mac_config.py',), 06:38:40 INFO - 'default_actions': ('clobber', 06:38:40 INFO - 'read-buildbot-config', 06:38:40 INFO - 'download-and-extract', 06:38:40 INFO - 'populate-webroot', 06:38:40 INFO - 'create-virtualenv', 06:38:40 INFO - 'install', 06:38:40 INFO - 'run-tests'), 06:38:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:38:40 INFO - 'download_minidump_stackwalk': True, 06:38:40 INFO - 'download_symbols': 'ondemand', 06:38:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:38:40 INFO - 'tooltool.py': '/tools/tooltool.py', 06:38:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:38:40 INFO - '/tools/misc-python/virtualenv.py')}, 06:38:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:38:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:38:40 INFO - 'installer_path': 'installer.exe', 06:38:40 INFO - 'log_level': 'info', 06:38:40 INFO - 'log_name': 'talos', 06:38:40 INFO - 'log_to_console': True, 06:38:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:38:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:38:40 INFO - 'opt_config_files': (), 06:38:40 INFO - 'pip_index': False, 06:38:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:38:40 INFO - 'cmd': ('bash', 06:38:40 INFO - '-c', 06:38:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:38:40 INFO - 'enabled': True, 06:38:40 INFO - 'halt_on_failure': False, 06:38:40 INFO - 'name': 'check_screen_resolution'},), 06:38:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:38:40 INFO - 'cmd': ('bash', 06:38:40 INFO - '-c', 06:38:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:38:40 INFO - 'enabled': True, 06:38:40 INFO - 'halt_on_failure': False, 06:38:40 INFO - 'name': 'check_screen_resolution'},), 06:38:40 INFO - 'run_cmd_checks_enabled': True, 06:38:40 INFO - 'sps_profile': False, 06:38:40 INFO - 'sps_profile_interval': 0, 06:38:40 INFO - 'suite': 'dromaeojs', 06:38:40 INFO - 'system_bits': '32', 06:38:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:38:40 INFO - 'title': 't-yosemite-r7-0292', 06:38:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:38:40 INFO - 'use_talos_json': True, 06:38:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:38:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:38:40 INFO - 'work_dir': 'build'} 06:38:40 INFO - ##### 06:38:40 INFO - ##### Running clobber step. 06:38:40 INFO - ##### 06:38:40 INFO - Running pre-action listener: _resource_record_pre_action 06:38:40 INFO - Running main action method: clobber 06:38:40 INFO - rmtree: /builds/slave/test/build 06:38:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:38:40 INFO - Running post-action listener: _resource_record_post_action 06:38:40 INFO - ##### 06:38:40 INFO - ##### Finished clobber step (success) 06:38:40 INFO - ##### 06:38:40 INFO - ##### 06:38:40 INFO - ##### Running read-buildbot-config step. 06:38:40 INFO - ##### 06:38:40 INFO - Running pre-action listener: _resource_record_pre_action 06:38:40 INFO - Running main action method: read_buildbot_config 06:38:40 INFO - Using buildbot properties: 06:38:40 INFO - { 06:38:40 INFO - "project": "", 06:38:40 INFO - "product": "firefox", 06:38:40 INFO - "script_repo_revision": "production", 06:38:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:38:40 INFO - "repository": "", 06:38:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:38:40 INFO - "buildid": "20160712054127", 06:38:40 INFO - "pgo_build": "False", 06:38:40 INFO - "basedir": "/builds/slave/test", 06:38:40 INFO - "buildnumber": 82, 06:38:40 INFO - "slavename": "t-yosemite-r7-0292", 06:38:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:38:40 INFO - "platform": "yosemite_r7", 06:38:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:38:40 INFO - "branch": "fx-team", 06:38:40 INFO - "repo_path": "integration/fx-team", 06:38:40 INFO - "revision": "d9c1917d74d588280f47213716cd6012b5a0902e", 06:38:40 INFO - "stage_platform": "macosx64", 06:38:40 INFO - "builduid": "4e015d0ed2844457a1140ade2c50af73", 06:38:40 INFO - "slavebuilddir": "test" 06:38:40 INFO - } 06:38:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 06:38:40 INFO - Running post-action listener: _resource_record_post_action 06:38:40 INFO - ##### 06:38:40 INFO - ##### Finished read-buildbot-config step (success) 06:38:40 INFO - ##### 06:38:40 INFO - ##### 06:38:40 INFO - ##### Running download-and-extract step. 06:38:40 INFO - ##### 06:38:40 INFO - Running pre-action listener: _resource_record_pre_action 06:38:40 INFO - Running main action method: download_and_extract 06:38:40 INFO - mkdir: /builds/slave/test/build/tests 06:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:38:40 INFO - https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:38:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:38:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:38:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:42 INFO - Downloaded 1199 bytes. 06:38:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:38:42 INFO - Using the following test package requirements: 06:38:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:38:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:38:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:38:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'jsshell-mac.zip'], 06:38:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:38:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:38:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:38:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:38:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:38:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:38:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:38:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:38:42 INFO - https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:38:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:38:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:38:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:43 INFO - Downloaded 18772462 bytes. 06:38:43 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'] 06:38:43 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 06:38:46 INFO - Return code: 0 06:38:46 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 06:38: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')]} 06:38:46 INFO - https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:38:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:38:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:38:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:47 INFO - Downloaded 18772462 bytes. 06:38:47 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'] 06:38:47 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 06:38:50 INFO - Return code: 0 06:38: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')]} 06:38:50 INFO - https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:38:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:38:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:38:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:52 INFO - Downloaded 14403317 bytes. 06:38:52 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'] 06:38:52 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 06:38:52 INFO - Return code: 0 06:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:38:52 INFO - https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:38:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:38:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:38:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:38:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InYo09UeRaSJyBfZIhURFw/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 06:38:56 INFO - Downloaded 112424848 bytes. 06:38:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:38:56 INFO - mkdir: /builds/slave/test/properties 06:38:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:38:56 INFO - Writing to file /builds/slave/test/properties/build_url 06:38:56 INFO - Contents: 06:38:56 INFO - build_url:https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:38:57 INFO - Running post-action listener: _resource_record_post_action 06:38:57 INFO - Running post-action listener: set_extra_try_arguments 06:38:57 INFO - ##### 06:38:57 INFO - ##### Finished download-and-extract step (success) 06:38:57 INFO - ##### 06:38:57 INFO - ##### 06:38:57 INFO - ##### Running populate-webroot step. 06:38:57 INFO - ##### 06:38:57 INFO - Running pre-action listener: _resource_record_pre_action 06:38:57 INFO - Running main action method: populate_webroot 06:38:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:38:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:38:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:38:57 INFO - '--tppagecycles', 06:38:57 INFO - '7'], 06:38:57 INFO - 'tests': ['tsvgm']}}, 06:38:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:38:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:38:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:38:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:38:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:38:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:38:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['damp', 'tps']}, 06:38:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'tests': ['damp', 'tps']}, 06:38:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['dromaeo_dom']}, 06:38:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:38:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['basic_compositor_video']}, 06:38:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:38:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'other-e10s': {'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['a11yr', 06:38:57 INFO - 'ts_paint', 06:38:57 INFO - 'tpaint', 06:38:57 INFO - 'sessionrestore', 06:38:57 INFO - 'sessionrestore_no_auto_restore', 06:38:57 INFO - 'tabpaint']}, 06:38:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['tsvgx', 06:38:57 INFO - 'tsvgr_opacity', 06:38:57 INFO - 'tart', 06:38:57 INFO - 'tscrollx', 06:38:57 INFO - 'cart']}, 06:38:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:38:57 INFO - 'tsvgr_opacity', 06:38:57 INFO - 'tart', 06:38:57 INFO - 'tscrollx', 06:38:57 INFO - 'cart']}, 06:38:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'talos_options': ['--disable-e10s'], 06:38:57 INFO - 'tests': ['tp5o']}, 06:38:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'tests': ['tp5o']}, 06:38:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'talos_options': ['--disable-e10s', 06:38:57 INFO - '--xperf_path', 06:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:38:57 INFO - 'tests': ['tp5n']}, 06:38:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:38:57 INFO - 'talos_options': ['--xperf_path', 06:38:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:38:57 INFO - 'tests': ['tp5n']}}, 06:38:57 INFO - 'talos.zip': {'path': '', 06:38:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:38:57 INFO - Running post-action listener: _resource_record_post_action 06:38:57 INFO - ##### 06:38:57 INFO - ##### Finished populate-webroot step (success) 06:38:57 INFO - ##### 06:38:57 INFO - ##### 06:38:57 INFO - ##### Running create-virtualenv step. 06:38:57 INFO - ##### 06:38:57 INFO - Running pre-action listener: _resource_record_pre_action 06:38:57 INFO - Running main action method: create_virtualenv 06:38:57 INFO - Creating virtualenv /builds/slave/test/build/venv 06:38:57 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 06:38:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:38:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:38:58 INFO - Using real prefix '/tools/python27' 06:38:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:38:58 INFO - Installing distribute.............................................................................................................................................................................................done. 06:39:01 INFO - Installing pip.................done. 06:39:01 INFO - Return code: 0 06:39:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:01 INFO - 'HOME': '/Users/cltbld', 06:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:01 INFO - 'LOGNAME': 'cltbld', 06:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:01 INFO - 'MOZ_NO_REMOTE': '1', 06:39:01 INFO - 'NO_EM_RESTART': '1', 06:39:01 INFO - 'PAGER': '/bin/cat', 06:39:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:01 INFO - 'PWD': '/builds/slave/test', 06:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:01 INFO - 'SHELL': '/bin/bash', 06:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:01 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:01 INFO - 'USER': 'cltbld', 06:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:01 INFO - 'XPC_FLAGS': '0x0', 06:39:01 INFO - 'XPC_SERVICE_NAME': '0', 06:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:01 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 06:39:01 INFO - Cleaning up... 06:39:01 INFO - Return code: 0 06:39:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:01 INFO - 'HOME': '/Users/cltbld', 06:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:01 INFO - 'LOGNAME': 'cltbld', 06:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:01 INFO - 'MOZ_NO_REMOTE': '1', 06:39:01 INFO - 'NO_EM_RESTART': '1', 06:39:01 INFO - 'PAGER': '/bin/cat', 06:39:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:01 INFO - 'PWD': '/builds/slave/test', 06:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:01 INFO - 'SHELL': '/bin/bash', 06:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:01 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:01 INFO - 'USER': 'cltbld', 06:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:01 INFO - 'XPC_FLAGS': '0x0', 06:39:01 INFO - 'XPC_SERVICE_NAME': '0', 06:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:02 INFO - Downloading/unpacking psutil>=3.1.1 06:39:02 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 06:39:02 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 06:39:02 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 06:39:02 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 06:39:02 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 06:39:02 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 06:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:39:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:39:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:39:05 INFO - Installing collected packages: psutil 06:39:05 INFO - Running setup.py install for psutil 06:39:05 INFO - building 'psutil._psutil_osx' extension 06:39:05 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 06:39:05 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 06:39:05 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 06:39:05 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 06:39:05 INFO - building 'psutil._psutil_posix' extension 06:39:05 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 06:39:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:39:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:39:05 INFO - ^ 06:39:05 INFO - 1 warning generated. 06:39:05 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 06:39:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:39:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:39:05 INFO - Successfully installed psutil 06:39:05 INFO - Cleaning up... 06:39:06 INFO - Return code: 0 06:39:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:06 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:06 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 06:39:06 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 06:39:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:06 INFO - 'HOME': '/Users/cltbld', 06:39:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:06 INFO - 'LOGNAME': 'cltbld', 06:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:06 INFO - 'MOZ_NO_REMOTE': '1', 06:39:06 INFO - 'NO_EM_RESTART': '1', 06:39:06 INFO - 'PAGER': '/bin/cat', 06:39:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:06 INFO - 'PWD': '/builds/slave/test', 06:39:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:06 INFO - 'SHELL': '/bin/bash', 06:39:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:06 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:06 INFO - 'USER': 'cltbld', 06:39:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:06 INFO - 'XPC_FLAGS': '0x0', 06:39:06 INFO - 'XPC_SERVICE_NAME': '0', 06:39:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:39:06 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 06:39:06 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 06:39:06 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 06:39:06 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 06:39:06 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 06:39:06 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 06:39:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:39:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:39:08 INFO - Installing collected packages: mozsystemmonitor 06:39:08 INFO - Running setup.py install for mozsystemmonitor 06:39:08 INFO - Successfully installed mozsystemmonitor 06:39:08 INFO - Cleaning up... 06:39:09 INFO - Return code: 0 06:39:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:09 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:09 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 06:39:09 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 06:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:09 INFO - 'HOME': '/Users/cltbld', 06:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:09 INFO - 'LOGNAME': 'cltbld', 06:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:09 INFO - 'MOZ_NO_REMOTE': '1', 06:39:09 INFO - 'NO_EM_RESTART': '1', 06:39:09 INFO - 'PAGER': '/bin/cat', 06:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:09 INFO - 'PWD': '/builds/slave/test', 06:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:09 INFO - 'SHELL': '/bin/bash', 06:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:09 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:09 INFO - 'USER': 'cltbld', 06:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:09 INFO - 'XPC_FLAGS': '0x0', 06:39:09 INFO - 'XPC_SERVICE_NAME': '0', 06:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:09 INFO - Downloading/unpacking jsonschema==2.5.1 06:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:39:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:39:11 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 06:39:11 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 06:39:11 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 06:39:11 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 06:39:11 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 06:39:11 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 06:39:12 INFO - Downloading functools32-3.2.3-2.tar.gz 06:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:39:12 INFO - warning: no files found matching '*.txt' 06:39:12 INFO - no previously-included directories found matching 'build' 06:39:12 INFO - no previously-included directories found matching 'dist' 06:39:12 INFO - no previously-included directories found matching '.git*' 06:39:12 INFO - Installing collected packages: jsonschema, functools32 06:39:12 INFO - Running setup.py install for functools32 06:39:12 INFO - warning: no files found matching '*.txt' 06:39:12 INFO - no previously-included directories found matching 'build' 06:39:12 INFO - no previously-included directories found matching 'dist' 06:39:12 INFO - no previously-included directories found matching '.git*' 06:39:12 INFO - Successfully installed jsonschema functools32 06:39:12 INFO - Cleaning up... 06:39:12 INFO - Return code: 0 06:39:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:12 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:12 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 06:39:12 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 06:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:12 INFO - 'HOME': '/Users/cltbld', 06:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:12 INFO - 'LOGNAME': 'cltbld', 06:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:12 INFO - 'MOZ_NO_REMOTE': '1', 06:39:12 INFO - 'NO_EM_RESTART': '1', 06:39:12 INFO - 'PAGER': '/bin/cat', 06:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:12 INFO - 'PWD': '/builds/slave/test', 06:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:12 INFO - 'SHELL': '/bin/bash', 06:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:12 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:12 INFO - 'USER': 'cltbld', 06:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:12 INFO - 'XPC_FLAGS': '0x0', 06:39:12 INFO - 'XPC_SERVICE_NAME': '0', 06:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:12 INFO - Downloading/unpacking blobuploader==1.2.4 06:39:12 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 06:39:12 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 06:39:12 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 06:39:12 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 06:39:12 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 06:39:12 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 06:39:15 INFO - Downloading blobuploader-1.2.4.tar.gz 06:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:39:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:39:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:39:16 INFO - Downloading docopt-0.6.1.tar.gz 06:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:39:16 INFO - Installing collected packages: blobuploader, requests, docopt 06:39:16 INFO - Running setup.py install for blobuploader 06:39:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:39:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:39:16 INFO - Running setup.py install for requests 06:39:17 INFO - Running setup.py install for docopt 06:39:17 INFO - Successfully installed blobuploader requests docopt 06:39:17 INFO - Cleaning up... 06:39:17 INFO - Return code: 0 06:39:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:17 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:17 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 06:39:17 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 06:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:17 INFO - 'HOME': '/Users/cltbld', 06:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:17 INFO - 'LOGNAME': 'cltbld', 06:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:17 INFO - 'MOZ_NO_REMOTE': '1', 06:39:17 INFO - 'NO_EM_RESTART': '1', 06:39:17 INFO - 'PAGER': '/bin/cat', 06:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:17 INFO - 'PWD': '/builds/slave/test', 06:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:17 INFO - 'SHELL': '/bin/bash', 06:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:17 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:17 INFO - 'USER': 'cltbld', 06:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:17 INFO - 'XPC_FLAGS': '0x0', 06:39:17 INFO - 'XPC_SERVICE_NAME': '0', 06:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:39:17 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-XuKF2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:39:17 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-kBFs_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:39:17 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Gyo89m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:39:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:39:17 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ChPZ21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_LXpwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-TfVbeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8qfmWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-jZ8dxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-2SZVK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-pybTMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-x6PtGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-dpQmY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:39:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:39:18 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-qtWgjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:39:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-pyG0Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:39:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Qqhvi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:39:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Td0auG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:39:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:39:19 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-C3McHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:39:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:39:19 INFO - Running setup.py install for manifestparser 06:39:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:39:19 INFO - Running setup.py install for mozcrash 06:39:19 INFO - Running setup.py install for mozdebug 06:39:19 INFO - Running setup.py install for mozdevice 06:39:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Running setup.py install for mozfile 06:39:20 INFO - Running setup.py install for mozhttpd 06:39:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Running setup.py install for mozinfo 06:39:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Running setup.py install for mozInstall 06:39:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Running setup.py install for mozleak 06:39:20 INFO - Running setup.py install for mozlog 06:39:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:39:20 INFO - Running setup.py install for moznetwork 06:39:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Running setup.py install for mozprocess 06:39:21 INFO - Running setup.py install for mozprofile 06:39:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Running setup.py install for mozrunner 06:39:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Running setup.py install for mozscreenshot 06:39:21 INFO - Running setup.py install for moztest 06:39:21 INFO - Running setup.py install for mozversion 06:39:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:39:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:39:21 INFO - Cleaning up... 06:39:22 INFO - Return code: 0 06:39:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:39:22 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')]} 06:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:22 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')]} 06:39:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:22 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:22 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 06:39:22 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 06:39:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:22 INFO - 'HOME': '/Users/cltbld', 06:39:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:22 INFO - 'LOGNAME': 'cltbld', 06:39:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:22 INFO - 'MOZ_NO_REMOTE': '1', 06:39:22 INFO - 'NO_EM_RESTART': '1', 06:39:22 INFO - 'PAGER': '/bin/cat', 06:39:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:22 INFO - 'PWD': '/builds/slave/test', 06:39:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:22 INFO - 'SHELL': '/bin/bash', 06:39:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:22 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:22 INFO - 'USER': 'cltbld', 06:39:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:22 INFO - 'XPC_FLAGS': '0x0', 06:39:22 INFO - 'XPC_SERVICE_NAME': '0', 06:39:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-0PsfNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-aAxl79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_cQlTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Y1tqsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-sXnk_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-6JaBhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:39:22 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)) 06:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:39:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-b7HHU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-PlkVMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-n2EsK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-nuchQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UcLySC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_wf2PJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gWOJll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-sWslNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-zBbeN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hzOqUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:39:23 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)) 06:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:39:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-KmWzy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:39:24 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)) 06:39:24 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)) 06:39:24 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)) 06:39:24 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)) 06:39:24 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)) 06:39:24 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)) 06:39:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:39:24 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 06:39:24 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 06:39:24 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 06:39:24 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 06:39:24 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 06:39:24 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 06:39:26 INFO - Downloading blessings-1.6.tar.gz 06:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:39:26 INFO - Installing collected packages: blessings 06:39:26 INFO - Running setup.py install for blessings 06:39:26 INFO - Successfully installed blessings 06:39:26 INFO - Cleaning up... 06:39:26 INFO - Return code: 0 06:39:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:39:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:39:27 INFO - Reading from file tmpfile_stdout 06:39:27 INFO - Current package versions: 06:39:27 INFO - blessings == 1.6 06:39:27 INFO - blobuploader == 1.2.4 06:39:27 INFO - docopt == 0.6.1 06:39:27 INFO - functools32 == 3.2.3-2 06:39:27 INFO - jsonschema == 2.5.1 06:39:27 INFO - manifestparser == 1.1 06:39:27 INFO - mozInstall == 1.12 06:39:27 INFO - mozcrash == 0.17 06:39:27 INFO - mozdebug == 0.1 06:39:27 INFO - mozdevice == 0.48 06:39:27 INFO - mozfile == 1.2 06:39:27 INFO - mozhttpd == 0.7 06:39:27 INFO - mozinfo == 0.9 06:39:27 INFO - mozleak == 0.1 06:39:27 INFO - mozlog == 3.2 06:39:27 INFO - moznetwork == 0.27 06:39:27 INFO - mozprocess == 0.23 06:39:27 INFO - mozprofile == 0.28 06:39:27 INFO - mozrunner == 6.12 06:39:27 INFO - mozscreenshot == 0.1 06:39:27 INFO - mozsystemmonitor == 0.3 06:39:27 INFO - moztest == 0.7 06:39:27 INFO - mozversion == 1.4 06:39:27 INFO - psutil == 3.1.1 06:39:27 INFO - requests == 1.2.3 06:39:27 INFO - wsgiref == 0.1.2 06:39:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:27 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:27 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 06:39:27 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 06:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:27 INFO - 'HOME': '/Users/cltbld', 06:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:27 INFO - 'LOGNAME': 'cltbld', 06:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:27 INFO - 'MOZ_NO_REMOTE': '1', 06:39:27 INFO - 'NO_EM_RESTART': '1', 06:39:27 INFO - 'PAGER': '/bin/cat', 06:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:27 INFO - 'PWD': '/builds/slave/test', 06:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:27 INFO - 'SHELL': '/bin/bash', 06:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:27 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:27 INFO - 'USER': 'cltbld', 06:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:27 INFO - 'XPC_FLAGS': '0x0', 06:39:27 INFO - 'XPC_SERVICE_NAME': '0', 06:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 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)) 06:39:27 INFO - Cleaning up... 06:39:27 INFO - Return code: 0 06:39:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:39:27 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 0x1069aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe94aca24a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, '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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 06:39:27 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 06:39:27 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 06:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:39:27 INFO - 'HOME': '/Users/cltbld', 06:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:39:27 INFO - 'LOGNAME': 'cltbld', 06:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:39:27 INFO - 'MOZ_NO_REMOTE': '1', 06:39:27 INFO - 'NO_EM_RESTART': '1', 06:39:27 INFO - 'PAGER': '/bin/cat', 06:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:39:27 INFO - 'PWD': '/builds/slave/test', 06:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:39:27 INFO - 'SHELL': '/bin/bash', 06:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:39:27 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:39:27 INFO - 'USER': 'cltbld', 06:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:39:27 INFO - 'XPC_FLAGS': '0x0', 06:39:27 INFO - 'XPC_SERVICE_NAME': '0', 06:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:39:27 INFO - Cleaning up... 06:39:27 INFO - Return code: 0 06:39:27 INFO - Running post-action listener: _resource_record_post_action 06:39:27 INFO - Running post-action listener: _start_resource_monitoring 06:39:27 INFO - Starting resource monitoring. 06:39:27 INFO - ##### 06:39:27 INFO - ##### Finished create-virtualenv step (success) 06:39:27 INFO - ##### 06:39:27 INFO - ##### 06:39:27 INFO - ##### Running install step. 06:39:27 INFO - ##### 06:39:27 INFO - Running pre-action listener: _resource_record_pre_action 06:39:27 INFO - Running main action method: install 06:39:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:39:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:39:28 INFO - Reading from file tmpfile_stdout 06:39:28 INFO - Detecting whether we're running mozinstall >=1.0... 06:39:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:39:28 INFO - Reading from file tmpfile_stdout 06:39:28 INFO - Output received: 06:39:28 INFO - Usage: mozinstall [options] installer 06:39:28 INFO - Options: 06:39:28 INFO - -h, --help show this help message and exit 06:39:28 INFO - -d DEST, --destination=DEST 06:39:28 INFO - Directory to install application into. [default: 06:39:28 INFO - "/builds/slave/test"] 06:39:28 INFO - --app=APP Application being installed. [default: firefox] 06:39:28 INFO - mkdir: /builds/slave/test/build/application 06:39:28 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'] 06:39:28 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 06:40:03 INFO - Reading from file tmpfile_stdout 06:40:03 INFO - Output received: 06:40:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:40:03 INFO - Running post-action listener: _resource_record_post_action 06:40:03 INFO - ##### 06:40:03 INFO - ##### Finished install step (success) 06:40:03 INFO - ##### 06:40:03 INFO - ##### 06:40:03 INFO - ##### Running run-tests step. 06:40:03 INFO - ##### 06:40:03 INFO - Running pre-action listener: _resource_record_pre_action 06:40:03 INFO - Running main action method: run_tests 06:40:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:40:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:40:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:40:03 INFO - 2016-07-12 06:40:03.282 screenresolution[2278:287847] starting screenresolution argv=screenresolution get 06:40:03 INFO - 2016-07-12 06:40:03.285 screenresolution[2278:287847] Display 0: 1600x1200x32@60 06:40:03 INFO - 2016-07-12 06:40:03.290 screenresolution[2279:287850] starting screenresolution argv=screenresolution list 06:40:03 INFO - Available Modes on Display 0 06:40:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:40:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:40:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:40:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:40:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:40:03 INFO - Intel Iris: 06:40:03 INFO - Chipset Model: Intel Iris 06:40:03 INFO - Type: GPU 06:40:03 INFO - Bus: Built-In 06:40:03 INFO - VRAM (Dynamic, Max): 1536 MB 06:40:03 INFO - Vendor: Intel (0x8086) 06:40:03 INFO - Device ID: 0x0a2e 06:40:03 INFO - Revision ID: 0x0009 06:40:03 INFO - Displays: 06:40:03 INFO - Display: 06:40:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:40:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:40:03 INFO - Display Serial Number: 1600x1200 60 06:40:03 INFO - Main Display: Yes 06:40:03 INFO - Mirror: Off 06:40:03 INFO - Online: Yes 06:40:03 INFO - Rotation: Supported 06:40:03 INFO - Return code: 0 06:40:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:40:03 INFO - Python 2.7.3 06:40:03 INFO - Return code: 0 06:40:03 INFO - grabbing minidump binary from tooltool 06:40:03 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')]} 06:40:03 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 0x10690fd30>, '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 0x106674db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106907b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:40:03 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 06:40:03 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 06:40:03 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 06:40:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:40:03 INFO - Return code: 0 06:40:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:40:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:40:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:40:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:40:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:40:03 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/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 06:40:03 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/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --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 06:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PNdeoC7KSU/Render', 06:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:40:03 INFO - 'HOME': '/Users/cltbld', 06:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:40:03 INFO - 'LOGNAME': 'cltbld', 06:40:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:40:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:03 INFO - 'MOZ_NO_REMOTE': '1', 06:40:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:40:03 INFO - 'NO_EM_RESTART': '1', 06:40:03 INFO - 'PAGER': '/bin/cat', 06:40:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:03 INFO - 'PWD': '/builds/slave/test', 06:40:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:40:03 INFO - 'SHELL': '/bin/bash', 06:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.glx0Qc4I5R/Listeners', 06:40:03 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 06:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:40:03 INFO - 'USER': 'cltbld', 06:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:03 INFO - 'XPC_FLAGS': '0x0', 06:40:03 INFO - 'XPC_SERVICE_NAME': '0', 06:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:40:03 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/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--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 06:40:03 INFO - mozversion application_buildid: 20160712054127 06:40:03 INFO - mozversion application_changeset: d9c1917d74d588280f47213716cd6012b5a0902e 06:40:03 INFO - mozversion application_display_name: Nightly 06:40:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:40:03 INFO - mozversion application_name: Firefox 06:40:03 INFO - mozversion application_remotingname: firefox 06:40:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:40:03 INFO - mozversion application_vendor: Mozilla 06:40:03 INFO - mozversion application_version: 50.0a1 06:40:03 INFO - mozversion platform_buildid: 20160712054127 06:40:03 INFO - mozversion platform_changeset: d9c1917d74d588280f47213716cd6012b5a0902e 06:40:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:40:03 INFO - mozversion platform_version: 50.0a1 06:40:03 INFO - using testdate: 1468330803 06:40:03 INFO - actual date: 1468330803 06:40:03 INFO - starting webserver on 'localhost:54585' 06:40:03 INFO - SUITE-START | Running 2 tests 06:40:03 INFO - TEST-START | dromaeo_css 06:40:03 INFO - operating with platform_type : mac_ 06:40:03 INFO - Initialising browser for dromaeo_css test... 06:40:03 INFO - TEST-INFO | started process 2289 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpybxIjo/profile http://localhost:54585/getInfo.html) 06:40:06 INFO - PROCESS | 2289 | __metrics Screen width/height:1600/1200 06:40:06 INFO - PROCESS | 2289 | colorDepth:24 06:40:06 INFO - PROCESS | 2289 | Browser inner width/height: 1024/689 06:40:06 INFO - PROCESS | 2289 | __metrics 06:40:06 INFO - PROCESS | 2289 | 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" 06:40:06 INFO - PROCESS | 2289 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:40:07 INFO - TEST-INFO | 2289: exit 0 06:40:07 INFO - Browser initialized. 06:40:07 INFO - Running cycle 1/1 for dromaeo_css test... 06:40:07 INFO - TEST-INFO | started process 2292 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpybxIjo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:40:19 INFO - PROCESS | 2292 | RSS: Main: 243634176 06:40:19 INFO - PROCESS | 2292 | 06:41:10 INFO - PROCESS | 2292 | 1468330870639 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] 06:43:21 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54585/tests/dromaeo/cssquery-ext.html) 06:43:22 INFO - PROCESS | 2292 | RSS: Main: 349179904 06:43:22 INFO - PROCESS | 2292 | 06:46:25 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-ext.html (next: http://localhost:54585/tests/dromaeo/cssquery-jquery.html) 06:46:25 INFO - PROCESS | 2292 | RSS: Main: 379633664 06:46:26 INFO - PROCESS | 2292 | 06:49:28 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54585/tests/dromaeo/cssquery-mootools.html) 06:49:28 INFO - PROCESS | 2292 | RSS: Main: 370622464 06:49:28 INFO - PROCESS | 2292 | 06:52:30 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54585/tests/dromaeo/cssquery-prototype.html) 06:52:31 INFO - PROCESS | 2292 | RSS: Main: 370610176 06:52:31 INFO - PROCESS | 2292 | 06:55:35 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54585/tests/dromaeo/cssquery-yui.html) 06:55:35 INFO - PROCESS | 2292 | RSS: Main: 339877888 06:55:35 INFO - PROCESS | 2292 | 06:58:38 INFO - PROCESS | 2292 | Cycle 1(1): loaded http://localhost:54585/tests/dromaeo/cssquery-yui.html (next: http://localhost:54585/tests/dromaeo/cssquery-dojo.html) 06:58:39 INFO - PROCESS | 2292 | RSS: Main: 383479808 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | __start_tp_report 06:58:39 INFO - PROCESS | 2292 | _x_x_mozilla_page_load 06:58:39 INFO - PROCESS | 2292 | _x_x_mozilla_page_load_details 06:58:39 INFO - PROCESS | 2292 | |i|pagename|runs| 06:58:39 INFO - PROCESS | 2292 | |0;dojo.html;2798.468291024705;3336.4161271777434;3338.9165270868225;3367.4275373186556;3406.50605662179;5587.022271102551;5650.858728531789;5667.263255776748;5745.741441635126;5753.626014309072;5673.914891276634;5733.885322293551;5796.217510636063;5811.883762324751;5842.357340692521;4259.828547149543;5007.674501157431;5125.384953805548;5143.4599367066485;5305.469453054683;4882.292067650189;4925.408950925876;5274.947250527484;5327.573794096463;5368.973155134219;5123.103456895028;5152.768125434364;5183.067047931371;5189.351331083614;5220.791168353261;5150.098732721796;5151.356080489939;5167.844964651066;5292.073887069786;5312.123499622597;4272.530021697611;5216.217567364888;5228.712420816853;5242.842714718565;5272.657277276965;5593.8321850344555;5646.096624540095;5764.51001664859;5836.708164591796;5861.944849927011;6194.287656919422;6223.097650840648;6250.468710159642;6264.6241225526155;6275.811725648238;6232.9376706232815;6297.4017468340435;6314.242290925118;6317.557770956035;6322.399372059647;5669.744861481202;5768.451997060272;5843.328017277982;5845.473907348359;5875.471207591295;6268.717907694074;6279.309275979638;6289.245290565162;6290.654014029273;6295.96852015737;6188.9690551546955;6191.907121393183;6209.2548894133;6209.875802484015;6212.596181248205;6078.199405501599;6142.713648198282;6212.409821066992;6227.346128656516;6296.118543403927;6208.875822483616;6252.155958945484;6262.6868656566985;6275.592086514362;6276.497880169575;6208.534359923024;6275.435210831047;6280.905786413207;6290.748370065237;6302.086197501337;4875.293082503017;4932.7286999215385;4950.25746138075;4989.825356112591;4992.101421744048;4431.822727090945;4483.641308695296;4526.524714904954;4558.27067669178;4561.110583436198;5793.797217097382;5814.970925145347;5827.184194212814;5842.094475356242;5846.181534585162;6242.2509298883515;6258.883375308598;6313.337099604475;6315.273743519462;6323.841903952438;5563.471470210324;5711.8000869969355;5789.623674461147;5803.984302747087;5850.414958504116;5376.1935709643085;5426.158945363396;5458.4814442627885;5489.176623506506;5501.862453438696;6327.936720632827;6330.39861213184;6340.1440805490665;6347.936520634735;6351.618902865843;6147.954847675996;6256.687165641791;6260.311365749855;6330.651814150266;6333.809985700436;5601.747921343468;6224.564280500322;6249.187605611241;6259.530535209787;6287.245530536365;6171.043488259327;6193.783217587363;6200.658963756856;6204.441600255998;6212.81361559154;4815.181419158681;6158.507319414346;6287.1512345832725;6290;6312.084747711648;4766.404199475066;6008.969955150196;6174.166487524127;6189.5048396129105;6204.317525072329;6149.2313460817395;6176;6192.0711893214575;6203.751849925952;6207.379262073757;4953.578945789648;5800.303963524404;5913.965056115248;6043.335233124265;6057.878842423039;6133.355997620186;6152.846178845564;6165.72254248551;6176.6911654418;6188.845278868064;5948.405159484017;5965;5979.730912108878;5980.132880732356;6006.039033754579 06:58:39 INFO - PROCESS | 2292 | |1;ext.html;487.09886709587266;487.6123481831941;515.8865049689068;528.9735513224339;529.3568314497884;29681.55477667834;31477.527837082464;43136.352954705624;44395.11209775811;44530;45370.546294537045;45868.311975320394;45919.31121033187;45939;46019;9755.560999755011;9950.203983681324;9956.502174891262;9959.402435853835;9967.401955882635;9788.951055244714;9902.55438505269;9903;9914.008599140065;9914.752131196705;9880.505974701236;9909.75225619358;9922.950385248063;9928.156106730936;9934.751631209205;8445.662173513052;9894.604215831358;9896.059874311928;9912.355696879717;9916.752081198028;9665.130138287588;9731.41611503312;9736.269779766475;9740;9741.610335586569;42582.14835703269;42915.1416971662;42970;42980.355294670604;43045.78477107626;3518.5601799775027;3563.572371315433;3604.044928094057;3625.4380571011534;3709;33358;33955.15112122217;34046.6595334046;34101.31797364064;34121;3304.4052070627276;3315.519249708803;3350.6816852398983;3359;3448.2758620689683;513923;515751;517299;518346;518810.4059479716;34740.47889281663;35360.82319588437;35519.961606526434;65002;65412;36382;36584.268314633555;36793.63206367956;36798.81600592034;36836.26327473489;15442.305096270695;15580.844191558166;15591.986520876108;15597.68804623901;15606.9219653901;12990.285534295515;13105.410256538478;13113.60659180207;13124.803127953088;13128.409221585052;8119.9188008120345;8137.674493020331;8137.95931020353;8221.588920553948;8225.300849427866;3285.5564498792837;3292.423825830518;3303.8182899940252;3303.884364047247;3326.284848757529;881.5239770523973;960.3325688646463;1004.638330201127;1045.46158728255;1130.6721050895148;31596.210094747814;31658.366832663218;31759.52360714591;32129.51805722916;32815.50776738351;1062.8246339354075;1106.31961343773;1113.2930589075995;1157.0049757208794;1171.7715045566204;14460.493882714265;15848.841511584737;16651.25069371881;16670.083145427117;16692.914960527527;169313;169751;173954.26045739633;173967;174051;62515.6874215626;62960.37039629545;62979.6851015742;63117;63487;1287.4914408808656;1417.957461276184;1426.600551845485;1441.0345068803927;1447.623617859343;32498;33075.66924330726;33182;33437.99686009424;33454.07077838211;53983.73008134934;54472.72763636157;55076.449235507134;55096.449035510734;55254;533068;534322;534541;534681;537610;24725;27458.862705686344;28146.01488947877;28168.01411950572;28226.85886570554;50374;50428.49571504238;50923.490765091876;51350;51779;41416.585834141275;41637.37543936904;41857.162856742085;42623.360649590904;42903.356449653904;41317.793411032755;41786.058720012006;41834.790826045675;42041.57958420377;42428.363574547024;40071.1985760289;40616.98457538585;40837.1832563353;40855.59144408518;41019.1796164081;31418.21454463565;33298.33403331968;34645.48031779576;36134;36500;22237.332880013626;26036.739632603432;26216;26441.70309599371;26477.602835957863 06:58:39 INFO - PROCESS | 2292 | |2;jquery.html;358.687941490903;381.1975790960029;385.56816365670437;389.2370952932251;407.3889166250624;15763.595679548302;16086.839131608695;21181;21447.927603619813;21477.892610536965;20955.16179352824;21296;21301.57396852062;21560.784392156074;21944.695806940283;5289.920651190235;6020.76574302267;6177.969110154443;6181.07283907413;6191.907121393183;6087.9695601521935;6154.323024467305;6174.6912654367325;6176.949918513864;6185.721642526097;5851.970740146315;6056.424639659225;6076.635401875901;6084.969575152118;6088.908666370008;5750.166225897222;6033.245844269466;6070.57505974582;6095.908561371583;6122.908156377635;5952.672603006833;5986.341502434729;5988.161657367972;5989.13157592151;6003.489703375219;13608.727825443435;17786.733199002025;25618;26626;26644.866775666193;2200;2215.8227341812776;2219.4451387153213;2490.0537795637542;2956.320046389321;10171.135453486388;10175.033371829664;10176.745581360452;10634.893651063545;10635.255532112755;1864.692325766247;1956;1975.8518111141577;1975.8913259770707;1983.980160198394;128540;136848.31575842158;139694.41222351196;140949;141034;10109.837368702547;10632.627858025087;11685.824712629317;11691.181617286707;11849.881501185051;14616.780748288784;14704.823614110845;14762.335694893756;14784.556463306119;14784.778228326584;8466.449680770731;8640.827183456297;8687.044425113232;8751;8758.824823503493;8386.706465273686;8389.95805020971;8508.276796472246;8508.65965361391;8534.189252021048;4506.301523263899;4585.082983403333;4585.9770701146945;4642.558956899089;4657.417822772153;1895.545069183386;1936.5933154037527;1948.6784680527837;1953.462797730607;1956.5597740508272;827.6358402303088;873.2402809555728;925.5927391947522;1015.5074788727461;1047.8166320893813;20464.69302960457;21443.67834482484;21550.676739848914;21637.675434868488;21826.454338641342;856.6037359104744;915.391264808908;925.2459245714721;983.9311248212556;1027.799579082072;9996.950015250022;11434.313941163557;11451.484683189277;11484.540618375171;11501.884981150082;616894;623130;637616;655545;656784;121994;124092;132237;132976;138077.3096134533;998.9100980911753;1005.1054561440324;1017.2574020964737;1017.704865588971;1047.36634336227;18871.245150193838;19039.71440428367;19129.90435047816;19149;19230.903845480683;53984.460155399516;55911.88176236371;57449;57636;57762.133567995676;79831;85050.57474712587;85692.57153714192;86334.1366586326;87310.69033964368;6830.93169068303;7959.721409750631;8060.992375953006;8184.590770461572;8226.917730822615;11894.524219031142;13247.66880827987;13294.53760086409;13419.329033548089;13447.462101516221;11233.157513186381;11335.943320283346;11401.118815395366;11419.543218271176;11522.53909843597;10037.695099637;10758.946205268923;11299.604513841976;11301.773964520828;11535.711607209887;11640.825387619023;11666.88333116658;11677.240979336317;11932.284062955903;11963.521459141537;4941.431735350337;7322.597257150908;7665.23347665229;7823.804404889924;8205.138460461565;9786.363886347364;11616.593419230287;11634.36011019402;11874.584389546324;12025.977791887437 06:58:39 INFO - PROCESS | 2292 | |3;mootools.html;1392.4360633943256;1577.8974366666166;1601.0473768107975;1792.865535084869;1911.5507855653927;7937.08723496798;8663.393562450623;8899.644014239422;8908.643654253838;8956.417832840878;8692.826143477127;8788.8681669775;8862.113788621135;8862.77843053924;8863.246624036965;2357.4813541021035;2400.735919048912;2401.4716762312264;2402.0752010475917;2403.3030421177837;2358.6344116661944;2389.486260454002;2393.1623931623967;2396.580598395283;2398.3764221302413;2354.799842013422;2372.3357459911253;2386.331827088418;2387.3673476528734;2393.042782886842;1984.7122167285736;2304.8501847380035;2336.077249486452;2336.4976530046038;2349.447879748258;2318.5362927414585;2337.2871274261342;2341.0986770093637;2354.3643216331493;2356.0929042318658;8709.346798990038;8749.868751968726;8852.026277109513;8861.955690221572;8863.689770857989;5268.868278293035;5417.26866872971;5423.701696406696;5429.294191755086;5443.074677304868;3574.535310409656;3616.6021737608844;3632.400653892105;3663.6885864701267;3676.74262801604;1091.2633972068822;1096.2381145104155;1101.7080473674482;1105.3312745788762;1116.5477981417457;11727.765444691144;11838.881611183864;11853.466594003205;11876.643700688994;11890.524379024828;4439.778011099432;4440.289553671397;4645.3032045193495;4654.394928659321;4664.976675116603;9268.304877134242;9274.350795444254;9277.95361023204;9295;9297.256219502424;5247.370315562081;5253.106971814801;5259.4477579853565;5264.973330200574;5269.919666468365;4241.957580424156;4393.472783266028;4561.996360800618;4834.347363106006;4928;3173.666764989689;3779.225258821935;3853.499045124116;3877.5540812806325;3900.3369427197445;1156.3582211872492;1162.3258510064143;1163.139276935062;1171.0865524890598;1173.4484792147678;884.5621417398429;954.1031430455349;956.6890760502725;980.789130336981;1024.9351369551086;6086.513078953673;6091.42131497507;6094.2077529920825;6143.692815359214;6161.229846269216;442.62155856742663;442.92913133899066;449.88752811797053;451.6477147824702;464.59115977939194;4180.91638167241;4210.221109094766;4332.393464914914;4387.846425375096;4413.7793110344355;9373.390729602554;10893.83659245112;10901.83647245292;11018.063464605439;11023.17326200538;10987.175961802897;11006.95433933775;11019.559217631366;11025.338479691245;11037;7845.215478452109;7995.440319177602;8013.27880490747;8211.794705132419;8434;6005.309389420273;6068.6965651716355;6137.478314343331;6167.599106058271;6260.874782504234;10138.391696498235;10293.485325733534;10314.742131446474;10316.329438586468;10318.896811031793;10044.447555384524;10053.748656283651;10209.081182693593;10407.167426606005;10414.166866650801;7050.964745176241;7263.673134709056;7267.200607933229;7628.237176282328;7789.415793815373;8067.435279530377;8130.308923741548;8253.917460825314;8476.957615211884;8499.74500764978;7340.082489688789;7555.73554925575;7604.12552556441;7641.961790191013;7652.311291983749;6308.33762454954;7450.329470347695;7467.402607791255;7591.8861217082895;7593.392528597588;7112.715491380287;7117.395021423237;7187.676554554953;7199.748008819666;7213.567185968858;4946.332245146859;5123.38519377677;5130.846074617767;5174.379074511083;5235.110031294613;5052.545270925634;5067.366579177603;5123.564497017646;5129.666571672829;5261.736913154404 06:58:39 INFO - PROCESS | 2292 | |4;prototype.html;306.7775862499688;312.93897689950467;314.4402962726347;320.5128205128205;324.4743515504882;5364.704941228231;5400.405955344914;5407.675539467635;5425.511703946644;5434.483724046219;4305.849295274666;5358.705271210082;5389.326334208224;5406.324209473816;5445.754941027653;3632.8365223565;3761.0012030810813;3976.76139431635;4139.461869956901;4179.916401671965;4074.186986790879;4142.71001029928;4157.729747566398;4203.402706971347;4218.915621687564;4126.360414135814;4127;4133.937990930123;4149.356849688304;4151.3150330195485;3769.492203118747;3898.883033508971;3916.4125381192766;3937.6808769062254;4152.231837110114;3224.3228921926425;3592.4431713084514;3602.189507360849;3602.9099272518133;3616.8734094306574;5312.92030619541;5318.441563635801;5325.60057995648;5336.466353364672;5336.759845806948;4390.319500477431;4419.784559246201;4426.468823741172;4680.555347242007;4786.688865223784;5136.6661167024395;5531.529819965308;5679.630823996468;5686.886262274773;5690.687012214326;3486.2504561519395;3808.7905165215902;3919.2357490289405;3959.980200098981;3962.5443074046566;5752.482276595085;5969.552283578662;5989.1733021428445;6004.849878753066;6125.039987204096;3336.399448099317;3637.436197389409;3640.3083414151756;3956.5449973252867;3989.68082553395;3545.0782796473104;4027.8993025174605;4038.24336413661;4039.818208180639;4059.3707975263883;4264.957350426518;4386.342048692722;4687.95312046882;4696.577308042292;4700.626183777239;5264.368275806928;5345.839624811262;5595.608307418523;5772.163036359704;5811.418858114154;1238.108561835477;1271.1800888426912;1289.8710128987027;1349.2039696579066;1387.8057072009926;711.4628455516053;829.850626887154;844.9704260350982;850.974470765878;867.5402036920442;5286.81496147633;5362.731863406892;5392.218128371364;5414.35027796667;5479.260299859548;5171.3018742469285;5245.501677340647;5656.406077361818;5854.448571128654;5936.614120082181;4740.66815322931;4972.353594032753;5117.513836185556;5302.92045619316;5394.518521480085;2356.316668166213;2436.9878150609134;2728.208819442379;3075.338802157502;3076.4310201797243;5745.88508229833;6015.218021657244;6040.909386359208;6043.214382130295;6062.575619706578;4807;5639.902434167969;5968.761249550056;6012.128241404972;6039.54703397247;4823.855284341475;5059.418166910852;5751.798687045934;5906.852328691817;6058.848528786815;5478.315210598676;5814.215080964016;5817.505512031525;5848.444397782204;5907.645541267538;4857.562819346276;4936.777844997056;4952.851414457572;5763.827085187451;5777.595568310178;5974.492168165754;5981.700914954322;6017.87964240704;6031.819045428644;6036.6679832609625;3197.976647472786;3887.3197190492115;3887.7667339959694;3995.6603688685623;4015.4378387026422;5181.844544663666;5365.007473617393;5553;5751.194832723506;5910.881782364243;5741.942580574141;5745.770169193185;5783.392743761844;5799.2750906136735;5824.5340372770925;4543.727376357429;5511.862203444946;5568.248286481273;5606;5748.626339287933;4596.655250856266;5417.997670430983;5529.972350138223;5605.32736071674;5609.298837645294;5530.336359636869;5560.138178582326;5713.714314284352;5720.456556627114;5736.713164341682;5236.554892834151;5315.176147697113;5608.831735047955;5610.943890561205;5623.297087864017 06:58:39 INFO - PROCESS | 2292 | |5;yui.html;752.6161657554647;851.8040850604361;857.3227150551065;861.7242482405629;889.7686601483613;15184.39262429503;15809.966915831297;16546.50360489186;16550.00699958002;16960.915195424004;15243.77134342983;15456.22718864058;15459.92270038651;15463.381464741426;15527.146006969611;895.8208358328328;911.8586619074055;920.9677661281856;923.529000209891;923.6905636611743;729.8686236477432;760.0916904299364;790.9644066017014;901.1124042817819;937.6858752317947;978.1587834462357;983.2183414185739;983.7688143286322;988.2686811759319;988.367444835306;967.2117224462041;968.4141094637713;969.9078587534173;970.1753509516925;970.4032020307495;555.089652969131;698.0471656189316;724.8151721311085;727.0403067950308;729.8941653460272;15704.528864134094;15732.134732589702;15770.921145394313;15771.29029193689;15773.211339432983;14307.213103279315;14567.635809104751;14642.41430342785;14695.77956330656;14707.338169782386;13011.414486348043;13286.53497127596;13292.667683307898;13470.528531501448;13548.59354219375;4713.5993440557595;4796.52034796521;4885.071836351082;4976.701397916137;4979.1784355581285;113352.4332378341;113411.43294283476;114617.85382146155;115881.99999999916;116526;11414.258073225214;11507.654770356903;11595.478203480863;11702.414879256003;11844.644660660195;23335.766642333358;23336.883315583313;23362;23404.414889627897;24167.274981750576;8401.327893768485;8410.217536580165;8444.113368096261;8477.23704866565;8728.127187281221;4489.595936365743;4560.156371071296;4568.885777855514;4574.108048930427;4630.976845115752;3568.803715795604;3707.4994875691436;3708.8331025103935;3711.202091550276;3717.4795528626014;688.1604442580044;688.9517733758689;694.6110178300169;704.5526090932192;707.8478127202676;587;648.8864448721549;651.6904470376534;688.579966220605;699.6501749125438;8819.735407937773;9063.682771103111;9182.403143795766;9186.282638809602;9223.861642075375;659.4790115808457;659.482306389482;672.1967249137332;691.20407949843;697.752297934226;4205;4407.757573333433;4412.823487060482;4505.279155335131;4554;111366;144485;148268;149462;151939;129346;129382.05926911104;129732;130492;131978;70339.64830175816;110750;112847.43576282066;114498;115575;8387.287080598218;8679.869801953102;8707;8735.1701588349;8825;64117.038244427305;64359;64438.67780661254;65049.67475162594;66918.99243022717;69893;80710;92883.60674590022;95387.04612953782;96165.51917240368;11916.225445346025;11921.82572201935;12078.09414293949;12292.463442069742;12640.178388405093;13044.934775326063;13077;13080.476780929037;13529.458821647024;13647.658808529866;9712.951435242778;11836.644900652649;11924;11984.880151198377;12213.938930305647;13627.757125154894;13980.58058258254;13997.440102396198;14439.989200755846;14602.926985365006;13429.932850335686;13502.392392342565;13541.526046588322;13765.587032389045;13967.860321397062;11091.168162387692;11292.548298067984;11350.205485616258;11592.948526632126;11747.765044698886;9451.716448506555;11144.387058711849;11239.157063220127;11316.26444281119;11536 06:58:39 INFO - PROCESS | 2292 | __end_tp_report 06:58:39 INFO - PROCESS | 2292 | __start_cc_report 06:58:39 INFO - PROCESS | 2292 | _x_x_mozilla_cycle_collect,1129 06:58:39 INFO - PROCESS | 2292 | __end_cc_report 06:58:39 INFO - PROCESS | 2292 | __startTimestamp1468331919122__endTimestamp 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | ------- Summary: start ------- 06:58:39 INFO - PROCESS | 2292 | Number of tests: 6 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#0] dojo.html Cycles:165 Average:5732.22 Median:5965.00 stddev:685.20 (11.5%) stddev-sans-first:647.50 06:58:39 INFO - PROCESS | 2292 | Values: 2798.5 3336.4 3338.9 3367.4 3406.5 5587.0 5650.9 5667.3 5745.7 5753.6 5673.9 5733.9 5796.2 5811.9 5842.4 4259.8 5007.7 5125.4 5143.5 5305.5 4882.3 4925.4 5274.9 5327.6 5369.0 5123.1 5152.8 5183.1 5189.4 5220.8 5150.1 5151.4 5167.8 5292.1 5312.1 4272.5 5216.2 5228.7 5242.8 5272.7 5593.8 5646.1 5764.5 5836.7 5861.9 6194.3 6223.1 6250.5 6264.6 6275.8 6232.9 6297.4 6314.2 6317.6 6322.4 5669.7 5768.5 5843.3 5845.5 5875.5 6268.7 6279.3 6289.2 6290.7 6296.0 6189.0 6191.9 6209.3 6209.9 6212.6 6078.2 6142.7 6212.4 6227.3 6296.1 6208.9 6252.2 6262.7 6275.6 6276.5 6208.5 6275.4 6280.9 6290.7 6302.1 4875.3 4932.7 4950.3 4989.8 4992.1 4431.8 4483.6 4526.5 4558.3 4561.1 5793.8 5815.0 5827.2 5842.1 5846.2 6242.3 6258.9 6313.3 6315.3 6323.8 5563.5 5711.8 5789.6 5804.0 5850.4 5376.2 5426.2 5458.5 5489.2 5501.9 6327.9 6330.4 6340.1 6347.9 6351.6 6148.0 6256.7 6260.3 6330.7 6333.8 5601.7 6224.6 6249.2 6259.5 6287.2 6171.0 6193.8 6200.7 6204.4 6212.8 4815.2 6158.5 6287.2 6290.0 6312.1 4766.4 6009.0 6174.2 6189.5 6204.3 6149.2 6176.0 6192.1 6203.8 6207.4 4953.6 5800.3 5914.0 6043.3 6057.9 6133.4 6152.8 6165.7 6176.7 6188.8 5948.4 5965.0 5979.7 5980.1 6006.0 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#1] ext.html Cycles:180 Average:56418.01 Median:40846.39 stddev:118107.04 (289.1%) stddev-sans-first:118363.71 06:58:39 INFO - PROCESS | 2292 | Values: 487.1 487.6 515.9 529.0 529.4 29681.6 31477.5 43136.4 44395.1 44530.0 45370.5 45868.3 45919.3 45939.0 46019.0 9755.6 9950.2 9956.5 9959.4 9967.4 9789.0 9902.6 9903.0 9914.0 9914.8 9880.5 9909.8 9923.0 9928.2 9934.8 8445.7 9894.6 9896.1 9912.4 9916.8 9665.1 9731.4 9736.3 9740.0 9741.6 42582.1 42915.1 42970.0 42980.4 43045.8 3518.6 3563.6 3604.0 3625.4 3709.0 33358.0 33955.2 34046.7 34101.3 34121.0 3304.4 3315.5 3350.7 3359.0 3448.3 513923.0 515751.0 517299.0 518346.0 518810.4 34740.5 35360.8 35520.0 65002.0 65412.0 36382.0 36584.3 36793.6 36798.8 36836.3 15442.3 15580.8 15592.0 15597.7 15606.9 12990.3 13105.4 13113.6 13124.8 13128.4 8119.9 8137.7 8138.0 8221.6 8225.3 3285.6 3292.4 3303.8 3303.9 3326.3 881.5 960.3 1004.6 1045.5 1130.7 31596.2 31658.4 31759.5 32129.5 32815.5 1062.8 1106.3 1113.3 1157.0 1171.8 14460.5 15848.8 16651.3 16670.1 16692.9 169313.0 169751.0 173954.3 173967.0 174051.0 62515.7 62960.4 62979.7 63117.0 63487.0 1287.5 1418.0 1426.6 1441.0 1447.6 32498.0 33075.7 33182.0 33438.0 33454.1 53983.7 54472.7 55076.4 55096.4 55254.0 533068.0 534322.0 534541.0 534681.0 537610.0 24725.0 27458.9 28146.0 28168.0 28226.9 50374.0 50428.5 50923.5 51350.0 51779.0 41416.6 41637.4 41857.2 42623.4 42903.4 41317.8 41786.1 41834.8 42041.6 42428.4 40071.2 40617.0 40837.2 40855.6 41019.2 31418.2 33298.3 34645.5 36134.0 36500.0 22237.3 26036.7 26216.0 26441.7 26477.6 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#2] jquery.html Cycles:180 Average:37010.17 Median:21885.58 stddev:106770.98 (487.9%) stddev-sans-first:107035.03 06:58:39 INFO - PROCESS | 2292 | Values: 358.7 381.2 385.6 389.2 407.4 15763.6 16086.8 21181.0 21447.9 21477.9 20955.2 21296.0 21301.6 21560.8 21944.7 5289.9 6020.8 6178.0 6181.1 6191.9 6088.0 6154.3 6174.7 6176.9 6185.7 5852.0 6056.4 6076.6 6085.0 6088.9 5750.2 6033.2 6070.6 6095.9 6122.9 5952.7 5986.3 5988.2 5989.1 6003.5 13608.7 17786.7 25618.0 26626.0 26644.9 2200.0 2215.8 2219.4 2490.1 2956.3 10171.1 10175.0 10176.7 10634.9 10635.3 1864.7 1956.0 1975.9 1975.9 1984.0 128540.0 136848.3 139694.4 140949.0 141034.0 10109.8 10632.6 11685.8 11691.2 11849.9 14616.8 14704.8 14762.3 14784.6 14784.8 8466.4 8640.8 8687.0 8751.0 8758.8 8386.7 8390.0 8508.3 8508.7 8534.2 4506.3 4585.1 4586.0 4642.6 4657.4 1895.5 1936.6 1948.7 1953.5 1956.6 827.6 873.2 925.6 1015.5 1047.8 20464.7 21443.7 21550.7 21637.7 21826.5 856.6 915.4 925.2 983.9 1027.8 9997.0 11434.3 11451.5 11484.5 11501.9 616894.0 623130.0 637616.0 655545.0 656784.0 121994.0 124092.0 132237.0 132976.0 138077.3 998.9 1005.1 1017.3 1017.7 1047.4 18871.2 19039.7 19129.9 19149.0 19230.9 53984.5 55911.9 57449.0 57636.0 57762.1 79831.0 85050.6 85692.6 86334.1 87310.7 6830.9 7959.7 8061.0 8184.6 8226.9 11894.5 13247.7 13294.5 13419.3 13447.5 11233.2 11335.9 11401.1 11419.5 11522.5 10037.7 10758.9 11299.6 11301.8 11535.7 11640.8 11666.9 11677.2 11932.3 11963.5 4941.4 7322.6 7665.2 7823.8 8205.1 9786.4 11616.6 11634.4 11874.6 12026.0 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#3] mootools.html Cycles:180 Average:5734.06 Median:4649.85 stddev:3238.77 (69.7%) stddev-sans-first:3231.42 06:58:39 INFO - PROCESS | 2292 | Values: 1392.4 1577.9 1601.0 1792.9 1911.6 7937.1 8663.4 8899.6 8908.6 8956.4 8692.8 8788.9 8862.1 8862.8 8863.2 2357.5 2400.7 2401.5 2402.1 2403.3 2358.6 2389.5 2393.2 2396.6 2398.4 2354.8 2372.3 2386.3 2387.4 2393.0 1984.7 2304.9 2336.1 2336.5 2349.4 2318.5 2337.3 2341.1 2354.4 2356.1 8709.3 8749.9 8852.0 8862.0 8863.7 5268.9 5417.3 5423.7 5429.3 5443.1 3574.5 3616.6 3632.4 3663.7 3676.7 1091.3 1096.2 1101.7 1105.3 1116.5 11727.8 11838.9 11853.5 11876.6 11890.5 4439.8 4440.3 4645.3 4654.4 4665.0 9268.3 9274.4 9278.0 9295.0 9297.3 5247.4 5253.1 5259.4 5265.0 5269.9 4242.0 4393.5 4562.0 4834.3 4928.0 3173.7 3779.2 3853.5 3877.6 3900.3 1156.4 1162.3 1163.1 1171.1 1173.4 884.6 954.1 956.7 980.8 1024.9 6086.5 6091.4 6094.2 6143.7 6161.2 442.6 442.9 449.9 451.6 464.6 4180.9 4210.2 4332.4 4387.8 4413.8 9373.4 10893.8 10901.8 11018.1 11023.2 10987.2 11007.0 11019.6 11025.3 11037.0 7845.2 7995.4 8013.3 8211.8 8434.0 6005.3 6068.7 6137.5 6167.6 6260.9 10138.4 10293.5 10314.7 10316.3 10318.9 10044.4 10053.7 10209.1 10407.2 10414.2 7051.0 7263.7 7267.2 7628.2 7789.4 8067.4 8130.3 8253.9 8477.0 8499.7 7340.1 7555.7 7604.1 7642.0 7652.3 6308.3 7450.3 7467.4 7591.9 7593.4 7112.7 7117.4 7187.7 7199.7 7213.6 4946.3 5123.4 5130.8 5174.4 5235.1 5052.5 5067.4 5123.6 5129.7 5261.7 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#4] prototype.html Cycles:180 Average:4603.83 Median:5314.05 stddev:1438.09 (27.1%) stddev-sans-first:1405.49 06:58:39 INFO - PROCESS | 2292 | Values: 306.8 312.9 314.4 320.5 324.5 5364.7 5400.4 5407.7 5425.5 5434.5 4305.8 5358.7 5389.3 5406.3 5445.8 3632.8 3761.0 3976.8 4139.5 4179.9 4074.2 4142.7 4157.7 4203.4 4218.9 4126.4 4127.0 4133.9 4149.4 4151.3 3769.5 3898.9 3916.4 3937.7 4152.2 3224.3 3592.4 3602.2 3602.9 3616.9 5312.9 5318.4 5325.6 5336.5 5336.8 4390.3 4419.8 4426.5 4680.6 4786.7 5136.7 5531.5 5679.6 5686.9 5690.7 3486.3 3808.8 3919.2 3960.0 3962.5 5752.5 5969.6 5989.2 6004.8 6125.0 3336.4 3637.4 3640.3 3956.5 3989.7 3545.1 4027.9 4038.2 4039.8 4059.4 4265.0 4386.3 4688.0 4696.6 4700.6 5264.4 5345.8 5595.6 5772.2 5811.4 1238.1 1271.2 1289.9 1349.2 1387.8 711.5 829.9 845.0 851.0 867.5 5286.8 5362.7 5392.2 5414.4 5479.3 5171.3 5245.5 5656.4 5854.4 5936.6 4740.7 4972.4 5117.5 5302.9 5394.5 2356.3 2437.0 2728.2 3075.3 3076.4 5745.9 6015.2 6040.9 6043.2 6062.6 4807.0 5639.9 5968.8 6012.1 6039.5 4823.9 5059.4 5751.8 5906.9 6058.8 5478.3 5814.2 5817.5 5848.4 5907.6 4857.6 4936.8 4952.9 5763.8 5777.6 5974.5 5981.7 6017.9 6031.8 6036.7 3198.0 3887.3 3887.8 3995.7 4015.4 5181.8 5365.0 5553.0 5751.2 5910.9 5741.9 5745.8 5783.4 5799.3 5824.5 4543.7 5511.9 5568.2 5606.0 5748.6 4596.7 5418.0 5530.0 5605.3 5609.3 5530.3 5560.1 5713.7 5720.5 5736.7 5236.6 5315.2 5608.8 5610.9 5623.3 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - PROCESS | 2292 | [#5] yui.html Cycles:180 Average:24761.35 Median:3714.34 stddev:39053.78 (1051.4%) stddev-sans-first:39121.73 06:58:39 INFO - PROCESS | 2292 | Values: 752.6 851.8 857.3 861.7 889.8 15184.4 15810.0 16546.5 16550.0 16960.9 15243.8 15456.2 15459.9 15463.4 15527.1 895.8 911.9 921.0 923.5 923.7 729.9 760.1 791.0 901.1 937.7 978.2 983.2 983.8 988.3 988.4 967.2 968.4 969.9 970.2 970.4 555.1 698.0 724.8 727.0 729.9 15704.5 15732.1 15770.9 15771.3 15773.2 14307.2 14567.6 14642.4 14695.8 14707.3 13011.4 13286.5 13292.7 13470.5 13548.6 4713.6 4796.5 4885.1 4976.7 4979.2 113352.4 113411.4 114617.9 115882.0 116526.0 11414.3 11507.7 11595.5 11702.4 11844.6 23335.8 23336.9 23362.0 23404.4 24167.3 8401.3 8410.2 8444.1 8477.2 8728.1 4489.6 4560.2 4568.9 4574.1 4631.0 3568.8 3707.5 3708.8 3711.2 3717.5 688.2 689.0 694.6 704.6 707.8 587.0 648.9 651.7 688.6 699.7 8819.7 9063.7 9182.4 9186.3 9223.9 659.5 659.5 672.2 691.2 697.8 4205.0 4407.8 4412.8 4505.3 4554.0 111366.0 144485.0 148268.0 149462.0 151939.0 129346.0 129382.1 129732.0 130492.0 131978.0 70339.6 110750.0 112847.4 114498.0 115575.0 8387.3 8679.9 8707.0 8735.2 8825.0 64117.0 64359.0 64438.7 65049.7 66919.0 69893.0 80710.0 92883.6 95387.0 96165.5 11916.2 11921.8 12078.1 12292.5 12640.2 13044.9 13077.0 13080.5 13529.5 13647.7 9713.0 11836.6 11924.0 11984.9 12213.9 13627.8 13980.6 13997.4 14440.0 14602.9 13429.9 13502.4 13541.5 13765.6 13967.9 11091.2 11292.5 11350.2 11592.9 11747.8 9451.7 11144.4 11239.2 11316.3 11536.0 06:58:39 INFO - PROCESS | 2292 | -------- Summary: end -------- 06:58:39 INFO - PROCESS | 2292 | 06:58:39 INFO - TEST-INFO | 2292: exit 0 06:58:39 INFO - TEST-OK | dromaeo_css | took 1115883ms 06:58:39 INFO - TEST-START | kraken 06:58:39 INFO - operating with platform_type : mac_ 06:58:39 INFO - Initialising browser for kraken test... 06:58:39 INFO - TEST-INFO | started process 2295 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpxNlMwL/profile http://localhost:54585/getInfo.html) 06:58:42 INFO - PROCESS | 2295 | __metrics Screen width/height:1600/1200 06:58:42 INFO - PROCESS | 2295 | colorDepth:24 06:58:42 INFO - PROCESS | 2295 | Browser inner width/height: 1024/689 06:58:42 INFO - PROCESS | 2295 | __metrics 06:58:42 INFO - PROCESS | 2295 | 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" 06:58:42 INFO - PROCESS | 2295 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:58:43 INFO - TEST-INFO | 2295: exit 0 06:58:43 INFO - Browser initialized. 06:58:43 INFO - Running cycle 1/1 for kraken test... 06:58:43 INFO - TEST-INFO | started process 2296 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpxNlMwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:58:55 INFO - PROCESS | 2296 | RSS: Main: 243507200 06:58:55 INFO - PROCESS | 2296 | 06:58:57 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54585/tests/kraken/driver.html?testName=audio-beat-detection) 06:58:58 INFO - PROCESS | 2296 | RSS: Main: 294510592 06:58:58 INFO - PROCESS | 2296 | 06:59:01 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54585/tests/kraken/driver.html?testName=audio-dft) 06:59:01 INFO - PROCESS | 2296 | RSS: Main: 304418816 06:59:01 INFO - PROCESS | 2296 | 06:59:05 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54585/tests/kraken/driver.html?testName=audio-fft) 06:59:06 INFO - PROCESS | 2296 | RSS: Main: 387805184 06:59:06 INFO - PROCESS | 2296 | 06:59:08 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54585/tests/kraken/driver.html?testName=audio-oscillator) 06:59:08 INFO - PROCESS | 2296 | RSS: Main: 291033088 06:59:08 INFO - PROCESS | 2296 | 06:59:10 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54585/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:59:11 INFO - PROCESS | 2296 | RSS: Main: 294944768 06:59:11 INFO - PROCESS | 2296 | 06:59:14 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54585/tests/kraken/driver.html?testName=imaging-darkroom) 06:59:14 INFO - PROCESS | 2296 | RSS: Main: 331939840 06:59:14 INFO - PROCESS | 2296 | 06:59:17 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54585/tests/kraken/driver.html?testName=imaging-desaturate) 06:59:18 INFO - PROCESS | 2296 | RSS: Main: 332009472 06:59:18 INFO - PROCESS | 2296 | 06:59:21 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54585/tests/kraken/driver.html?testName=json-parse-financial) 06:59:21 INFO - PROCESS | 2296 | RSS: Main: 333262848 06:59:21 INFO - PROCESS | 2296 | 06:59:23 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54585/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:59:24 INFO - PROCESS | 2296 | RSS: Main: 311324672 06:59:24 INFO - PROCESS | 2296 | 06:59:26 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:59:26 INFO - PROCESS | 2296 | RSS: Main: 318332928 06:59:26 INFO - PROCESS | 2296 | 06:59:28 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:59:29 INFO - PROCESS | 2296 | RSS: Main: 315781120 06:59:29 INFO - PROCESS | 2296 | 06:59:32 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:59:33 INFO - PROCESS | 2296 | RSS: Main: 325013504 06:59:33 INFO - PROCESS | 2296 | 06:59:36 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:59:36 INFO - PROCESS | 2296 | RSS: Main: 327217152 06:59:36 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:54585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54585/tests/kraken/driver.html?testName=ai-astar) 06:59:38 INFO - PROCESS | 2296 | RSS: Main: 370630656 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | __start_tp_report 06:59:38 INFO - PROCESS | 2296 | _x_x_mozilla_page_load 06:59:38 INFO - PROCESS | 2296 | _x_x_mozilla_page_load_details 06:59:38 INFO - PROCESS | 2296 | |i|pagename|runs| 06:59:38 INFO - PROCESS | 2296 | |0;ai-astar;101;112;112;112;113;113;113;113;113;113 06:59:38 INFO - PROCESS | 2296 | |1;audio-beat-detection;144;103;107;170;96;102;116;124;178;135 06:59:38 INFO - PROCESS | 2296 | |2;audio-dft;185;184;187;182;183;186;179;180;183;186 06:59:38 INFO - PROCESS | 2296 | |3;audio-fft;79;74;57;88;58;56;64;63;91;77 06:59:38 INFO - PROCESS | 2296 | |4;audio-oscillator;86;88;94;72;72;68;97;69;68;103 06:59:38 INFO - PROCESS | 2296 | |5;imaging-gaussian-blur;89;86;87;85;87;85;89;86;88;85 06:59:38 INFO - PROCESS | 2296 | |6;imaging-darkroom;96;97;92;92;93;92;93;93;92;91 06:59:38 INFO - PROCESS | 2296 | |7;imaging-desaturate;101;121;88;85;84;94;134;128;85;86 06:59:38 INFO - PROCESS | 2296 | |8;json-parse-financial;55;53;53;54;54;53;55;53;54;54 06:59:38 INFO - PROCESS | 2296 | |9;json-stringify-tinderbox;66;67;86;96;106;101;70;71;69;70 06:59:38 INFO - PROCESS | 2296 | |10;stanford-crypto-aes;85;82;85;82;81;83;82;88;83;84 06:59:38 INFO - PROCESS | 2296 | |11;stanford-crypto-ccm;137;140;133;137;137;141;144;150;224;149 06:59:38 INFO - PROCESS | 2296 | |12;stanford-crypto-pbkdf2;219;228;160;170;229;212;164;162;162;162 06:59:38 INFO - PROCESS | 2296 | |13;stanford-crypto-sha256-iterative;67;66;67;66;65;66;82;85;84;87 06:59:38 INFO - PROCESS | 2296 | __end_tp_report 06:59:38 INFO - PROCESS | 2296 | __start_cc_report 06:59:38 INFO - PROCESS | 2296 | _x_x_mozilla_cycle_collect,1993 06:59:38 INFO - PROCESS | 2296 | __end_cc_report 06:59:38 INFO - PROCESS | 2296 | __startTimestamp1468331978888__endTimestamp 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | ------- Summary: start ------- 06:59:38 INFO - PROCESS | 2296 | Number of tests: 14 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.72 (3.3%) stddev-sans-first:0.50 06:59:38 INFO - PROCESS | 2296 | Values: 101.0 112.0 112.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:139.50 stddev:28.85 (20.7%) stddev-sans-first:29.98 06:59:38 INFO - PROCESS | 2296 | Values: 144.0 103.0 107.0 170.0 96.0 102.0 116.0 124.0 178.0 135.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.50 stddev:2.64 (1.4%) stddev-sans-first:2.74 06:59:38 INFO - PROCESS | 2296 | Values: 185.0 184.0 187.0 182.0 183.0 186.0 179.0 180.0 183.0 186.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#3] audio-fft Cycles:10 Average:70.70 Median:75.50 stddev:12.91 (17.1%) stddev-sans-first:13.34 06:59:38 INFO - PROCESS | 2296 | Values: 79.0 74.0 57.0 88.0 58.0 56.0 64.0 63.0 91.0 77.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:79.00 stddev:13.43 (17.0%) stddev-sans-first:14.15 06:59:38 INFO - PROCESS | 2296 | Values: 86.0 88.0 94.0 72.0 72.0 68.0 97.0 69.0 68.0 103.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:1.57 (1.8%) stddev-sans-first:1.42 06:59:38 INFO - PROCESS | 2296 | Values: 89.0 86.0 87.0 85.0 87.0 85.0 89.0 86.0 88.0 85.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.91 (2.1%) stddev-sans-first:1.72 06:59:38 INFO - PROCESS | 2296 | Values: 96.0 97.0 92.0 92.0 93.0 92.0 93.0 93.0 92.0 91.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#7] imaging-desaturate Cycles:10 Average:100.60 Median:85.00 stddev:19.61 (23.1%) stddev-sans-first:20.80 06:59:38 INFO - PROCESS | 2296 | Values: 101.0 121.0 88.0 85.0 84.0 94.0 134.0 128.0 85.0 86.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.71 06:59:38 INFO - PROCESS | 2296 | Values: 55.0 53.0 53.0 54.0 54.0 53.0 55.0 53.0 54.0 54.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:70.00 stddev:15.55 (22.2%) stddev-sans-first:15.62 06:59:38 INFO - PROCESS | 2296 | Values: 66.0 67.0 86.0 96.0 106.0 101.0 70.0 71.0 69.0 70.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:2.07 (2.5%) stddev-sans-first:2.12 06:59:38 INFO - PROCESS | 2296 | Values: 85.0 82.0 85.0 82.0 81.0 83.0 82.0 88.0 83.0 84.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:142.50 stddev:26.84 (18.8%) stddev-sans-first:28.10 06:59:38 INFO - PROCESS | 2296 | Values: 137.0 140.0 133.0 137.0 137.0 141.0 144.0 150.0 224.0 149.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:191.00 stddev:30.76 (16.1%) stddev-sans-first:30.34 06:59:38 INFO - PROCESS | 2296 | Values: 219.0 228.0 160.0 170.0 229.0 212.0 164.0 162.0 162.0 162.0 06:59:38 INFO - PROCESS | 2296 | 06:59:38 INFO - PROCESS | 2296 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:74.50 stddev:9.56 (12.8%) stddev-sans-first:9.85 06:59:38 INFO - PROCESS | 2296 | Values: 67.0 66.0 67.0 66.0 65.0 66.0 82.0 85.0 84.0 87.0 06:59:38 INFO - PROCESS | 2296 | -------- Summary: end -------- 06:59:38 INFO - PROCESS | 2296 | 06:59:39 INFO - TEST-INFO | 2296: exit 0 06:59:39 INFO - TEST-OK | kraken | took 59993ms 06:59:39 INFO - SUITE-END | took 1175s 06:59:40 INFO - Completed test suite (00:19:36) 06:59:40 INFO - javascript benchmark 06:59:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2798.468291024705, 3336.4161271777434, 3338.9165270868225, 3367.4275373186556, 3406.50605662179, 5587.022271102551, 5650.858728531789, 5667.263255776748, 5745.741441635126, 5753.626014309072, 5673.914891276634, 5733.885322293551, 5796.217510636063, 5811.883762324751, 5842.357340692521, 4259.828547149543, 5007.674501157431, 5125.384953805548, 5143.4599367066485, 5305.469453054683, 4882.292067650189, 4925.408950925876, 5274.947250527484, 5327.573794096463, 5368.973155134219, 5123.103456895028, 5152.768125434364, 5183.067047931371, 5189.351331083614, 5220.791168353261, 5150.098732721796, 5151.356080489939, 5167.844964651066, 5292.073887069786, 5312.123499622597, 4272.530021697611, 5216.217567364888, 5228.712420816853, 5242.842714718565, 5272.657277276965, 5593.8321850344555, 5646.096624540095, 5764.51001664859, 5836.708164591796, 5861.944849927011, 6194.287656919422, 6223.097650840648, 6250.468710159642, 6264.6241225526155, 6275.811725648238, 6232.9376706232815, 6297.4017468340435, 6314.242290925118, 6317.557770956035, 6322.399372059647, 5669.744861481202, 5768.451997060272, 5843.328017277982, 5845.473907348359, 5875.471207591295, 6268.717907694074, 6279.309275979638, 6289.245290565162, 6290.654014029273, 6295.96852015737, 6188.9690551546955, 6191.907121393183, 6209.2548894133, 6209.875802484015, 6212.596181248205, 6078.199405501599, 6142.713648198282, 6212.409821066992, 6227.346128656516, 6296.118543403927, 6208.875822483616, 6252.155958945484, 6262.6868656566985, 6275.592086514362, 6276.497880169575, 6208.534359923024, 6275.435210831047, 6280.905786413207, 6290.748370065237, 6302.086197501337, 4875.293082503017, 4932.7286999215385, 4950.25746138075, 4989.825356112591, 4992.101421744048, 4431.822727090945, 4483.641308695296, 4526.524714904954, 4558.27067669178, 4561.110583436198, 5793.797217097382, 5814.970925145347, 5827.184194212814, 5842.094475356242, 5846.181534585162, 6242.2509298883515, 6258.883375308598, 6313.337099604475, 6315.273743519462, 6323.841903952438, 5563.471470210324, 5711.8000869969355, 5789.623674461147, 5803.984302747087, 5850.414958504116, 5376.1935709643085, 5426.158945363396, 5458.4814442627885, 5489.176623506506, 5501.862453438696, 6327.936720632827, 6330.39861213184, 6340.1440805490665, 6347.936520634735, 6351.618902865843, 6147.954847675996, 6256.687165641791, 6260.311365749855, 6330.651814150266, 6333.809985700436, 5601.747921343468, 6224.564280500322, 6249.187605611241, 6259.530535209787, 6287.245530536365, 6171.043488259327, 6193.783217587363, 6200.658963756856, 6204.441600255998, 6212.81361559154, 4815.181419158681, 6158.507319414346, 6287.1512345832725, 6290.0, 6312.084747711648, 4766.404199475066, 6008.969955150196, 6174.166487524127, 6189.5048396129105, 6204.317525072329, 6149.2313460817395, 6176.0, 6192.0711893214575, 6203.751849925952, 6207.379262073757, 4953.578945789648, 5800.303963524404, 5913.965056115248, 6043.335233124265, 6057.878842423039, 6133.355997620186, 6152.846178845564, 6165.72254248551, 6176.6911654418, 6188.845278868064, 5948.405159484017, 5965.0, 5979.730912108878, 5980.132880732356, 6006.039033754579], "value": 5687.4190924327095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.09886709587266, 487.6123481831941, 515.8865049689068, 528.9735513224339, 529.3568314497884, 29681.55477667834, 31477.527837082464, 43136.352954705624, 44395.11209775811, 44530.0, 45370.546294537045, 45868.311975320394, 45919.31121033187, 45939.0, 46019.0, 9755.560999755011, 9950.203983681324, 9956.502174891262, 9959.402435853835, 9967.401955882635, 9788.951055244714, 9902.55438505269, 9903.0, 9914.008599140065, 9914.752131196705, 9880.505974701236, 9909.75225619358, 9922.950385248063, 9928.156106730936, 9934.751631209205, 8445.662173513052, 9894.604215831358, 9896.059874311928, 9912.355696879717, 9916.752081198028, 9665.130138287588, 9731.41611503312, 9736.269779766475, 9740.0, 9741.610335586569, 42582.14835703269, 42915.1416971662, 42970.0, 42980.355294670604, 43045.78477107626, 3518.5601799775027, 3563.572371315433, 3604.044928094057, 3625.4380571011534, 3709.0, 33358.0, 33955.15112122217, 34046.6595334046, 34101.31797364064, 34121.0, 3304.4052070627276, 3315.519249708803, 3350.6816852398983, 3359.0, 3448.2758620689683, 513923.0, 515751.0, 517299.0, 518346.0, 518810.4059479716, 34740.47889281663, 35360.82319588437, 35519.961606526434, 65002.0, 65412.0, 36382.0, 36584.268314633555, 36793.63206367956, 36798.81600592034, 36836.26327473489, 15442.305096270695, 15580.844191558166, 15591.986520876108, 15597.68804623901, 15606.9219653901, 12990.285534295515, 13105.410256538478, 13113.60659180207, 13124.803127953088, 13128.409221585052, 8119.9188008120345, 8137.674493020331, 8137.95931020353, 8221.588920553948, 8225.300849427866, 3285.5564498792837, 3292.423825830518, 3303.8182899940252, 3303.884364047247, 3326.284848757529, 881.5239770523973, 960.3325688646463, 1004.638330201127, 1045.46158728255, 1130.6721050895148, 31596.210094747814, 31658.366832663218, 31759.52360714591, 32129.51805722916, 32815.50776738351, 1062.8246339354075, 1106.31961343773, 1113.2930589075995, 1157.0049757208794, 1171.7715045566204, 14460.493882714265, 15848.841511584737, 16651.25069371881, 16670.083145427117, 16692.914960527527, 169313.0, 169751.0, 173954.26045739633, 173967.0, 174051.0, 62515.6874215626, 62960.37039629545, 62979.6851015742, 63117.0, 63487.0, 1287.4914408808656, 1417.957461276184, 1426.600551845485, 1441.0345068803927, 1447.623617859343, 32498.0, 33075.66924330726, 33182.0, 33437.99686009424, 33454.07077838211, 53983.73008134934, 54472.72763636157, 55076.449235507134, 55096.449035510734, 55254.0, 533068.0, 534322.0, 534541.0, 534681.0, 537610.0, 24725.0, 27458.862705686344, 28146.01488947877, 28168.01411950572, 28226.85886570554, 50374.0, 50428.49571504238, 50923.490765091876, 51350.0, 51779.0, 41416.585834141275, 41637.37543936904, 41857.162856742085, 42623.360649590904, 42903.356449653904, 41317.793411032755, 41786.058720012006, 41834.790826045675, 42041.57958420377, 42428.363574547024, 40071.1985760289, 40616.98457538585, 40837.1832563353, 40855.59144408518, 41019.1796164081, 31418.21454463565, 33298.33403331968, 34645.48031779576, 36134.0, 36500.0, 22237.332880013626, 26036.739632603432, 26216.0, 26441.70309599371, 26477.602835957863], "value": 18631.236273966577, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.687941490903, 381.1975790960029, 385.56816365670437, 389.2370952932251, 407.3889166250624, 15763.595679548302, 16086.839131608695, 21181.0, 21447.927603619813, 21477.892610536965, 20955.16179352824, 21296.0, 21301.57396852062, 21560.784392156074, 21944.695806940283, 5289.920651190235, 6020.76574302267, 6177.969110154443, 6181.07283907413, 6191.907121393183, 6087.9695601521935, 6154.323024467305, 6174.6912654367325, 6176.949918513864, 6185.721642526097, 5851.970740146315, 6056.424639659225, 6076.635401875901, 6084.969575152118, 6088.908666370008, 5750.166225897222, 6033.245844269466, 6070.57505974582, 6095.908561371583, 6122.908156377635, 5952.672603006833, 5986.341502434729, 5988.161657367972, 5989.13157592151, 6003.489703375219, 13608.727825443435, 17786.733199002025, 25618.0, 26626.0, 26644.866775666193, 2200.0, 2215.8227341812776, 2219.4451387153213, 2490.0537795637542, 2956.320046389321, 10171.135453486388, 10175.033371829664, 10176.745581360452, 10634.893651063545, 10635.255532112755, 1864.692325766247, 1956.0, 1975.8518111141577, 1975.8913259770707, 1983.980160198394, 128540.0, 136848.31575842158, 139694.41222351196, 140949.0, 141034.0, 10109.837368702547, 10632.627858025087, 11685.824712629317, 11691.181617286707, 11849.881501185051, 14616.780748288784, 14704.823614110845, 14762.335694893756, 14784.556463306119, 14784.778228326584, 8466.449680770731, 8640.827183456297, 8687.044425113232, 8751.0, 8758.824823503493, 8386.706465273686, 8389.95805020971, 8508.276796472246, 8508.65965361391, 8534.189252021048, 4506.301523263899, 4585.082983403333, 4585.9770701146945, 4642.558956899089, 4657.417822772153, 1895.545069183386, 1936.5933154037527, 1948.6784680527837, 1953.462797730607, 1956.5597740508272, 827.6358402303088, 873.2402809555728, 925.5927391947522, 1015.5074788727461, 1047.8166320893813, 20464.69302960457, 21443.67834482484, 21550.676739848914, 21637.675434868488, 21826.454338641342, 856.6037359104744, 915.391264808908, 925.2459245714721, 983.9311248212556, 1027.799579082072, 9996.950015250022, 11434.313941163557, 11451.484683189277, 11484.540618375171, 11501.884981150082, 616894.0, 623130.0, 637616.0, 655545.0, 656784.0, 121994.0, 124092.0, 132237.0, 132976.0, 138077.3096134533, 998.9100980911753, 1005.1054561440324, 1017.2574020964737, 1017.704865588971, 1047.36634336227, 18871.245150193838, 19039.71440428367, 19129.90435047816, 19149.0, 19230.903845480683, 53984.460155399516, 55911.88176236371, 57449.0, 57636.0, 57762.133567995676, 79831.0, 85050.57474712587, 85692.57153714192, 86334.1366586326, 87310.69033964368, 6830.93169068303, 7959.721409750631, 8060.992375953006, 8184.590770461572, 8226.917730822615, 11894.524219031142, 13247.66880827987, 13294.53760086409, 13419.329033548089, 13447.462101516221, 11233.157513186381, 11335.943320283346, 11401.118815395366, 11419.543218271176, 11522.53909843597, 10037.695099637, 10758.946205268923, 11299.604513841976, 11301.773964520828, 11535.711607209887, 11640.825387619023, 11666.88333116658, 11677.240979336317, 11932.284062955903, 11963.521459141537, 4941.431735350337, 7322.597257150908, 7665.23347665229, 7823.804404889924, 8205.138460461565, 9786.363886347364, 11616.593419230287, 11634.36011019402, 11874.584389546324, 12025.977791887437], "value": 9753.666943346514, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.4360633943256, 1577.8974366666166, 1601.0473768107975, 1792.865535084869, 1911.5507855653927, 7937.08723496798, 8663.393562450623, 8899.644014239422, 8908.643654253838, 8956.417832840878, 8692.826143477127, 8788.8681669775, 8862.113788621135, 8862.77843053924, 8863.246624036965, 2357.4813541021035, 2400.735919048912, 2401.4716762312264, 2402.0752010475917, 2403.3030421177837, 2358.6344116661944, 2389.486260454002, 2393.1623931623967, 2396.580598395283, 2398.3764221302413, 2354.799842013422, 2372.3357459911253, 2386.331827088418, 2387.3673476528734, 2393.042782886842, 1984.7122167285736, 2304.8501847380035, 2336.077249486452, 2336.4976530046038, 2349.447879748258, 2318.5362927414585, 2337.2871274261342, 2341.0986770093637, 2354.3643216331493, 2356.0929042318658, 8709.346798990038, 8749.868751968726, 8852.026277109513, 8861.955690221572, 8863.689770857989, 5268.868278293035, 5417.26866872971, 5423.701696406696, 5429.294191755086, 5443.074677304868, 3574.535310409656, 3616.6021737608844, 3632.400653892105, 3663.6885864701267, 3676.74262801604, 1091.2633972068822, 1096.2381145104155, 1101.7080473674482, 1105.3312745788762, 1116.5477981417457, 11727.765444691144, 11838.881611183864, 11853.466594003205, 11876.643700688994, 11890.524379024828, 4439.778011099432, 4440.289553671397, 4645.3032045193495, 4654.394928659321, 4664.976675116603, 9268.304877134242, 9274.350795444254, 9277.95361023204, 9295.0, 9297.256219502424, 5247.370315562081, 5253.106971814801, 5259.4477579853565, 5264.973330200574, 5269.919666468365, 4241.957580424156, 4393.472783266028, 4561.996360800618, 4834.347363106006, 4928.0, 3173.666764989689, 3779.225258821935, 3853.499045124116, 3877.5540812806325, 3900.3369427197445, 1156.3582211872492, 1162.3258510064143, 1163.139276935062, 1171.0865524890598, 1173.4484792147678, 884.5621417398429, 954.1031430455349, 956.6890760502725, 980.789130336981, 1024.9351369551086, 6086.513078953673, 6091.42131497507, 6094.2077529920825, 6143.692815359214, 6161.229846269216, 442.62155856742663, 442.92913133899066, 449.88752811797053, 451.6477147824702, 464.59115977939194, 4180.91638167241, 4210.221109094766, 4332.393464914914, 4387.846425375096, 4413.7793110344355, 9373.390729602554, 10893.83659245112, 10901.83647245292, 11018.063464605439, 11023.17326200538, 10987.175961802897, 11006.95433933775, 11019.559217631366, 11025.338479691245, 11037.0, 7845.215478452109, 7995.440319177602, 8013.27880490747, 8211.794705132419, 8434.0, 6005.309389420273, 6068.6965651716355, 6137.478314343331, 6167.599106058271, 6260.874782504234, 10138.391696498235, 10293.485325733534, 10314.742131446474, 10316.329438586468, 10318.896811031793, 10044.447555384524, 10053.748656283651, 10209.081182693593, 10407.167426606005, 10414.166866650801, 7050.964745176241, 7263.673134709056, 7267.200607933229, 7628.237176282328, 7789.415793815373, 8067.435279530377, 8130.308923741548, 8253.917460825314, 8476.957615211884, 8499.74500764978, 7340.082489688789, 7555.73554925575, 7604.12552556441, 7641.961790191013, 7652.311291983749, 6308.33762454954, 7450.329470347695, 7467.402607791255, 7591.8861217082895, 7593.392528597588, 7112.715491380287, 7117.395021423237, 7187.676554554953, 7199.748008819666, 7213.567185968858, 4946.332245146859, 5123.38519377677, 5130.846074617767, 5174.379074511083, 5235.110031294613, 5052.545270925634, 5067.366579177603, 5123.564497017646, 5129.666571672829, 5261.736913154404], "value": 4526.169330364408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.7775862499688, 312.93897689950467, 314.4402962726347, 320.5128205128205, 324.4743515504882, 5364.704941228231, 5400.405955344914, 5407.675539467635, 5425.511703946644, 5434.483724046219, 4305.849295274666, 5358.705271210082, 5389.326334208224, 5406.324209473816, 5445.754941027653, 3632.8365223565, 3761.0012030810813, 3976.76139431635, 4139.461869956901, 4179.916401671965, 4074.186986790879, 4142.71001029928, 4157.729747566398, 4203.402706971347, 4218.915621687564, 4126.360414135814, 4127.0, 4133.937990930123, 4149.356849688304, 4151.3150330195485, 3769.492203118747, 3898.883033508971, 3916.4125381192766, 3937.6808769062254, 4152.231837110114, 3224.3228921926425, 3592.4431713084514, 3602.189507360849, 3602.9099272518133, 3616.8734094306574, 5312.92030619541, 5318.441563635801, 5325.60057995648, 5336.466353364672, 5336.759845806948, 4390.319500477431, 4419.784559246201, 4426.468823741172, 4680.555347242007, 4786.688865223784, 5136.6661167024395, 5531.529819965308, 5679.630823996468, 5686.886262274773, 5690.687012214326, 3486.2504561519395, 3808.7905165215902, 3919.2357490289405, 3959.980200098981, 3962.5443074046566, 5752.482276595085, 5969.552283578662, 5989.1733021428445, 6004.849878753066, 6125.039987204096, 3336.399448099317, 3637.436197389409, 3640.3083414151756, 3956.5449973252867, 3989.68082553395, 3545.0782796473104, 4027.8993025174605, 4038.24336413661, 4039.818208180639, 4059.3707975263883, 4264.957350426518, 4386.342048692722, 4687.95312046882, 4696.577308042292, 4700.626183777239, 5264.368275806928, 5345.839624811262, 5595.608307418523, 5772.163036359704, 5811.418858114154, 1238.108561835477, 1271.1800888426912, 1289.8710128987027, 1349.2039696579066, 1387.8057072009926, 711.4628455516053, 829.850626887154, 844.9704260350982, 850.974470765878, 867.5402036920442, 5286.81496147633, 5362.731863406892, 5392.218128371364, 5414.35027796667, 5479.260299859548, 5171.3018742469285, 5245.501677340647, 5656.406077361818, 5854.448571128654, 5936.614120082181, 4740.66815322931, 4972.353594032753, 5117.513836185556, 5302.92045619316, 5394.518521480085, 2356.316668166213, 2436.9878150609134, 2728.208819442379, 3075.338802157502, 3076.4310201797243, 5745.88508229833, 6015.218021657244, 6040.909386359208, 6043.214382130295, 6062.575619706578, 4807.0, 5639.902434167969, 5968.761249550056, 6012.128241404972, 6039.54703397247, 4823.855284341475, 5059.418166910852, 5751.798687045934, 5906.852328691817, 6058.848528786815, 5478.315210598676, 5814.215080964016, 5817.505512031525, 5848.444397782204, 5907.645541267538, 4857.562819346276, 4936.777844997056, 4952.851414457572, 5763.827085187451, 5777.595568310178, 5974.492168165754, 5981.700914954322, 6017.87964240704, 6031.819045428644, 6036.6679832609625, 3197.976647472786, 3887.3197190492115, 3887.7667339959694, 3995.6603688685623, 4015.4378387026422, 5181.844544663666, 5365.007473617393, 5553.0, 5751.194832723506, 5910.881782364243, 5741.942580574141, 5745.770169193185, 5783.392743761844, 5799.2750906136735, 5824.5340372770925, 4543.727376357429, 5511.862203444946, 5568.248286481273, 5606.0, 5748.626339287933, 4596.655250856266, 5417.997670430983, 5529.972350138223, 5605.32736071674, 5609.298837645294, 5530.336359636869, 5560.138178582326, 5713.714314284352, 5720.456556627114, 5736.713164341682, 5236.554892834151, 5315.176147697113, 5608.831735047955, 5610.943890561205, 5623.297087864017], "value": 4137.176426156478, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.6161657554647, 851.8040850604361, 857.3227150551065, 861.7242482405629, 889.7686601483613, 15184.39262429503, 15809.966915831297, 16546.50360489186, 16550.00699958002, 16960.915195424004, 15243.77134342983, 15456.22718864058, 15459.92270038651, 15463.381464741426, 15527.146006969611, 895.8208358328328, 911.8586619074055, 920.9677661281856, 923.529000209891, 923.6905636611743, 729.8686236477432, 760.0916904299364, 790.9644066017014, 901.1124042817819, 937.6858752317947, 978.1587834462357, 983.2183414185739, 983.7688143286322, 988.2686811759319, 988.367444835306, 967.2117224462041, 968.4141094637713, 969.9078587534173, 970.1753509516925, 970.4032020307495, 555.089652969131, 698.0471656189316, 724.8151721311085, 727.0403067950308, 729.8941653460272, 15704.528864134094, 15732.134732589702, 15770.921145394313, 15771.29029193689, 15773.211339432983, 14307.213103279315, 14567.635809104751, 14642.41430342785, 14695.77956330656, 14707.338169782386, 13011.414486348043, 13286.53497127596, 13292.667683307898, 13470.528531501448, 13548.59354219375, 4713.5993440557595, 4796.52034796521, 4885.071836351082, 4976.701397916137, 4979.1784355581285, 113352.4332378341, 113411.43294283476, 114617.85382146155, 115881.99999999916, 116526.0, 11414.258073225214, 11507.654770356903, 11595.478203480863, 11702.414879256003, 11844.644660660195, 23335.766642333358, 23336.883315583313, 23362.0, 23404.414889627897, 24167.274981750576, 8401.327893768485, 8410.217536580165, 8444.113368096261, 8477.23704866565, 8728.127187281221, 4489.595936365743, 4560.156371071296, 4568.885777855514, 4574.108048930427, 4630.976845115752, 3568.803715795604, 3707.4994875691436, 3708.8331025103935, 3711.202091550276, 3717.4795528626014, 688.1604442580044, 688.9517733758689, 694.6110178300169, 704.5526090932192, 707.8478127202676, 587.0, 648.8864448721549, 651.6904470376534, 688.579966220605, 699.6501749125438, 8819.735407937773, 9063.682771103111, 9182.403143795766, 9186.282638809602, 9223.861642075375, 659.4790115808457, 659.482306389482, 672.1967249137332, 691.20407949843, 697.752297934226, 4205.0, 4407.757573333433, 4412.823487060482, 4505.279155335131, 4554.0, 111366.0, 144485.0, 148268.0, 149462.0, 151939.0, 129346.0, 129382.05926911104, 129732.0, 130492.0, 131978.0, 70339.64830175816, 110750.0, 112847.43576282066, 114498.0, 115575.0, 8387.287080598218, 8679.869801953102, 8707.0, 8735.1701588349, 8825.0, 64117.038244427305, 64359.0, 64438.67780661254, 65049.67475162594, 66918.99243022717, 69893.0, 80710.0, 92883.60674590022, 95387.04612953782, 96165.51917240368, 11916.225445346025, 11921.82572201935, 12078.09414293949, 12292.463442069742, 12640.178388405093, 13044.934775326063, 13077.0, 13080.476780929037, 13529.458821647024, 13647.658808529866, 9712.951435242778, 11836.644900652649, 11924.0, 11984.880151198377, 12213.938930305647, 13627.757125154894, 13980.58058258254, 13997.440102396198, 14439.989200755846, 14602.926985365006, 13429.932850335686, 13502.392392342565, 13541.526046588322, 13765.587032389045, 13967.860321397062, 11091.168162387692, 11292.548298067984, 11350.205485616258, 11592.948526632126, 11747.765044698886, 9451.716448506555, 11144.387058711849, 11239.157063220127, 11316.26444281119, 11536.0], "value": 8027.863086456087, "unit": "score"}], "name": "dromaeo_css", "value": 7332.1933262799885, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 103.0, 107.0, 170.0, 96.0, 102.0, 116.0, 124.0, 178.0, 135.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 187.0, 182.0, 183.0, 186.0, 179.0, 180.0, 183.0, 186.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 88.0, 58.0, 56.0, 64.0, 63.0, 91.0, 77.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 94.0, 72.0, 72.0, 68.0, 97.0, 69.0, 68.0, 103.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 87.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 92.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 91.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 121.0, 88.0, 85.0, 84.0, 94.0, 134.0, 128.0, 85.0, 86.0], "value": 100.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 54.0, 54.0, 53.0, 55.0, 53.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 86.0, 96.0, 106.0, 101.0, 70.0, 71.0, 69.0, 70.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 82.0, 81.0, 83.0, 82.0, 88.0, 83.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 133.0, 137.0, 137.0, 141.0, 144.0, 150.0, 224.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 228.0, 160.0, 170.0, 229.0, 212.0, 164.0, 162.0, 162.0, 162.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 65.0, 66.0, 82.0, 85.0, 84.0, 87.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1482.3000000000002, "alertThreshold": 2.0}]} 06:59:40 INFO - Return code: 0 06:59:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:59:40 INFO - # TBPL SUCCESS # 06:59:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:59:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:59:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:59:40 INFO - 2016-07-12 06:59:40.172 screenresolution[2300:289719] starting screenresolution argv=screenresolution get 06:59:40 INFO - 2016-07-12 06:59:40.175 screenresolution[2300:289719] Display 0: 1600x1200x32@60 06:59:40 INFO - 2016-07-12 06:59:40.181 screenresolution[2301:289722] starting screenresolution argv=screenresolution list 06:59:40 INFO - Available Modes on Display 0 06:59:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:59:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:59:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:59:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:59:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:59:40 INFO - Intel Iris: 06:59:40 INFO - Chipset Model: Intel Iris 06:59:40 INFO - Type: GPU 06:59:40 INFO - Bus: Built-In 06:59:40 INFO - VRAM (Dynamic, Max): 1536 MB 06:59:40 INFO - Vendor: Intel (0x8086) 06:59:40 INFO - Device ID: 0x0a2e 06:59:40 INFO - Revision ID: 0x0009 06:59:40 INFO - Displays: 06:59:40 INFO - Display: 06:59:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:59:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:59:40 INFO - Display Serial Number: 1600x1200 60 06:59:40 INFO - Main Display: Yes 06:59:40 INFO - Mirror: Off 06:59:40 INFO - Online: Yes 06:59:40 INFO - Rotation: Supported 06:59:40 INFO - Return code: 0 06:59:40 INFO - Running post-action listener: _resource_record_post_action 06:59:40 INFO - ##### 06:59:40 INFO - ##### Finished run-tests step (success) 06:59:40 INFO - ##### 06:59:40 INFO - Running post-run listener: _resource_record_post_run 06:59:40 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 770048; Write bytes: 703223296; Read time: 5; Write time: 1971 06:59:40 INFO - TinderboxPrint: CPU usage
53.4% 06:59:40 INFO - TinderboxPrint: I/O read bytes / time
770,048 / 5 06:59:40 INFO - TinderboxPrint: I/O write bytes / time
703,223,296 / 1,971 06:59:40 INFO - TinderboxPrint: CPU idle
2,258.8 (46.6%) 06:59:40 INFO - TinderboxPrint: CPU system
966.8 (19.9%) 06:59:40 INFO - TinderboxPrint: CPU user
1,624.1 (33.5%) 06:59:40 INFO - TinderboxPrint: Swap in / out
420,782,080 / 0 06:59:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397857280; Write bytes: 392402944; Read time: 30322; Write time: 797 06:59:40 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 770048; Write bytes: 293187072; Read time: 5; Write time: 1127 06:59:40 INFO - Running post-run listener: _upload_blobber_files 06:59:40 INFO - Blob upload gear active. 06:59:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:59:40 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 06:59:40 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'] 06:59:40 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 06:59:41 INFO - (blobuploader) - INFO - Open directory for files ... 06:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:59:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:59:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:59:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:59:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:59:41 INFO - (blobuploader) - INFO - Done attempting. 06:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:59:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:59:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:59:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:59:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:59:42 INFO - (blobuploader) - INFO - Done attempting. 06:59:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:59:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:59:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:59:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:59:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:59:44 INFO - (blobuploader) - INFO - Done attempting. 06:59:44 INFO - (blobuploader) - INFO - Iteration through files over. 06:59:44 INFO - Return code: 0 06:59:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:59:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:59:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46474b5d67faabcd9c19aada1a118748e1b6fd4d1a188cfad7a82f0f5be343c54fb7a8ba45df761ba2a350d558699f369b4b1217badcc21aeac087d29cafb751", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03a9d145dbd78daf4c273fb5ce8d922c5e7b1cbc2f64a903bdac56681815efa45568434dec12a5a7a42c35b71178663f7bd9651763850fb048c1fc1f8e7cf63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65511c0302df058017a0cbc5143c8d204b7351f8aed9b9af1c22ecfab6efa35c28243ae87e035ffa057ea86376afcb682b7392eeff9300851caa7f08cbcf4a03"} 06:59:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:59:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:59:44 INFO - Contents: 06:59:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46474b5d67faabcd9c19aada1a118748e1b6fd4d1a188cfad7a82f0f5be343c54fb7a8ba45df761ba2a350d558699f369b4b1217badcc21aeac087d29cafb751", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03a9d145dbd78daf4c273fb5ce8d922c5e7b1cbc2f64a903bdac56681815efa45568434dec12a5a7a42c35b71178663f7bd9651763850fb048c1fc1f8e7cf63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65511c0302df058017a0cbc5143c8d204b7351f8aed9b9af1c22ecfab6efa35c28243ae87e035ffa057ea86376afcb682b7392eeff9300851caa7f08cbcf4a03"} 06:59:44 INFO - Running post-run listener: copy_logs_to_upload_dir 06:59:44 INFO - Copying logs to upload dir... 06:59:44 INFO - mkdir: /builds/slave/test/build/upload/logs 06:59:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.950666 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-12 06:59:45.062596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:59:45.066156) ========= 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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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/46474b5d67faabcd9c19aada1a118748e1b6fd4d1a188cfad7a82f0f5be343c54fb7a8ba45df761ba2a350d558699f369b4b1217badcc21aeac087d29cafb751", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03a9d145dbd78daf4c273fb5ce8d922c5e7b1cbc2f64a903bdac56681815efa45568434dec12a5a7a42c35b71178663f7bd9651763850fb048c1fc1f8e7cf63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65511c0302df058017a0cbc5143c8d204b7351f8aed9b9af1c22ecfab6efa35c28243ae87e035ffa057ea86376afcb682b7392eeff9300851caa7f08cbcf4a03"} build_url:https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010402 build_url: 'https://queue.taskcluster.net/v1/task/InYo09UeRaSJyBfZIhURFw/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/46474b5d67faabcd9c19aada1a118748e1b6fd4d1a188cfad7a82f0f5be343c54fb7a8ba45df761ba2a350d558699f369b4b1217badcc21aeac087d29cafb751", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e03a9d145dbd78daf4c273fb5ce8d922c5e7b1cbc2f64a903bdac56681815efa45568434dec12a5a7a42c35b71178663f7bd9651763850fb048c1fc1f8e7cf63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65511c0302df058017a0cbc5143c8d204b7351f8aed9b9af1c22ecfab6efa35c28243ae87e035ffa057ea86376afcb682b7392eeff9300851caa7f08cbcf4a03"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 06:59:45.099048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:59:45.099401) ========= 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.PNdeoC7KSU/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.glx0Qc4I5R/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005202 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 06:59:45.149055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:59:45.149419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 06:59:45.149807) ========= ========= Total master_lag: 0.20 =========