builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1468666283.33 results: success (0) buildid: 20160716011451 builduid: ab09e0ced6fc4a619aab0d09aca414c2 revision: 2708134823cb57d0cd7b4aa6046151153a61c9de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.326073) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.326517) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.326792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.350633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.351040) ========= 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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-16 03:51:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2016-07-16 03:51:23 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103634 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.476619) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.476988) ========= 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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.020914 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:23.526340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-16 03:51:23.526743) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2708134823cb57d0cd7b4aa6046151153a61c9de --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 2708134823cb57d0cd7b4aa6046151153a61c9de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-16 03:51:23,595 truncating revision to first 12 chars 2016-07-16 03:51:23,596 Setting DEBUG logging. 2016-07-16 03:51:23,596 attempt 1/10 2016-07-16 03:51:23,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2708134823cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-16 03:51:25,083 unpacking tar archive at: fx-team-2708134823cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-16 03:51:25.314737) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:25.315067) ========= script_repo_revision: 2708134823cb57d0cd7b4aa6046151153a61c9de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:25.315438) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:25.315698) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 03:51:25.333427) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-16 03:51:25.333775) ========= /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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:25 INFO - MultiFileLogger online at 20160716 03:51:25 in /builds/slave/test 03:51:25 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 03:51:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:51:25 INFO - {'append_to_log': False, 03:51:25 INFO - 'base_work_dir': '/builds/slave/test', 03:51:25 INFO - 'blob_upload_branch': 'Fx-Team', 03:51:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:51:25 INFO - 'branch': 'Fx-Team', 03:51:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:51:25 INFO - 'config_files': ('talos/mac_config.py',), 03:51:25 INFO - 'default_actions': ('clobber', 03:51:25 INFO - 'read-buildbot-config', 03:51:25 INFO - 'download-and-extract', 03:51:25 INFO - 'populate-webroot', 03:51:25 INFO - 'create-virtualenv', 03:51:25 INFO - 'install', 03:51:25 INFO - 'run-tests'), 03:51:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:51:25 INFO - 'download_minidump_stackwalk': True, 03:51:25 INFO - 'download_symbols': 'ondemand', 03:51:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:51:25 INFO - 'tooltool.py': '/tools/tooltool.py', 03:51:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:51:25 INFO - '/tools/misc-python/virtualenv.py')}, 03:51:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:51:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:51:25 INFO - 'installer_path': 'installer.exe', 03:51:25 INFO - 'log_level': 'info', 03:51:25 INFO - 'log_name': 'talos', 03:51:25 INFO - 'log_to_console': True, 03:51:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:51:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:51:25 INFO - 'opt_config_files': (), 03:51:25 INFO - 'pip_index': False, 03:51:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:51:25 INFO - 'cmd': ('bash', 03:51:25 INFO - '-c', 03:51:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:51:25 INFO - 'enabled': True, 03:51:25 INFO - 'halt_on_failure': False, 03:51:25 INFO - 'name': 'check_screen_resolution'},), 03:51:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:51:25 INFO - 'cmd': ('bash', 03:51:25 INFO - '-c', 03:51:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:51:25 INFO - 'enabled': True, 03:51:25 INFO - 'halt_on_failure': False, 03:51:25 INFO - 'name': 'check_screen_resolution'},), 03:51:25 INFO - 'run_cmd_checks_enabled': True, 03:51:25 INFO - 'sps_profile': False, 03:51:25 INFO - 'sps_profile_interval': 0, 03:51:25 INFO - 'suite': 'dromaeojs', 03:51:25 INFO - 'system_bits': '32', 03:51:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:51:25 INFO - 'title': 't-yosemite-r7-0175', 03:51:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:51:25 INFO - 'use_talos_json': True, 03:51:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:51:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:51:25 INFO - 'work_dir': 'build'} 03:51:25 INFO - ##### 03:51:25 INFO - ##### Running clobber step. 03:51:25 INFO - ##### 03:51:25 INFO - Running pre-action listener: _resource_record_pre_action 03:51:25 INFO - Running main action method: clobber 03:51:25 INFO - rmtree: /builds/slave/test/build 03:51:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:51:27 INFO - Running post-action listener: _resource_record_post_action 03:51:27 INFO - ##### 03:51:27 INFO - ##### Finished clobber step (success) 03:51:27 INFO - ##### 03:51:27 INFO - ##### 03:51:27 INFO - ##### Running read-buildbot-config step. 03:51:27 INFO - ##### 03:51:27 INFO - Running pre-action listener: _resource_record_pre_action 03:51:27 INFO - Running main action method: read_buildbot_config 03:51:27 INFO - Using buildbot properties: 03:51:27 INFO - { 03:51:27 INFO - "project": "", 03:51:27 INFO - "product": "firefox", 03:51:27 INFO - "script_repo_revision": "production", 03:51:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:51:27 INFO - "repository": "", 03:51:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:51:27 INFO - "buildid": "20160716011451", 03:51:27 INFO - "pgo_build": "False", 03:51:27 INFO - "basedir": "/builds/slave/test", 03:51:27 INFO - "buildnumber": 100, 03:51:27 INFO - "slavename": "t-yosemite-r7-0175", 03:51:27 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:51:27 INFO - "platform": "yosemite_r7", 03:51:27 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 03:51:27 INFO - "branch": "fx-team", 03:51:27 INFO - "repo_path": "integration/fx-team", 03:51:27 INFO - "revision": "2708134823cb57d0cd7b4aa6046151153a61c9de", 03:51:27 INFO - "stage_platform": "macosx64", 03:51:27 INFO - "builduid": "ab09e0ced6fc4a619aab0d09aca414c2", 03:51:27 INFO - "slavebuilddir": "test" 03:51:27 INFO - } 03:51:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:51:27 INFO - Running post-action listener: _resource_record_post_action 03:51:27 INFO - ##### 03:51:27 INFO - ##### Finished read-buildbot-config step (success) 03:51:27 INFO - ##### 03:51:27 INFO - ##### 03:51:27 INFO - ##### Running download-and-extract step. 03:51:27 INFO - ##### 03:51:27 INFO - Running pre-action listener: _resource_record_pre_action 03:51:27 INFO - Running main action method: download_and_extract 03:51:27 INFO - mkdir: /builds/slave/test/build/tests 03:51: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')]} 03:51:27 INFO - https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:51:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:51:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:51:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:28 INFO - Downloaded 1199 bytes. 03:51:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:51:28 INFO - Using the following test package requirements: 03:51:28 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:51:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:51:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:51:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'jsshell-mac.zip'], 03:51:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:51:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:51:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:51:28 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:51:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:51:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:51:28 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:51:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:51:28 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')]} 03:51:28 INFO - https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:51:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:51:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:51:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:29 INFO - Downloaded 18829439 bytes. 03:51:29 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'] 03:51:29 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 03:51:32 INFO - Return code: 0 03:51:32 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 03:51:32 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')]} 03:51:32 INFO - https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:51:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:51:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:51:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:33 INFO - Downloaded 18829439 bytes. 03:51:33 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'] 03:51:33 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 03:51:37 INFO - Return code: 0 03:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:37 INFO - https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:51:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:51:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:51:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:38 INFO - Downloaded 14403317 bytes. 03:51:38 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'] 03:51:38 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 03:51:38 INFO - Return code: 0 03:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:38 INFO - https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:51:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:51:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:51:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eseI8-jXTBGqT68KEH-p1A/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 03:51:42 INFO - Downloaded 112782849 bytes. 03:51:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:51:42 INFO - mkdir: /builds/slave/test/properties 03:51:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:51:42 INFO - Writing to file /builds/slave/test/properties/build_url 03:51:42 INFO - Contents: 03:51:42 INFO - build_url:https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:51:43 INFO - Running post-action listener: _resource_record_post_action 03:51:43 INFO - Running post-action listener: set_extra_try_arguments 03:51:43 INFO - ##### 03:51:43 INFO - ##### Finished download-and-extract step (success) 03:51:43 INFO - ##### 03:51:43 INFO - ##### 03:51:43 INFO - ##### Running populate-webroot step. 03:51:43 INFO - ##### 03:51:43 INFO - Running pre-action listener: _resource_record_pre_action 03:51:43 INFO - Running main action method: populate_webroot 03:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:51:43 INFO - '--tppagecycles', 03:51:43 INFO - '7'], 03:51:43 INFO - 'tests': ['tsvgm']}}, 03:51:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:51:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:51:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:51:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:51:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:51:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:51:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['damp', 'tps']}, 03:51:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'tests': ['damp', 'tps']}, 03:51:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['dromaeo_dom']}, 03:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:51:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['basic_compositor_video']}, 03:51:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:51:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['a11yr', 03:51:43 INFO - 'ts_paint', 03:51:43 INFO - 'tpaint', 03:51:43 INFO - 'sessionrestore', 03:51:43 INFO - 'sessionrestore_no_auto_restore', 03:51:43 INFO - 'tabpaint']}, 03:51:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['tsvgx', 03:51:43 INFO - 'tsvgr_opacity', 03:51:43 INFO - 'tart', 03:51:43 INFO - 'tscrollx', 03:51:43 INFO - 'cart']}, 03:51:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:51:43 INFO - 'tsvgr_opacity', 03:51:43 INFO - 'tart', 03:51:43 INFO - 'tscrollx', 03:51:43 INFO - 'cart']}, 03:51:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'talos_options': ['--disable-e10s'], 03:51:43 INFO - 'tests': ['tp5o']}, 03:51:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'tests': ['tp5o']}, 03:51:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'talos_options': ['--disable-e10s', 03:51:43 INFO - '--xperf_path', 03:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:51:43 INFO - 'tests': ['tp5n']}, 03:51:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:43 INFO - 'talos_options': ['--xperf_path', 03:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:51:43 INFO - 'tests': ['tp5n']}}, 03:51:43 INFO - 'talos.zip': {'path': '', 03:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:51:43 INFO - Running post-action listener: _resource_record_post_action 03:51:43 INFO - ##### 03:51:43 INFO - ##### Finished populate-webroot step (success) 03:51:43 INFO - ##### 03:51:43 INFO - ##### 03:51:43 INFO - ##### Running create-virtualenv step. 03:51:43 INFO - ##### 03:51:43 INFO - Running pre-action listener: _resource_record_pre_action 03:51:43 INFO - Running main action method: create_virtualenv 03:51:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:51:43 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 03:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:51:43 INFO - Using real prefix '/tools/python27' 03:51:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:51:44 INFO - Installing distribute.............................................................................................................................................................................................done. 03:51:47 INFO - Installing pip.................done. 03:51:47 INFO - Return code: 0 03:51:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:47 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:47 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 03:51:47 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 03:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:47 INFO - 'HOME': '/Users/cltbld', 03:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:47 INFO - 'LOGNAME': 'cltbld', 03:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:47 INFO - 'MOZ_NO_REMOTE': '1', 03:51:47 INFO - 'NO_EM_RESTART': '1', 03:51:47 INFO - 'PAGER': '/bin/cat', 03:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:47 INFO - 'PWD': '/builds/slave/test', 03:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:47 INFO - 'SHELL': '/bin/bash', 03:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:51:47 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:47 INFO - 'USER': 'cltbld', 03:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:47 INFO - 'XPC_FLAGS': '0x0', 03:51:47 INFO - 'XPC_SERVICE_NAME': '0', 03:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:47 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 03:51:47 INFO - Cleaning up... 03:51:47 INFO - Return code: 0 03:51:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:47 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:47 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 03:51:47 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 03:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:47 INFO - 'HOME': '/Users/cltbld', 03:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:47 INFO - 'LOGNAME': 'cltbld', 03:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:47 INFO - 'MOZ_NO_REMOTE': '1', 03:51:47 INFO - 'NO_EM_RESTART': '1', 03:51:47 INFO - 'PAGER': '/bin/cat', 03:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:47 INFO - 'PWD': '/builds/slave/test', 03:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:47 INFO - 'SHELL': '/bin/bash', 03:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:51:47 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:47 INFO - 'USER': 'cltbld', 03:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:47 INFO - 'XPC_FLAGS': '0x0', 03:51:47 INFO - 'XPC_SERVICE_NAME': '0', 03:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:47 INFO - Downloading/unpacking psutil>=3.1.1 03:51:47 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 03:51:47 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 03:51:47 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 03:51:47 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 03:51:47 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 03:51:47 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 03:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:51:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:50 INFO - Installing collected packages: psutil 03:51:50 INFO - Running setup.py install for psutil 03:51:50 INFO - building 'psutil._psutil_osx' extension 03:51:50 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 03:51:51 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 03:51:51 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 03:51:51 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 03:51:51 INFO - building 'psutil._psutil_posix' extension 03:51:51 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 03:51:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:51:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:51:51 INFO - ^ 03:51:51 INFO - 1 warning generated. 03:51:51 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 03:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:51 INFO - Successfully installed psutil 03:51:51 INFO - Cleaning up... 03:51:51 INFO - Return code: 0 03:51:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:51 INFO - 'HOME': '/Users/cltbld', 03:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:51 INFO - 'LOGNAME': 'cltbld', 03:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:51 INFO - 'MOZ_NO_REMOTE': '1', 03:51:51 INFO - 'NO_EM_RESTART': '1', 03:51:51 INFO - 'PAGER': '/bin/cat', 03:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:51 INFO - 'PWD': '/builds/slave/test', 03:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:51 INFO - 'SHELL': '/bin/bash', 03:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:51:51 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:51 INFO - 'USER': 'cltbld', 03:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:51 INFO - 'XPC_FLAGS': '0x0', 03:51:51 INFO - 'XPC_SERVICE_NAME': '0', 03:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:51:51 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 03:51:51 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 03:51:51 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 03:51:51 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 03:51:51 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 03:51:51 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 03:51:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:51:54 INFO - Installing collected packages: mozsystemmonitor 03:51:54 INFO - Running setup.py install for mozsystemmonitor 03:51:54 INFO - Successfully installed mozsystemmonitor 03:51:54 INFO - Cleaning up... 03:51:54 INFO - Return code: 0 03:51:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:51:54 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')]} 03:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:54 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')]} 03:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:54 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:54 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 03:51:54 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 03:51:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:51:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:54 INFO - 'HOME': '/Users/cltbld', 03:51:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:54 INFO - 'LOGNAME': 'cltbld', 03:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:54 INFO - 'MOZ_NO_REMOTE': '1', 03:51:54 INFO - 'NO_EM_RESTART': '1', 03:51:54 INFO - 'PAGER': '/bin/cat', 03:51:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:54 INFO - 'PWD': '/builds/slave/test', 03:51:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:54 INFO - 'SHELL': '/bin/bash', 03:51:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:51:54 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:51:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:54 INFO - 'USER': 'cltbld', 03:51:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:54 INFO - 'XPC_FLAGS': '0x0', 03:51:54 INFO - 'XPC_SERVICE_NAME': '0', 03:51:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:54 INFO - Downloading/unpacking jsonschema==2.5.1 03:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:51:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:51:57 INFO - warning: no files found matching '*.txt' 03:51:57 INFO - no previously-included directories found matching 'build' 03:51:57 INFO - no previously-included directories found matching 'dist' 03:51:57 INFO - no previously-included directories found matching '.git*' 03:51:57 INFO - Installing collected packages: jsonschema, functools32 03:51:57 INFO - Running setup.py install for functools32 03:51:57 INFO - warning: no files found matching '*.txt' 03:51:57 INFO - no previously-included directories found matching 'build' 03:51:57 INFO - no previously-included directories found matching 'dist' 03:51:57 INFO - no previously-included directories found matching '.git*' 03:51:57 INFO - Successfully installed jsonschema functools32 03:51:57 INFO - Cleaning up... 03:51:58 INFO - Return code: 0 03:51:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:51:58 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')]} 03:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:58 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')]} 03:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:58 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:51:58 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 03:51:58 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 03:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:58 INFO - 'HOME': '/Users/cltbld', 03:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:58 INFO - 'LOGNAME': 'cltbld', 03:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:58 INFO - 'MOZ_NO_REMOTE': '1', 03:51:58 INFO - 'NO_EM_RESTART': '1', 03:51:58 INFO - 'PAGER': '/bin/cat', 03:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:58 INFO - 'PWD': '/builds/slave/test', 03:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:58 INFO - 'SHELL': '/bin/bash', 03:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:51:58 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:58 INFO - 'USER': 'cltbld', 03:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:58 INFO - 'XPC_FLAGS': '0x0', 03:51:58 INFO - 'XPC_SERVICE_NAME': '0', 03:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:51:58 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 03:51:58 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 03:51:58 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 03:51:58 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 03:51:58 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 03:51:58 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 03:52:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:52:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:52:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:52:00 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 03:52:00 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 03:52:00 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 03:52:00 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 03:52:00 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 03:52:00 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 03:52:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:52:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:52:01 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 03:52:01 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 03:52:01 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 03:52:01 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 03:52:01 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 03:52:01 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 03:52:01 INFO - Downloading docopt-0.6.1.tar.gz 03:52:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:52:02 INFO - Installing collected packages: blobuploader, requests, docopt 03:52:02 INFO - Running setup.py install for blobuploader 03:52:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:52:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:52:02 INFO - Running setup.py install for requests 03:52:02 INFO - Running setup.py install for docopt 03:52:02 INFO - Successfully installed blobuploader requests docopt 03:52:02 INFO - Cleaning up... 03:52:02 INFO - Return code: 0 03:52:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:02 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')]} 03:52:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:02 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')]} 03:52:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:02 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:52:02 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 03:52:02 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 03:52:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:52:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:02 INFO - 'HOME': '/Users/cltbld', 03:52:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:02 INFO - 'LOGNAME': 'cltbld', 03:52:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:02 INFO - 'MOZ_NO_REMOTE': '1', 03:52:02 INFO - 'NO_EM_RESTART': '1', 03:52:02 INFO - 'PAGER': '/bin/cat', 03:52:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:02 INFO - 'PWD': '/builds/slave/test', 03:52:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:02 INFO - 'SHELL': '/bin/bash', 03:52:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:52:02 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:52:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:02 INFO - 'USER': 'cltbld', 03:52:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:02 INFO - 'XPC_FLAGS': '0x0', 03:52:02 INFO - 'XPC_SERVICE_NAME': '0', 03:52:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-YWAXLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-4ejwAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-nYPRRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-1RoA9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wACP7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-s_nCri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uby4Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:03 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ddAHqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-zD4aNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-XBDphJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-4VZX4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kzPI2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Wn4F3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-6g1rgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-An1LRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-cdJBLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:04 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wECQ2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:52:04 INFO - Running setup.py install for manifestparser 03:52:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:52:05 INFO - Running setup.py install for mozcrash 03:52:05 INFO - Running setup.py install for mozdebug 03:52:05 INFO - Running setup.py install for mozdevice 03:52:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:52:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:52:05 INFO - Running setup.py install for mozfile 03:52:05 INFO - Running setup.py install for mozhttpd 03:52:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:52:05 INFO - Running setup.py install for mozinfo 03:52:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:52:05 INFO - Running setup.py install for mozInstall 03:52:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Running setup.py install for mozleak 03:52:06 INFO - Running setup.py install for mozlog 03:52:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Running setup.py install for moznetwork 03:52:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Running setup.py install for mozprocess 03:52:06 INFO - Running setup.py install for mozprofile 03:52:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:52:06 INFO - Running setup.py install for mozrunner 03:52:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:52:07 INFO - Running setup.py install for mozscreenshot 03:52:07 INFO - Running setup.py install for moztest 03:52:07 INFO - Running setup.py install for mozversion 03:52:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:52:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:52:07 INFO - Cleaning up... 03:52:07 INFO - Return code: 0 03:52:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:07 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:52:07 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 03:52:07 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 03:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:07 INFO - 'HOME': '/Users/cltbld', 03:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:07 INFO - 'LOGNAME': 'cltbld', 03:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:07 INFO - 'MOZ_NO_REMOTE': '1', 03:52:07 INFO - 'NO_EM_RESTART': '1', 03:52:07 INFO - 'PAGER': '/bin/cat', 03:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:07 INFO - 'PWD': '/builds/slave/test', 03:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:07 INFO - 'SHELL': '/bin/bash', 03:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:52:07 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:07 INFO - 'USER': 'cltbld', 03:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:07 INFO - 'XPC_FLAGS': '0x0', 03:52:07 INFO - 'XPC_SERVICE_NAME': '0', 03:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-mjcabD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:07 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)) 03:52:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:07 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-yewuoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-sIUPm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-PhcreR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-EFop8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KPv27s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_G1r0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QvN_83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-7PoHm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-26wzR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-BSiuVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:08 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)) 03:52:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:08 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-buP4kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:09 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)) 03:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:09 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-WKLDRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:09 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)) 03:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:09 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_V10LJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:09 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)) 03:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:09 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-3YmQTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:09 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)) 03:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:09 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-SxdqSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:09 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)) 03:52:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:09 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-dETNXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:09 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)) 03:52:09 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)) 03:52:09 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)) 03:52:09 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)) 03:52:09 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)) 03:52:09 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)) 03:52:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:12 INFO - Downloading blessings-1.6.tar.gz 03:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:52:12 INFO - Installing collected packages: blessings 03:52:12 INFO - Running setup.py install for blessings 03:52:12 INFO - Successfully installed blessings 03:52:12 INFO - Cleaning up... 03:52:12 INFO - Return code: 0 03:52:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:52:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:12 INFO - Reading from file tmpfile_stdout 03:52:12 INFO - Current package versions: 03:52:12 INFO - blessings == 1.6 03:52:12 INFO - blobuploader == 1.2.4 03:52:12 INFO - docopt == 0.6.1 03:52:12 INFO - functools32 == 3.2.3-2 03:52:12 INFO - jsonschema == 2.5.1 03:52:12 INFO - manifestparser == 1.1 03:52:12 INFO - mozInstall == 1.12 03:52:12 INFO - mozcrash == 0.17 03:52:12 INFO - mozdebug == 0.1 03:52:12 INFO - mozdevice == 0.48 03:52:12 INFO - mozfile == 1.2 03:52:12 INFO - mozhttpd == 0.7 03:52:12 INFO - mozinfo == 0.9 03:52:12 INFO - mozleak == 0.1 03:52:12 INFO - mozlog == 3.2 03:52:12 INFO - moznetwork == 0.27 03:52:12 INFO - mozprocess == 0.23 03:52:12 INFO - mozprofile == 0.28 03:52:12 INFO - mozrunner == 6.12 03:52:12 INFO - mozscreenshot == 0.1 03:52:12 INFO - mozsystemmonitor == 0.3 03:52:12 INFO - moztest == 0.7 03:52:12 INFO - mozversion == 1.4 03:52:12 INFO - psutil == 3.1.1 03:52:12 INFO - requests == 1.2.3 03:52:12 INFO - wsgiref == 0.1.2 03:52:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:12 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:52:12 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 03:52:12 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 03:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:12 INFO - 'HOME': '/Users/cltbld', 03:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:12 INFO - 'LOGNAME': 'cltbld', 03:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:12 INFO - 'MOZ_NO_REMOTE': '1', 03:52:12 INFO - 'NO_EM_RESTART': '1', 03:52:12 INFO - 'PAGER': '/bin/cat', 03:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:12 INFO - 'PWD': '/builds/slave/test', 03:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:12 INFO - 'SHELL': '/bin/bash', 03:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:52:12 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:12 INFO - 'USER': 'cltbld', 03:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:12 INFO - 'XPC_FLAGS': '0x0', 03:52:12 INFO - 'XPC_SERVICE_NAME': '0', 03:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 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)) 03:52:12 INFO - Cleaning up... 03:52:13 INFO - Return code: 0 03:52:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:13 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 0x102640a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d5618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe18bda4430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, '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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 03:52:13 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 03:52:13 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 03:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:13 INFO - 'HOME': '/Users/cltbld', 03:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:13 INFO - 'LOGNAME': 'cltbld', 03:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:13 INFO - 'MOZ_NO_REMOTE': '1', 03:52:13 INFO - 'NO_EM_RESTART': '1', 03:52:13 INFO - 'PAGER': '/bin/cat', 03:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:13 INFO - 'PWD': '/builds/slave/test', 03:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:13 INFO - 'SHELL': '/bin/bash', 03:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:52:13 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:13 INFO - 'USER': 'cltbld', 03:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:13 INFO - 'XPC_FLAGS': '0x0', 03:52:13 INFO - 'XPC_SERVICE_NAME': '0', 03:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:52:13 INFO - Cleaning up... 03:52:13 INFO - Return code: 0 03:52:13 INFO - Running post-action listener: _resource_record_post_action 03:52:13 INFO - Running post-action listener: _start_resource_monitoring 03:52:13 INFO - Starting resource monitoring. 03:52:13 INFO - ##### 03:52:13 INFO - ##### Finished create-virtualenv step (success) 03:52:13 INFO - ##### 03:52:13 INFO - ##### 03:52:13 INFO - ##### Running install step. 03:52:13 INFO - ##### 03:52:13 INFO - Running pre-action listener: _resource_record_pre_action 03:52:13 INFO - Running main action method: install 03:52:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:13 INFO - Reading from file tmpfile_stdout 03:52:13 INFO - Detecting whether we're running mozinstall >=1.0... 03:52:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:52:13 INFO - Reading from file tmpfile_stdout 03:52:13 INFO - Output received: 03:52:13 INFO - Usage: mozinstall [options] installer 03:52:13 INFO - Options: 03:52:13 INFO - -h, --help show this help message and exit 03:52:13 INFO - -d DEST, --destination=DEST 03:52:13 INFO - Directory to install application into. [default: 03:52:13 INFO - "/builds/slave/test"] 03:52:13 INFO - --app=APP Application being installed. [default: firefox] 03:52:13 INFO - mkdir: /builds/slave/test/build/application 03:52:13 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'] 03:52:13 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 03:52:48 INFO - Reading from file tmpfile_stdout 03:52:48 INFO - Output received: 03:52:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:52:48 INFO - Running post-action listener: _resource_record_post_action 03:52:48 INFO - ##### 03:52:48 INFO - ##### Finished install step (success) 03:52:48 INFO - ##### 03:52:48 INFO - ##### 03:52:48 INFO - ##### Running run-tests step. 03:52:48 INFO - ##### 03:52:48 INFO - Running pre-action listener: _resource_record_pre_action 03:52:48 INFO - Running main action method: run_tests 03:52:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:52:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:52:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:52:48 INFO - 2016-07-16 03:52:48.143 screenresolution[1946:14662] starting screenresolution argv=screenresolution get 03:52:48 INFO - 2016-07-16 03:52:48.145 screenresolution[1946:14662] Display 0: 1600x1200x32@60 03:52:48 INFO - 2016-07-16 03:52:48.151 screenresolution[1947:14664] starting screenresolution argv=screenresolution list 03:52:48 INFO - Available Modes on Display 0 03:52:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:52:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:52:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:52:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:52:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:52:48 INFO - Intel Iris: 03:52:48 INFO - Chipset Model: Intel Iris 03:52:48 INFO - Type: GPU 03:52:48 INFO - Bus: Built-In 03:52:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:52:48 INFO - Vendor: Intel (0x8086) 03:52:48 INFO - Device ID: 0x0a2e 03:52:48 INFO - Revision ID: 0x0009 03:52:48 INFO - Displays: 03:52:48 INFO - Display: 03:52:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:52:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:52:48 INFO - Display Serial Number: 1600x1200 60 03:52:48 INFO - Main Display: Yes 03:52:48 INFO - Mirror: Off 03:52:48 INFO - Online: Yes 03:52:48 INFO - Rotation: Supported 03:52:48 INFO - Return code: 0 03:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:52:48 INFO - Python 2.7.3 03:52:48 INFO - Return code: 0 03:52:48 INFO - grabbing minidump binary from tooltool 03:52:48 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')]} 03:52:48 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 0x1025a4d30>, '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 0x102309db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:52:48 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 03:52:48 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 03:52:48 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 03:52:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:52:48 INFO - Return code: 0 03:52:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:52:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:52:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:52:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:52:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:52:48 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/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 03:52:48 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/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 03:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ocJ1U2Zj5F/Render', 03:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:48 INFO - 'HOME': '/Users/cltbld', 03:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:48 INFO - 'LOGNAME': 'cltbld', 03:52:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:52:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:48 INFO - 'MOZ_NO_REMOTE': '1', 03:52:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:52:48 INFO - 'NO_EM_RESTART': '1', 03:52:48 INFO - 'PAGER': '/bin/cat', 03:52:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:48 INFO - 'PWD': '/builds/slave/test', 03:52:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:48 INFO - 'SHELL': '/bin/bash', 03:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pgo1Eccqy8/Listeners', 03:52:48 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 03:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:48 INFO - 'USER': 'cltbld', 03:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:48 INFO - 'XPC_FLAGS': '0x0', 03:52:48 INFO - 'XPC_SERVICE_NAME': '0', 03:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:48 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/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 03:52:48 INFO - mozversion application_buildid: 20160716011451 03:52:48 INFO - mozversion application_changeset: 2708134823cb57d0cd7b4aa6046151153a61c9de 03:52:48 INFO - mozversion application_display_name: Nightly 03:52:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:52:48 INFO - mozversion application_name: Firefox 03:52:48 INFO - mozversion application_remotingname: firefox 03:52:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:52:48 INFO - mozversion application_vendor: Mozilla 03:52:48 INFO - mozversion application_version: 50.0a1 03:52:48 INFO - mozversion platform_buildid: 20160716011451 03:52:48 INFO - mozversion platform_changeset: 2708134823cb57d0cd7b4aa6046151153a61c9de 03:52:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:52:48 INFO - mozversion platform_version: 50.0a1 03:52:48 INFO - using testdate: 1468666368 03:52:48 INFO - actual date: 1468666368 03:52:48 INFO - starting webserver on 'localhost:52109' 03:52:48 INFO - SUITE-START | Running 2 tests 03:52:48 INFO - TEST-START | dromaeo_css 03:52:48 INFO - operating with platform_type : mac_ 03:52:48 INFO - Initialising browser for dromaeo_css test... 03:52:48 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp_fzP0K/profile http://localhost:52109/getInfo.html) 03:52:50 INFO - PROCESS | 1957 | __metrics Screen width/height:1600/1200 03:52:50 INFO - PROCESS | 1957 | colorDepth:24 03:52:50 INFO - PROCESS | 1957 | Browser inner width/height: 1024/689 03:52:50 INFO - PROCESS | 1957 | __metrics 03:52:50 INFO - PROCESS | 1957 | 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" 03:52:50 INFO - PROCESS | 1957 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:52:51 INFO - TEST-INFO | 1957: exit 0 03:52:51 INFO - Browser initialized. 03:52:51 INFO - Running cycle 1/1 for dromaeo_css test... 03:52:51 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp_fzP0K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:03 INFO - PROCESS | 1959 | RSS: Main: 243838976 03:53:03 INFO - PROCESS | 1959 | 03:53:54 INFO - PROCESS | 1959 | 1468666434689 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] 03:56:05 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52109/tests/dromaeo/cssquery-ext.html) 03:56:06 INFO - PROCESS | 1959 | RSS: Main: 333733888 03:56:06 INFO - PROCESS | 1959 | 03:59:09 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-ext.html (next: http://localhost:52109/tests/dromaeo/cssquery-jquery.html) 03:59:10 INFO - PROCESS | 1959 | RSS: Main: 378449920 03:59:10 INFO - PROCESS | 1959 | 04:02:12 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52109/tests/dromaeo/cssquery-mootools.html) 04:02:13 INFO - PROCESS | 1959 | RSS: Main: 371884032 04:02:13 INFO - PROCESS | 1959 | 04:05:15 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52109/tests/dromaeo/cssquery-prototype.html) 04:05:16 INFO - PROCESS | 1959 | RSS: Main: 369201152 04:05:16 INFO - PROCESS | 1959 | 04:08:19 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52109/tests/dromaeo/cssquery-yui.html) 04:08:20 INFO - PROCESS | 1959 | RSS: Main: 335015936 04:08:20 INFO - PROCESS | 1959 | 04:11:23 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:52109/tests/dromaeo/cssquery-yui.html (next: http://localhost:52109/tests/dromaeo/cssquery-dojo.html) 04:11:24 INFO - PROCESS | 1959 | RSS: Main: 387067904 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | __start_tp_report 04:11:24 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 04:11:24 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 04:11:24 INFO - PROCESS | 1959 | |i|pagename|runs| 04:11:24 INFO - PROCESS | 1959 | |0;dojo.html;3278.426275401805;3311.205310725425;3317.988013655837;3331.6501767314426;3357.697807197353;5527.2538207341995;5530.889382212364;5552.278203833501;5616.550675945925;5639.605227634062;4397.538258482861;5644.435556444353;5678.43215678432;5704.77180912764;5732.0255556555385;5089.46560611136;5124.769385377667;5188.351456067991;5200.271961925334;5378.381486129106;5288.8413347599635;5290.761915713802;5299.311089558352;5316;5317.255584218213;5068.087744206023;5138.974305128488;5139;5192.299039629639;5202.323697919284;5164.845054648366;5173.965206958585;5177.8187763428095;5178.17149256121;5182.611304152166;4138.937915931264;5085.160948443502;5091.185410334366;5123.410807757118;5178.559822415099;5591.02157122506;5732.22614946981;5734.168545560912;5735.655860648333;5738.741756620962;6031.155638210654;6217.285012223561;6229.595076320024;6244.500439964837;6248.250209974786;6076.598461645884;6208.286047104551;6248.468880145193;6318.936810631881;6364.968175159094;5790.507806836382;5810.21810695495;5827.533797296291;5871.706414679249;5876.706164691748;6356.904646430307;6359.9364006360265;6367.076773867856;6368;6404.967975160094;5870.236869206976;5968.731407086691;6009.128676341906;6047.879042419127;6128.233970753656;6275.968620156961;6279.654618995908;6280.528960327993;6293.622382657055;6293.842653933688;5464.508194262456;6084.665343406155;6215.9378406215355;6224.159738435344;6239.251289845248;6013.098035294741;6070.392960703983;6146.477549408351;6216.347283535254;6226.968865155645;4996.90006199874;4996.925046124311;4997.90004199914;5005.899882002413;5014.473480284591;4461.643068554507;4466.129104824593;4478.596926276651;4478.977605111953;4501.527339629291;5491.972540137273;5540.556755459554;5547.833564993057;5551.028570000234;5635.5773317001385;6190.102435146879;6276.843078923063;6286.119943207955;6331.240251169889;6355.459785918156;5660.349059858087;5665.093585026459;5676.744546495418;5684.857878552986;5687.175359572839;5268.736563171826;5344.893102138013;5381.354237491448;5401.513863752202;5416.729163541886;5120;5372.650777699437;6327.430531252209;6355.237371515448;6358.968205158944;6271.2613538735195;6331.2085989251345;6342.207224096988;6347.301796802441;6348.746050158126;6225.719842607185;6250.49996000328;6264.342244064308;6275.435210831047;6323.304436511888;6195.101710251898;6202.844928876814;6224.595401298901;6228.626282423069;6237.438630523275;6245.906311405423;6296.244450665949;6298.401651843067;6310.5267104968225;6310.716017779119;5835.503982161564;6018.127371531191;6110.541709371726;6120.143179954721;6144.938550614436;6148.907766383419;6175.289841668266;6183.752649893954;6188.81433556994;6208.50331973432;5291;5998.7300571473515;6013.759449621966;6014.939850601613;6051.878962420639;5824.184614153937;6023.1266466363;6054.485368743706;6085.391460853879;6111.052786818051;5860.443257890493;5873.970630146822;5890.352061273342;5892.9116063258225;5908.527317814651 04:11:24 INFO - PROCESS | 1959 | |1;ext.html;458.72934968368656;488.8191369193399;489.9118158731428;494.7853020833853;536.9812056578021;31500;32094;41962.95092622686;42547.36178957318;42570.36144457828;42100;44785.32822007664;44945.775271123595;45150.87122821931;45171.77414112925;8361;9659.613615455391;9855.408675479459;9863.802723945517;9872.901270987306;9736.269779766546;9868.407895526256;9869.506524673774;9871.062249086326;9879.3578417403;9845.212383009379;9902.207823374112;9902.851457228147;9906.752331191707;9954.65158719445;9824.508774561278;9868.506574671237;9869.555869985868;9875.950620246924;9881.555330010167;9703.223742100616;9782.266330025277;9794.412335259836;9813.656522021694;9818.607255709763;42135.15729685389;42351.78824105891;42412;42419;42443.787781061204;3507.527547727757;3614.367485689994;3695.3533131701843;3766.9246615067545;3981.442598036277;27021.189364319103;32447.837760811046;33047.6695233047;33128;33496.49755253673;3293.176705823544;3369.427197376452;3375.881844135468;3417.8803741869165;3452.223249768782;496232;498060;499382.9999999964;499677;501177;64382;64605;64816;64980.67509662516;65074;66381;67082.32917670762;67364;67370.98943515851;67436;17047.744283835753;17204;17238.965662060316;17252.827471725373;17255.3960611378;12167.904888560071;12222.511099556095;13263;13302.733945321233;13319.001074919419;18069.638607227782;18331.35840245585;18336;18374.35689750879;18448.446546603624;7134.785956421316;7138.857222855514;7203.71185152588;7349.669264882986;7427.85144297111;916.2669864108686;916.6562539047857;995.865558149655;1000.7147962830559;1039.8752149741929;31272.687273127434;31306.06081817549;31307.843460783006;31582.684173157973;32141.839290803862;1043.9634612788516;1079.7462596289865;1110.6334909479851;1130.1862658885589;1239.39269757818;16315.51053468398;16456.83543164553;16560.917195414186;16710.379907804596;16748.581285467717;681030;689358;695311;695355;706964;62798;62920;62935;62941.055884161775;63098.36901630925;1289.2006955687534;1396.9092009019382;1421.8293804743496;1426.179946530749;1451.7241724072392;31587;32031.199220018752;32094.51858222082;32499.67500325061;33269.00192994214;52411.95176096533;54419.91160176695;59624.40375596189;59932;64489.677551613815;639346;646661.7666911635;651285;651852;652006;24481.632775508024;27241.727582723917;27735.44529109366;28196.295092622848;29018.854905725337;50130;50966.490335096176;51313.48686513087;51449;51462;39365;41771.7911410441;42172.789136054125;42194.945126372084;43230;32692.83653581717;41283.7935810319;41941.161176776906;42715.57284427116;43908.90227744332;39745.60254397419;40060.79969600133;40126.79619611416;40368.596314036484;40971.590284096776;33716;34841.825790870884;35293;35412.291754165286;35857.28285434329;24654.260372188863;26188.476230474902;26544.8672756635;26608.86695566587;27238.319042023315 04:11:24 INFO - PROCESS | 1959 | |2;jquery.html;357.08051766700754;380.75821853123267;382.2584186677845;386.42036944583134;407.98208469867683;15726.449574264903;15803.920980395096;21264.14943402262;21398.46503837403;21466.356009319745;20663.896680516576;20907;21550.245741399016;22553.323400297977;22555.774442255613;5632.915506267409;5836.241288632472;5989.161517387569;5997.130416089686;6097.359777223394;3846.442265871447;4089.5705950875176;5720.8283751487525;5940.554458415614;6000.819975400723;6013.939860601382;6058.969705151468;6062.211912451375;6076.149339092509;6083.634981901078;6040.848978775522;6374.139491168679;6564.179477565304;6648.501362397839;6661.367170118831;5049;5930.318013428468;5945.791897283618;5948.464638182541;5949.524038076944;13101.672458188526;13188.208707477486;13684.794728079087;13710.725785484334;13715.382807773574;2198.9890050549807;2249.7637748036386;2302.838801283894;2322.5006623576014;2380.8214383921104;8371.748847534584;10100.393976361369;10160.136388406994;10208.48957552127;10258.7435314117;1721.3975108711975;1831.0387046800404;1833.972490412645;1839.8712090153695;1857.9071046447623;97336;117311.24033139509;125604.37197814044;125711.3714431422;126223;11576.131790115774;11651.708707282385;11660.941695291469;11711.531538738354;11861.940690296493;12138.422005139275;14905.031172973939;14906.4782732606;14917.030393024419;15016.249187540798;8558.61486233121;8627.870581941275;8647.437916535406;8647.610857511427;8740.562971851383;8659.653613855502;8691.913080869237;8726.25826804716;8739.781505462413;8846.380753347205;4592.10453961481;4614.238650622609;4628.699134556243;4658.440987081572;4705.200115980291;1658.2372108829832;1663.717168081429;1887.2356695538328;1917.3864363403577;1950.8927009014355;381.393584201116;382.64987536404436;844.3793811548506;889.1775108025051;1023.4729114506035;11689.240199387013;12077.818832717516;12144.939275303566;12169.756604867853;12253.93873030647;418.6352490879754;425.46816479400746;433.49930829891525;436.559075333915;441.96022357987937;5805.593608447452;6212.47193988516;6239.781607643801;6309.905351419732;6318.210223722035;626388;641747;642353;648538;648870;145942;149210.50789492184;150209;152990;153168;428.60782384118806;987.6691308411619;1043.6347278452633;1055.5724931402794;1064.6752740414092;16399.42602008924;16616.750748738537;20028.499287517927;20220.78675279488;20864.061117249457;53672.46327536675;56487;57488.42511574998;57600.71199644142;58190;85024.57487712523;85321.1467885338;85379;85469.1453085486;86132.88716556026;8237.217464340878;8368.24685778283;8497.532635705096;8576.356773241907;8670.609822558126;12808;13134.802977955529;13287.601371958857;13413.530526431528;13606.183628982293;11557.768844622893;11568.826467602825;11582.015528680156;11668.416579171177;11756.412179390825;11119.388433635904;11262.324260544394;11271.718207044889;11491.367974761133;11550.88449115498;10662.573497060032;11763.470748802602;11793.410329483662;11832.99419549348;12023.939880300542;8302.418830681794;8335.124811894664;8458.619362128842;8581.463879270184;8623.87064194025;7044.260352663102;10648.361098334124;11271.943640281746;11455.36995465224;11594 04:11:24 INFO - PROCESS | 1959 | |3;mootools.html;1405.6134562995169;1552.5187191970485;1613.8224795272522;1836.2287839107573;1993.8404927605793;7980.9201907980905;8887.64449422023;9102.27181825454;9111.954440227797;9237.214836738885;8802.64789408423;9029.819403611924;9031.142041506062;9043.366964312501;9058.592363343649;2106.2312256026516;2185.4208634711736;2189.025883481851;2382.213869423086;2432.464857731305;2195.1658369819525;2217.6361537654307;2366.1954935321987;2453.6074228123503;2456.7666071723156;2384.8569085854906;2392.7726865947793;2393.940151496218;2396.6524853896176;2396.856188628688;2334.101370972171;2380.8928598213124;2381.297517232429;2382.463945612241;2390.8445951013127;2115.7672656007826;2326.139328448468;2402.9639555406507;2412.662227288181;2414.3119211024773;9140.954295228481;9185.954070229605;9192.494412807293;9192.540372981324;9198.98811130775;5347.331583552056;5518.172274158868;5521.4478552144865;5523.309586301712;5524.779008839681;3438.0717206354393;3541.628129046439;3550.840212190458;3650.0144960860684;3736.6637002669618;981.3817295104038;1066.8399740038979;1091.9781604367947;1121.1703339528772;1123.2081382625229;9917.702468925943;12130.150889437742;12216.755664886741;12242.693932651755;12249.897509224213;4604.378408914754;4610.792514336863;4669.649776266793;4760.404949381327;4947.455779864212;10222;10265.230107741949;10280.383177009404;10282.434466104287;10310.123639490726;5497.120460726344;5511.58663100261;5516.613837031369;5518.586106042063;5529.198266251371;4479.238529450001;4986.825461108916;5037.748112594356;5051.570616497565;5051.797928082836;3305.3968825119796;3763.3226019316785;3888.5528164260904;3900.4344370066183;3902.726809123334;1255.3158528601934;1289.5293217975372;1305.9412326445333;1307.895368370528;1310.9082364234603;935.6117211357347;954.1079091049752;955.6273053509137;981.7005813227034;992.7030394114926;6094.268687757409;6120.571559990758;6168.1056246844855;6232.8130156095385;6390.968045159838;877.4033657113097;944.3767113704922;962.8590120706984;981.8183636027358;1011.5296387179997;3728.3475391806323;4109.794510274534;4117.629413352753;4181.707280490397;4183.5398106208295;9408.717738467856;9796.216302695768;10742.570297188026;11052;11225.438728063564;10018.29871908974;10494.632687855888;10502.842457363146;11186.776264474665;11198.21612487118;7620.390368770485;8233.588320583947;8531.274841638531;8567.314614830922;8573.614187361583;5962.612430192024;6077.331493535796;6139.3553676863285;6141.109539116893;6176.7838125665385;10063;10444.321119127233;10460.790784184426;10483;10570.101541368762;10093.343932644355;10539.209559282932;10693.411862347646;10720.624778132727;10750.51622676997;7767.339776119094;7767.3786097113225;7922.207779222032;7962.641681124247;7978.321842643209;7674.347680447001;7915.406344524068;8544.273736732448;8649.091845356399;8656.22094011542;6329.588576742497;7648.043994500687;7799.9220007799195;7894.842103158019;7931.682732690628;7163.283671632795;7598.772036838904;7709;7742.496737712256;7799.5320280783335;6799.490038247251;7027.508074434741;7199.06412166415;7259.092613423322;7286.96356518214;4998.950010499849;5290.788368465218;5327.6270661054905;5362.946370536245;5498.340199176125;5323.30796996388;5353.411124776349;5362.919556206582;5500.477454641803;5605.215269862304 04:11:24 INFO - PROCESS | 1959 | |4;prototype.html;301.4814519027273;310.30336893673694;314.4402962726347;317.44605663117864;320.29535022949517;4383.561643835615;4683.227267500867;4892.523341142859;5417.783288668448;5429.4570542945685;4564.90870182597;4728.598069164116;5333.786648534054;5367.516923476886;5401.351837779472;3498.9153362457655;3766.8681596144;3925.2738243424915;4135.400366946791;4149.481314835645;4023.7384570002855;4114.855980040699;4114.991827002374;4135.214309281227;4159.251334759757;4075.4498142750795;4075.531313898895;4098.672106231509;4104.42538044674;4136.358864376027;3570.9822700530353;3661.286049220403;3805.6955443564448;3811.161544460242;4085.1412607263624;3232.385846689145;3540.5220295260324;3551.485034669984;3553.6979356754705;3556.364072526641;4672.252439609681;4899.755012249373;5175.741212939338;5235.968304405625;5287.259783630295;4536.7504787236685;4635.837745678885;4687.367205427257;4693.976530117362;4756.595689366408;5026.497350264981;5525.254090697745;5539.584531160179;5547.889042219133;5664.971675141639;3256.4138455078078;3934.744241624314;3942.7831469269177;3954.070793363557;3976.89801954476;4692.554207350297;5585.273914391184;5807.709614519299;5951.2858456985;5952.940470595282;3460.826958652108;3704.370257056307;3816.748118609978;3927.0953587510035;3961.742486738353;4013;4019;4020.718549701551;4020.8994775130855;4023.376376661622;4002.69979751514;4278.764667943293;4324.394584758136;4406.184855801687;4676.929846052312;5704.201411802351;5704.629199102128;5711.200431939532;5711.857203569944;5740.425957404226;1150.5915400033034;1207.5833837326109;1239.6962744127566;1259.6270065628325;1375.9243241621607;751.597895126105;802.8474589827914;821.8315245374683;831.2518733140222;832.4921797703396;4424.7123936944;4437.955620443754;5052.964142350809;5102.4642412546145;5113.769880355393;5562.443755624486;5635.802746903839;5773.364929857712;5774.48029677331;5854.824355269348;4449.087936972913;4892.535209155125;5075.1625981712605;5187.584993200535;5258.6581872178185;2539.2382285314334;2872.850859656154;3015.638123425159;3024.9546256806166;3037.9544306835414;4972.751362431936;5623.690697011703;5920.171176035358;5930.525557955353;5962;5016.573591244785;5606.6916319602;5811.3026436827;5959.880802384015;5973.193618861398;5161.793528258828;5880.558958078162;5892.528597712173;5904.5163353621565;5926.111083337534;4372.978135109304;5081.145381935641;5508.614396992332;5722.599418040697;5746.310442746897;4770.165221086227;4985.401751789808;5576.9163462547285;5652.576056795839;5752.597318187687;5548.583856210719;5953.374895635896;6004.459598636144;6035.223797053404;6059.878802424015;3515.7167633813983;3594.5686517618055;3709;3871.7997420799643;3875.6124387561017;5039.496050395078;5461.317335333083;5667.668097996885;5741.311042674905;5836.474717275466;5568.582356323211;5579.414161512983;5594.3286805581965;5625.409332020243;5683.460071293221;5190.377154741455;5609.4390560943575;5628.971855140698;5635.830925072254;5710.771569137188;5492.890142197213;5502.669839809624;5508.421615730291;5527.391986881359;5540;5358.410574836843;5469.75386107634;5561.277033985556;5574.693391863487;5671.971640141773;5404.244694132334;5453.26380938584;5482.424345443671;5497.972510137583;5561.416051314553 04:11:24 INFO - PROCESS | 1959 | |5;yui.html;751.6955632968646;764.7246991083213;795.0618270440881;863.0808189278418;874.9868751968718;15177.39290428383;16456.83543164568;16456.83543164568;16482.505524834243;16499.257533410997;14917.104973701586;15113.92443037786;15136.243187840619;15152.469663561778;15166;700.5901547594664;726.4624178108188;885.0441523154977;906.333844624204;906.365544119116;879.8020445399798;892.054422312351;896.6771962093641;897.129784109416;897.9012308646044;949.9620015199383;950.8621249918792;952.0479520479521;952.7141857442739;953.2469349214147;747.8915557244196;767.6622286194057;769.769069279214;770.8650986077414;944.9935818353466;633.2527617411453;688.3288793426419;706.5584009993754;711.0507472524187;711.1856923822246;11964;14836;14839.70320593582;14866.405343786235;14886.255687215596;13629.863701362958;13822.239776812272;13872.237026963512;13884.722305553933;14107.153570785786;10202.387856728546;12529.74940501194;12610.558630447982;12789.040821938299;12873;4534.5692159245145;4548.113117942003;4557.270836666151;4644.95355046452;4715.740634265114;117670.41164794206;120383.3980830099;120387;120616.39691801573;121017.39491302575;11496.655100347003;11653.41732913368;11708.473118709679;11839.88160118405;12123.33321667299;22885.199018034287;22919;22951;23127.959241834156;23305.53388932212;7668.463207575528;7914.722984695508;7986.600669966479;8011.639476223584;8372.162783721578;4488.798004089824;4555.111753208159;4561;4582.564656357641;4645.86062418128;3560.1099725068734;3704.1850792825535;3705.5923848376656;3710.942381421282;3716.869909553153;620.6431302001367;638.6647010319526;662.7315937045504;671.8824205763972;685.5235611250233;533.5704757670113;611.2909025530453;642.540583482812;648.661456970645;650.6974256970532;9651.074249369198;9878.802423951625;9995.80008399828;10006.999300069934;10078.215282978575;582.657715981751;631.6462780842743;639.8624295776431;658.3153520338794;679.1849780263703;4347.673924455678;4596.067753491778;4604.470485894165;4606.278564471377;4688.367070445514;95631.52184239174;125397.11904321442;154922;160745;165883;114325;115890;117273;119727.40136299263;121007;71199.64400178069;79650.6017469909;107525;112108.87891121148;113500;9650.517474126405;9825.459599721798;9876.901230987598;9879.30844840883;9882.802343952937;65399;66131.07230523818;66205.66897165483;66795.66602166957;68046;82278;93416;98251;99921.00078999408;100383.49808250912;13010.414531345923;13565.525206617722;13995;14294.411785254559;14416.783748243575;13009.674758131123;15319.38722451134;15548.300326485562;15703.764443533128;15790.289436975134;14209;14520.419183232552;14576.708465830412;14610.707785844435;14784.852151478348;14036;14067.085639433404;14438.566842994727;14799.92600036993;14856.03435776671;13803.378847951666;14143.36354864013;14210.147391156564;14626.85373146255;14722.411103556165;11626.88373116258;11640.89154526211;11758.294502329887;11831.585894493996;12093.0093368445;11185.21703480781;11211;11410.885891140982;11837.940810295893;11873.406329683654 04:11:24 INFO - PROCESS | 1959 | __end_tp_report 04:11:24 INFO - PROCESS | 1959 | __start_cc_report 04:11:24 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,1357 04:11:24 INFO - PROCESS | 1959 | __end_cc_report 04:11:24 INFO - PROCESS | 1959 | __startTimestamp1468667484020__endTimestamp 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | ------- Summary: start ------- 04:11:24 INFO - PROCESS | 1959 | Number of tests: 6 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#0] dojo.html Cycles:165 Average:5705.09 Median:5873.97 stddev:663.51 (11.3%) stddev-sans-first:637.65 04:11:24 INFO - PROCESS | 1959 | Values: 3278.4 3311.2 3318.0 3331.7 3357.7 5527.3 5530.9 5552.3 5616.6 5639.6 4397.5 5644.4 5678.4 5704.8 5732.0 5089.5 5124.8 5188.4 5200.3 5378.4 5288.8 5290.8 5299.3 5316.0 5317.3 5068.1 5139.0 5139.0 5192.3 5202.3 5164.8 5174.0 5177.8 5178.2 5182.6 4138.9 5085.2 5091.2 5123.4 5178.6 5591.0 5732.2 5734.2 5735.7 5738.7 6031.2 6217.3 6229.6 6244.5 6248.3 6076.6 6208.3 6248.5 6318.9 6365.0 5790.5 5810.2 5827.5 5871.7 5876.7 6356.9 6359.9 6367.1 6368.0 6405.0 5870.2 5968.7 6009.1 6047.9 6128.2 6276.0 6279.7 6280.5 6293.6 6293.8 5464.5 6084.7 6215.9 6224.2 6239.3 6013.1 6070.4 6146.5 6216.3 6227.0 4996.9 4996.9 4997.9 5005.9 5014.5 4461.6 4466.1 4478.6 4479.0 4501.5 5492.0 5540.6 5547.8 5551.0 5635.6 6190.1 6276.8 6286.1 6331.2 6355.5 5660.3 5665.1 5676.7 5684.9 5687.2 5268.7 5344.9 5381.4 5401.5 5416.7 5120.0 5372.7 6327.4 6355.2 6359.0 6271.3 6331.2 6342.2 6347.3 6348.7 6225.7 6250.5 6264.3 6275.4 6323.3 6195.1 6202.8 6224.6 6228.6 6237.4 6245.9 6296.2 6298.4 6310.5 6310.7 5835.5 6018.1 6110.5 6120.1 6144.9 6148.9 6175.3 6183.8 6188.8 6208.5 5291.0 5998.7 6013.8 6014.9 6051.9 5824.2 6023.1 6054.5 6085.4 6111.1 5860.4 5874.0 5890.4 5892.9 5908.5 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#1] ext.html Cycles:180 Average:75240.83 Median:42415.50 stddev:165687.31 (390.6%) stddev-sans-first:166056.97 04:11:24 INFO - PROCESS | 1959 | Values: 458.7 488.8 489.9 494.8 537.0 31500.0 32094.0 41963.0 42547.4 42570.4 42100.0 44785.3 44945.8 45150.9 45171.8 8361.0 9659.6 9855.4 9863.8 9872.9 9736.3 9868.4 9869.5 9871.1 9879.4 9845.2 9902.2 9902.9 9906.8 9954.7 9824.5 9868.5 9869.6 9876.0 9881.6 9703.2 9782.3 9794.4 9813.7 9818.6 42135.2 42351.8 42412.0 42419.0 42443.8 3507.5 3614.4 3695.4 3766.9 3981.4 27021.2 32447.8 33047.7 33128.0 33496.5 3293.2 3369.4 3375.9 3417.9 3452.2 496232.0 498060.0 499383.0 499677.0 501177.0 64382.0 64605.0 64816.0 64980.7 65074.0 66381.0 67082.3 67364.0 67371.0 67436.0 17047.7 17204.0 17239.0 17252.8 17255.4 12167.9 12222.5 13263.0 13302.7 13319.0 18069.6 18331.4 18336.0 18374.4 18448.4 7134.8 7138.9 7203.7 7349.7 7427.9 916.3 916.7 995.9 1000.7 1039.9 31272.7 31306.1 31307.8 31582.7 32141.8 1044.0 1079.7 1110.6 1130.2 1239.4 16315.5 16456.8 16560.9 16710.4 16748.6 681030.0 689358.0 695311.0 695355.0 706964.0 62798.0 62920.0 62935.0 62941.1 63098.4 1289.2 1396.9 1421.8 1426.2 1451.7 31587.0 32031.2 32094.5 32499.7 33269.0 52412.0 54419.9 59624.4 59932.0 64489.7 639346.0 646661.8 651285.0 651852.0 652006.0 24481.6 27241.7 27735.4 28196.3 29018.9 50130.0 50966.5 51313.5 51449.0 51462.0 39365.0 41771.8 42172.8 42194.9 43230.0 32692.8 41283.8 41941.2 42715.6 43908.9 39745.6 40060.8 40126.8 40368.6 40971.6 33716.0 34841.8 35293.0 35412.3 35857.3 24654.3 26188.5 26544.9 26608.9 27238.3 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#2] jquery.html Cycles:180 Average:36515.03 Median:1368.95 stddev:107557.42 (7856.9%) stddev-sans-first:107824.88 04:11:24 INFO - PROCESS | 1959 | Values: 357.1 380.8 382.3 386.4 408.0 15726.4 15803.9 21264.1 21398.5 21466.4 20663.9 20907.0 21550.2 22553.3 22555.8 5632.9 5836.2 5989.2 5997.1 6097.4 3846.4 4089.6 5720.8 5940.6 6000.8 6013.9 6059.0 6062.2 6076.1 6083.6 6040.8 6374.1 6564.2 6648.5 6661.4 5049.0 5930.3 5945.8 5948.5 5949.5 13101.7 13188.2 13684.8 13710.7 13715.4 2199.0 2249.8 2302.8 2322.5 2380.8 8371.7 10100.4 10160.1 10208.5 10258.7 1721.4 1831.0 1834.0 1839.9 1857.9 97336.0 117311.2 125604.4 125711.4 126223.0 11576.1 11651.7 11660.9 11711.5 11861.9 12138.4 14905.0 14906.5 14917.0 15016.2 8558.6 8627.9 8647.4 8647.6 8740.6 8659.7 8691.9 8726.3 8739.8 8846.4 4592.1 4614.2 4628.7 4658.4 4705.2 1658.2 1663.7 1887.2 1917.4 1950.9 381.4 382.6 844.4 889.2 1023.5 11689.2 12077.8 12144.9 12169.8 12253.9 418.6 425.5 433.5 436.6 442.0 5805.6 6212.5 6239.8 6309.9 6318.2 626388.0 641747.0 642353.0 648538.0 648870.0 145942.0 149210.5 150209.0 152990.0 153168.0 428.6 987.7 1043.6 1055.6 1064.7 16399.4 16616.8 20028.5 20220.8 20864.1 53672.5 56487.0 57488.4 57600.7 58190.0 85024.6 85321.1 85379.0 85469.1 86132.9 8237.2 8368.2 8497.5 8576.4 8670.6 12808.0 13134.8 13287.6 13413.5 13606.2 11557.8 11568.8 11582.0 11668.4 11756.4 11119.4 11262.3 11271.7 11491.4 11550.9 10662.6 11763.5 11793.4 11833.0 12023.9 8302.4 8335.1 8458.6 8581.5 8623.9 7044.3 10648.4 11271.9 11455.4 11594.0 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#3] mootools.html Cycles:180 Average:5849.95 Median:5044.66 stddev:3277.09 (65.0%) stddev-sans-first:3269.26 04:11:24 INFO - PROCESS | 1959 | Values: 1405.6 1552.5 1613.8 1836.2 1993.8 7980.9 8887.6 9102.3 9112.0 9237.2 8802.6 9029.8 9031.1 9043.4 9058.6 2106.2 2185.4 2189.0 2382.2 2432.5 2195.2 2217.6 2366.2 2453.6 2456.8 2384.9 2392.8 2393.9 2396.7 2396.9 2334.1 2380.9 2381.3 2382.5 2390.8 2115.8 2326.1 2403.0 2412.7 2414.3 9141.0 9186.0 9192.5 9192.5 9199.0 5347.3 5518.2 5521.4 5523.3 5524.8 3438.1 3541.6 3550.8 3650.0 3736.7 981.4 1066.8 1092.0 1121.2 1123.2 9917.7 12130.2 12216.8 12242.7 12249.9 4604.4 4610.8 4669.6 4760.4 4947.5 10222.0 10265.2 10280.4 10282.4 10310.1 5497.1 5511.6 5516.6 5518.6 5529.2 4479.2 4986.8 5037.7 5051.6 5051.8 3305.4 3763.3 3888.6 3900.4 3902.7 1255.3 1289.5 1305.9 1307.9 1310.9 935.6 954.1 955.6 981.7 992.7 6094.3 6120.6 6168.1 6232.8 6391.0 877.4 944.4 962.9 981.8 1011.5 3728.3 4109.8 4117.6 4181.7 4183.5 9408.7 9796.2 10742.6 11052.0 11225.4 10018.3 10494.6 10502.8 11186.8 11198.2 7620.4 8233.6 8531.3 8567.3 8573.6 5962.6 6077.3 6139.4 6141.1 6176.8 10063.0 10444.3 10460.8 10483.0 10570.1 10093.3 10539.2 10693.4 10720.6 10750.5 7767.3 7767.4 7922.2 7962.6 7978.3 7674.3 7915.4 8544.3 8649.1 8656.2 6329.6 7648.0 7799.9 7894.8 7931.7 7163.3 7598.8 7709.0 7742.5 7799.5 6799.5 7027.5 7199.1 7259.1 7287.0 4999.0 5290.8 5327.6 5362.9 5498.3 5323.3 5353.4 5362.9 5500.5 5605.2 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#4] prototype.html Cycles:180 Average:4534.66 Median:5078.15 stddev:1407.79 (27.7%) stddev-sans-first:1375.42 04:11:24 INFO - PROCESS | 1959 | Values: 301.5 310.3 314.4 317.4 320.3 4383.6 4683.2 4892.5 5417.8 5429.5 4564.9 4728.6 5333.8 5367.5 5401.4 3498.9 3766.9 3925.3 4135.4 4149.5 4023.7 4114.9 4115.0 4135.2 4159.3 4075.4 4075.5 4098.7 4104.4 4136.4 3571.0 3661.3 3805.7 3811.2 4085.1 3232.4 3540.5 3551.5 3553.7 3556.4 4672.3 4899.8 5175.7 5236.0 5287.3 4536.8 4635.8 4687.4 4694.0 4756.6 5026.5 5525.3 5539.6 5547.9 5665.0 3256.4 3934.7 3942.8 3954.1 3976.9 4692.6 5585.3 5807.7 5951.3 5952.9 3460.8 3704.4 3816.7 3927.1 3961.7 4013.0 4019.0 4020.7 4020.9 4023.4 4002.7 4278.8 4324.4 4406.2 4676.9 5704.2 5704.6 5711.2 5711.9 5740.4 1150.6 1207.6 1239.7 1259.6 1375.9 751.6 802.8 821.8 831.3 832.5 4424.7 4438.0 5053.0 5102.5 5113.8 5562.4 5635.8 5773.4 5774.5 5854.8 4449.1 4892.5 5075.2 5187.6 5258.7 2539.2 2872.9 3015.6 3025.0 3038.0 4972.8 5623.7 5920.2 5930.5 5962.0 5016.6 5606.7 5811.3 5959.9 5973.2 5161.8 5880.6 5892.5 5904.5 5926.1 4373.0 5081.1 5508.6 5722.6 5746.3 4770.2 4985.4 5576.9 5652.6 5752.6 5548.6 5953.4 6004.5 6035.2 6059.9 3515.7 3594.6 3709.0 3871.8 3875.6 5039.5 5461.3 5667.7 5741.3 5836.5 5568.6 5579.4 5594.3 5625.4 5683.5 5190.4 5609.4 5629.0 5635.8 5710.8 5492.9 5502.7 5508.4 5527.4 5540.0 5358.4 5469.8 5561.3 5574.7 5672.0 5404.2 5453.3 5482.4 5498.0 5561.4 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - PROCESS | 1959 | [#5] yui.html Cycles:180 Average:24731.43 Median:3632.15 stddev:38547.25 (1061.3%) stddev-sans-first:38613.33 04:11:24 INFO - PROCESS | 1959 | Values: 751.7 764.7 795.1 863.1 875.0 15177.4 16456.8 16456.8 16482.5 16499.3 14917.1 15113.9 15136.2 15152.5 15166.0 700.6 726.5 885.0 906.3 906.4 879.8 892.1 896.7 897.1 897.9 950.0 950.9 952.0 952.7 953.2 747.9 767.7 769.8 770.9 945.0 633.3 688.3 706.6 711.1 711.2 11964.0 14836.0 14839.7 14866.4 14886.3 13629.9 13822.2 13872.2 13884.7 14107.2 10202.4 12529.7 12610.6 12789.0 12873.0 4534.6 4548.1 4557.3 4645.0 4715.7 117670.4 120383.4 120387.0 120616.4 121017.4 11496.7 11653.4 11708.5 11839.9 12123.3 22885.2 22919.0 22951.0 23128.0 23305.5 7668.5 7914.7 7986.6 8011.6 8372.2 4488.8 4555.1 4561.0 4582.6 4645.9 3560.1 3704.2 3705.6 3710.9 3716.9 620.6 638.7 662.7 671.9 685.5 533.6 611.3 642.5 648.7 650.7 9651.1 9878.8 9995.8 10007.0 10078.2 582.7 631.6 639.9 658.3 679.2 4347.7 4596.1 4604.5 4606.3 4688.4 95631.5 125397.1 154922.0 160745.0 165883.0 114325.0 115890.0 117273.0 119727.4 121007.0 71199.6 79650.6 107525.0 112108.9 113500.0 9650.5 9825.5 9876.9 9879.3 9882.8 65399.0 66131.1 66205.7 66795.7 68046.0 82278.0 93416.0 98251.0 99921.0 100383.5 13010.4 13565.5 13995.0 14294.4 14416.8 13009.7 15319.4 15548.3 15703.8 15790.3 14209.0 14520.4 14576.7 14610.7 14784.9 14036.0 14067.1 14438.6 14799.9 14856.0 13803.4 14143.4 14210.1 14626.9 14722.4 11626.9 11640.9 11758.3 11831.6 12093.0 11185.2 11211.0 11410.9 11837.9 11873.4 04:11:24 INFO - PROCESS | 1959 | -------- Summary: end -------- 04:11:24 INFO - PROCESS | 1959 | 04:11:24 INFO - TEST-INFO | 1959: exit 0 04:11:24 INFO - TEST-OK | dromaeo_css | took 1115880ms 04:11:24 INFO - TEST-START | kraken 04:11:24 INFO - operating with platform_type : mac_ 04:11:24 INFO - Initialising browser for kraken test... 04:11:24 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpf7JwpY/profile http://localhost:52109/getInfo.html) 04:11:26 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 04:11:26 INFO - PROCESS | 1962 | colorDepth:24 04:11:26 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 04:11:26 INFO - PROCESS | 1962 | __metrics 04:11:26 INFO - PROCESS | 1962 | 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" 04:11:26 INFO - PROCESS | 1962 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:11:27 INFO - TEST-INFO | 1962: exit 0 04:11:27 INFO - Browser initialized. 04:11:27 INFO - Running cycle 1/1 for kraken test... 04:11:27 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpf7JwpY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:11:39 INFO - PROCESS | 1963 | RSS: Main: 245088256 04:11:39 INFO - PROCESS | 1963 | 04:11:42 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52109/tests/kraken/driver.html?testName=audio-beat-detection) 04:11:43 INFO - PROCESS | 1963 | RSS: Main: 294776832 04:11:43 INFO - PROCESS | 1963 | 04:11:46 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52109/tests/kraken/driver.html?testName=audio-dft) 04:11:46 INFO - PROCESS | 1963 | RSS: Main: 304525312 04:11:46 INFO - PROCESS | 1963 | 04:11:50 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52109/tests/kraken/driver.html?testName=audio-fft) 04:11:50 INFO - PROCESS | 1963 | RSS: Main: 388567040 04:11:50 INFO - PROCESS | 1963 | 04:11:52 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52109/tests/kraken/driver.html?testName=audio-oscillator) 04:11:53 INFO - PROCESS | 1963 | RSS: Main: 290398208 04:11:53 INFO - PROCESS | 1963 | 04:11:55 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52109/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:11:56 INFO - PROCESS | 1963 | RSS: Main: 294535168 04:11:56 INFO - PROCESS | 1963 | 04:11:59 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52109/tests/kraken/driver.html?testName=imaging-darkroom) 04:11:59 INFO - PROCESS | 1963 | RSS: Main: 331948032 04:11:59 INFO - PROCESS | 1963 | 04:12:02 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52109/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:03 INFO - PROCESS | 1963 | RSS: Main: 335015936 04:12:03 INFO - PROCESS | 1963 | 04:12:06 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52109/tests/kraken/driver.html?testName=json-parse-financial) 04:12:06 INFO - PROCESS | 1963 | RSS: Main: 331190272 04:12:06 INFO - PROCESS | 1963 | 04:12:08 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52109/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:08 INFO - PROCESS | 1963 | RSS: Main: 312340480 04:12:08 INFO - PROCESS | 1963 | 04:12:10 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:11 INFO - PROCESS | 1963 | RSS: Main: 319303680 04:12:11 INFO - PROCESS | 1963 | 04:12:13 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:14 INFO - PROCESS | 1963 | RSS: Main: 316444672 04:12:14 INFO - PROCESS | 1963 | 04:12:17 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:12:17 INFO - PROCESS | 1963 | RSS: Main: 325705728 04:12:17 INFO - PROCESS | 1963 | 04:12:20 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:12:21 INFO - PROCESS | 1963 | RSS: Main: 326905856 04:12:21 INFO - PROCESS | 1963 | 04:12:22 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52109/tests/kraken/driver.html?testName=ai-astar) 04:12:23 INFO - PROCESS | 1963 | RSS: Main: 370229248 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | __start_tp_report 04:12:23 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 04:12:23 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 04:12:23 INFO - PROCESS | 1963 | |i|pagename|runs| 04:12:23 INFO - PROCESS | 1963 | |0;ai-astar;102;111;111;110;116;163;167;111;110;112 04:12:23 INFO - PROCESS | 1963 | |1;audio-beat-detection;162;144;136;156;106;137;152;137;160;109 04:12:23 INFO - PROCESS | 1963 | |2;audio-dft;186;185;184;183;184;184;185;178;188;232 04:12:23 INFO - PROCESS | 1963 | |3;audio-fft;79;74;58;88;59;56;60;65;86;72 04:12:23 INFO - PROCESS | 1963 | |4;audio-oscillator;87;90;91;71;70;69;98;83;97;135 04:12:23 INFO - PROCESS | 1963 | |5;imaging-gaussian-blur;88;85;89;86;87;84;88;85;87;84 04:12:23 INFO - PROCESS | 1963 | |6;imaging-darkroom;94;94;94;95;93;93;94;95;93;93 04:12:23 INFO - PROCESS | 1963 | |7;imaging-desaturate;88;89;89;89;109;89;89;91;88;85 04:12:23 INFO - PROCESS | 1963 | |8;json-parse-financial;55;53;53;53;53;53;52;54;53;52 04:12:23 INFO - PROCESS | 1963 | |9;json-stringify-tinderbox;66;77;75;82;72;73;73;72;71;72 04:12:23 INFO - PROCESS | 1963 | |10;stanford-crypto-aes;87;82;85;85;83;83;81;84;84;88 04:12:23 INFO - PROCESS | 1963 | |11;stanford-crypto-ccm;137;137;138;135;134;138;153;148;185;136 04:12:23 INFO - PROCESS | 1963 | |12;stanford-crypto-pbkdf2;164;165;165;165;165;162;164;162;167;163 04:12:23 INFO - PROCESS | 1963 | |13;stanford-crypto-sha256-iterative;66;66;67;66;68;69;84;85;86;75 04:12:23 INFO - PROCESS | 1963 | __end_tp_report 04:12:23 INFO - PROCESS | 1963 | __start_cc_report 04:12:23 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,2034 04:12:23 INFO - PROCESS | 1963 | __end_cc_report 04:12:23 INFO - PROCESS | 1963 | __startTimestamp1468667543226__endTimestamp 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | ------- Summary: start ------- 04:12:23 INFO - PROCESS | 1963 | Number of tests: 14 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#0] ai-astar Cycles:10 Average:121.30 Median:111.50 stddev:23.30 (20.9%) stddev-sans-first:23.65 04:12:23 INFO - PROCESS | 1963 | Values: 102.0 111.0 111.0 110.0 116.0 163.0 167.0 111.0 110.0 112.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:148.00 stddev:19.58 (13.2%) stddev-sans-first:19.07 04:12:23 INFO - PROCESS | 1963 | Values: 162.0 144.0 136.0 156.0 106.0 137.0 152.0 137.0 160.0 109.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#2] audio-dft Cycles:10 Average:188.90 Median:185.00 stddev:15.36 (8.3%) stddev-sans-first:16.25 04:12:23 INFO - PROCESS | 1963 | Values: 186.0 185.0 184.0 183.0 184.0 184.0 185.0 178.0 188.0 232.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#3] audio-fft Cycles:10 Average:69.70 Median:73.00 stddev:11.86 (16.2%) stddev-sans-first:12.09 04:12:23 INFO - PROCESS | 1963 | Values: 79.0 74.0 58.0 88.0 59.0 56.0 60.0 65.0 86.0 72.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:88.50 stddev:19.41 (21.9%) stddev-sans-first:20.57 04:12:23 INFO - PROCESS | 1963 | Values: 87.0 90.0 91.0 71.0 70.0 69.0 98.0 83.0 97.0 135.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.77 (2.0%) stddev-sans-first:1.76 04:12:23 INFO - PROCESS | 1963 | Values: 88.0 85.0 89.0 86.0 87.0 84.0 88.0 85.0 87.0 84.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 04:12:23 INFO - PROCESS | 1963 | Values: 94.0 94.0 94.0 95.0 93.0 93.0 94.0 95.0 93.0 93.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#7] imaging-desaturate Cycles:10 Average:90.60 Median:89.00 stddev:6.64 (7.5%) stddev-sans-first:6.97 04:12:23 INFO - PROCESS | 1963 | Values: 88.0 89.0 89.0 89.0 109.0 89.0 89.0 91.0 88.0 85.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.60 04:12:23 INFO - PROCESS | 1963 | Values: 55.0 53.0 53.0 53.0 53.0 53.0 52.0 54.0 53.0 52.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.00 stddev:4.16 (5.7%) stddev-sans-first:3.48 04:12:23 INFO - PROCESS | 1963 | Values: 66.0 77.0 75.0 82.0 72.0 73.0 73.0 72.0 71.0 72.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:2.15 (2.5%) stddev-sans-first:2.03 04:12:23 INFO - PROCESS | 1963 | Values: 87.0 82.0 85.0 85.0 83.0 83.0 81.0 84.0 84.0 88.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:138.00 stddev:15.61 (11.3%) stddev-sans-first:16.34 04:12:23 INFO - PROCESS | 1963 | Values: 137.0 137.0 138.0 135.0 134.0 138.0 153.0 148.0 185.0 136.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.00 stddev:1.55 (0.9%) stddev-sans-first:1.64 04:12:23 INFO - PROCESS | 1963 | Values: 164.0 165.0 165.0 165.0 165.0 162.0 164.0 162.0 167.0 163.0 04:12:23 INFO - PROCESS | 1963 | 04:12:23 INFO - PROCESS | 1963 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:72.00 stddev:8.57 (11.9%) stddev-sans-first:8.69 04:12:23 INFO - PROCESS | 1963 | Values: 66.0 66.0 67.0 66.0 68.0 69.0 84.0 85.0 86.0 75.0 04:12:23 INFO - PROCESS | 1963 | -------- Summary: end -------- 04:12:23 INFO - PROCESS | 1963 | 04:12:24 INFO - TEST-INFO | 1963: exit 0 04:12:24 INFO - TEST-OK | kraken | took 59460ms 04:12:24 INFO - SUITE-END | took 1175s 04:12:24 INFO - Completed test suite (00:19:35) 04:12:24 INFO - javascript benchmark 04:12:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.426275401805, 3311.205310725425, 3317.988013655837, 3331.6501767314426, 3357.697807197353, 5527.2538207341995, 5530.889382212364, 5552.278203833501, 5616.550675945925, 5639.605227634062, 4397.538258482861, 5644.435556444353, 5678.43215678432, 5704.77180912764, 5732.0255556555385, 5089.46560611136, 5124.769385377667, 5188.351456067991, 5200.271961925334, 5378.381486129106, 5288.8413347599635, 5290.761915713802, 5299.311089558352, 5316.0, 5317.255584218213, 5068.087744206023, 5138.974305128488, 5139.0, 5192.299039629639, 5202.323697919284, 5164.845054648366, 5173.965206958585, 5177.8187763428095, 5178.17149256121, 5182.611304152166, 4138.937915931264, 5085.160948443502, 5091.185410334366, 5123.410807757118, 5178.559822415099, 5591.02157122506, 5732.22614946981, 5734.168545560912, 5735.655860648333, 5738.741756620962, 6031.155638210654, 6217.285012223561, 6229.595076320024, 6244.500439964837, 6248.250209974786, 6076.598461645884, 6208.286047104551, 6248.468880145193, 6318.936810631881, 6364.968175159094, 5790.507806836382, 5810.21810695495, 5827.533797296291, 5871.706414679249, 5876.706164691748, 6356.904646430307, 6359.9364006360265, 6367.076773867856, 6368.0, 6404.967975160094, 5870.236869206976, 5968.731407086691, 6009.128676341906, 6047.879042419127, 6128.233970753656, 6275.968620156961, 6279.654618995908, 6280.528960327993, 6293.622382657055, 6293.842653933688, 5464.508194262456, 6084.665343406155, 6215.9378406215355, 6224.159738435344, 6239.251289845248, 6013.098035294741, 6070.392960703983, 6146.477549408351, 6216.347283535254, 6226.968865155645, 4996.90006199874, 4996.925046124311, 4997.90004199914, 5005.899882002413, 5014.473480284591, 4461.643068554507, 4466.129104824593, 4478.596926276651, 4478.977605111953, 4501.527339629291, 5491.972540137273, 5540.556755459554, 5547.833564993057, 5551.028570000234, 5635.5773317001385, 6190.102435146879, 6276.843078923063, 6286.119943207955, 6331.240251169889, 6355.459785918156, 5660.349059858087, 5665.093585026459, 5676.744546495418, 5684.857878552986, 5687.175359572839, 5268.736563171826, 5344.893102138013, 5381.354237491448, 5401.513863752202, 5416.729163541886, 5120.0, 5372.650777699437, 6327.430531252209, 6355.237371515448, 6358.968205158944, 6271.2613538735195, 6331.2085989251345, 6342.207224096988, 6347.301796802441, 6348.746050158126, 6225.719842607185, 6250.49996000328, 6264.342244064308, 6275.435210831047, 6323.304436511888, 6195.101710251898, 6202.844928876814, 6224.595401298901, 6228.626282423069, 6237.438630523275, 6245.906311405423, 6296.244450665949, 6298.401651843067, 6310.5267104968225, 6310.716017779119, 5835.503982161564, 6018.127371531191, 6110.541709371726, 6120.143179954721, 6144.938550614436, 6148.907766383419, 6175.289841668266, 6183.752649893954, 6188.81433556994, 6208.50331973432, 5291.0, 5998.7300571473515, 6013.759449621966, 6014.939850601613, 6051.878962420639, 5824.184614153937, 6023.1266466363, 6054.485368743706, 6085.391460853879, 6111.052786818051, 5860.443257890493, 5873.970630146822, 5890.352061273342, 5892.9116063258225, 5908.527317814651], "value": 5662.721496277515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.72934968368656, 488.8191369193399, 489.9118158731428, 494.7853020833853, 536.9812056578021, 31500.0, 32094.0, 41962.95092622686, 42547.36178957318, 42570.36144457828, 42100.0, 44785.32822007664, 44945.775271123595, 45150.87122821931, 45171.77414112925, 8361.0, 9659.613615455391, 9855.408675479459, 9863.802723945517, 9872.901270987306, 9736.269779766546, 9868.407895526256, 9869.506524673774, 9871.062249086326, 9879.3578417403, 9845.212383009379, 9902.207823374112, 9902.851457228147, 9906.752331191707, 9954.65158719445, 9824.508774561278, 9868.506574671237, 9869.555869985868, 9875.950620246924, 9881.555330010167, 9703.223742100616, 9782.266330025277, 9794.412335259836, 9813.656522021694, 9818.607255709763, 42135.15729685389, 42351.78824105891, 42412.0, 42419.0, 42443.787781061204, 3507.527547727757, 3614.367485689994, 3695.3533131701843, 3766.9246615067545, 3981.442598036277, 27021.189364319103, 32447.837760811046, 33047.6695233047, 33128.0, 33496.49755253673, 3293.176705823544, 3369.427197376452, 3375.881844135468, 3417.8803741869165, 3452.223249768782, 496232.0, 498060.0, 499382.9999999964, 499677.0, 501177.0, 64382.0, 64605.0, 64816.0, 64980.67509662516, 65074.0, 66381.0, 67082.32917670762, 67364.0, 67370.98943515851, 67436.0, 17047.744283835753, 17204.0, 17238.965662060316, 17252.827471725373, 17255.3960611378, 12167.904888560071, 12222.511099556095, 13263.0, 13302.733945321233, 13319.001074919419, 18069.638607227782, 18331.35840245585, 18336.0, 18374.35689750879, 18448.446546603624, 7134.785956421316, 7138.857222855514, 7203.71185152588, 7349.669264882986, 7427.85144297111, 916.2669864108686, 916.6562539047857, 995.865558149655, 1000.7147962830559, 1039.8752149741929, 31272.687273127434, 31306.06081817549, 31307.843460783006, 31582.684173157973, 32141.839290803862, 1043.9634612788516, 1079.7462596289865, 1110.6334909479851, 1130.1862658885589, 1239.39269757818, 16315.51053468398, 16456.83543164553, 16560.917195414186, 16710.379907804596, 16748.581285467717, 681030.0, 689358.0, 695311.0, 695355.0, 706964.0, 62798.0, 62920.0, 62935.0, 62941.055884161775, 63098.36901630925, 1289.2006955687534, 1396.9092009019382, 1421.8293804743496, 1426.179946530749, 1451.7241724072392, 31587.0, 32031.199220018752, 32094.51858222082, 32499.67500325061, 33269.00192994214, 52411.95176096533, 54419.91160176695, 59624.40375596189, 59932.0, 64489.677551613815, 639346.0, 646661.7666911635, 651285.0, 651852.0, 652006.0, 24481.632775508024, 27241.727582723917, 27735.44529109366, 28196.295092622848, 29018.854905725337, 50130.0, 50966.490335096176, 51313.48686513087, 51449.0, 51462.0, 39365.0, 41771.7911410441, 42172.789136054125, 42194.945126372084, 43230.0, 32692.83653581717, 41283.7935810319, 41941.161176776906, 42715.57284427116, 43908.90227744332, 39745.60254397419, 40060.79969600133, 40126.79619611416, 40368.596314036484, 40971.590284096776, 33716.0, 34841.825790870884, 35293.0, 35412.291754165286, 35857.28285434329, 24654.260372188863, 26188.476230474902, 26544.8672756635, 26608.86695566587, 27238.319042023315], "value": 20835.99810267649, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.08051766700754, 380.75821853123267, 382.2584186677845, 386.42036944583134, 407.98208469867683, 15726.449574264903, 15803.920980395096, 21264.14943402262, 21398.46503837403, 21466.356009319745, 20663.896680516576, 20907.0, 21550.245741399016, 22553.323400297977, 22555.774442255613, 5632.915506267409, 5836.241288632472, 5989.161517387569, 5997.130416089686, 6097.359777223394, 3846.442265871447, 4089.5705950875176, 5720.8283751487525, 5940.554458415614, 6000.819975400723, 6013.939860601382, 6058.969705151468, 6062.211912451375, 6076.149339092509, 6083.634981901078, 6040.848978775522, 6374.139491168679, 6564.179477565304, 6648.501362397839, 6661.367170118831, 5049.0, 5930.318013428468, 5945.791897283618, 5948.464638182541, 5949.524038076944, 13101.672458188526, 13188.208707477486, 13684.794728079087, 13710.725785484334, 13715.382807773574, 2198.9890050549807, 2249.7637748036386, 2302.838801283894, 2322.5006623576014, 2380.8214383921104, 8371.748847534584, 10100.393976361369, 10160.136388406994, 10208.48957552127, 10258.7435314117, 1721.3975108711975, 1831.0387046800404, 1833.972490412645, 1839.8712090153695, 1857.9071046447623, 97336.0, 117311.24033139509, 125604.37197814044, 125711.3714431422, 126223.0, 11576.131790115774, 11651.708707282385, 11660.941695291469, 11711.531538738354, 11861.940690296493, 12138.422005139275, 14905.031172973939, 14906.4782732606, 14917.030393024419, 15016.249187540798, 8558.61486233121, 8627.870581941275, 8647.437916535406, 8647.610857511427, 8740.562971851383, 8659.653613855502, 8691.913080869237, 8726.25826804716, 8739.781505462413, 8846.380753347205, 4592.10453961481, 4614.238650622609, 4628.699134556243, 4658.440987081572, 4705.200115980291, 1658.2372108829832, 1663.717168081429, 1887.2356695538328, 1917.3864363403577, 1950.8927009014355, 381.393584201116, 382.64987536404436, 844.3793811548506, 889.1775108025051, 1023.4729114506035, 11689.240199387013, 12077.818832717516, 12144.939275303566, 12169.756604867853, 12253.93873030647, 418.6352490879754, 425.46816479400746, 433.49930829891525, 436.559075333915, 441.96022357987937, 5805.593608447452, 6212.47193988516, 6239.781607643801, 6309.905351419732, 6318.210223722035, 626388.0, 641747.0, 642353.0, 648538.0, 648870.0, 145942.0, 149210.50789492184, 150209.0, 152990.0, 153168.0, 428.60782384118806, 987.6691308411619, 1043.6347278452633, 1055.5724931402794, 1064.6752740414092, 16399.42602008924, 16616.750748738537, 20028.499287517927, 20220.78675279488, 20864.061117249457, 53672.46327536675, 56487.0, 57488.42511574998, 57600.71199644142, 58190.0, 85024.57487712523, 85321.1467885338, 85379.0, 85469.1453085486, 86132.88716556026, 8237.217464340878, 8368.24685778283, 8497.532635705096, 8576.356773241907, 8670.609822558126, 12808.0, 13134.802977955529, 13287.601371958857, 13413.530526431528, 13606.183628982293, 11557.768844622893, 11568.826467602825, 11582.015528680156, 11668.416579171177, 11756.412179390825, 11119.388433635904, 11262.324260544394, 11271.718207044889, 11491.367974761133, 11550.88449115498, 10662.573497060032, 11763.470748802602, 11793.410329483662, 11832.99419549348, 12023.939880300542, 8302.418830681794, 8335.124811894664, 8458.619362128842, 8581.463879270184, 8623.87064194025, 7044.260352663102, 10648.361098334124, 11271.943640281746, 11455.36995465224, 11594.0], "value": 8982.288652560723, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.6134562995169, 1552.5187191970485, 1613.8224795272522, 1836.2287839107573, 1993.8404927605793, 7980.9201907980905, 8887.64449422023, 9102.27181825454, 9111.954440227797, 9237.214836738885, 8802.64789408423, 9029.819403611924, 9031.142041506062, 9043.366964312501, 9058.592363343649, 2106.2312256026516, 2185.4208634711736, 2189.025883481851, 2382.213869423086, 2432.464857731305, 2195.1658369819525, 2217.6361537654307, 2366.1954935321987, 2453.6074228123503, 2456.7666071723156, 2384.8569085854906, 2392.7726865947793, 2393.940151496218, 2396.6524853896176, 2396.856188628688, 2334.101370972171, 2380.8928598213124, 2381.297517232429, 2382.463945612241, 2390.8445951013127, 2115.7672656007826, 2326.139328448468, 2402.9639555406507, 2412.662227288181, 2414.3119211024773, 9140.954295228481, 9185.954070229605, 9192.494412807293, 9192.540372981324, 9198.98811130775, 5347.331583552056, 5518.172274158868, 5521.4478552144865, 5523.309586301712, 5524.779008839681, 3438.0717206354393, 3541.628129046439, 3550.840212190458, 3650.0144960860684, 3736.6637002669618, 981.3817295104038, 1066.8399740038979, 1091.9781604367947, 1121.1703339528772, 1123.2081382625229, 9917.702468925943, 12130.150889437742, 12216.755664886741, 12242.693932651755, 12249.897509224213, 4604.378408914754, 4610.792514336863, 4669.649776266793, 4760.404949381327, 4947.455779864212, 10222.0, 10265.230107741949, 10280.383177009404, 10282.434466104287, 10310.123639490726, 5497.120460726344, 5511.58663100261, 5516.613837031369, 5518.586106042063, 5529.198266251371, 4479.238529450001, 4986.825461108916, 5037.748112594356, 5051.570616497565, 5051.797928082836, 3305.3968825119796, 3763.3226019316785, 3888.5528164260904, 3900.4344370066183, 3902.726809123334, 1255.3158528601934, 1289.5293217975372, 1305.9412326445333, 1307.895368370528, 1310.9082364234603, 935.6117211357347, 954.1079091049752, 955.6273053509137, 981.7005813227034, 992.7030394114926, 6094.268687757409, 6120.571559990758, 6168.1056246844855, 6232.8130156095385, 6390.968045159838, 877.4033657113097, 944.3767113704922, 962.8590120706984, 981.8183636027358, 1011.5296387179997, 3728.3475391806323, 4109.794510274534, 4117.629413352753, 4181.707280490397, 4183.5398106208295, 9408.717738467856, 9796.216302695768, 10742.570297188026, 11052.0, 11225.438728063564, 10018.29871908974, 10494.632687855888, 10502.842457363146, 11186.776264474665, 11198.21612487118, 7620.390368770485, 8233.588320583947, 8531.274841638531, 8567.314614830922, 8573.614187361583, 5962.612430192024, 6077.331493535796, 6139.3553676863285, 6141.109539116893, 6176.7838125665385, 10063.0, 10444.321119127233, 10460.790784184426, 10483.0, 10570.101541368762, 10093.343932644355, 10539.209559282932, 10693.411862347646, 10720.624778132727, 10750.51622676997, 7767.339776119094, 7767.3786097113225, 7922.207779222032, 7962.641681124247, 7978.321842643209, 7674.347680447001, 7915.406344524068, 8544.273736732448, 8649.091845356399, 8656.22094011542, 6329.588576742497, 7648.043994500687, 7799.9220007799195, 7894.842103158019, 7931.682732690628, 7163.283671632795, 7598.772036838904, 7709.0, 7742.496737712256, 7799.5320280783335, 6799.490038247251, 7027.508074434741, 7199.06412166415, 7259.092613423322, 7286.96356518214, 4998.950010499849, 5290.788368465218, 5327.6270661054905, 5362.946370536245, 5498.340199176125, 5323.30796996388, 5353.411124776349, 5362.919556206582, 5500.477454641803, 5605.215269862304], "value": 4695.136295154505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.4814519027273, 310.30336893673694, 314.4402962726347, 317.44605663117864, 320.29535022949517, 4383.561643835615, 4683.227267500867, 4892.523341142859, 5417.783288668448, 5429.4570542945685, 4564.90870182597, 4728.598069164116, 5333.786648534054, 5367.516923476886, 5401.351837779472, 3498.9153362457655, 3766.8681596144, 3925.2738243424915, 4135.400366946791, 4149.481314835645, 4023.7384570002855, 4114.855980040699, 4114.991827002374, 4135.214309281227, 4159.251334759757, 4075.4498142750795, 4075.531313898895, 4098.672106231509, 4104.42538044674, 4136.358864376027, 3570.9822700530353, 3661.286049220403, 3805.6955443564448, 3811.161544460242, 4085.1412607263624, 3232.385846689145, 3540.5220295260324, 3551.485034669984, 3553.6979356754705, 3556.364072526641, 4672.252439609681, 4899.755012249373, 5175.741212939338, 5235.968304405625, 5287.259783630295, 4536.7504787236685, 4635.837745678885, 4687.367205427257, 4693.976530117362, 4756.595689366408, 5026.497350264981, 5525.254090697745, 5539.584531160179, 5547.889042219133, 5664.971675141639, 3256.4138455078078, 3934.744241624314, 3942.7831469269177, 3954.070793363557, 3976.89801954476, 4692.554207350297, 5585.273914391184, 5807.709614519299, 5951.2858456985, 5952.940470595282, 3460.826958652108, 3704.370257056307, 3816.748118609978, 3927.0953587510035, 3961.742486738353, 4013.0, 4019.0, 4020.718549701551, 4020.8994775130855, 4023.376376661622, 4002.69979751514, 4278.764667943293, 4324.394584758136, 4406.184855801687, 4676.929846052312, 5704.201411802351, 5704.629199102128, 5711.200431939532, 5711.857203569944, 5740.425957404226, 1150.5915400033034, 1207.5833837326109, 1239.6962744127566, 1259.6270065628325, 1375.9243241621607, 751.597895126105, 802.8474589827914, 821.8315245374683, 831.2518733140222, 832.4921797703396, 4424.7123936944, 4437.955620443754, 5052.964142350809, 5102.4642412546145, 5113.769880355393, 5562.443755624486, 5635.802746903839, 5773.364929857712, 5774.48029677331, 5854.824355269348, 4449.087936972913, 4892.535209155125, 5075.1625981712605, 5187.584993200535, 5258.6581872178185, 2539.2382285314334, 2872.850859656154, 3015.638123425159, 3024.9546256806166, 3037.9544306835414, 4972.751362431936, 5623.690697011703, 5920.171176035358, 5930.525557955353, 5962.0, 5016.573591244785, 5606.6916319602, 5811.3026436827, 5959.880802384015, 5973.193618861398, 5161.793528258828, 5880.558958078162, 5892.528597712173, 5904.5163353621565, 5926.111083337534, 4372.978135109304, 5081.145381935641, 5508.614396992332, 5722.599418040697, 5746.310442746897, 4770.165221086227, 4985.401751789808, 5576.9163462547285, 5652.576056795839, 5752.597318187687, 5548.583856210719, 5953.374895635896, 6004.459598636144, 6035.223797053404, 6059.878802424015, 3515.7167633813983, 3594.5686517618055, 3709.0, 3871.7997420799643, 3875.6124387561017, 5039.496050395078, 5461.317335333083, 5667.668097996885, 5741.311042674905, 5836.474717275466, 5568.582356323211, 5579.414161512983, 5594.3286805581965, 5625.409332020243, 5683.460071293221, 5190.377154741455, 5609.4390560943575, 5628.971855140698, 5635.830925072254, 5710.771569137188, 5492.890142197213, 5502.669839809624, 5508.421615730291, 5527.391986881359, 5540.0, 5358.410574836843, 5469.75386107634, 5561.277033985556, 5574.693391863487, 5671.971640141773, 5404.244694132334, 5453.26380938584, 5482.424345443671, 5497.972510137583, 5561.416051314553], "value": 4077.713962316294, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.6955632968646, 764.7246991083213, 795.0618270440881, 863.0808189278418, 874.9868751968718, 15177.39290428383, 16456.83543164568, 16456.83543164568, 16482.505524834243, 16499.257533410997, 14917.104973701586, 15113.92443037786, 15136.243187840619, 15152.469663561778, 15166.0, 700.5901547594664, 726.4624178108188, 885.0441523154977, 906.333844624204, 906.365544119116, 879.8020445399798, 892.054422312351, 896.6771962093641, 897.129784109416, 897.9012308646044, 949.9620015199383, 950.8621249918792, 952.0479520479521, 952.7141857442739, 953.2469349214147, 747.8915557244196, 767.6622286194057, 769.769069279214, 770.8650986077414, 944.9935818353466, 633.2527617411453, 688.3288793426419, 706.5584009993754, 711.0507472524187, 711.1856923822246, 11964.0, 14836.0, 14839.70320593582, 14866.405343786235, 14886.255687215596, 13629.863701362958, 13822.239776812272, 13872.237026963512, 13884.722305553933, 14107.153570785786, 10202.387856728546, 12529.74940501194, 12610.558630447982, 12789.040821938299, 12873.0, 4534.5692159245145, 4548.113117942003, 4557.270836666151, 4644.95355046452, 4715.740634265114, 117670.41164794206, 120383.3980830099, 120387.0, 120616.39691801573, 121017.39491302575, 11496.655100347003, 11653.41732913368, 11708.473118709679, 11839.88160118405, 12123.33321667299, 22885.199018034287, 22919.0, 22951.0, 23127.959241834156, 23305.53388932212, 7668.463207575528, 7914.722984695508, 7986.600669966479, 8011.639476223584, 8372.162783721578, 4488.798004089824, 4555.111753208159, 4561.0, 4582.564656357641, 4645.86062418128, 3560.1099725068734, 3704.1850792825535, 3705.5923848376656, 3710.942381421282, 3716.869909553153, 620.6431302001367, 638.6647010319526, 662.7315937045504, 671.8824205763972, 685.5235611250233, 533.5704757670113, 611.2909025530453, 642.540583482812, 648.661456970645, 650.6974256970532, 9651.074249369198, 9878.802423951625, 9995.80008399828, 10006.999300069934, 10078.215282978575, 582.657715981751, 631.6462780842743, 639.8624295776431, 658.3153520338794, 679.1849780263703, 4347.673924455678, 4596.067753491778, 4604.470485894165, 4606.278564471377, 4688.367070445514, 95631.52184239174, 125397.11904321442, 154922.0, 160745.0, 165883.0, 114325.0, 115890.0, 117273.0, 119727.40136299263, 121007.0, 71199.64400178069, 79650.6017469909, 107525.0, 112108.87891121148, 113500.0, 9650.517474126405, 9825.459599721798, 9876.901230987598, 9879.30844840883, 9882.802343952937, 65399.0, 66131.07230523818, 66205.66897165483, 66795.66602166957, 68046.0, 82278.0, 93416.0, 98251.0, 99921.00078999408, 100383.49808250912, 13010.414531345923, 13565.525206617722, 13995.0, 14294.411785254559, 14416.783748243575, 13009.674758131123, 15319.38722451134, 15548.300326485562, 15703.764443533128, 15790.289436975134, 14209.0, 14520.419183232552, 14576.708465830412, 14610.707785844435, 14784.852151478348, 14036.0, 14067.085639433404, 14438.566842994727, 14799.92600036993, 14856.03435776671, 13803.378847951666, 14143.36354864013, 14210.147391156564, 14626.85373146255, 14722.411103556165, 11626.88373116258, 11640.89154526211, 11758.294502329887, 11831.585894493996, 12093.0093368445, 11185.21703480781, 11211.0, 11410.885891140982, 11837.940810295893, 11873.406329683654], "value": 8030.195516578477, "unit": "score"}], "name": "dromaeo_css", "value": 7390.559711208286, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 110.0, 116.0, 163.0, 167.0, 111.0, 110.0, 112.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 144.0, 136.0, 156.0, 106.0, 137.0, 152.0, 137.0, 160.0, 109.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 183.0, 184.0, 184.0, 185.0, 178.0, 188.0, 232.0], "value": 188.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 88.0, 59.0, 56.0, 60.0, 65.0, 86.0, 72.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 91.0, 71.0, 70.0, 69.0, 98.0, 83.0, 97.0, 135.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 86.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 93.0, 94.0, 95.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 89.0, 109.0, 89.0, 89.0, 91.0, 88.0, 85.0], "value": 90.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 53.0, 53.0, 52.0, 54.0, 53.0, 52.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 75.0, 82.0, 72.0, 73.0, 73.0, 72.0, 71.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 85.0, 85.0, 83.0, 83.0, 81.0, 84.0, 84.0, 88.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 138.0, 135.0, 134.0, 138.0, 153.0, 148.0, 185.0, 136.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 165.0, 165.0, 165.0, 162.0, 164.0, 162.0, 167.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 68.0, 69.0, 84.0, 85.0, 86.0, 75.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1471.7, "alertThreshold": 2.0}]} 04:12:24 INFO - Return code: 0 04:12:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:12:24 INFO - # TBPL SUCCESS # 04:12:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:12:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:12:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:12:24 INFO - 2016-07-16 04:12:24.511 screenresolution[1967:16479] starting screenresolution argv=screenresolution get 04:12:24 INFO - 2016-07-16 04:12:24.513 screenresolution[1967:16479] Display 0: 1600x1200x32@60 04:12:24 INFO - 2016-07-16 04:12:24.519 screenresolution[1968:16482] starting screenresolution argv=screenresolution list 04:12:24 INFO - Available Modes on Display 0 04:12:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:12:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:12:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:12:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:12:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:12:24 INFO - Intel Iris: 04:12:24 INFO - Chipset Model: Intel Iris 04:12:24 INFO - Type: GPU 04:12:24 INFO - Bus: Built-In 04:12:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:12:24 INFO - Vendor: Intel (0x8086) 04:12:24 INFO - Device ID: 0x0a2e 04:12:24 INFO - Revision ID: 0x0009 04:12:24 INFO - Displays: 04:12:24 INFO - Display: 04:12:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:12:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:12:24 INFO - Display Serial Number: 1600x1200 60 04:12:24 INFO - Main Display: Yes 04:12:24 INFO - Mirror: Off 04:12:24 INFO - Online: Yes 04:12:24 INFO - Rotation: Supported 04:12:24 INFO - Return code: 0 04:12:24 INFO - Running post-action listener: _resource_record_post_action 04:12:24 INFO - ##### 04:12:24 INFO - ##### Finished run-tests step (success) 04:12:24 INFO - ##### 04:12:24 INFO - Running post-run listener: _resource_record_post_run 04:12:24 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 618496; Write bytes: 703742976; Read time: 8; Write time: 2088 04:12:24 INFO - TinderboxPrint: CPU usage
53.4% 04:12:24 INFO - TinderboxPrint: I/O read bytes / time
618,496 / 8 04:12:24 INFO - TinderboxPrint: I/O write bytes / time
703,742,976 / 2,088 04:12:24 INFO - TinderboxPrint: CPU idle
2,256.5 (46.6%) 04:12:24 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 04:12:24 INFO - TinderboxPrint: CPU user
1,620.4 (33.4%) 04:12:24 INFO - TinderboxPrint: Swap in / out
407,461,888 / 0 04:12:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390416896; Write bytes: 383313408; Read time: 29198; Write time: 722 04:12:24 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 618496; Write bytes: 294215168; Read time: 8; Write time: 1314 04:12:25 INFO - Running post-run listener: _upload_blobber_files 04:12:25 INFO - Blob upload gear active. 04:12:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:12:25 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 04:12:25 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'] 04:12:25 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 04:12:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:12:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:12:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:12:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:26 INFO - (blobuploader) - INFO - Done attempting. 04:12:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:12:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:12:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:27 INFO - (blobuploader) - INFO - Done attempting. 04:12:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:12:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:12:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:29 INFO - (blobuploader) - INFO - Done attempting. 04:12:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:12:29 INFO - Return code: 0 04:12:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:12:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:12:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9cfcfd1a33ebf1fddce16acfb5f6553ac123c2e14832f4fe42fc608183c2470e460edc06ec991eb8fbcdf84c8382b2edbfccd7ad5c6bccc4c0772060f203330", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3051ccc2ed4e2effddea7d8be02dba29bd5d6f7c8ccfcc5398cabf8a9a8404d6c0c64e625229808c82d0d39dfa92c5f37953190566fac660c23d97405c28aef7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4307af09c30b22ba9472c6895c5d2207bdcdfe00ad0e243cc438ae69d4d8b0c7c57a46a88e2c5633fa6683314e2a5a6c84dfb3c83425f74494a9481fad4c30fe"} 04:12:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:12:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:12:29 INFO - Contents: 04:12:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9cfcfd1a33ebf1fddce16acfb5f6553ac123c2e14832f4fe42fc608183c2470e460edc06ec991eb8fbcdf84c8382b2edbfccd7ad5c6bccc4c0772060f203330", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3051ccc2ed4e2effddea7d8be02dba29bd5d6f7c8ccfcc5398cabf8a9a8404d6c0c64e625229808c82d0d39dfa92c5f37953190566fac660c23d97405c28aef7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4307af09c30b22ba9472c6895c5d2207bdcdfe00ad0e243cc438ae69d4d8b0c7c57a46a88e2c5633fa6683314e2a5a6c84dfb3c83425f74494a9481fad4c30fe"} 04:12:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:12:29 INFO - Copying logs to upload dir... 04:12:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:12:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.244849 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-16 04:12:29.629384) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 04:12:29.633261) ========= 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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/d9cfcfd1a33ebf1fddce16acfb5f6553ac123c2e14832f4fe42fc608183c2470e460edc06ec991eb8fbcdf84c8382b2edbfccd7ad5c6bccc4c0772060f203330", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3051ccc2ed4e2effddea7d8be02dba29bd5d6f7c8ccfcc5398cabf8a9a8404d6c0c64e625229808c82d0d39dfa92c5f37953190566fac660c23d97405c28aef7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4307af09c30b22ba9472c6895c5d2207bdcdfe00ad0e243cc438ae69d4d8b0c7c57a46a88e2c5633fa6683314e2a5a6c84dfb3c83425f74494a9481fad4c30fe"} build_url:https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/eseI8-jXTBGqT68KEH-p1A/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/d9cfcfd1a33ebf1fddce16acfb5f6553ac123c2e14832f4fe42fc608183c2470e460edc06ec991eb8fbcdf84c8382b2edbfccd7ad5c6bccc4c0772060f203330", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3051ccc2ed4e2effddea7d8be02dba29bd5d6f7c8ccfcc5398cabf8a9a8404d6c0c64e625229808c82d0d39dfa92c5f37953190566fac660c23d97405c28aef7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4307af09c30b22ba9472c6895c5d2207bdcdfe00ad0e243cc438ae69d4d8b0c7c57a46a88e2c5633fa6683314e2a5a6c84dfb3c83425f74494a9481fad4c30fe"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 04:12:29.669726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:12:29.670122) ========= 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.ocJ1U2Zj5F/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.Pgo1Eccqy8/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005161 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:12:29.721825) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 04:12:29.722192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 04:12:29.722592) ========= ========= Total master_lag: 0.19 =========