builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0192 starttime: 1467745241.28 results: success (0) buildid: 20160705085522 builduid: afcca04ea8ec4ff3adcce55944b2f5de revision: e3d304afd3f2bb3301374a37486f48174a367c27 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.284369) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.284863) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.285149) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.310732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.311153) ========= 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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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-05 12:00:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 37.5M=0s 2016-07-05 12:00:41 (37.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.460480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.460823) ========= 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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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.028025 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.510429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:41.510834) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e3d304afd3f2bb3301374a37486f48174a367c27 --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 e3d304afd3f2bb3301374a37486f48174a367c27 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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-05 12:00:41,585 truncating revision to first 12 chars 2016-07-05 12:00:41,585 Setting DEBUG logging. 2016-07-05 12:00:41,585 attempt 1/10 2016-07-05 12:00:41,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e3d304afd3f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 12:00:42,234 unpacking tar archive at: fx-team-e3d304afd3f2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.894001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:42.426683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:42.427017) ========= script_repo_revision: e3d304afd3f2bb3301374a37486f48174a367c27 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:42.427451) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:42.427741) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 12:00:42.452550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-05 12:00:42.453000) ========= /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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:00:42 INFO - MultiFileLogger online at 20160705 12:00:42 in /builds/slave/test 12:00:42 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 12:00:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:00:42 INFO - {'append_to_log': False, 12:00:42 INFO - 'base_work_dir': '/builds/slave/test', 12:00:42 INFO - 'blob_upload_branch': 'Fx-Team', 12:00:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:00:42 INFO - 'branch': 'Fx-Team', 12:00:42 INFO - 'buildbot_json_path': 'buildprops.json', 12:00:42 INFO - 'config_files': ('talos/mac_config.py',), 12:00:42 INFO - 'default_actions': ('clobber', 12:00:42 INFO - 'read-buildbot-config', 12:00:42 INFO - 'download-and-extract', 12:00:42 INFO - 'populate-webroot', 12:00:42 INFO - 'create-virtualenv', 12:00:42 INFO - 'install', 12:00:42 INFO - 'run-tests'), 12:00:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:00:42 INFO - 'download_minidump_stackwalk': True, 12:00:42 INFO - 'download_symbols': 'ondemand', 12:00:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:00:42 INFO - 'tooltool.py': '/tools/tooltool.py', 12:00:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:00:42 INFO - '/tools/misc-python/virtualenv.py')}, 12:00:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:00:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:00:42 INFO - 'installer_path': 'installer.exe', 12:00:42 INFO - 'log_level': 'info', 12:00:42 INFO - 'log_name': 'talos', 12:00:42 INFO - 'log_to_console': True, 12:00:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:00:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:00:42 INFO - 'opt_config_files': (), 12:00:42 INFO - 'pip_index': False, 12:00:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:00:42 INFO - 'cmd': ('bash', 12:00:42 INFO - '-c', 12:00:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:00:42 INFO - 'enabled': True, 12:00:42 INFO - 'halt_on_failure': False, 12:00:42 INFO - 'name': 'check_screen_resolution'},), 12:00:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:00:42 INFO - 'cmd': ('bash', 12:00:42 INFO - '-c', 12:00:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:00:42 INFO - 'enabled': True, 12:00:42 INFO - 'halt_on_failure': False, 12:00:42 INFO - 'name': 'check_screen_resolution'},), 12:00:42 INFO - 'run_cmd_checks_enabled': True, 12:00:42 INFO - 'sps_profile': False, 12:00:42 INFO - 'sps_profile_interval': 0, 12:00:42 INFO - 'suite': 'dromaeojs', 12:00:42 INFO - 'system_bits': '32', 12:00:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:00:42 INFO - 'title': 't-yosemite-r7-0192', 12:00:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:00:42 INFO - 'use_talos_json': True, 12:00:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:00:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:00:42 INFO - 'work_dir': 'build'} 12:00:42 INFO - ##### 12:00:42 INFO - ##### Running clobber step. 12:00:42 INFO - ##### 12:00:42 INFO - Running pre-action listener: _resource_record_pre_action 12:00:42 INFO - Running main action method: clobber 12:00:42 INFO - rmtree: /builds/slave/test/build 12:00:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:00:44 INFO - Running post-action listener: _resource_record_post_action 12:00:44 INFO - ##### 12:00:44 INFO - ##### Finished clobber step (success) 12:00:44 INFO - ##### 12:00:44 INFO - ##### 12:00:44 INFO - ##### Running read-buildbot-config step. 12:00:44 INFO - ##### 12:00:44 INFO - Running pre-action listener: _resource_record_pre_action 12:00:44 INFO - Running main action method: read_buildbot_config 12:00:44 INFO - Using buildbot properties: 12:00:44 INFO - { 12:00:44 INFO - "project": "", 12:00:44 INFO - "product": "firefox", 12:00:44 INFO - "script_repo_revision": "production", 12:00:44 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:00:44 INFO - "repository": "", 12:00:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:00:44 INFO - "buildid": "20160705085522", 12:00:44 INFO - "pgo_build": "False", 12:00:44 INFO - "basedir": "/builds/slave/test", 12:00:44 INFO - "buildnumber": 78, 12:00:44 INFO - "slavename": "t-yosemite-r7-0192", 12:00:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:00:44 INFO - "platform": "yosemite_r7", 12:00:44 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:00:44 INFO - "branch": "fx-team", 12:00:44 INFO - "repo_path": "integration/fx-team", 12:00:44 INFO - "revision": "e3d304afd3f2bb3301374a37486f48174a367c27", 12:00:44 INFO - "stage_platform": "macosx64", 12:00:44 INFO - "builduid": "afcca04ea8ec4ff3adcce55944b2f5de", 12:00:44 INFO - "slavebuilddir": "test" 12:00:44 INFO - } 12:00:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:00:44 INFO - Running post-action listener: _resource_record_post_action 12:00:44 INFO - ##### 12:00:44 INFO - ##### Finished read-buildbot-config step (success) 12:00:44 INFO - ##### 12:00:44 INFO - ##### 12:00:44 INFO - ##### Running download-and-extract step. 12:00:44 INFO - ##### 12:00:44 INFO - Running pre-action listener: _resource_record_pre_action 12:00:44 INFO - Running main action method: download_and_extract 12:00:44 INFO - mkdir: /builds/slave/test/build/tests 12:00:44 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')]} 12:00:44 INFO - https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:47 INFO - Downloaded 1199 bytes. 12:00:47 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:00:47 INFO - Using the following test package requirements: 12:00:47 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:00:47 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:00:47 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:00:47 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'jsshell-mac.zip'], 12:00:47 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:00:47 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:00:47 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:00:47 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:00:47 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:00:47 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:00:47 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:00:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:00: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')]} 12:00:47 INFO - https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:49 INFO - Downloaded 18801599 bytes. 12:00:49 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'] 12:00:49 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 12:00:52 INFO - Return code: 0 12:00:52 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 12:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:52 INFO - https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:00:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:00:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:00:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:53 INFO - Downloaded 18801599 bytes. 12:00:53 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'] 12:00:53 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 12:00:57 INFO - Return code: 0 12:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:57 INFO - https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:00:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:00:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:00:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:00:58 INFO - Downloaded 14403333 bytes. 12:00:58 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'] 12:00:58 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 12:00:58 INFO - Return code: 0 12:00: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')]} 12:00:58 INFO - https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:00:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:00:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:00:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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 12:01:02 INFO - Downloaded 112530184 bytes. 12:01:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:01:02 INFO - mkdir: /builds/slave/test/properties 12:01:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:02 INFO - Contents: 12:01:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:01:03 INFO - Running post-action listener: _resource_record_post_action 12:01:03 INFO - Running post-action listener: set_extra_try_arguments 12:01:03 INFO - ##### 12:01:03 INFO - ##### Finished download-and-extract step (success) 12:01:03 INFO - ##### 12:01:03 INFO - ##### 12:01:03 INFO - ##### Running populate-webroot step. 12:01:03 INFO - ##### 12:01:03 INFO - Running pre-action listener: _resource_record_pre_action 12:01:03 INFO - Running main action method: populate_webroot 12:01:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:01:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:01:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:01:03 INFO - '--tppagecycles', 12:01:03 INFO - '7'], 12:01:03 INFO - 'tests': ['tsvgm']}}, 12:01:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:01:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:01:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['damp', 'tps']}, 12:01:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'tests': ['damp', 'tps']}, 12:01:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['dromaeo_dom']}, 12:01:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['basic_compositor_video']}, 12:01:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:01:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['a11yr', 12:01:03 INFO - 'ts_paint', 12:01:03 INFO - 'tpaint', 12:01:03 INFO - 'sessionrestore', 12:01:03 INFO - 'sessionrestore_no_auto_restore', 12:01:03 INFO - 'tabpaint']}, 12:01:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['tsvgx', 12:01:03 INFO - 'tsvgr_opacity', 12:01:03 INFO - 'tart', 12:01:03 INFO - 'tscrollx', 12:01:03 INFO - 'cart']}, 12:01:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:03 INFO - 'tsvgr_opacity', 12:01:03 INFO - 'tart', 12:01:03 INFO - 'tscrollx', 12:01:03 INFO - 'cart']}, 12:01:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'talos_options': ['--disable-e10s'], 12:01:03 INFO - 'tests': ['tp5o']}, 12:01:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'tests': ['tp5o']}, 12:01:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'talos_options': ['--disable-e10s', 12:01:03 INFO - '--xperf_path', 12:01:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:03 INFO - 'tests': ['tp5n']}, 12:01:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:01:03 INFO - 'talos_options': ['--xperf_path', 12:01:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:03 INFO - 'tests': ['tp5n']}}, 12:01:03 INFO - 'talos.zip': {'path': '', 12:01:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:01:03 INFO - Running post-action listener: _resource_record_post_action 12:01:03 INFO - ##### 12:01:03 INFO - ##### Finished populate-webroot step (success) 12:01:03 INFO - ##### 12:01:03 INFO - ##### 12:01:03 INFO - ##### Running create-virtualenv step. 12:01:03 INFO - ##### 12:01:03 INFO - Running pre-action listener: _resource_record_pre_action 12:01:03 INFO - Running main action method: create_virtualenv 12:01:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:03 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 12:01:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:01:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:03 INFO - Using real prefix '/tools/python27' 12:01:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:06 INFO - Installing pip.................done. 12:01:06 INFO - Return code: 0 12:01:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:06 INFO - 'HOME': '/Users/cltbld', 12:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:06 INFO - 'LOGNAME': 'cltbld', 12:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:06 INFO - 'MOZ_NO_REMOTE': '1', 12:01:06 INFO - 'NO_EM_RESTART': '1', 12:01:06 INFO - 'PAGER': '/bin/cat', 12:01:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:06 INFO - 'PWD': '/builds/slave/test', 12:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:06 INFO - 'SHELL': '/bin/bash', 12:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:06 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:06 INFO - 'USER': 'cltbld', 12:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:06 INFO - 'XPC_FLAGS': '0x0', 12:01:06 INFO - 'XPC_SERVICE_NAME': '0', 12:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:07 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 12:01:07 INFO - Cleaning up... 12:01:07 INFO - Return code: 0 12:01:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:07 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:07 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 12:01:07 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 12:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:07 INFO - 'HOME': '/Users/cltbld', 12:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:07 INFO - 'LOGNAME': 'cltbld', 12:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:07 INFO - 'MOZ_NO_REMOTE': '1', 12:01:07 INFO - 'NO_EM_RESTART': '1', 12:01:07 INFO - 'PAGER': '/bin/cat', 12:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:07 INFO - 'PWD': '/builds/slave/test', 12:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:07 INFO - 'SHELL': '/bin/bash', 12:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:07 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:07 INFO - 'USER': 'cltbld', 12:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:07 INFO - 'XPC_FLAGS': '0x0', 12:01:07 INFO - 'XPC_SERVICE_NAME': '0', 12:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:07 INFO - Downloading/unpacking psutil>=3.1.1 12:01:07 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 12:01:07 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 12:01:07 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 12:01:07 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 12:01:07 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 12:01:07 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 12:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:10 INFO - Installing collected packages: psutil 12:01:10 INFO - Running setup.py install for psutil 12:01:10 INFO - building 'psutil._psutil_osx' extension 12:01:10 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 12:01:11 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 12:01:11 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 12:01:11 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 12:01:11 INFO - building 'psutil._psutil_posix' extension 12:01:11 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 12:01:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:01:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:01:11 INFO - ^ 12:01:11 INFO - 1 warning generated. 12:01:11 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 12:01:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:11 INFO - Successfully installed psutil 12:01:11 INFO - Cleaning up... 12:01:11 INFO - Return code: 0 12:01:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01:11 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')]} 12:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:11 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')]} 12:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:11 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:11 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 12:01:11 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 12:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:11 INFO - 'HOME': '/Users/cltbld', 12:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:11 INFO - 'LOGNAME': 'cltbld', 12:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:11 INFO - 'MOZ_NO_REMOTE': '1', 12:01:11 INFO - 'NO_EM_RESTART': '1', 12:01:11 INFO - 'PAGER': '/bin/cat', 12:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:11 INFO - 'PWD': '/builds/slave/test', 12:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:11 INFO - 'SHELL': '/bin/bash', 12:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:11 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:11 INFO - 'USER': 'cltbld', 12:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:11 INFO - 'XPC_FLAGS': '0x0', 12:01:11 INFO - 'XPC_SERVICE_NAME': '0', 12:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:01:14 INFO - Installing collected packages: mozsystemmonitor 12:01:14 INFO - Running setup.py install for mozsystemmonitor 12:01:14 INFO - Successfully installed mozsystemmonitor 12:01:14 INFO - Cleaning up... 12:01:14 INFO - Return code: 0 12:01:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:01:14 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')]} 12:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 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')]} 12:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:14 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 12:01:14 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 12:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:14 INFO - 'HOME': '/Users/cltbld', 12:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:14 INFO - 'LOGNAME': 'cltbld', 12:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:14 INFO - 'MOZ_NO_REMOTE': '1', 12:01:14 INFO - 'NO_EM_RESTART': '1', 12:01:14 INFO - 'PAGER': '/bin/cat', 12:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:14 INFO - 'PWD': '/builds/slave/test', 12:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:14 INFO - 'SHELL': '/bin/bash', 12:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:14 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:14 INFO - 'USER': 'cltbld', 12:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:14 INFO - 'XPC_FLAGS': '0x0', 12:01:14 INFO - 'XPC_SERVICE_NAME': '0', 12:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:15 INFO - Downloading/unpacking jsonschema==2.5.1 12:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:01:17 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 12:01:17 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 12:01:17 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 12:01:17 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 12:01:17 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 12:01:17 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 12:01:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:17 INFO - warning: no files found matching '*.txt' 12:01:17 INFO - no previously-included directories found matching 'build' 12:01:17 INFO - no previously-included directories found matching 'dist' 12:01:17 INFO - no previously-included directories found matching '.git*' 12:01:17 INFO - Installing collected packages: jsonschema, functools32 12:01:17 INFO - Running setup.py install for functools32 12:01:18 INFO - warning: no files found matching '*.txt' 12:01:18 INFO - no previously-included directories found matching 'build' 12:01:18 INFO - no previously-included directories found matching 'dist' 12:01:18 INFO - no previously-included directories found matching '.git*' 12:01:18 INFO - Successfully installed jsonschema functools32 12:01:18 INFO - Cleaning up... 12:01:18 INFO - Return code: 0 12:01:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:18 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:18 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 12:01:18 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 12:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:18 INFO - 'HOME': '/Users/cltbld', 12:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:18 INFO - 'LOGNAME': 'cltbld', 12:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:18 INFO - 'MOZ_NO_REMOTE': '1', 12:01:18 INFO - 'NO_EM_RESTART': '1', 12:01:18 INFO - 'PAGER': '/bin/cat', 12:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:18 INFO - 'PWD': '/builds/slave/test', 12:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:18 INFO - 'SHELL': '/bin/bash', 12:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:18 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:18 INFO - 'USER': 'cltbld', 12:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:18 INFO - 'XPC_FLAGS': '0x0', 12:01:18 INFO - 'XPC_SERVICE_NAME': '0', 12:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:18 INFO - Downloading/unpacking blobuploader==1.2.4 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 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 12:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:01:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:22 INFO - Downloading docopt-0.6.1.tar.gz 12:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:22 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:22 INFO - Running setup.py install for blobuploader 12:01:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:01:22 INFO - Running setup.py install for requests 12:01:22 INFO - Running setup.py install for docopt 12:01:22 INFO - Successfully installed blobuploader requests docopt 12:01:22 INFO - Cleaning up... 12:01:23 INFO - Return code: 0 12:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:23 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:23 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 12:01:23 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 12:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:23 INFO - 'HOME': '/Users/cltbld', 12:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:23 INFO - 'LOGNAME': 'cltbld', 12:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:23 INFO - 'MOZ_NO_REMOTE': '1', 12:01:23 INFO - 'NO_EM_RESTART': '1', 12:01:23 INFO - 'PAGER': '/bin/cat', 12:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:23 INFO - 'PWD': '/builds/slave/test', 12:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:23 INFO - 'SHELL': '/bin/bash', 12:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:23 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:23 INFO - 'USER': 'cltbld', 12:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:23 INFO - 'XPC_FLAGS': '0x0', 12:01:23 INFO - 'XPC_SERVICE_NAME': '0', 12:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-0pEget-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-6eMELn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-g4d3Yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-iJcF9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-QfnHgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-7S5Vyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:23 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-MXROGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-KVoh4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-pXCMTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-4qnwTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-5W_6la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-jR1Yme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-tSikXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-LQZ4TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-hkOTho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-gmXO83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:24 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Uq_XEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:25 INFO - Running setup.py install for manifestparser 12:01:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:01:25 INFO - Running setup.py install for mozcrash 12:01:25 INFO - Running setup.py install for mozdebug 12:01:25 INFO - Running setup.py install for mozdevice 12:01:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:01:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:01:25 INFO - Running setup.py install for mozfile 12:01:25 INFO - Running setup.py install for mozhttpd 12:01:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:01:25 INFO - Running setup.py install for mozinfo 12:01:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Running setup.py install for mozInstall 12:01:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Running setup.py install for mozleak 12:01:26 INFO - Running setup.py install for mozlog 12:01:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Running setup.py install for moznetwork 12:01:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:01:26 INFO - Running setup.py install for mozprocess 12:01:26 INFO - Running setup.py install for mozprofile 12:01:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:01:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:01:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:01:27 INFO - Running setup.py install for mozrunner 12:01:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:01:27 INFO - Running setup.py install for mozscreenshot 12:01:27 INFO - Running setup.py install for moztest 12:01:27 INFO - Running setup.py install for mozversion 12:01:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:01:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:27 INFO - Cleaning up... 12:01:27 INFO - Return code: 0 12:01:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:27 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:27 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 12:01:27 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 12:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:27 INFO - 'HOME': '/Users/cltbld', 12:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:27 INFO - 'LOGNAME': 'cltbld', 12:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:27 INFO - 'MOZ_NO_REMOTE': '1', 12:01:27 INFO - 'NO_EM_RESTART': '1', 12:01:27 INFO - 'PAGER': '/bin/cat', 12:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:27 INFO - 'PWD': '/builds/slave/test', 12:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:27 INFO - 'SHELL': '/bin/bash', 12:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:27 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:27 INFO - 'USER': 'cltbld', 12:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:27 INFO - 'XPC_FLAGS': '0x0', 12:01:27 INFO - 'XPC_SERVICE_NAME': '0', 12:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-frmAge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-h_0_Ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-TZcnqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-irmu3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-n_ge_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-IfrsNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-a8SuKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-U78IYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-4erscL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:28 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)) 12:01:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:28 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-UY7Zxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:29 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)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-y4dZ9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:29 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)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-fFKUd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-kRfumW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:29 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)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-hdXUTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-sB2hQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:29 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)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-b91sEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:29 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)) 12:01:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:29 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ogyNxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:29 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)) 12:01:29 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)) 12:01:29 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)) 12:01:29 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)) 12:01:29 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)) 12:01:29 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)) 12:01:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:29 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 12:01:29 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 12:01:29 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 12:01:29 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 12:01:29 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 12:01:29 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 12:01:32 INFO - Downloading blessings-1.6.tar.gz 12:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:01:32 INFO - Installing collected packages: blessings 12:01:32 INFO - Running setup.py install for blessings 12:01:32 INFO - Successfully installed blessings 12:01:32 INFO - Cleaning up... 12:01:32 INFO - Return code: 0 12:01:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:01:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:32 INFO - Reading from file tmpfile_stdout 12:01:32 INFO - Current package versions: 12:01:32 INFO - blessings == 1.6 12:01:32 INFO - blobuploader == 1.2.4 12:01:32 INFO - docopt == 0.6.1 12:01:32 INFO - functools32 == 3.2.3-2 12:01:32 INFO - jsonschema == 2.5.1 12:01:32 INFO - manifestparser == 1.1 12:01:32 INFO - mozInstall == 1.12 12:01:32 INFO - mozcrash == 0.17 12:01:32 INFO - mozdebug == 0.1 12:01:32 INFO - mozdevice == 0.48 12:01:32 INFO - mozfile == 1.2 12:01:32 INFO - mozhttpd == 0.7 12:01:32 INFO - mozinfo == 0.9 12:01:32 INFO - mozleak == 0.1 12:01:32 INFO - mozlog == 3.2 12:01:32 INFO - moznetwork == 0.27 12:01:32 INFO - mozprocess == 0.22 12:01:32 INFO - mozprofile == 0.28 12:01:32 INFO - mozrunner == 6.11 12:01:32 INFO - mozscreenshot == 0.1 12:01:32 INFO - mozsystemmonitor == 0.3 12:01:32 INFO - moztest == 0.7 12:01:32 INFO - mozversion == 1.4 12:01:32 INFO - psutil == 3.1.1 12:01:32 INFO - requests == 1.2.3 12:01:32 INFO - wsgiref == 0.1.2 12:01:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01: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')]} 12:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:32 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 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:32 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 12:01:32 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 12:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:32 INFO - 'HOME': '/Users/cltbld', 12:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:32 INFO - 'LOGNAME': 'cltbld', 12:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:32 INFO - 'MOZ_NO_REMOTE': '1', 12:01:32 INFO - 'NO_EM_RESTART': '1', 12:01:32 INFO - 'PAGER': '/bin/cat', 12:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:32 INFO - 'PWD': '/builds/slave/test', 12:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:32 INFO - 'SHELL': '/bin/bash', 12:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:32 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:32 INFO - 'USER': 'cltbld', 12:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:32 INFO - 'XPC_FLAGS': '0x0', 12:01:32 INFO - 'XPC_SERVICE_NAME': '0', 12:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 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)) 12:01:33 INFO - Cleaning up... 12:01:33 INFO - Return code: 0 12:01:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a967a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9fc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f886954f540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, '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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 12:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:33 INFO - 'HOME': '/Users/cltbld', 12:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:33 INFO - 'LOGNAME': 'cltbld', 12:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:33 INFO - 'MOZ_NO_REMOTE': '1', 12:01:33 INFO - 'NO_EM_RESTART': '1', 12:01:33 INFO - 'PAGER': '/bin/cat', 12:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:33 INFO - 'PWD': '/builds/slave/test', 12:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:33 INFO - 'SHELL': '/bin/bash', 12:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:01:33 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:33 INFO - 'USER': 'cltbld', 12:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:33 INFO - 'XPC_FLAGS': '0x0', 12:01:33 INFO - 'XPC_SERVICE_NAME': '0', 12:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:01:33 INFO - Cleaning up... 12:01:33 INFO - Return code: 0 12:01:33 INFO - Running post-action listener: _resource_record_post_action 12:01:33 INFO - Running post-action listener: _start_resource_monitoring 12:01:33 INFO - Starting resource monitoring. 12:01:33 INFO - ##### 12:01:33 INFO - ##### Finished create-virtualenv step (success) 12:01:33 INFO - ##### 12:01:33 INFO - ##### 12:01:33 INFO - ##### Running install step. 12:01:33 INFO - ##### 12:01:33 INFO - Running pre-action listener: _resource_record_pre_action 12:01:33 INFO - Running main action method: install 12:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:33 INFO - Reading from file tmpfile_stdout 12:01:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:01:33 INFO - Reading from file tmpfile_stdout 12:01:33 INFO - Output received: 12:01:33 INFO - Usage: mozinstall [options] installer 12:01:33 INFO - Options: 12:01:33 INFO - -h, --help show this help message and exit 12:01:33 INFO - -d DEST, --destination=DEST 12:01:33 INFO - Directory to install application into. [default: 12:01:33 INFO - "/builds/slave/test"] 12:01:33 INFO - --app=APP Application being installed. [default: firefox] 12:01:33 INFO - mkdir: /builds/slave/test/build/application 12:01:33 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'] 12:01:33 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 12:02:08 INFO - Reading from file tmpfile_stdout 12:02:08 INFO - Output received: 12:02:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:02:08 INFO - Running post-action listener: _resource_record_post_action 12:02:08 INFO - ##### 12:02:08 INFO - ##### Finished install step (success) 12:02:08 INFO - ##### 12:02:08 INFO - ##### 12:02:08 INFO - ##### Running run-tests step. 12:02:08 INFO - ##### 12:02:08 INFO - Running pre-action listener: _resource_record_pre_action 12:02:08 INFO - Running main action method: run_tests 12:02:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:08 INFO - 2016-07-05 12:02:08.225 screenresolution[1634:7305] starting screenresolution argv=screenresolution get 12:02:08 INFO - 2016-07-05 12:02:08.227 screenresolution[1634:7305] Display 0: 1600x1200x32@60 12:02:08 INFO - 2016-07-05 12:02:08.233 screenresolution[1635:7308] starting screenresolution argv=screenresolution list 12:02:08 INFO - Available Modes on Display 0 12:02:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:08 INFO - Intel Iris: 12:02:08 INFO - Chipset Model: Intel Iris 12:02:08 INFO - Type: GPU 12:02:08 INFO - Bus: Built-In 12:02:08 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:08 INFO - Vendor: Intel (0x8086) 12:02:08 INFO - Device ID: 0x0a2e 12:02:08 INFO - Revision ID: 0x0009 12:02:08 INFO - Displays: 12:02:08 INFO - Display: 12:02:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:08 INFO - Display Serial Number: 1600x1200 60 12:02:08 INFO - Main Display: Yes 12:02:08 INFO - Mirror: Off 12:02:08 INFO - Online: Yes 12:02:08 INFO - Rotation: Supported 12:02:08 INFO - Return code: 0 12:02:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:08 INFO - Python 2.7.3 12:02:08 INFO - Return code: 0 12:02:08 INFO - grabbing minidump binary from tooltool 12:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:02:08 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 0x10a8cbd30>, '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 0x10a630db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:08 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 12:02:08 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 12:02:08 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 12:02:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:08 INFO - Return code: 0 12:02:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:02:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:02:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:08 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/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0192', '--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 12:02:08 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/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0192 --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 12:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:08 INFO - 'HOME': '/Users/cltbld', 12:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:08 INFO - 'LOGNAME': 'cltbld', 12:02:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:08 INFO - 'MOZ_NO_REMOTE': '1', 12:02:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:08 INFO - 'NO_EM_RESTART': '1', 12:02:08 INFO - 'PAGER': '/bin/cat', 12:02:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:08 INFO - 'PWD': '/builds/slave/test', 12:02:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:08 INFO - 'SHELL': '/bin/bash', 12:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:02:08 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:08 INFO - 'USER': 'cltbld', 12:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:08 INFO - 'XPC_FLAGS': '0x0', 12:02:08 INFO - 'XPC_SERVICE_NAME': '0', 12:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:08 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/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0192', '--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 12:02:08 INFO - mozversion application_buildid: 20160705085522 12:02:08 INFO - mozversion application_changeset: e3d304afd3f2bb3301374a37486f48174a367c27 12:02:08 INFO - mozversion application_display_name: Nightly 12:02:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:08 INFO - mozversion application_name: Firefox 12:02:08 INFO - mozversion application_remotingname: firefox 12:02:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:02:08 INFO - mozversion application_vendor: Mozilla 12:02:08 INFO - mozversion application_version: 50.0a1 12:02:08 INFO - mozversion platform_buildid: 20160705085522 12:02:08 INFO - mozversion platform_changeset: e3d304afd3f2bb3301374a37486f48174a367c27 12:02:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:02:08 INFO - mozversion platform_version: 50.0a1 12:02:08 INFO - using testdate: 1467745328 12:02:08 INFO - actual date: 1467745328 12:02:08 INFO - starting webserver on 'localhost:49266' 12:02:08 INFO - SUITE-START | Running 2 tests 12:02:08 INFO - TEST-START | dromaeo_css 12:02:08 INFO - operating with platform_type : mac_ 12:02:08 INFO - Initialising browser for dromaeo_css test... 12:02:08 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpzPRsHk/profile http://localhost:49266/getInfo.html) 12:02:11 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 12:02:11 INFO - PROCESS | 1645 | colorDepth:24 12:02:11 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 12:02:11 INFO - PROCESS | 1645 | __metrics 12:02:11 INFO - PROCESS | 1645 | 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" 12:02:11 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:02:12 INFO - TEST-INFO | 1645: exit 0 12:02:12 INFO - Browser initialized. 12:02:12 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:12 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpzPRsHk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:24 INFO - PROCESS | 1647 | RSS: Main: 244948992 12:02:24 INFO - PROCESS | 1647 | 12:03:15 INFO - PROCESS | 1647 | 1467745395886 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] 12:05:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 12:05:27 INFO - PROCESS | 1647 | RSS: Main: 346292224 12:05:27 INFO - PROCESS | 1647 | 12:08:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 12:08:31 INFO - PROCESS | 1647 | RSS: Main: 379760640 12:08:31 INFO - PROCESS | 1647 | 12:11:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 12:11:34 INFO - PROCESS | 1647 | RSS: Main: 370872320 12:11:34 INFO - PROCESS | 1647 | 12:14:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 12:14:37 INFO - PROCESS | 1647 | RSS: Main: 365670400 12:14:37 INFO - PROCESS | 1647 | 12:17:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 12:17:41 INFO - PROCESS | 1647 | RSS: Main: 336252928 12:17:41 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 12:20:44 INFO - PROCESS | 1647 | RSS: Main: 445550592 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | __start_tp_report 12:20:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 12:20:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 12:20:44 INFO - PROCESS | 1647 | |i|pagename|runs| 12:20:44 INFO - PROCESS | 1647 | |0;dojo.html;2866.226118947884;3314.9005529834108;3334.0664613908116;3346.4980252962037;3382.137554923494;5590.385057643656;5619.32568091829;5654.717264136792;5666.979943610148;5715.742791574384;5366.711989122621;5619.943800562003;5700.885982280362;5723.9124566332475;5752.827415177541;4477.149341625097;5058.671186372892;5125.2312153176945;5134.974325128369;5309.256704061435;5224.138017227153;5246.7638956247065;5270.314859068335;5283.735813209343;5284.947150528484;5060.848174554732;5089.312942752718;5103.770330335144;5130.07658621448;5147;5127.5385215330425;5132.076226279269;5154.53609175176;5167.09575824233;5173.1722924331925;5150.510701483353;5162.045021671003;5169.069567477853;5172.27588137661;5215.400228973679;5547.528460080898;5603;5708.71456427177;5710.971445142748;5796.2464879565805;6054.138831260383;6113.537117903944;6144.9246381882995;6160.784372546984;6177.752889884444;6188.535859810532;6243.56295059346;6255.718492667841;6262.373762623747;6267.153934218868;5629.831105066854;5704.8859022819315;5789.2763404574425;5794.985877471426;5835.212246346731;6237.656928868956;6265.780697675559;6275.152854364694;6290.402411770874;6301;6134.570580059353;6142.78500252498;6147.354527774609;6160.476359509492;6165.475934545614;6113.113598528189;6142.989934931851;6222.937770622327;6262.029385445263;6298;6107.6640784757265;6132.110769107974;6133.059244993061;6161.938380616226;6216.968915155486;6183.103449999725;6200.596961197508;6202;6220.502359811204;6234.625922444668;4838.056578967067;4901.485344038896;4954.058728841581;4967.528084831935;4979.502049795064;4414.09511050234;4449.755263460476;4457.64338852891;4491.169133710274;4497.482789479252;5681.0626246668835;5694.316681998187;5694.345150307685;5706.115552089433;5758.078707406795;6130.6628135452975;6130.816075517741;6164.907526387108;6269.122322874801;6287.528435367366;5560.443955604407;5599.860003499945;5599.972000139973;5637.971810141005;5657.264555607827;5415.296011518556;5421.864453388697;5424.783008679688;5445.37378201504;5468;6229.190205273286;6234.875302494015;6253.030780229072;6253.437190652863;6274.466670332981;6055.515558755201;6145.156453064153;6176.69116544162;6267.874642507216;6281.905771413341;6211.9689401552705;6254.155688981929;6284.780032699016;6288.308286088618;6296.118543403835;6108.908366374597;6167.290761562478;6172.444479996821;6179.412955769195;6185.165002724754;6087.512998959984;6135.661410523283;6269.843253918689;6269.874602507833;6301.117843502005;5972.402759723993;6029.065494848305;6077.088436734349;6082.9695851520455;6099.420555047263;5827.679477628772;6022.548308876764;6031.60794548353;6035.879282414415;6052.364501727431;5705.885882282414;5803.12953057028;5908.615939963888;5910.497607703393;5941.1385349125;5972.343042265435;6082.695865206811;6099.115628233792;6110.511159107172;6119.479844213113;5887.4406931341455;5910.556708246812;5931;5931.317898441562;5933.792317268875 12:20:44 INFO - PROCESS | 1647 | |1;ext.html;466.07484143974204;486.2293265174698;509.5465036117858;509.5668681620622;552.4530714592553;30244.395112097773;40821.79589102054;41727.582724172746;41910.16179676405;44343.3348499772;43469.56530434695;43696.78151609238;43699.12601747963;43768;43909.56090439095;8624.568771561428;9673.274504412162;9818.90181098187;9825.803483930316;9836;8629.784255393603;8855.335849811258;8857.689980850671;8863.069377715343;11709.414529273545;11586.942065289662;11937.701557461089;11954.342511161884;11978.460969256404;11993.280403175837;10141.18870490359;11780.94109529451;11957.162998590102;12015.639530814089;12027.977621902193;8581.656733730642;8790.560471976376;8796.032436431988;8882.067382924764;11800.291982481078;40091;40518;40662.18675626471;42881.713548593594;42947.57052429467;3540.504329393887;3582.0507565495163;3590.8743193988084;3602.189507360849;3635.581908080578;30582;32428.351432971445;32553.860614878366;32619.85830495945;32755.344893102003;3341;3352.715019223369;3401.8639254429795;3450.396180668373;3456.9308613827834;542025;542970;543405;544933.999999996;545818;59185.11222331668;59385;59629.72036538032;59734.70132649309;59860.70069649624;62357;62377.68811155915;62421;62435;62576.68711656413;16053.277602507915;16638.91680541614;16646.08446535428;16679.583010424838;16681.416150434918;13030.869691303154;13104.279264640536;13124.540641077516;13131.60605181827;13133.540326088541;13709.451621935012;13945.16329020262;15286.770698439532;15292.311845966959;15638.98346607467;6460.127882735865;6475.514336424787;6504.707288172043;6508.219013718384;6523.315051919575;859.4628357276702;935.4153653966271;956.6941125363828;1017.7404761785697;1100.1968562949091;30335.696643033727;30417;30491.695083048886;31168.22079448032;31492.527612085378;931.7484279244566;1035.782485678016;1059.5338051257422;1084.4577711144427;1102.5755084292605;15910.920445397931;15926.522204333889;16308.347666093461;16488.09315487636;16535.421260255833;504525.47737261077;580313;582705;585744;585822;60372.39627603668;60657.09014364788;60672.696636516535;60789;60836;1397.3013493253372;1434.6413396650837;1443;1451.4484495891493;1453.781932710102;30320.090397288117;30401.087967361014;30475.847620761753;31275.061748147593;31287.217819554695;50592;50843.72890677763;50912;50927.74536127296;51062.97874042424;618888;622274;622403;623024;623861;23359.53280934406;25763.871180643975;26255.474890502464;26490.602640960788;26631.869450444305;36207.818960905024;48209.62532622683;48617;48782.512174879215;50958.49041509537;40115.59884401118;40164.59835401725;40193.39709904412;41108.38337425001;41185;39581.80209098936;40448.98877528085;40521.59478405296;40691.389629156176;41714.79142604389;38412;38909.6109038906;39243.60756392399;39394;39410.605893940694;33767.81812636546;34962.300753985284;35429.645703542636;35491.290174195856;35504.64495355013;25263.62104568396;25584.8720756395;25669.871650641628;26269;26376.4724705501 12:20:44 INFO - PROCESS | 1647 | |2;jquery.html;384.08395975598194;393.4897127033235;393.6575179593752;395.91883663848915;415.58649144101605;14237.71524569508;20610.89694551527;21303.36089917301;21859;22079.227227047035;15793.92103039486;17995.640087198248;20781.376558703265;20956.26653067143;21043;5870.295564532262;6120.234970628671;6166.105914642374;6183.783567575136;6184.010558310649;5847.883042339151;5999.190109335227;6069.908951365733;6094.268687757475;6108.847278818021;5988.940110598904;6019.067044608093;6028.065649824284;6028.8191354259225;6030.8793824123495;5794.217780599628;5991.400859914017;5996.16053752475;6004.819855404323;6030.457258846703;5184.040952423776;5197.506236907488;5208.765605547759;5218.399884013311;5222.32109825724;25617;26836.865815670993;26934.73065269342;27173.04894328709;27217.727822721718;2279.1453205048106;2284.920027799036;2325.7674232576605;2552;2972.4649563078638;15746.842531574652;15970.760438593314;16367.42714005016;16399.83600163995;16510.587235319093;1760.3926645307477;1815.7911840138422;1867.4864412286595;1869.2522990803652;1901.6767149584466;98231.01768982361;116257;120721.39639301835;123630;124664.37667811602;9544.665936692183;10359.342505199133;10555.366677999345;11051.281666691639;11229.943850280697;13821;13830.515931942333;13836.30818459073;13846.169229846242;13866.4453421862;7149.249328820399;7189.96405017982;7432.182459929403;8616.827663446822;8825.338099642553;7605.935169076333;8980.416272942239;9002;9033.367664263438;9160.404573702688;6578.473722102316;6693.397594216543;6713.765018224339;6723.663816809238;6748.122744043341;1851.120717659101;1874.3158747057498;1878.6994080946984;1883.510287325278;1911.4647898588416;918.673870775865;968.7142293023575;1001.249063202598;1028.7016765138176;1150.3040660400338;20850.58298834045;23282.185123520594;23311.883440583028;23327.76672233256;23336.53326933452;967.1537404770826;982.3271059324386;990.9950450247702;1003.1924300937815;1086.1853609792656;10302.587896484056;11584.826227606593;11903.88096119045;12187.512499500099;12297.631071067883;530305;595773;603443.9655603475;617063.9146804328;622856;96426.07147857004;116733;117193;118794.21808672877;120958.3952080234;990.6730778842965;991.8363470027509;1011.9342242754198;1014.624588902111;1058.5554067291755;19768.90115549413;20028.198872044955;20175.39473815788;20182.39452816359;20242.291519796738;57121.428785711614;57689.134662979246;58017.709911450176;58193.70903145457;60622;85267;86186.56906715427;86776;87258.3822485301;87908;7571.3564347031115;8112.391570632344;8185.71350002747;8214.753557393287;8405.411621186702;9221;11104.167187460811;12579.74840503203;12833.422495987516;13230.669233269246;11584.710382240512;11618.070554355461;11749.530018799494;11786.233894796811;11878.346690932081;11522.76954460923;11567.710807229887;11580.942095289469;11642.94178529102;11880.346580937785;11660.475278612652;11835;11924.761504769682;11932.940335298616;12027.699307517032;8207.425480216327;8317.001959764866;8461.61922713467;8474.3644226682;8584.184502472504;11350;11505.942470287595;11711.063114950954;11844.230125041844;11846.585369512026 12:20:44 INFO - PROCESS | 1647 | |3;mootools.html;1410.3653355989807;1514.144508352781;1595.2342875419797;1656.7349224124143;1952.7070939359094;7872.842543149133;8621.525816080113;8849.5575221239;8861.379703420764;8983.416077954927;8647.567621618926;8726.52004139772;8819.073997230294;8819.470831750083;8825.161609647073;2363.728171260301;2392.3660230039054;2392.569337519246;2397.5444665513583;2404.110479122727;2338.6024375856146;2377.5601513720017;2391.988040059797;2397.352714767012;2398.6162214045758;2332.3469428559947;2334.2997100869757;2338.801201897832;2384.4277373430427;2389.6893403857475;2335.5328934213226;2357.917472888449;2359.7050368703913;2363.680903078097;2367.881605919697;2300.861948283092;2327.51122264323;2333.4166458385403;2337.1936681844736;2344.976550234493;8572.399932004762;8841.734747957571;8870.42342247752;8899.288056955427;8910;5238.1618940969265;5463.863403414906;5466.562674985991;5467.5625949923915;5471.699056551888;3618.9819050904575;3644.5990940996735;3688.5942546319884;3699.741018128732;3707.258548290353;1087.7280679830042;1088.3633074651339;1095.3263742798122;1106.5573770491787;1110.5113749949996;11620.360880151587;11739.58911438104;11746.706332341675;11761.529538818437;11791.056715462742;4403.3174857897075;4500.707454015478;4629.699069560468;4664.160451118763;4669.0195059037305;9300.441973481613;9307.90692093084;9309.44143351401;9311.90688093124;9317.276303883804;5246.344206974129;5248.580113590903;5249.7112658804135;5251.52736253739;5252.684838909678;4459.130469558405;4616.976915115403;4794.448638406628;4899.632527560376;4948.826791062295;3799;3804.1440675848266;3897.629725176104;3912.7065470089856;3933.4099885017476;1156.3004382348727;1163.5404015413865;1166.9183157179086;1177.776222517736;1181.8168183931505;664.6311297230084;815.8537255156515;815.9796005099802;823.7940514871282;969.5879251318162;5815.621984570989;6027.186329845415;6031.909521357183;6065.2418447694035;6068.51451883857;887.3921363865644;945.8864936207699;991.8413053911339;1004.4826914139225;1008.8385858262643;4021.195760847842;4071.8574849880706;4081.3673880548563;4110.219058378899;4142.2543942090115;10728.463576821128;11051.889481105087;11077;11090.001899828892;11111.111111111091;11098.112151028059;11098.2786118902;11108.888911110786;11109.388983605817;11120.721981950353;7386.815329616803;8251.587420629065;8260.545669988209;8262.132476090044;8336.208060234268;5979.431953964367;6091.968950738826;6109.389061093855;6143.1706719592285;6284.183056202664;9729.027097290214;9921.107100361001;10187.796244075225;10195.388276703421;10196.949015254877;10137.23970702215;10421.843672344768;10428.947855260978;10468.581256749645;10501.842472363074;8220.260176584135;8377.83244335122;8390.370722195981;8470.068292487698;8476.957615211884;8380.58097095155;8380.832383352421;8491.15088491146;8496.320294376312;8511.702090426805;6151.784687535915;7492.62536873165;7569.545827250383;7634.4655874088285;7708.113566939649;7451.254874512505;7568.091828980338;7627.008488896408;7690.846183076195;7705.22947705225;7103.183133939457;7146.285371462812;7192.532485388433;7214.386777124003;7223.097112860893;4867.902641947212;5024.598032157492;5053.3829174665225;5064.544191022678;5069.8732531687;5082.898342033212;5090.363704536933;5107.055194788976;5185.429602743619;5264.131418315935 12:20:44 INFO - PROCESS | 1647 | |4;prototype.html;270.78607899759186;312.5343238574525;314.34302308175916;317.5696930658957;324.1782082421063;4469.642428605712;5408.945910540899;5411.107167317393;5462.808801691942;5496.697681627509;5325.3077099977045;5332.680039197641;5335.893282134355;5353.625246232756;5391.406945236021;3104.4567200739893;3883.922321553567;4011.3180759270995;4083.8162282697203;4090.6931980101613;3986.9235306467235;4001.6398524132824;4049.3802479008486;4093.9795301023455;4170.749755014694;4021.2158629067344;4023.316036273826;4058.370952502367;4062.6140516651017;4069.165821006701;3603.261331427051;3816.4924854682386;4031.153457773871;4043.9191216175514;4055.513338399396;3466.3933811582874;3480.6693364130365;3500.457429098494;3523.4010218262956;3550.9822450887837;4326.783660816978;4867.586255168315;5158.613104017214;5167.689938603658;5232.764525596357;4098.7130900836955;4297.634701050414;4646.279826626878;4647.071465341879;4671.299305104228;4505.166544189336;5297.046531624306;5488.941646882419;5489.58828087891;5499.972500137514;3846.211526637033;3880.9715425412287;3881.6894648428056;3883.49514563105;3931.174453364797;5474.945250547484;5756.769729210827;5800.187973683687;5835.620684655484;5958.463738263534;3850.8652197172964;3865.1496670732395;3873.670737987303;3874.302625527432;3903.4925459690057;3319.71782398494;3625.567900679217;3768.4347347897487;3779.848806047782;3961.7028722845325;4507.81968721254;4564.497905230422;4583.587477127075;4607.493175750665;4620.3762492063815;4707.670463067552;4868.099401610713;5301.363836339664;5604;5635.943640563624;1306.2554344024006;1332.4144268320667;1333.7862545083926;1340.3700260877372;1349.2983648502723;743.7917383132732;761.3110135327537;792.3779832831315;793.4287313134533;798.2775588092785;4887.608991280689;5059.215821547666;5112.386513618315;5120.590352771769;5239.659422137549;4889.926651100236;5054;5067.949320506821;5707.857303567444;5786.392428795;4897.96326277703;4939.839137802614;5208.635395522277;5212.3223980883;5218.478152184751;2563.6026415905562;2601.6877974642784;2905.1575043237226;3019.7282244597654;3023.56158357037;5582.7766889323975;5593.328800543961;5910.408959104056;5919.348871624118;6036;5290.289038320955;5605.831825045255;5664.631798933056;5748.971255143698;5895.557833162444;5281.973590132025;5853.912191317134;5899;5929.9110513341475;5969.522438204933;4677.7894994726;5324.414314425333;5506.614536982373;5725.427457254241;5772.191893135048;5676.9497642936185;5700.144978253129;5716.171155182392;5722.284714410699;5759.366469688414;5057.0138822929175;5205.817796377109;5451.754671039734;5794.667226537836;5942.405759424023;2880.1791489425414;3210.341879914565;3345.3476572069185;3353.7987720736833;3357.966420335863;4282.8008157715885;4652.511486294025;4812.470628230962;4816.446108697404;4834.564889159992;3982.323005089084;4058.776767277828;4152.380571577148;4426.830852882129;4648.976755116203;4412.132114125716;4675.438947326343;4676.8129274828625;4678.485366609602;4742.174262908874;4080.8367665294245;4454.398656181504;5533.501984821385;5549.7780088796;5555.8888822224135;4384.49578298487;5010.348654675015;5461.832137360242;5604.6917419542315;5628.212050313042;4764.927891224447;4833.468318485035;5123.797626483448;5498.625343664084;5536.612437129363 12:20:44 INFO - PROCESS | 1647 | |5;yui.html;645.3675398109852;757.6552668535816;794.6503538443085;859.2996707683242;862.4609618988133;14909.030912990662;16154.111523866182;16174.191290435461;16179.271932763024;16193.514194574152;12894.484220631188;14843.257837108154;14883.479078232263;14891.25543722815;14903.552893413214;707.6992278281723;732.4287056096254;892.6429428228728;922.423485321674;927.8469052606312;909.2135302962922;915.7390143809016;916.1525588830306;916.8716379706847;919.5448253114685;951.3483263964207;967.274544091931;967.89837067108;968.0513097164747;970.2044323654607;745.1840234942729;772.6638912073266;772.7720322504874;963.205355581648;964.0793042644258;572.9656220626734;700.3626699703296;718.4109030594914;724.5833645653718;726.8800647893091;14918.477853275192;14960.850391496055;14968.47610333633;14971.550853474413;14972.700545989019;11602.593909213138;13975.763877779507;14000.789988150185;14057.367418466194;14086.859131408657;12551.246925184518;12637.557685480964;12677.36613169347;12899.742005159844;12902.354882255944;4491.708038977456;4533.705309154894;4606.193916064675;4677.859664210045;4702.036082603058;112429.31356029748;114071.71856562822;115241.4237928805;115260.66066366347;115301;9467.905320946702;10724.571017159225;11082.501287442085;11256.212065155525;11659.533618655329;21936.670949935764;22253.77746222517;22312.442188945406;22324.55350892973;22326.776732232796;7812.57030863308;8087.595620218965;8100.513969161869;8196.918030819616;8203.179682031869;4389.670774691847;4498.145352383037;4512.120136573337;4577.862664120082;4679.883002924954;3604.621514740967;3685.9262814743556;3721.7394782364972;3939.074317535377;4111.650509706708;754.3399525415261;755.2824816424419;760.2663429790279;765.689895592286;773.8723110686674;660.4419265720459;678.959262444245;682.8395327098128;697.3096634331974;708.2386434582844;8151.592420378957;8185.754427367188;9863.260255480867;9991.550380232908;9994.450305233282;612.3753771153398;642.1876326447049;681.993180068203;686.4954258619911;711.3313485323779;4442.622377097983;4456.64346852251;4691.7184968901975;4744.928826067612;4835.492273311322;119334;120047.39976300062;165339.4660533937;177663;179140;93493;105251;150569.43736189228;151231.9999999978;153263;93332;98520;103962;105482.47258763657;107207;9737.80524389494;9910.950445248016;9916.652917147865;9934.254930880068;9965.40207587547;62377.06434403578;62658.68670656617;63098.68450657717;65693.67153164203;67632;92957.07042929484;98885;100442;100819.48770768591;105156;13355.265460399383;13443.126196797177;13615.79576306376;14077.587667288097;14319.713605728035;15024.924875375553;15226.619334516725;15346.539603811903;15446.30491627857;15548.222588870738;14066.226357550433;14290.285485725464;14297.571072867831;14308;14710.632234193803;14143.504977325743;14451.421943122157;14609.634759131106;14845.832504475917;14898.1806000671;13742.65643358924;14100.78848817248;14175.00774945744;14395.928020359832;14597.635059123182;11455.713607159554;11573.594924177612;11611.419429028683;11771.764564708828;11781;8871.290296776371;10453.216008799218;10610.946945265225;11233.943830280796;11358.772824543297 12:20:44 INFO - PROCESS | 1647 | __end_tp_report 12:20:44 INFO - PROCESS | 1647 | __start_cc_report 12:20:44 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1140 12:20:44 INFO - PROCESS | 1647 | __end_cc_report 12:20:44 INFO - PROCESS | 1647 | __startTimestamp1467746444670__endTimestamp 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | ------- Summary: start ------- 12:20:44 INFO - PROCESS | 1647 | Number of tests: 6 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5707.44 Median:5931.00 stddev:647.62 (10.9%) stddev-sans-first:610.04 12:20:44 INFO - PROCESS | 1647 | Values: 2866.2 3314.9 3334.1 3346.5 3382.1 5590.4 5619.3 5654.7 5667.0 5715.7 5366.7 5619.9 5700.9 5723.9 5752.8 4477.1 5058.7 5125.2 5135.0 5309.3 5224.1 5246.8 5270.3 5283.7 5284.9 5060.8 5089.3 5103.8 5130.1 5147.0 5127.5 5132.1 5154.5 5167.1 5173.2 5150.5 5162.0 5169.1 5172.3 5215.4 5547.5 5603.0 5708.7 5711.0 5796.2 6054.1 6113.5 6144.9 6160.8 6177.8 6188.5 6243.6 6255.7 6262.4 6267.2 5629.8 5704.9 5789.3 5795.0 5835.2 6237.7 6265.8 6275.2 6290.4 6301.0 6134.6 6142.8 6147.4 6160.5 6165.5 6113.1 6143.0 6222.9 6262.0 6298.0 6107.7 6132.1 6133.1 6161.9 6217.0 6183.1 6200.6 6202.0 6220.5 6234.6 4838.1 4901.5 4954.1 4967.5 4979.5 4414.1 4449.8 4457.6 4491.2 4497.5 5681.1 5694.3 5694.3 5706.1 5758.1 6130.7 6130.8 6164.9 6269.1 6287.5 5560.4 5599.9 5600.0 5638.0 5657.3 5415.3 5421.9 5424.8 5445.4 5468.0 6229.2 6234.9 6253.0 6253.4 6274.5 6055.5 6145.2 6176.7 6267.9 6281.9 6212.0 6254.2 6284.8 6288.3 6296.1 6108.9 6167.3 6172.4 6179.4 6185.2 6087.5 6135.7 6269.8 6269.9 6301.1 5972.4 6029.1 6077.1 6083.0 6099.4 5827.7 6022.5 6031.6 6035.9 6052.4 5705.9 5803.1 5908.6 5910.5 5941.1 5972.3 6082.7 6099.1 6110.5 6119.5 5887.4 5910.6 5931.0 5931.3 5933.8 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:71365.13 Median:39836.40 stddev:154615.19 (388.1%) stddev-sans-first:154957.29 12:20:44 INFO - PROCESS | 1647 | Values: 466.1 486.2 509.5 509.6 552.5 30244.4 40821.8 41727.6 41910.2 44343.3 43469.6 43696.8 43699.1 43768.0 43909.6 8624.6 9673.3 9818.9 9825.8 9836.0 8629.8 8855.3 8857.7 8863.1 11709.4 11586.9 11937.7 11954.3 11978.5 11993.3 10141.2 11780.9 11957.2 12015.6 12028.0 8581.7 8790.6 8796.0 8882.1 11800.3 40091.0 40518.0 40662.2 42881.7 42947.6 3540.5 3582.1 3590.9 3602.2 3635.6 30582.0 32428.4 32553.9 32619.9 32755.3 3341.0 3352.7 3401.9 3450.4 3456.9 542025.0 542970.0 543405.0 544934.0 545818.0 59185.1 59385.0 59629.7 59734.7 59860.7 62357.0 62377.7 62421.0 62435.0 62576.7 16053.3 16638.9 16646.1 16679.6 16681.4 13030.9 13104.3 13124.5 13131.6 13133.5 13709.5 13945.2 15286.8 15292.3 15639.0 6460.1 6475.5 6504.7 6508.2 6523.3 859.5 935.4 956.7 1017.7 1100.2 30335.7 30417.0 30491.7 31168.2 31492.5 931.7 1035.8 1059.5 1084.5 1102.6 15910.9 15926.5 16308.3 16488.1 16535.4 504525.5 580313.0 582705.0 585744.0 585822.0 60372.4 60657.1 60672.7 60789.0 60836.0 1397.3 1434.6 1443.0 1451.4 1453.8 30320.1 30401.1 30475.8 31275.1 31287.2 50592.0 50843.7 50912.0 50927.7 51063.0 618888.0 622274.0 622403.0 623024.0 623861.0 23359.5 25763.9 26255.5 26490.6 26631.9 36207.8 48209.6 48617.0 48782.5 50958.5 40115.6 40164.6 40193.4 41108.4 41185.0 39581.8 40449.0 40521.6 40691.4 41714.8 38412.0 38909.6 39243.6 39394.0 39410.6 33767.8 34962.3 35429.6 35491.3 35504.6 25263.6 25584.9 25669.9 26269.0 26376.5 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35230.11 Median:23332.15 stddev:99040.87 (424.5%) stddev-sans-first:99284.15 12:20:44 INFO - PROCESS | 1647 | Values: 384.1 393.5 393.7 395.9 415.6 14237.7 20610.9 21303.4 21859.0 22079.2 15793.9 17995.6 20781.4 20956.3 21043.0 5870.3 6120.2 6166.1 6183.8 6184.0 5847.9 5999.2 6069.9 6094.3 6108.8 5988.9 6019.1 6028.1 6028.8 6030.9 5794.2 5991.4 5996.2 6004.8 6030.5 5184.0 5197.5 5208.8 5218.4 5222.3 25617.0 26836.9 26934.7 27173.0 27217.7 2279.1 2284.9 2325.8 2552.0 2972.5 15746.8 15970.8 16367.4 16399.8 16510.6 1760.4 1815.8 1867.5 1869.3 1901.7 98231.0 116257.0 120721.4 123630.0 124664.4 9544.7 10359.3 10555.4 11051.3 11229.9 13821.0 13830.5 13836.3 13846.2 13866.4 7149.2 7190.0 7432.2 8616.8 8825.3 7605.9 8980.4 9002.0 9033.4 9160.4 6578.5 6693.4 6713.8 6723.7 6748.1 1851.1 1874.3 1878.7 1883.5 1911.5 918.7 968.7 1001.2 1028.7 1150.3 20850.6 23282.2 23311.9 23327.8 23336.5 967.2 982.3 991.0 1003.2 1086.2 10302.6 11584.8 11903.9 12187.5 12297.6 530305.0 595773.0 603444.0 617063.9 622856.0 96426.1 116733.0 117193.0 118794.2 120958.4 990.7 991.8 1011.9 1014.6 1058.6 19768.9 20028.2 20175.4 20182.4 20242.3 57121.4 57689.1 58017.7 58193.7 60622.0 85267.0 86186.6 86776.0 87258.4 87908.0 7571.4 8112.4 8185.7 8214.8 8405.4 9221.0 11104.2 12579.7 12833.4 13230.7 11584.7 11618.1 11749.5 11786.2 11878.3 11522.8 11567.7 11580.9 11642.9 11880.3 11660.5 11835.0 11924.8 11932.9 12027.7 8207.4 8317.0 8461.6 8474.4 8584.2 11350.0 11505.9 11711.1 11844.2 11846.6 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5784.66 Median:4986.71 stddev:3253.43 (65.2%) stddev-sans-first:3245.95 12:20:44 INFO - PROCESS | 1647 | Values: 1410.4 1514.1 1595.2 1656.7 1952.7 7872.8 8621.5 8849.6 8861.4 8983.4 8647.6 8726.5 8819.1 8819.5 8825.2 2363.7 2392.4 2392.6 2397.5 2404.1 2338.6 2377.6 2392.0 2397.4 2398.6 2332.3 2334.3 2338.8 2384.4 2389.7 2335.5 2357.9 2359.7 2363.7 2367.9 2300.9 2327.5 2333.4 2337.2 2345.0 8572.4 8841.7 8870.4 8899.3 8910.0 5238.2 5463.9 5466.6 5467.6 5471.7 3619.0 3644.6 3688.6 3699.7 3707.3 1087.7 1088.4 1095.3 1106.6 1110.5 11620.4 11739.6 11746.7 11761.5 11791.1 4403.3 4500.7 4629.7 4664.2 4669.0 9300.4 9307.9 9309.4 9311.9 9317.3 5246.3 5248.6 5249.7 5251.5 5252.7 4459.1 4617.0 4794.4 4899.6 4948.8 3799.0 3804.1 3897.6 3912.7 3933.4 1156.3 1163.5 1166.9 1177.8 1181.8 664.6 815.9 816.0 823.8 969.6 5815.6 6027.2 6031.9 6065.2 6068.5 887.4 945.9 991.8 1004.5 1008.8 4021.2 4071.9 4081.4 4110.2 4142.3 10728.5 11051.9 11077.0 11090.0 11111.1 11098.1 11098.3 11108.9 11109.4 11120.7 7386.8 8251.6 8260.5 8262.1 8336.2 5979.4 6092.0 6109.4 6143.2 6284.2 9729.0 9921.1 10187.8 10195.4 10196.9 10137.2 10421.8 10428.9 10468.6 10501.8 8220.3 8377.8 8390.4 8470.1 8477.0 8380.6 8380.8 8491.2 8496.3 8511.7 6151.8 7492.6 7569.5 7634.5 7708.1 7451.3 7568.1 7627.0 7690.8 7705.2 7103.2 7146.3 7192.5 7214.4 7223.1 4867.9 5024.6 5053.4 5064.5 5069.9 5082.9 5090.4 5107.1 5185.4 5264.1 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4400.47 Median:4788.70 stddev:1360.95 (28.4%) stddev-sans-first:1329.00 12:20:44 INFO - PROCESS | 1647 | Values: 270.8 312.5 314.3 317.6 324.2 4469.6 5408.9 5411.1 5462.8 5496.7 5325.3 5332.7 5335.9 5353.6 5391.4 3104.5 3883.9 4011.3 4083.8 4090.7 3986.9 4001.6 4049.4 4094.0 4170.7 4021.2 4023.3 4058.4 4062.6 4069.2 3603.3 3816.5 4031.2 4043.9 4055.5 3466.4 3480.7 3500.5 3523.4 3551.0 4326.8 4867.6 5158.6 5167.7 5232.8 4098.7 4297.6 4646.3 4647.1 4671.3 4505.2 5297.0 5488.9 5489.6 5500.0 3846.2 3881.0 3881.7 3883.5 3931.2 5474.9 5756.8 5800.2 5835.6 5958.5 3850.9 3865.1 3873.7 3874.3 3903.5 3319.7 3625.6 3768.4 3779.8 3961.7 4507.8 4564.5 4583.6 4607.5 4620.4 4707.7 4868.1 5301.4 5604.0 5635.9 1306.3 1332.4 1333.8 1340.4 1349.3 743.8 761.3 792.4 793.4 798.3 4887.6 5059.2 5112.4 5120.6 5239.7 4889.9 5054.0 5067.9 5707.9 5786.4 4898.0 4939.8 5208.6 5212.3 5218.5 2563.6 2601.7 2905.2 3019.7 3023.6 5582.8 5593.3 5910.4 5919.3 6036.0 5290.3 5605.8 5664.6 5749.0 5895.6 5282.0 5853.9 5899.0 5929.9 5969.5 4677.8 5324.4 5506.6 5725.4 5772.2 5676.9 5700.1 5716.2 5722.3 5759.4 5057.0 5205.8 5451.8 5794.7 5942.4 2880.2 3210.3 3345.3 3353.8 3358.0 4282.8 4652.5 4812.5 4816.4 4834.6 3982.3 4058.8 4152.4 4426.8 4649.0 4412.1 4675.4 4676.8 4678.5 4742.2 4080.8 4454.4 5533.5 5549.8 5555.9 4384.5 5010.3 5461.8 5604.7 5628.2 4764.9 4833.5 5123.8 5498.6 5536.6 12:20:44 INFO - PROCESS | 1647 | 12:20:44 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25393.71 Median:3830.41 stddev:40702.86 (1062.6%) stddev-sans-first:40774.63 12:20:44 INFO - PROCESS | 1647 | Values: 645.4 757.7 794.7 859.3 862.5 14909.0 16154.1 16174.2 16179.3 16193.5 12894.5 14843.3 14883.5 14891.3 14903.6 707.7 732.4 892.6 922.4 927.8 909.2 915.7 916.2 916.9 919.5 951.3 967.3 967.9 968.1 970.2 745.2 772.7 772.8 963.2 964.1 573.0 700.4 718.4 724.6 726.9 14918.5 14960.9 14968.5 14971.6 14972.7 11602.6 13975.8 14000.8 14057.4 14086.9 12551.2 12637.6 12677.4 12899.7 12902.4 4491.7 4533.7 4606.2 4677.9 4702.0 112429.3 114071.7 115241.4 115260.7 115301.0 9467.9 10724.6 11082.5 11256.2 11659.5 21936.7 22253.8 22312.4 22324.6 22326.8 7812.6 8087.6 8100.5 8196.9 8203.2 4389.7 4498.1 4512.1 4577.9 4679.9 3604.6 3685.9 3721.7 3939.1 4111.7 754.3 755.3 760.3 765.7 773.9 660.4 679.0 682.8 697.3 708.2 8151.6 8185.8 9863.3 9991.6 9994.5 612.4 642.2 682.0 686.5 711.3 4442.6 4456.6 4691.7 4744.9 4835.5 119334.0 120047.4 165339.5 177663.0 179140.0 93493.0 105251.0 150569.4 151232.0 153263.0 93332.0 98520.0 103962.0 105482.5 107207.0 9737.8 9911.0 9916.7 9934.3 9965.4 62377.1 62658.7 63098.7 65693.7 67632.0 92957.1 98885.0 100442.0 100819.5 105156.0 13355.3 13443.1 13615.8 14077.6 14319.7 15024.9 15226.6 15346.5 15446.3 15548.2 14066.2 14290.3 14297.6 14308.0 14710.6 14143.5 14451.4 14609.6 14845.8 14898.2 13742.7 14100.8 14175.0 14395.9 14597.6 11455.7 11573.6 11611.4 11771.8 11781.0 8871.3 10453.2 10610.9 11233.9 11358.8 12:20:44 INFO - PROCESS | 1647 | -------- Summary: end -------- 12:20:44 INFO - PROCESS | 1647 | 12:20:45 INFO - TEST-INFO | 1647: exit 0 12:20:45 INFO - TEST-OK | dromaeo_css | took 1116500ms 12:20:45 INFO - TEST-START | kraken 12:20:45 INFO - operating with platform_type : mac_ 12:20:45 INFO - Initialising browser for kraken test... 12:20:45 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpI9OBJR/profile http://localhost:49266/getInfo.html) 12:20:47 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 12:20:47 INFO - PROCESS | 1656 | colorDepth:24 12:20:47 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 12:20:47 INFO - PROCESS | 1656 | __metrics 12:20:47 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:20:47 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:20:48 INFO - TEST-INFO | 1656: exit 0 12:20:48 INFO - Browser initialized. 12:20:48 INFO - Running cycle 1/1 for kraken test... 12:20:48 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpI9OBJR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:21:00 INFO - PROCESS | 1657 | RSS: Main: 243974144 12:21:00 INFO - PROCESS | 1657 | 12:21:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:03 INFO - PROCESS | 1657 | RSS: Main: 294158336 12:21:03 INFO - PROCESS | 1657 | 12:21:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 12:21:07 INFO - PROCESS | 1657 | RSS: Main: 304443392 12:21:07 INFO - PROCESS | 1657 | 12:21:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 12:21:11 INFO - PROCESS | 1657 | RSS: Main: 388935680 12:21:11 INFO - PROCESS | 1657 | 12:21:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 12:21:13 INFO - PROCESS | 1657 | RSS: Main: 289759232 12:21:13 INFO - PROCESS | 1657 | 12:21:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:16 INFO - PROCESS | 1657 | RSS: Main: 293912576 12:21:16 INFO - PROCESS | 1657 | 12:21:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:20 INFO - PROCESS | 1657 | RSS: Main: 332079104 12:21:20 INFO - PROCESS | 1657 | 12:21:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:23 INFO - PROCESS | 1657 | RSS: Main: 330756096 12:21:23 INFO - PROCESS | 1657 | 12:21:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 12:21:26 INFO - PROCESS | 1657 | RSS: Main: 329236480 12:21:26 INFO - PROCESS | 1657 | 12:21:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:29 INFO - PROCESS | 1657 | RSS: Main: 311717888 12:21:29 INFO - PROCESS | 1657 | 12:21:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:31 INFO - PROCESS | 1657 | RSS: Main: 318808064 12:21:31 INFO - PROCESS | 1657 | 12:21:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:34 INFO - PROCESS | 1657 | RSS: Main: 315838464 12:21:34 INFO - PROCESS | 1657 | 12:21:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:38 INFO - PROCESS | 1657 | RSS: Main: 324751360 12:21:38 INFO - PROCESS | 1657 | 12:21:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:41 INFO - PROCESS | 1657 | RSS: Main: 327454720 12:21:41 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 12:21:43 INFO - PROCESS | 1657 | RSS: Main: 370782208 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | __start_tp_report 12:21:43 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 12:21:43 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 12:21:43 INFO - PROCESS | 1657 | |i|pagename|runs| 12:21:43 INFO - PROCESS | 1657 | |0;ai-astar;101;113;113;113;113;110;112;112;113;112 12:21:43 INFO - PROCESS | 1657 | |1;audio-beat-detection;140;110;108;161;141;94;150;137;143;135 12:21:43 INFO - PROCESS | 1657 | |2;audio-dft;185;186;186;186;184;183;180;182;184;184 12:21:43 INFO - PROCESS | 1657 | |3;audio-fft;83;75;58;107;58;58;61;67;85;57 12:21:43 INFO - PROCESS | 1657 | |4;audio-oscillator;87;89;92;71;70;68;96;71;66;101 12:21:43 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;89;86;89;71;117;130;90;86 12:21:43 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;93;95;94;94;94;94;95;94;95 12:21:43 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;86;87;86;84;84;86;86;86;87 12:21:43 INFO - PROCESS | 1657 | |8;json-parse-financial;56;54;55;55;54;55;55;55;54;53 12:21:43 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;70;72;75;72;69;70;69;70;70 12:21:43 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;83;86;84;82;81;81;75;82;92;99 12:21:43 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;137;135;138;132;155;146;157;226;137 12:21:43 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;170;163;162;192;161;163;164;161;163;163 12:21:43 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;67;66;66;65;66;66;65;65 12:21:43 INFO - PROCESS | 1657 | __end_tp_report 12:21:43 INFO - PROCESS | 1657 | __start_cc_report 12:21:43 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2095 12:21:43 INFO - PROCESS | 1657 | __end_cc_report 12:21:43 INFO - PROCESS | 1657 | __startTimestamp1467746503514__endTimestamp 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | ------- Summary: start ------- 12:21:43 INFO - PROCESS | 1657 | Number of tests: 14 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:3.71 (3.3%) stddev-sans-first:1.00 12:21:43 INFO - PROCESS | 1657 | Values: 101.0 113.0 113.0 113.0 113.0 110.0 112.0 112.0 113.0 112.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:142.00 stddev:21.00 (14.8%) stddev-sans-first:22.07 12:21:43 INFO - PROCESS | 1657 | Values: 140.0 110.0 108.0 161.0 141.0 94.0 150.0 137.0 143.0 135.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.94 (1.1%) stddev-sans-first:2.03 12:21:43 INFO - PROCESS | 1657 | Values: 185.0 186.0 186.0 186.0 184.0 183.0 180.0 182.0 184.0 184.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:70.90 Median:64.00 stddev:16.57 (25.9%) stddev-sans-first:16.99 12:21:43 INFO - PROCESS | 1657 | Values: 83.0 75.0 58.0 107.0 58.0 58.0 61.0 67.0 85.0 57.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:79.00 stddev:13.17 (16.7%) stddev-sans-first:13.79 12:21:43 INFO - PROCESS | 1657 | Values: 87.0 89.0 92.0 71.0 70.0 68.0 96.0 71.0 66.0 101.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:87.50 stddev:17.10 (19.5%) stddev-sans-first:18.07 12:21:43 INFO - PROCESS | 1657 | Values: 89.0 86.0 89.0 86.0 89.0 71.0 117.0 130.0 90.0 86.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 12:21:43 INFO - PROCESS | 1657 | Values: 94.0 93.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.03 (1.2%) stddev-sans-first:1.09 12:21:43 INFO - PROCESS | 1657 | Values: 86.0 86.0 87.0 86.0 84.0 84.0 86.0 86.0 86.0 87.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.84 (1.5%) stddev-sans-first:0.73 12:21:43 INFO - PROCESS | 1657 | Values: 56.0 54.0 55.0 55.0 54.0 55.0 55.0 55.0 54.0 53.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.00 stddev:2.57 (3.7%) stddev-sans-first:1.92 12:21:43 INFO - PROCESS | 1657 | Values: 65.0 70.0 72.0 75.0 72.0 69.0 70.0 69.0 70.0 70.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:83.50 stddev:6.65 (8.0%) stddev-sans-first:7.04 12:21:43 INFO - PROCESS | 1657 | Values: 83.0 86.0 84.0 82.0 81.0 81.0 75.0 82.0 92.0 99.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:142.00 stddev:28.02 (19.7%) stddev-sans-first:29.32 12:21:43 INFO - PROCESS | 1657 | Values: 137.0 137.0 135.0 138.0 132.0 155.0 146.0 157.0 226.0 137.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:163.00 stddev:9.41 (5.8%) stddev-sans-first:9.88 12:21:43 INFO - PROCESS | 1657 | Values: 170.0 163.0 162.0 192.0 161.0 163.0 164.0 161.0 163.0 163.0 12:21:43 INFO - PROCESS | 1657 | 12:21:43 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 12:21:43 INFO - PROCESS | 1657 | Values: 67.0 68.0 67.0 66.0 66.0 65.0 66.0 66.0 65.0 65.0 12:21:43 INFO - PROCESS | 1657 | -------- Summary: end -------- 12:21:43 INFO - PROCESS | 1657 | 12:21:44 INFO - TEST-INFO | 1657: exit 0 12:21:44 INFO - TEST-OK | kraken | took 59005ms 12:21:44 INFO - SUITE-END | took 1175s 12:21:44 INFO - Completed test suite (00:19:35) 12:21:44 INFO - javascript benchmark 12:21:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2866.226118947884, 3314.9005529834108, 3334.0664613908116, 3346.4980252962037, 3382.137554923494, 5590.385057643656, 5619.32568091829, 5654.717264136792, 5666.979943610148, 5715.742791574384, 5366.711989122621, 5619.943800562003, 5700.885982280362, 5723.9124566332475, 5752.827415177541, 4477.149341625097, 5058.671186372892, 5125.2312153176945, 5134.974325128369, 5309.256704061435, 5224.138017227153, 5246.7638956247065, 5270.314859068335, 5283.735813209343, 5284.947150528484, 5060.848174554732, 5089.312942752718, 5103.770330335144, 5130.07658621448, 5147.0, 5127.5385215330425, 5132.076226279269, 5154.53609175176, 5167.09575824233, 5173.1722924331925, 5150.510701483353, 5162.045021671003, 5169.069567477853, 5172.27588137661, 5215.400228973679, 5547.528460080898, 5603.0, 5708.71456427177, 5710.971445142748, 5796.2464879565805, 6054.138831260383, 6113.537117903944, 6144.9246381882995, 6160.784372546984, 6177.752889884444, 6188.535859810532, 6243.56295059346, 6255.718492667841, 6262.373762623747, 6267.153934218868, 5629.831105066854, 5704.8859022819315, 5789.2763404574425, 5794.985877471426, 5835.212246346731, 6237.656928868956, 6265.780697675559, 6275.152854364694, 6290.402411770874, 6301.0, 6134.570580059353, 6142.78500252498, 6147.354527774609, 6160.476359509492, 6165.475934545614, 6113.113598528189, 6142.989934931851, 6222.937770622327, 6262.029385445263, 6298.0, 6107.6640784757265, 6132.110769107974, 6133.059244993061, 6161.938380616226, 6216.968915155486, 6183.103449999725, 6200.596961197508, 6202.0, 6220.502359811204, 6234.625922444668, 4838.056578967067, 4901.485344038896, 4954.058728841581, 4967.528084831935, 4979.502049795064, 4414.09511050234, 4449.755263460476, 4457.64338852891, 4491.169133710274, 4497.482789479252, 5681.0626246668835, 5694.316681998187, 5694.345150307685, 5706.115552089433, 5758.078707406795, 6130.6628135452975, 6130.816075517741, 6164.907526387108, 6269.122322874801, 6287.528435367366, 5560.443955604407, 5599.860003499945, 5599.972000139973, 5637.971810141005, 5657.264555607827, 5415.296011518556, 5421.864453388697, 5424.783008679688, 5445.37378201504, 5468.0, 6229.190205273286, 6234.875302494015, 6253.030780229072, 6253.437190652863, 6274.466670332981, 6055.515558755201, 6145.156453064153, 6176.69116544162, 6267.874642507216, 6281.905771413341, 6211.9689401552705, 6254.155688981929, 6284.780032699016, 6288.308286088618, 6296.118543403835, 6108.908366374597, 6167.290761562478, 6172.444479996821, 6179.412955769195, 6185.165002724754, 6087.512998959984, 6135.661410523283, 6269.843253918689, 6269.874602507833, 6301.117843502005, 5972.402759723993, 6029.065494848305, 6077.088436734349, 6082.9695851520455, 6099.420555047263, 5827.679477628772, 6022.548308876764, 6031.60794548353, 6035.879282414415, 6052.364501727431, 5705.885882282414, 5803.12953057028, 5908.615939963888, 5910.497607703393, 5941.1385349125, 5972.343042265435, 6082.695865206811, 6099.115628233792, 6110.511159107172, 6119.479844213113, 5887.4406931341455, 5910.556708246812, 5931.0, 5931.317898441562, 5933.792317268875], "value": 5664.056559201767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.07484143974204, 486.2293265174698, 509.5465036117858, 509.5668681620622, 552.4530714592553, 30244.395112097773, 40821.79589102054, 41727.582724172746, 41910.16179676405, 44343.3348499772, 43469.56530434695, 43696.78151609238, 43699.12601747963, 43768.0, 43909.56090439095, 8624.568771561428, 9673.274504412162, 9818.90181098187, 9825.803483930316, 9836.0, 8629.784255393603, 8855.335849811258, 8857.689980850671, 8863.069377715343, 11709.414529273545, 11586.942065289662, 11937.701557461089, 11954.342511161884, 11978.460969256404, 11993.280403175837, 10141.18870490359, 11780.94109529451, 11957.162998590102, 12015.639530814089, 12027.977621902193, 8581.656733730642, 8790.560471976376, 8796.032436431988, 8882.067382924764, 11800.291982481078, 40091.0, 40518.0, 40662.18675626471, 42881.713548593594, 42947.57052429467, 3540.504329393887, 3582.0507565495163, 3590.8743193988084, 3602.189507360849, 3635.581908080578, 30582.0, 32428.351432971445, 32553.860614878366, 32619.85830495945, 32755.344893102003, 3341.0, 3352.715019223369, 3401.8639254429795, 3450.396180668373, 3456.9308613827834, 542025.0, 542970.0, 543405.0, 544933.999999996, 545818.0, 59185.11222331668, 59385.0, 59629.72036538032, 59734.70132649309, 59860.70069649624, 62357.0, 62377.68811155915, 62421.0, 62435.0, 62576.68711656413, 16053.277602507915, 16638.91680541614, 16646.08446535428, 16679.583010424838, 16681.416150434918, 13030.869691303154, 13104.279264640536, 13124.540641077516, 13131.60605181827, 13133.540326088541, 13709.451621935012, 13945.16329020262, 15286.770698439532, 15292.311845966959, 15638.98346607467, 6460.127882735865, 6475.514336424787, 6504.707288172043, 6508.219013718384, 6523.315051919575, 859.4628357276702, 935.4153653966271, 956.6941125363828, 1017.7404761785697, 1100.1968562949091, 30335.696643033727, 30417.0, 30491.695083048886, 31168.22079448032, 31492.527612085378, 931.7484279244566, 1035.782485678016, 1059.5338051257422, 1084.4577711144427, 1102.5755084292605, 15910.920445397931, 15926.522204333889, 16308.347666093461, 16488.09315487636, 16535.421260255833, 504525.47737261077, 580313.0, 582705.0, 585744.0, 585822.0, 60372.39627603668, 60657.09014364788, 60672.696636516535, 60789.0, 60836.0, 1397.3013493253372, 1434.6413396650837, 1443.0, 1451.4484495891493, 1453.781932710102, 30320.090397288117, 30401.087967361014, 30475.847620761753, 31275.061748147593, 31287.217819554695, 50592.0, 50843.72890677763, 50912.0, 50927.74536127296, 51062.97874042424, 618888.0, 622274.0, 622403.0, 623024.0, 623861.0, 23359.53280934406, 25763.871180643975, 26255.474890502464, 26490.602640960788, 26631.869450444305, 36207.818960905024, 48209.62532622683, 48617.0, 48782.512174879215, 50958.49041509537, 40115.59884401118, 40164.59835401725, 40193.39709904412, 41108.38337425001, 41185.0, 39581.80209098936, 40448.98877528085, 40521.59478405296, 40691.389629156176, 41714.79142604389, 38412.0, 38909.6109038906, 39243.60756392399, 39394.0, 39410.605893940694, 33767.81812636546, 34962.300753985284, 35429.645703542636, 35491.290174195856, 35504.64495355013, 25263.62104568396, 25584.8720756395, 25669.871650641628, 26269.0, 26376.4724705501], "value": 20307.17846581247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.08395975598194, 393.4897127033235, 393.6575179593752, 395.91883663848915, 415.58649144101605, 14237.71524569508, 20610.89694551527, 21303.36089917301, 21859.0, 22079.227227047035, 15793.92103039486, 17995.640087198248, 20781.376558703265, 20956.26653067143, 21043.0, 5870.295564532262, 6120.234970628671, 6166.105914642374, 6183.783567575136, 6184.010558310649, 5847.883042339151, 5999.190109335227, 6069.908951365733, 6094.268687757475, 6108.847278818021, 5988.940110598904, 6019.067044608093, 6028.065649824284, 6028.8191354259225, 6030.8793824123495, 5794.217780599628, 5991.400859914017, 5996.16053752475, 6004.819855404323, 6030.457258846703, 5184.040952423776, 5197.506236907488, 5208.765605547759, 5218.399884013311, 5222.32109825724, 25617.0, 26836.865815670993, 26934.73065269342, 27173.04894328709, 27217.727822721718, 2279.1453205048106, 2284.920027799036, 2325.7674232576605, 2552.0, 2972.4649563078638, 15746.842531574652, 15970.760438593314, 16367.42714005016, 16399.83600163995, 16510.587235319093, 1760.3926645307477, 1815.7911840138422, 1867.4864412286595, 1869.2522990803652, 1901.6767149584466, 98231.01768982361, 116257.0, 120721.39639301835, 123630.0, 124664.37667811602, 9544.665936692183, 10359.342505199133, 10555.366677999345, 11051.281666691639, 11229.943850280697, 13821.0, 13830.515931942333, 13836.30818459073, 13846.169229846242, 13866.4453421862, 7149.249328820399, 7189.96405017982, 7432.182459929403, 8616.827663446822, 8825.338099642553, 7605.935169076333, 8980.416272942239, 9002.0, 9033.367664263438, 9160.404573702688, 6578.473722102316, 6693.397594216543, 6713.765018224339, 6723.663816809238, 6748.122744043341, 1851.120717659101, 1874.3158747057498, 1878.6994080946984, 1883.510287325278, 1911.4647898588416, 918.673870775865, 968.7142293023575, 1001.249063202598, 1028.7016765138176, 1150.3040660400338, 20850.58298834045, 23282.185123520594, 23311.883440583028, 23327.76672233256, 23336.53326933452, 967.1537404770826, 982.3271059324386, 990.9950450247702, 1003.1924300937815, 1086.1853609792656, 10302.587896484056, 11584.826227606593, 11903.88096119045, 12187.512499500099, 12297.631071067883, 530305.0, 595773.0, 603443.9655603475, 617063.9146804328, 622856.0, 96426.07147857004, 116733.0, 117193.0, 118794.21808672877, 120958.3952080234, 990.6730778842965, 991.8363470027509, 1011.9342242754198, 1014.624588902111, 1058.5554067291755, 19768.90115549413, 20028.198872044955, 20175.39473815788, 20182.39452816359, 20242.291519796738, 57121.428785711614, 57689.134662979246, 58017.709911450176, 58193.70903145457, 60622.0, 85267.0, 86186.56906715427, 86776.0, 87258.3822485301, 87908.0, 7571.3564347031115, 8112.391570632344, 8185.71350002747, 8214.753557393287, 8405.411621186702, 9221.0, 11104.167187460811, 12579.74840503203, 12833.422495987516, 13230.669233269246, 11584.710382240512, 11618.070554355461, 11749.530018799494, 11786.233894796811, 11878.346690932081, 11522.76954460923, 11567.710807229887, 11580.942095289469, 11642.94178529102, 11880.346580937785, 11660.475278612652, 11835.0, 11924.761504769682, 11932.940335298616, 12027.699307517032, 8207.425480216327, 8317.001959764866, 8461.61922713467, 8474.3644226682, 8584.184502472504, 11350.0, 11505.942470287595, 11711.063114950954, 11844.230125041844, 11846.585369512026], "value": 9966.541064261482, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.3653355989807, 1514.144508352781, 1595.2342875419797, 1656.7349224124143, 1952.7070939359094, 7872.842543149133, 8621.525816080113, 8849.5575221239, 8861.379703420764, 8983.416077954927, 8647.567621618926, 8726.52004139772, 8819.073997230294, 8819.470831750083, 8825.161609647073, 2363.728171260301, 2392.3660230039054, 2392.569337519246, 2397.5444665513583, 2404.110479122727, 2338.6024375856146, 2377.5601513720017, 2391.988040059797, 2397.352714767012, 2398.6162214045758, 2332.3469428559947, 2334.2997100869757, 2338.801201897832, 2384.4277373430427, 2389.6893403857475, 2335.5328934213226, 2357.917472888449, 2359.7050368703913, 2363.680903078097, 2367.881605919697, 2300.861948283092, 2327.51122264323, 2333.4166458385403, 2337.1936681844736, 2344.976550234493, 8572.399932004762, 8841.734747957571, 8870.42342247752, 8899.288056955427, 8910.0, 5238.1618940969265, 5463.863403414906, 5466.562674985991, 5467.5625949923915, 5471.699056551888, 3618.9819050904575, 3644.5990940996735, 3688.5942546319884, 3699.741018128732, 3707.258548290353, 1087.7280679830042, 1088.3633074651339, 1095.3263742798122, 1106.5573770491787, 1110.5113749949996, 11620.360880151587, 11739.58911438104, 11746.706332341675, 11761.529538818437, 11791.056715462742, 4403.3174857897075, 4500.707454015478, 4629.699069560468, 4664.160451118763, 4669.0195059037305, 9300.441973481613, 9307.90692093084, 9309.44143351401, 9311.90688093124, 9317.276303883804, 5246.344206974129, 5248.580113590903, 5249.7112658804135, 5251.52736253739, 5252.684838909678, 4459.130469558405, 4616.976915115403, 4794.448638406628, 4899.632527560376, 4948.826791062295, 3799.0, 3804.1440675848266, 3897.629725176104, 3912.7065470089856, 3933.4099885017476, 1156.3004382348727, 1163.5404015413865, 1166.9183157179086, 1177.776222517736, 1181.8168183931505, 664.6311297230084, 815.8537255156515, 815.9796005099802, 823.7940514871282, 969.5879251318162, 5815.621984570989, 6027.186329845415, 6031.909521357183, 6065.2418447694035, 6068.51451883857, 887.3921363865644, 945.8864936207699, 991.8413053911339, 1004.4826914139225, 1008.8385858262643, 4021.195760847842, 4071.8574849880706, 4081.3673880548563, 4110.219058378899, 4142.2543942090115, 10728.463576821128, 11051.889481105087, 11077.0, 11090.001899828892, 11111.111111111091, 11098.112151028059, 11098.2786118902, 11108.888911110786, 11109.388983605817, 11120.721981950353, 7386.815329616803, 8251.587420629065, 8260.545669988209, 8262.132476090044, 8336.208060234268, 5979.431953964367, 6091.968950738826, 6109.389061093855, 6143.1706719592285, 6284.183056202664, 9729.027097290214, 9921.107100361001, 10187.796244075225, 10195.388276703421, 10196.949015254877, 10137.23970702215, 10421.843672344768, 10428.947855260978, 10468.581256749645, 10501.842472363074, 8220.260176584135, 8377.83244335122, 8390.370722195981, 8470.068292487698, 8476.957615211884, 8380.58097095155, 8380.832383352421, 8491.15088491146, 8496.320294376312, 8511.702090426805, 6151.784687535915, 7492.62536873165, 7569.545827250383, 7634.4655874088285, 7708.113566939649, 7451.254874512505, 7568.091828980338, 7627.008488896408, 7690.846183076195, 7705.22947705225, 7103.183133939457, 7146.285371462812, 7192.532485388433, 7214.386777124003, 7223.097112860893, 4867.902641947212, 5024.598032157492, 5053.3829174665225, 5064.544191022678, 5069.8732531687, 5082.898342033212, 5090.363704536933, 5107.055194788976, 5185.429602743619, 5264.131418315935], "value": 4625.785107095113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.78607899759186, 312.5343238574525, 314.34302308175916, 317.5696930658957, 324.1782082421063, 4469.642428605712, 5408.945910540899, 5411.107167317393, 5462.808801691942, 5496.697681627509, 5325.3077099977045, 5332.680039197641, 5335.893282134355, 5353.625246232756, 5391.406945236021, 3104.4567200739893, 3883.922321553567, 4011.3180759270995, 4083.8162282697203, 4090.6931980101613, 3986.9235306467235, 4001.6398524132824, 4049.3802479008486, 4093.9795301023455, 4170.749755014694, 4021.2158629067344, 4023.316036273826, 4058.370952502367, 4062.6140516651017, 4069.165821006701, 3603.261331427051, 3816.4924854682386, 4031.153457773871, 4043.9191216175514, 4055.513338399396, 3466.3933811582874, 3480.6693364130365, 3500.457429098494, 3523.4010218262956, 3550.9822450887837, 4326.783660816978, 4867.586255168315, 5158.613104017214, 5167.689938603658, 5232.764525596357, 4098.7130900836955, 4297.634701050414, 4646.279826626878, 4647.071465341879, 4671.299305104228, 4505.166544189336, 5297.046531624306, 5488.941646882419, 5489.58828087891, 5499.972500137514, 3846.211526637033, 3880.9715425412287, 3881.6894648428056, 3883.49514563105, 3931.174453364797, 5474.945250547484, 5756.769729210827, 5800.187973683687, 5835.620684655484, 5958.463738263534, 3850.8652197172964, 3865.1496670732395, 3873.670737987303, 3874.302625527432, 3903.4925459690057, 3319.71782398494, 3625.567900679217, 3768.4347347897487, 3779.848806047782, 3961.7028722845325, 4507.81968721254, 4564.497905230422, 4583.587477127075, 4607.493175750665, 4620.3762492063815, 4707.670463067552, 4868.099401610713, 5301.363836339664, 5604.0, 5635.943640563624, 1306.2554344024006, 1332.4144268320667, 1333.7862545083926, 1340.3700260877372, 1349.2983648502723, 743.7917383132732, 761.3110135327537, 792.3779832831315, 793.4287313134533, 798.2775588092785, 4887.608991280689, 5059.215821547666, 5112.386513618315, 5120.590352771769, 5239.659422137549, 4889.926651100236, 5054.0, 5067.949320506821, 5707.857303567444, 5786.392428795, 4897.96326277703, 4939.839137802614, 5208.635395522277, 5212.3223980883, 5218.478152184751, 2563.6026415905562, 2601.6877974642784, 2905.1575043237226, 3019.7282244597654, 3023.56158357037, 5582.7766889323975, 5593.328800543961, 5910.408959104056, 5919.348871624118, 6036.0, 5290.289038320955, 5605.831825045255, 5664.631798933056, 5748.971255143698, 5895.557833162444, 5281.973590132025, 5853.912191317134, 5899.0, 5929.9110513341475, 5969.522438204933, 4677.7894994726, 5324.414314425333, 5506.614536982373, 5725.427457254241, 5772.191893135048, 5676.9497642936185, 5700.144978253129, 5716.171155182392, 5722.284714410699, 5759.366469688414, 5057.0138822929175, 5205.817796377109, 5451.754671039734, 5794.667226537836, 5942.405759424023, 2880.1791489425414, 3210.341879914565, 3345.3476572069185, 3353.7987720736833, 3357.966420335863, 4282.8008157715885, 4652.511486294025, 4812.470628230962, 4816.446108697404, 4834.564889159992, 3982.323005089084, 4058.776767277828, 4152.380571577148, 4426.830852882129, 4648.976755116203, 4412.132114125716, 4675.438947326343, 4676.8129274828625, 4678.485366609602, 4742.174262908874, 4080.8367665294245, 4454.398656181504, 5533.501984821385, 5549.7780088796, 5555.8888822224135, 4384.49578298487, 5010.348654675015, 5461.832137360242, 5604.6917419542315, 5628.212050313042, 4764.927891224447, 4833.468318485035, 5123.797626483448, 5498.625343664084, 5536.612437129363], "value": 3966.067491320486, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.3675398109852, 757.6552668535816, 794.6503538443085, 859.2996707683242, 862.4609618988133, 14909.030912990662, 16154.111523866182, 16174.191290435461, 16179.271932763024, 16193.514194574152, 12894.484220631188, 14843.257837108154, 14883.479078232263, 14891.25543722815, 14903.552893413214, 707.6992278281723, 732.4287056096254, 892.6429428228728, 922.423485321674, 927.8469052606312, 909.2135302962922, 915.7390143809016, 916.1525588830306, 916.8716379706847, 919.5448253114685, 951.3483263964207, 967.274544091931, 967.89837067108, 968.0513097164747, 970.2044323654607, 745.1840234942729, 772.6638912073266, 772.7720322504874, 963.205355581648, 964.0793042644258, 572.9656220626734, 700.3626699703296, 718.4109030594914, 724.5833645653718, 726.8800647893091, 14918.477853275192, 14960.850391496055, 14968.47610333633, 14971.550853474413, 14972.700545989019, 11602.593909213138, 13975.763877779507, 14000.789988150185, 14057.367418466194, 14086.859131408657, 12551.246925184518, 12637.557685480964, 12677.36613169347, 12899.742005159844, 12902.354882255944, 4491.708038977456, 4533.705309154894, 4606.193916064675, 4677.859664210045, 4702.036082603058, 112429.31356029748, 114071.71856562822, 115241.4237928805, 115260.66066366347, 115301.0, 9467.905320946702, 10724.571017159225, 11082.501287442085, 11256.212065155525, 11659.533618655329, 21936.670949935764, 22253.77746222517, 22312.442188945406, 22324.55350892973, 22326.776732232796, 7812.57030863308, 8087.595620218965, 8100.513969161869, 8196.918030819616, 8203.179682031869, 4389.670774691847, 4498.145352383037, 4512.120136573337, 4577.862664120082, 4679.883002924954, 3604.621514740967, 3685.9262814743556, 3721.7394782364972, 3939.074317535377, 4111.650509706708, 754.3399525415261, 755.2824816424419, 760.2663429790279, 765.689895592286, 773.8723110686674, 660.4419265720459, 678.959262444245, 682.8395327098128, 697.3096634331974, 708.2386434582844, 8151.592420378957, 8185.754427367188, 9863.260255480867, 9991.550380232908, 9994.450305233282, 612.3753771153398, 642.1876326447049, 681.993180068203, 686.4954258619911, 711.3313485323779, 4442.622377097983, 4456.64346852251, 4691.7184968901975, 4744.928826067612, 4835.492273311322, 119334.0, 120047.39976300062, 165339.4660533937, 177663.0, 179140.0, 93493.0, 105251.0, 150569.43736189228, 151231.9999999978, 153263.0, 93332.0, 98520.0, 103962.0, 105482.47258763657, 107207.0, 9737.80524389494, 9910.950445248016, 9916.652917147865, 9934.254930880068, 9965.40207587547, 62377.06434403578, 62658.68670656617, 63098.68450657717, 65693.67153164203, 67632.0, 92957.07042929484, 98885.0, 100442.0, 100819.48770768591, 105156.0, 13355.265460399383, 13443.126196797177, 13615.79576306376, 14077.587667288097, 14319.713605728035, 15024.924875375553, 15226.619334516725, 15346.539603811903, 15446.30491627857, 15548.222588870738, 14066.226357550433, 14290.285485725464, 14297.571072867831, 14308.0, 14710.632234193803, 14143.504977325743, 14451.421943122157, 14609.634759131106, 14845.832504475917, 14898.1806000671, 13742.65643358924, 14100.78848817248, 14175.00774945744, 14395.928020359832, 14597.635059123182, 11455.713607159554, 11573.594924177612, 11611.419429028683, 11771.764564708828, 11781.0, 8871.290296776371, 10453.216008799218, 10610.946945265225, 11233.943830280796, 11358.772824543297], "value": 8107.569200514888, "unit": "score"}], "name": "dromaeo_css", "value": 7446.766747524596, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 113.0, 110.0, 112.0, 112.0, 113.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 108.0, 161.0, 141.0, 94.0, 150.0, 137.0, 143.0, 135.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 186.0, 184.0, 183.0, 180.0, 182.0, 184.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 58.0, 107.0, 58.0, 58.0, 61.0, 67.0, 85.0, 57.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 92.0, 71.0, 70.0, 68.0, 96.0, 71.0, 66.0, 101.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 89.0, 71.0, 117.0, 130.0, 90.0, 86.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 84.0, 84.0, 86.0, 86.0, 86.0, 87.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 53.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 72.0, 75.0, 72.0, 69.0, 70.0, 69.0, 70.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 82.0, 81.0, 81.0, 75.0, 82.0, 92.0, 99.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 135.0, 138.0, 132.0, 155.0, 146.0, 157.0, 226.0, 137.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 162.0, 192.0, 161.0, 163.0, 164.0, 161.0, 163.0, 163.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1444.0, "alertThreshold": 2.0}]} 12:21:44 INFO - Return code: 0 12:21:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:21:44 INFO - # TBPL SUCCESS # 12:21:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:44 INFO - 2016-07-05 12:21:44.821 screenresolution[1661:9455] starting screenresolution argv=screenresolution get 12:21:44 INFO - 2016-07-05 12:21:44.824 screenresolution[1661:9455] Display 0: 1600x1200x32@60 12:21:44 INFO - 2016-07-05 12:21:44.830 screenresolution[1662:9458] starting screenresolution argv=screenresolution list 12:21:44 INFO - Available Modes on Display 0 12:21:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:45 INFO - Intel Iris: 12:21:45 INFO - Chipset Model: Intel Iris 12:21:45 INFO - Type: GPU 12:21:45 INFO - Bus: Built-In 12:21:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:45 INFO - Vendor: Intel (0x8086) 12:21:45 INFO - Device ID: 0x0a2e 12:21:45 INFO - Revision ID: 0x0009 12:21:45 INFO - Displays: 12:21:45 INFO - Display: 12:21:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:45 INFO - Display Serial Number: 1600x1200 60 12:21:45 INFO - Main Display: Yes 12:21:45 INFO - Mirror: Off 12:21:45 INFO - Online: Yes 12:21:45 INFO - Rotation: Supported 12:21:45 INFO - Return code: 0 12:21:45 INFO - Running post-action listener: _resource_record_post_action 12:21:45 INFO - ##### 12:21:45 INFO - ##### Finished run-tests step (success) 12:21:45 INFO - ##### 12:21:45 INFO - Running post-run listener: _resource_record_post_run 12:21:45 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37664256; Write bytes: 704385024; Read time: 390; Write time: 2122 12:21:45 INFO - TinderboxPrint: CPU usage
53.5% 12:21:45 INFO - TinderboxPrint: I/O read bytes / time
37,664,256 / 390 12:21:45 INFO - TinderboxPrint: I/O write bytes / time
704,385,024 / 2,122 12:21:45 INFO - TinderboxPrint: CPU idle
2,251.9 (46.5%) 12:21:45 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 12:21:45 INFO - TinderboxPrint: CPU user
1,627.5 (33.6%) 12:21:45 INFO - TinderboxPrint: Swap in / out
444,506,112 / 0 12:21:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390357504; Write bytes: 384706048; Read time: 29096; Write time: 716 12:21:45 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36685312; Write bytes: 294357504; Read time: 375; Write time: 1296 12:21:45 INFO - Running post-run listener: _upload_blobber_files 12:21:45 INFO - Blob upload gear active. 12:21:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:45 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 12:21:45 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'] 12:21:45 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 12:21:45 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:21:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:47 INFO - (blobuploader) - INFO - Done attempting. 12:21:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:21:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:21:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:48 INFO - (blobuploader) - INFO - Done attempting. 12:21:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:21:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:51 INFO - (blobuploader) - INFO - Done attempting. 12:21:51 INFO - (blobuploader) - INFO - Iteration through files over. 12:21:51 INFO - Return code: 0 12:21:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:21:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:21:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ca611fc5e57f1d2222baa3f1b8df83fec9d2bdebf5bbcd998b327f00f5711a2910d1600e98bb6d4d643f32a63691e0fe24e448dc4f99188339cfbabdb2fba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93f31de7f44316ea8e7109caf1053abe2a70c984c0421c4948eee953ae4451244b110c13a2fab3ea53a122ac7be142060ed13dc33fc3a7a21a584752081f202d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f4d836f620ca02ba37514bd6a25a1da1edc62549902508e27c08c2577322903189dd056e1f4c227c38e6c170d4f1fbf10f4045522e177b471b36ee87d63477e"} 12:21:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:21:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:21:51 INFO - Contents: 12:21:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/59ca611fc5e57f1d2222baa3f1b8df83fec9d2bdebf5bbcd998b327f00f5711a2910d1600e98bb6d4d643f32a63691e0fe24e448dc4f99188339cfbabdb2fba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93f31de7f44316ea8e7109caf1053abe2a70c984c0421c4948eee953ae4451244b110c13a2fab3ea53a122ac7be142060ed13dc33fc3a7a21a584752081f202d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f4d836f620ca02ba37514bd6a25a1da1edc62549902508e27c08c2577322903189dd056e1f4c227c38e6c170d4f1fbf10f4045522e177b471b36ee87d63477e"} 12:21:51 INFO - Running post-run listener: copy_logs_to_upload_dir 12:21:51 INFO - Copying logs to upload dir... 12:21:51 INFO - mkdir: /builds/slave/test/build/upload/logs 12:21:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.085585 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-05 12:21:51.574201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:21:51.578077) ========= 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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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/59ca611fc5e57f1d2222baa3f1b8df83fec9d2bdebf5bbcd998b327f00f5711a2910d1600e98bb6d4d643f32a63691e0fe24e448dc4f99188339cfbabdb2fba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93f31de7f44316ea8e7109caf1053abe2a70c984c0421c4948eee953ae4451244b110c13a2fab3ea53a122ac7be142060ed13dc33fc3a7a21a584752081f202d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f4d836f620ca02ba37514bd6a25a1da1edc62549902508e27c08c2577322903189dd056e1f4c227c38e6c170d4f1fbf10f4045522e177b471b36ee87d63477e"} build_url:https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/Yb5hnr2RRvm46R_g5z8z7Q/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/59ca611fc5e57f1d2222baa3f1b8df83fec9d2bdebf5bbcd998b327f00f5711a2910d1600e98bb6d4d643f32a63691e0fe24e448dc4f99188339cfbabdb2fba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93f31de7f44316ea8e7109caf1053abe2a70c984c0421c4948eee953ae4451244b110c13a2fab3ea53a122ac7be142060ed13dc33fc3a7a21a584752081f202d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f4d836f620ca02ba37514bd6a25a1da1edc62549902508e27c08c2577322903189dd056e1f4c227c38e6c170d4f1fbf10f4045522e177b471b36ee87d63477e"}' ========= master_lag: 0.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:21:52.061158) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:21:52.061510) ========= 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.aClPBLvdyg/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.NHgnVzCoJ1/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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.005130 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:21:52.088390) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:21:52.088757) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:21:52.089104) ========= ========= Total master_lag: 0.60 =========