builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0192 starttime: 1467746567.39 results: success (0) buildid: 20160705095323 builduid: 30ca9cf586024eab854ec351beeebd97 revision: 5abbfd6f277958e00df60b8118280dc24bac6377 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.391805) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.392236) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.392505) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.414951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.415254) ========= 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:22:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 33.3M=0s 2016-07-05 12:22:47 (33.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095031 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.531924) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.532230) ========= 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.020716 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:47.580494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 12:22:47.580848) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5abbfd6f277958e00df60b8118280dc24bac6377 --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 5abbfd6f277958e00df60b8118280dc24bac6377 --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:22:47,654 truncating revision to first 12 chars 2016-07-05 12:22:47,654 Setting DEBUG logging. 2016-07-05 12:22:47,654 attempt 1/10 2016-07-05 12:22:47,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5abbfd6f2779?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 12:22:48,864 unpacking tar archive at: fx-team-5abbfd6f2779/testing/mozharness/ program finished with exit code 0 elapsedTime=1.470917 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 12:22:49.073679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:49.074066) ========= script_repo_revision: 5abbfd6f277958e00df60b8118280dc24bac6377 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:49.074531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:49.074995) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 12:22:49.094678) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-05 12:22:49.095295) ========= /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:22:49 INFO - MultiFileLogger online at 20160705 12:22:49 in /builds/slave/test 12:22:49 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:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:49 INFO - {'append_to_log': False, 12:22:49 INFO - 'base_work_dir': '/builds/slave/test', 12:22:49 INFO - 'blob_upload_branch': 'Fx-Team', 12:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:49 INFO - 'branch': 'Fx-Team', 12:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:49 INFO - 'config_files': ('talos/mac_config.py',), 12:22:49 INFO - 'default_actions': ('clobber', 12:22:49 INFO - 'read-buildbot-config', 12:22:49 INFO - 'download-and-extract', 12:22:49 INFO - 'populate-webroot', 12:22:49 INFO - 'create-virtualenv', 12:22:49 INFO - 'install', 12:22:49 INFO - 'run-tests'), 12:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:49 INFO - 'download_minidump_stackwalk': True, 12:22:49 INFO - 'download_symbols': 'ondemand', 12:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:49 INFO - 'installer_path': 'installer.exe', 12:22:49 INFO - 'log_level': 'info', 12:22:49 INFO - 'log_name': 'talos', 12:22:49 INFO - 'log_to_console': True, 12:22:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:22:49 INFO - 'opt_config_files': (), 12:22:49 INFO - 'pip_index': False, 12:22:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:49 INFO - 'cmd': ('bash', 12:22:49 INFO - '-c', 12:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:49 INFO - 'enabled': True, 12:22:49 INFO - 'halt_on_failure': False, 12:22:49 INFO - 'name': 'check_screen_resolution'},), 12:22:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:22:49 INFO - 'cmd': ('bash', 12:22:49 INFO - '-c', 12:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:22:49 INFO - 'enabled': True, 12:22:49 INFO - 'halt_on_failure': False, 12:22:49 INFO - 'name': 'check_screen_resolution'},), 12:22:49 INFO - 'run_cmd_checks_enabled': True, 12:22:49 INFO - 'sps_profile': False, 12:22:49 INFO - 'sps_profile_interval': 0, 12:22:49 INFO - 'suite': 'dromaeojs', 12:22:49 INFO - 'system_bits': '32', 12:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:49 INFO - 'title': 't-yosemite-r7-0192', 12:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:49 INFO - 'use_talos_json': True, 12:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:49 INFO - 'work_dir': 'build'} 12:22:49 INFO - ##### 12:22:49 INFO - ##### Running clobber step. 12:22:49 INFO - ##### 12:22:49 INFO - Running pre-action listener: _resource_record_pre_action 12:22:49 INFO - Running main action method: clobber 12:22:49 INFO - rmtree: /builds/slave/test/build 12:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:50 INFO - Running post-action listener: _resource_record_post_action 12:22:50 INFO - ##### 12:22:50 INFO - ##### Finished clobber step (success) 12:22:50 INFO - ##### 12:22:50 INFO - ##### 12:22:50 INFO - ##### Running read-buildbot-config step. 12:22:50 INFO - ##### 12:22:50 INFO - Running pre-action listener: _resource_record_pre_action 12:22:50 INFO - Running main action method: read_buildbot_config 12:22:50 INFO - Using buildbot properties: 12:22:50 INFO - { 12:22:50 INFO - "project": "", 12:22:50 INFO - "product": "firefox", 12:22:50 INFO - "script_repo_revision": "production", 12:22:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:22:50 INFO - "repository": "", 12:22:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:22:50 INFO - "buildid": "20160705095323", 12:22:50 INFO - "pgo_build": "False", 12:22:50 INFO - "basedir": "/builds/slave/test", 12:22:50 INFO - "buildnumber": 79, 12:22:50 INFO - "slavename": "t-yosemite-r7-0192", 12:22:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:22:50 INFO - "platform": "yosemite_r7", 12:22:50 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:22:50 INFO - "branch": "fx-team", 12:22:50 INFO - "repo_path": "integration/fx-team", 12:22:50 INFO - "revision": "5abbfd6f277958e00df60b8118280dc24bac6377", 12:22:50 INFO - "stage_platform": "macosx64", 12:22:50 INFO - "builduid": "30ca9cf586024eab854ec351beeebd97", 12:22:50 INFO - "slavebuilddir": "test" 12:22:50 INFO - } 12:22:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:22:50 INFO - Running post-action listener: _resource_record_post_action 12:22:50 INFO - ##### 12:22:50 INFO - ##### Finished read-buildbot-config step (success) 12:22:50 INFO - ##### 12:22:50 INFO - ##### 12:22:50 INFO - ##### Running download-and-extract step. 12:22:50 INFO - ##### 12:22:50 INFO - Running pre-action listener: _resource_record_pre_action 12:22:50 INFO - Running main action method: download_and_extract 12:22:50 INFO - mkdir: /builds/slave/test/build/tests 12:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:50 INFO - https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:51 INFO - Downloaded 1199 bytes. 12:22:51 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:22:51 INFO - Using the following test package requirements: 12:22:51 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:22:51 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:22:51 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:22:51 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'jsshell-mac.zip'], 12:22:51 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:22:51 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:22:51 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:22:51 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:22:51 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:22:51 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:22:51 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:22:51 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:22:51 INFO - https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:22:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:22:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:22:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:53 INFO - Downloaded 18801589 bytes. 12:22: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:22: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:22:56 INFO - Return code: 0 12:22:56 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:22:56 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:22:56 INFO - https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:22:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:22:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:22:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:22:57 INFO - Downloaded 18801589 bytes. 12:22:57 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:22:57 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:23:01 INFO - Return code: 0 12:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:01 INFO - https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:23:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:23:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:23:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:23:02 INFO - Downloaded 14403333 bytes. 12:23:02 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:23:02 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:23:02 INFO - Return code: 0 12:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:02 INFO - https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:23:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:23:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:23:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:23:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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:23:05 INFO - Downloaded 112528243 bytes. 12:23:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:23:05 INFO - mkdir: /builds/slave/test/properties 12:23:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:05 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:05 INFO - Contents: 12:23:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:23:06 INFO - Running post-action listener: _resource_record_post_action 12:23:06 INFO - Running post-action listener: set_extra_try_arguments 12:23:06 INFO - ##### 12:23:06 INFO - ##### Finished download-and-extract step (success) 12:23:06 INFO - ##### 12:23:06 INFO - ##### 12:23:06 INFO - ##### Running populate-webroot step. 12:23:06 INFO - ##### 12:23:06 INFO - Running pre-action listener: _resource_record_pre_action 12:23:06 INFO - Running main action method: populate_webroot 12:23:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:06 INFO - '--tppagecycles', 12:23:06 INFO - '7'], 12:23:06 INFO - 'tests': ['tsvgm']}}, 12:23:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['damp', 'tps']}, 12:23:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'tests': ['damp', 'tps']}, 12:23:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['dromaeo_dom']}, 12:23:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['basic_compositor_video']}, 12:23:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['a11yr', 12:23:06 INFO - 'ts_paint', 12:23:06 INFO - 'tpaint', 12:23:06 INFO - 'sessionrestore', 12:23:06 INFO - 'sessionrestore_no_auto_restore', 12:23:06 INFO - 'tabpaint']}, 12:23:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['tsvgx', 12:23:06 INFO - 'tsvgr_opacity', 12:23:06 INFO - 'tart', 12:23:06 INFO - 'tscrollx', 12:23:06 INFO - 'cart']}, 12:23:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:06 INFO - 'tsvgr_opacity', 12:23:06 INFO - 'tart', 12:23:06 INFO - 'tscrollx', 12:23:06 INFO - 'cart']}, 12:23:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'talos_options': ['--disable-e10s'], 12:23:06 INFO - 'tests': ['tp5o']}, 12:23:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'tests': ['tp5o']}, 12:23:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'talos_options': ['--disable-e10s', 12:23:06 INFO - '--xperf_path', 12:23:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:06 INFO - 'tests': ['tp5n']}, 12:23:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:23:06 INFO - 'talos_options': ['--xperf_path', 12:23:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:06 INFO - 'tests': ['tp5n']}}, 12:23:06 INFO - 'talos.zip': {'path': '', 12:23:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:06 INFO - Running post-action listener: _resource_record_post_action 12:23:06 INFO - ##### 12:23:06 INFO - ##### Finished populate-webroot step (success) 12:23:06 INFO - ##### 12:23:06 INFO - ##### 12:23:06 INFO - ##### Running create-virtualenv step. 12:23:06 INFO - ##### 12:23:06 INFO - Running pre-action listener: _resource_record_pre_action 12:23:06 INFO - Running main action method: create_virtualenv 12:23:06 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:06 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:23:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:06 INFO - Using real prefix '/tools/python27' 12:23:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:07 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:10 INFO - Installing pip.................done. 12:23:10 INFO - Return code: 0 12:23:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:10 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:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:10 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:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:10 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:10 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:23:10 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:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:10 INFO - 'HOME': '/Users/cltbld', 12:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:10 INFO - 'LOGNAME': 'cltbld', 12:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:10 INFO - 'MOZ_NO_REMOTE': '1', 12:23:10 INFO - 'NO_EM_RESTART': '1', 12:23:10 INFO - 'PAGER': '/bin/cat', 12:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:10 INFO - 'PWD': '/builds/slave/test', 12:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:10 INFO - 'SHELL': '/bin/bash', 12:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:10 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:10 INFO - 'USER': 'cltbld', 12:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:10 INFO - 'XPC_FLAGS': '0x0', 12:23:10 INFO - 'XPC_SERVICE_NAME': '0', 12:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:10 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:23:10 INFO - Cleaning up... 12:23:10 INFO - Return code: 0 12:23:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23:10 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:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:10 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:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:10 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:10 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:23:10 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:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:10 INFO - 'HOME': '/Users/cltbld', 12:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:10 INFO - 'LOGNAME': 'cltbld', 12:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:10 INFO - 'MOZ_NO_REMOTE': '1', 12:23:10 INFO - 'NO_EM_RESTART': '1', 12:23:10 INFO - 'PAGER': '/bin/cat', 12:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:10 INFO - 'PWD': '/builds/slave/test', 12:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:10 INFO - 'SHELL': '/bin/bash', 12:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:10 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:10 INFO - 'USER': 'cltbld', 12:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:10 INFO - 'XPC_FLAGS': '0x0', 12:23:10 INFO - 'XPC_SERVICE_NAME': '0', 12:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:10 INFO - Downloading/unpacking psutil>=3.1.1 12:23:10 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:23:10 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:23:10 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:23:10 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:23:10 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:23:10 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:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:13 INFO - Installing collected packages: psutil 12:23:13 INFO - Running setup.py install for psutil 12:23:13 INFO - building 'psutil._psutil_osx' extension 12:23:13 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:23:14 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:23:14 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:23:14 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:23:14 INFO - building 'psutil._psutil_posix' extension 12:23:14 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:23:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:23:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:23:14 INFO - ^ 12:23:14 INFO - 1 warning generated. 12:23:14 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:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:14 INFO - Successfully installed psutil 12:23:14 INFO - Cleaning up... 12:23:14 INFO - Return code: 0 12:23:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23: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:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:23: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 mozsystemmonitor==0.3 12:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:14 INFO - 'HOME': '/Users/cltbld', 12:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:14 INFO - 'LOGNAME': 'cltbld', 12:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:14 INFO - 'MOZ_NO_REMOTE': '1', 12:23:14 INFO - 'NO_EM_RESTART': '1', 12:23:14 INFO - 'PAGER': '/bin/cat', 12:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:14 INFO - 'PWD': '/builds/slave/test', 12:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:14 INFO - 'SHELL': '/bin/bash', 12:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:14 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:14 INFO - 'USER': 'cltbld', 12:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:14 INFO - 'XPC_FLAGS': '0x0', 12:23:14 INFO - 'XPC_SERVICE_NAME': '0', 12:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23: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:23: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:23: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:23: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:23: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:23: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:23:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:17 INFO - Installing collected packages: mozsystemmonitor 12:23:17 INFO - Running setup.py install for mozsystemmonitor 12:23:17 INFO - Successfully installed mozsystemmonitor 12:23:17 INFO - Cleaning up... 12:23:17 INFO - Return code: 0 12:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:17 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:17 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:23:17 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:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:17 INFO - 'HOME': '/Users/cltbld', 12:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:17 INFO - 'LOGNAME': 'cltbld', 12:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:17 INFO - 'MOZ_NO_REMOTE': '1', 12:23:17 INFO - 'NO_EM_RESTART': '1', 12:23:17 INFO - 'PAGER': '/bin/cat', 12:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:17 INFO - 'PWD': '/builds/slave/test', 12:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:17 INFO - 'SHELL': '/bin/bash', 12:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:17 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:17 INFO - 'USER': 'cltbld', 12:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:17 INFO - 'XPC_FLAGS': '0x0', 12:23:17 INFO - 'XPC_SERVICE_NAME': '0', 12:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 12:23: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:23: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:23: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:23: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:23: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:23: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:23:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23: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:23: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:23: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:23: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:23: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:23: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:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:20 INFO - warning: no files found matching '*.txt' 12:23:20 INFO - no previously-included directories found matching 'build' 12:23:20 INFO - no previously-included directories found matching 'dist' 12:23:20 INFO - no previously-included directories found matching '.git*' 12:23:20 INFO - Installing collected packages: jsonschema, functools32 12:23:20 INFO - Running setup.py install for functools32 12:23:21 INFO - warning: no files found matching '*.txt' 12:23:21 INFO - no previously-included directories found matching 'build' 12:23:21 INFO - no previously-included directories found matching 'dist' 12:23:21 INFO - no previously-included directories found matching '.git*' 12:23:21 INFO - Successfully installed jsonschema functools32 12:23:21 INFO - Cleaning up... 12:23:21 INFO - Return code: 0 12:23:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23:21 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:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:21 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:21 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:23:21 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:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:21 INFO - 'HOME': '/Users/cltbld', 12:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:21 INFO - 'LOGNAME': 'cltbld', 12:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:21 INFO - 'MOZ_NO_REMOTE': '1', 12:23:21 INFO - 'NO_EM_RESTART': '1', 12:23:21 INFO - 'PAGER': '/bin/cat', 12:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:21 INFO - 'PWD': '/builds/slave/test', 12:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:21 INFO - 'SHELL': '/bin/bash', 12:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:21 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:21 INFO - 'USER': 'cltbld', 12:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:21 INFO - 'XPC_FLAGS': '0x0', 12:23:21 INFO - 'XPC_SERVICE_NAME': '0', 12:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:21 INFO - Downloading/unpacking blobuploader==1.2.4 12:23: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:23: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:23: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:23: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:23: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:23: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:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:23 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:23:23 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:23:23 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:23:23 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:23:23 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:23:23 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:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:24 INFO - Downloading docopt-0.6.1.tar.gz 12:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:25 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:25 INFO - Running setup.py install for blobuploader 12:23:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:25 INFO - Running setup.py install for requests 12:23:25 INFO - Running setup.py install for docopt 12:23:25 INFO - Successfully installed blobuploader requests docopt 12:23:25 INFO - Cleaning up... 12:23:26 INFO - Return code: 0 12:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:26 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:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:26 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:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:26 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:26 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:23:26 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:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:26 INFO - 'HOME': '/Users/cltbld', 12:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:26 INFO - 'LOGNAME': 'cltbld', 12:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:26 INFO - 'MOZ_NO_REMOTE': '1', 12:23:26 INFO - 'NO_EM_RESTART': '1', 12:23:26 INFO - 'PAGER': '/bin/cat', 12:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:26 INFO - 'PWD': '/builds/slave/test', 12:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:26 INFO - 'SHELL': '/bin/bash', 12:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:26 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:26 INFO - 'USER': 'cltbld', 12:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:26 INFO - 'XPC_FLAGS': '0x0', 12:23:26 INFO - 'XPC_SERVICE_NAME': '0', 12:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-4WUUq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-b_edCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-JUP2JD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-L6TKhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-JGKO6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-3hXqt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:26 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-xXsJQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-oZQ28t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Kk1q2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-_q1x5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-u1xHeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ZOXpV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-PFvWic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ihySSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-5QmelZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ddTOC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:27 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-LNaS2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:28 INFO - Running setup.py install for manifestparser 12:23:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Running setup.py install for mozcrash 12:23:28 INFO - Running setup.py install for mozdebug 12:23:28 INFO - Running setup.py install for mozdevice 12:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Running setup.py install for mozfile 12:23:28 INFO - Running setup.py install for mozhttpd 12:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:28 INFO - Running setup.py install for mozinfo 12:23:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Running setup.py install for mozInstall 12:23:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Running setup.py install for mozleak 12:23:29 INFO - Running setup.py install for mozlog 12:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Running setup.py install for moznetwork 12:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Running setup.py install for mozprocess 12:23:29 INFO - Running setup.py install for mozprofile 12:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:30 INFO - Running setup.py install for mozrunner 12:23:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:30 INFO - Running setup.py install for mozscreenshot 12:23:30 INFO - Running setup.py install for moztest 12:23:30 INFO - Running setup.py install for mozversion 12:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:30 INFO - Cleaning up... 12:23:30 INFO - Return code: 0 12:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:30 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:30 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:23:30 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:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:30 INFO - 'HOME': '/Users/cltbld', 12:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:30 INFO - 'LOGNAME': 'cltbld', 12:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:30 INFO - 'MOZ_NO_REMOTE': '1', 12:23:30 INFO - 'NO_EM_RESTART': '1', 12:23:30 INFO - 'PAGER': '/bin/cat', 12:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:30 INFO - 'PWD': '/builds/slave/test', 12:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:30 INFO - 'SHELL': '/bin/bash', 12:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:30 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:30 INFO - 'USER': 'cltbld', 12:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:30 INFO - 'XPC_FLAGS': '0x0', 12:23:30 INFO - 'XPC_SERVICE_NAME': '0', 12:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:30 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-siqak7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-U6vBkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-VL3x2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-u4iykO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-V9B8Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Z6Zkls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-vYwpI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ux2V1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-z6jQeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-hKkHVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:31 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:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:31 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-rnfzjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-NKWeGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-rnvRR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-xFpJmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-1pyVtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-r8QLDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:32 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:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:32 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-bgigcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:32 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:23:35 INFO - Downloading blessings-1.6.tar.gz 12:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:35 INFO - Installing collected packages: blessings 12:23:35 INFO - Running setup.py install for blessings 12:23:35 INFO - Successfully installed blessings 12:23:35 INFO - Cleaning up... 12:23:35 INFO - Return code: 0 12:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:35 INFO - Reading from file tmpfile_stdout 12:23:35 INFO - Current package versions: 12:23:35 INFO - blessings == 1.6 12:23:35 INFO - blobuploader == 1.2.4 12:23:35 INFO - docopt == 0.6.1 12:23:35 INFO - functools32 == 3.2.3-2 12:23:35 INFO - jsonschema == 2.5.1 12:23:35 INFO - manifestparser == 1.1 12:23:35 INFO - mozInstall == 1.12 12:23:35 INFO - mozcrash == 0.17 12:23:35 INFO - mozdebug == 0.1 12:23:35 INFO - mozdevice == 0.48 12:23:35 INFO - mozfile == 1.2 12:23:35 INFO - mozhttpd == 0.7 12:23:35 INFO - mozinfo == 0.9 12:23:35 INFO - mozleak == 0.1 12:23:35 INFO - mozlog == 3.2 12:23:35 INFO - moznetwork == 0.27 12:23:35 INFO - mozprocess == 0.22 12:23:35 INFO - mozprofile == 0.28 12:23:35 INFO - mozrunner == 6.11 12:23:35 INFO - mozscreenshot == 0.1 12:23:35 INFO - mozsystemmonitor == 0.3 12:23:35 INFO - moztest == 0.7 12:23:35 INFO - mozversion == 1.4 12:23:35 INFO - psutil == 3.1.1 12:23:35 INFO - requests == 1.2.3 12:23:35 INFO - wsgiref == 0.1.2 12:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:35 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:35 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:23:35 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:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:35 INFO - 'HOME': '/Users/cltbld', 12:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:35 INFO - 'LOGNAME': 'cltbld', 12:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:35 INFO - 'MOZ_NO_REMOTE': '1', 12:23:35 INFO - 'NO_EM_RESTART': '1', 12:23:35 INFO - 'PAGER': '/bin/cat', 12:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:35 INFO - 'PWD': '/builds/slave/test', 12:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:35 INFO - 'SHELL': '/bin/bash', 12:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:35 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:35 INFO - 'USER': 'cltbld', 12:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:35 INFO - 'XPC_FLAGS': '0x0', 12:23:35 INFO - 'XPC_SERVICE_NAME': '0', 12:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 INFO - Cleaning up... 12:23:36 INFO - Return code: 0 12:23:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:36 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:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:36 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:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:36 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 0x107b3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff45acc41a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, '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:23:36 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:23:36 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:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:36 INFO - 'HOME': '/Users/cltbld', 12:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:36 INFO - 'LOGNAME': 'cltbld', 12:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:36 INFO - 'MOZ_NO_REMOTE': '1', 12:23:36 INFO - 'NO_EM_RESTART': '1', 12:23:36 INFO - 'PAGER': '/bin/cat', 12:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:36 INFO - 'PWD': '/builds/slave/test', 12:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:36 INFO - 'SHELL': '/bin/bash', 12:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:23:36 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:36 INFO - 'USER': 'cltbld', 12:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:36 INFO - 'XPC_FLAGS': '0x0', 12:23:36 INFO - 'XPC_SERVICE_NAME': '0', 12:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:23:36 INFO - Cleaning up... 12:23:36 INFO - Return code: 0 12:23:36 INFO - Running post-action listener: _resource_record_post_action 12:23:36 INFO - Running post-action listener: _start_resource_monitoring 12:23:36 INFO - Starting resource monitoring. 12:23:36 INFO - ##### 12:23:36 INFO - ##### Finished create-virtualenv step (success) 12:23:36 INFO - ##### 12:23:36 INFO - ##### 12:23:36 INFO - ##### Running install step. 12:23:36 INFO - ##### 12:23:36 INFO - Running pre-action listener: _resource_record_pre_action 12:23:36 INFO - Running main action method: install 12:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:36 INFO - Reading from file tmpfile_stdout 12:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:36 INFO - Reading from file tmpfile_stdout 12:23:36 INFO - Output received: 12:23:36 INFO - Usage: mozinstall [options] installer 12:23:36 INFO - Options: 12:23:36 INFO - -h, --help show this help message and exit 12:23:36 INFO - -d DEST, --destination=DEST 12:23:36 INFO - Directory to install application into. [default: 12:23:36 INFO - "/builds/slave/test"] 12:23:36 INFO - --app=APP Application being installed. [default: firefox] 12:23:36 INFO - mkdir: /builds/slave/test/build/application 12:23:36 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:23:36 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:24:11 INFO - Reading from file tmpfile_stdout 12:24:11 INFO - Output received: 12:24:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:24:11 INFO - Running post-action listener: _resource_record_post_action 12:24:11 INFO - ##### 12:24:11 INFO - ##### Finished install step (success) 12:24:11 INFO - ##### 12:24:11 INFO - ##### 12:24:11 INFO - ##### Running run-tests step. 12:24:11 INFO - ##### 12:24:11 INFO - Running pre-action listener: _resource_record_pre_action 12:24:11 INFO - Running main action method: run_tests 12:24:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:24:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:24:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:24:11 INFO - 2016-07-05 12:24:11.653 screenresolution[1917:10246] starting screenresolution argv=screenresolution get 12:24:11 INFO - 2016-07-05 12:24:11.656 screenresolution[1917:10246] Display 0: 1600x1200x32@60 12:24:11 INFO - 2016-07-05 12:24:11.662 screenresolution[1918:10248] starting screenresolution argv=screenresolution list 12:24:11 INFO - Available Modes on Display 0 12:24:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:24:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:24:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:24:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:24:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:24:11 INFO - Intel Iris: 12:24:11 INFO - Chipset Model: Intel Iris 12:24:11 INFO - Type: GPU 12:24:11 INFO - Bus: Built-In 12:24:11 INFO - VRAM (Dynamic, Max): 1536 MB 12:24:11 INFO - Vendor: Intel (0x8086) 12:24:11 INFO - Device ID: 0x0a2e 12:24:11 INFO - Revision ID: 0x0009 12:24:11 INFO - Displays: 12:24:11 INFO - Display: 12:24:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:24:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:24:11 INFO - Display Serial Number: 1600x1200 60 12:24:11 INFO - Main Display: Yes 12:24:11 INFO - Mirror: Off 12:24:11 INFO - Online: Yes 12:24:11 INFO - Rotation: Supported 12:24:11 INFO - Return code: 0 12:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:24:11 INFO - Python 2.7.3 12:24:11 INFO - Return code: 0 12:24:11 INFO - grabbing minidump binary from tooltool 12:24: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:24:11 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 0x107aa2d30>, '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 0x107807db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:24:11 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:24:11 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:24:11 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:24:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:24:12 INFO - Return code: 0 12:24:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:24:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:24:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:24:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:24:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:24:12 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/Zb9Wb-AVTBS8eCkgS845Jg/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:24:12 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/Zb9Wb-AVTBS8eCkgS845Jg/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:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aClPBLvdyg/Render', 12:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:24:12 INFO - 'HOME': '/Users/cltbld', 12:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:24:12 INFO - 'LOGNAME': 'cltbld', 12:24:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:12 INFO - 'MOZ_NO_REMOTE': '1', 12:24:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:12 INFO - 'NO_EM_RESTART': '1', 12:24:12 INFO - 'PAGER': '/bin/cat', 12:24:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:12 INFO - 'PWD': '/builds/slave/test', 12:24:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:24:12 INFO - 'SHELL': '/bin/bash', 12:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NHgnVzCoJ1/Listeners', 12:24:12 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 12:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:24:12 INFO - 'USER': 'cltbld', 12:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:12 INFO - 'XPC_FLAGS': '0x0', 12:24:12 INFO - 'XPC_SERVICE_NAME': '0', 12:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:24:12 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/Zb9Wb-AVTBS8eCkgS845Jg/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:24:12 INFO - mozversion application_buildid: 20160705095323 12:24:12 INFO - mozversion application_changeset: 5abbfd6f277958e00df60b8118280dc24bac6377 12:24:12 INFO - mozversion application_display_name: Nightly 12:24:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:24:12 INFO - mozversion application_name: Firefox 12:24:12 INFO - mozversion application_remotingname: firefox 12:24:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:24:12 INFO - mozversion application_vendor: Mozilla 12:24:12 INFO - mozversion application_version: 50.0a1 12:24:12 INFO - mozversion platform_buildid: 20160705095323 12:24:12 INFO - mozversion platform_changeset: 5abbfd6f277958e00df60b8118280dc24bac6377 12:24:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:24:12 INFO - mozversion platform_version: 50.0a1 12:24:12 INFO - using testdate: 1467746652 12:24:12 INFO - actual date: 1467746652 12:24:12 INFO - starting webserver on 'localhost:49651' 12:24:12 INFO - SUITE-START | Running 2 tests 12:24:12 INFO - TEST-START | dromaeo_css 12:24:12 INFO - operating with platform_type : mac_ 12:24:12 INFO - Initialising browser for dromaeo_css test... 12:24:12 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmp_ZNWou/profile http://localhost:49651/getInfo.html) 12:24:14 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 12:24:14 INFO - PROCESS | 1928 | colorDepth:24 12:24:14 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 12:24:14 INFO - PROCESS | 1928 | __metrics 12:24:14 INFO - PROCESS | 1928 | 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:24:14 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:24:15 INFO - TEST-INFO | 1928: exit 0 12:24:15 INFO - Browser initialized. 12:24:15 INFO - Running cycle 1/1 for dromaeo_css test... 12:24:15 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmp_ZNWou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:27 INFO - PROCESS | 1930 | RSS: Main: 243851264 12:24:27 INFO - PROCESS | 1930 | 12:25:18 INFO - PROCESS | 1930 | 1467746718244 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:27:29 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49651/tests/dromaeo/cssquery-ext.html) 12:27:31 INFO - PROCESS | 1930 | RSS: Main: 367529984 12:27:31 INFO - PROCESS | 1930 | 12:30:34 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-ext.html (next: http://localhost:49651/tests/dromaeo/cssquery-jquery.html) 12:30:35 INFO - PROCESS | 1930 | RSS: Main: 376979456 12:30:35 INFO - PROCESS | 1930 | 12:33:37 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49651/tests/dromaeo/cssquery-mootools.html) 12:33:38 INFO - PROCESS | 1930 | RSS: Main: 371580928 12:33:38 INFO - PROCESS | 1930 | 12:36:40 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49651/tests/dromaeo/cssquery-prototype.html) 12:36:41 INFO - PROCESS | 1930 | RSS: Main: 366215168 12:36:41 INFO - PROCESS | 1930 | 12:39:44 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49651/tests/dromaeo/cssquery-yui.html) 12:39:44 INFO - PROCESS | 1930 | RSS: Main: 332668928 12:39:44 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-yui.html (next: http://localhost:49651/tests/dromaeo/cssquery-dojo.html) 12:42:48 INFO - PROCESS | 1930 | RSS: Main: 381489152 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | __start_tp_report 12:42:48 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 12:42:48 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 12:42:48 INFO - PROCESS | 1930 | |i|pagename|runs| 12:42:48 INFO - PROCESS | 1930 | |0;dojo.html;3329.384063948168;3331.2671212333285;3340.816255105969;3367.1918739502526;3410.249745056087;5736.885262294752;5768.134779783035;5828.125781132827;5923.229980102581;5935.109733539965;5178.731210853337;5606.299212598426;5851.7074146292625;5871.29544454666;5896.321922978858;5218.086834803894;5232.372115346164;5262.236975638531;5285.180796976475;5348.438413966536;5254.842354729345;5254.894902101955;5256.237845512398;5296.496832800878;5303.734813259341;5240.161574148137;5306.3632364116165;5318.202269659562;5330.92003619946;5346.705931173784;5161.870953226199;5317.787288508455;5328.5470734987575;5336.039512887679;5362.49056339651;5217.321748172713;5231;5231.424543300234;5260.8684782880355;5272.657277276965;5713.400092990218;5816.621919575214;5869.559783016248;5871.4715675589405;5986.910196347058;6111.166351794787;6153.046277826899;6168.722407491674;6214.8135555933395;6234.657093859836;6237.313895471494;6243.8126856194385;6247.687615619201;6265.530085243625;6290.811275661737;5953.3451320354725;5955.196048533479;5972.343042265347;6006.729697163638;6054.96972515139;6195.380461953859;6227.314995350508;6244.500439964792;6287.779927702509;6317.46301564363;6238.65687387189;6257.405546473078;6259.937400625936;6262.12330273762;6263.31103578606;6233.470155036782;6284.340144284875;6332.620042797447;6350.809475715736;6351.269603995507;6091.08883416005;6210.689465526705;6239.594426362272;6266.780662676784;6330.05049242608;6339.144215530937;6339.2709838368255;6346.809595712044;6347.206599175103;6362.300146983828;5033.421156567042;5045.066662667418;5049.9495005049475;5050.873728156752;5087.414947281036;4438.511763705989;4489.393931819228;4491.371208030879;4494.550544945545;4525.959029423185;5788.218590490314;5807.157962095473;5824.6796426196115;5832.679202643895;5859.589828711949;6151.169592105098;6229.813105606839;6297;6304.621722696653;6312.084747711556;5735.569832262597;5738.282714660668;5738.512226460798;5803.593748437654;5827.737751801179;5474.425185355481;5477.616566840283;5484.2047903053835;5489.39616642169;5489.835304940778;6195.4424101831055;6197.628142311476;6280.2777680566405;6316.021016742412;6317.652529110943;6251.093591429127;6313.179286692701;6316.7157477914525;6323.810285691437;6326.304106548184;5571.164325351229;6265.655388953652;6281.842953926189;6303;6316.084167795736;6083.8174854754425;6098.939010609837;6099.551912188739;6242.094896240111;6243.937560624518;5704.5453409382;6060.212172417734;6245.344238855037;6290.779822706183;6296.842578935563;6056.182415373868;6139.969300153471;6142.938570614237;6145.35473775265;6146.508279337731;5975.37258587842;6098.207233059851;6108;6137.662428566472;6139.185504090546;6047.637141771508;6069.3354524685665;6080.543959202989;6100.481459076028;6106.480949119375;6144.6620435874665;6158.230221222348;6159.199304090619;6159.476444502267;6161.137440758387;5875.970620146872;5899.498542623754;5931.466168044896;6000.8799824004145;6014.977453832947 12:42:48 INFO - PROCESS | 1930 | |1;ext.html;486.0885839051778;504.94445610982797;513.7354262554783;517.6402400331771;535.5661913849781;31035;31611.68388316116;40923.99999999996;42134;42299.77002299768;43776.343354849625;44075.77962110193;44092.33861492072;44118;44139;9844.950775246114;10028.799424011515;10037.34757240777;10041;10050.497475126214;8761.51811650359;8987.101289871025;12194.63416097514;12439.315837628927;12529.248245105277;12178.939105304506;12212.145149839514;12228.266304021743;12328.81506777399;12358.44387002587;8779;8957.417767845069;12230.266184028942;12299.877001230006;12309.630711078593;8861.73414797557;8921.01868794432;11762.353070581115;12070.27578345302;12088.576899808464;41949.79025104886;42129.368059478824;42147.157056858996;42180.78909605432;42191.789041054595;3543.5924868640177;3548.3258180945536;3675.4119340905595;3881.359575670011;3886.261610294035;32460;32623.673763262537;32994.6700532994;34069;34449.82775086134;3034.1656044587694;3074.738647214989;3292.242784159657;3426.7429942754393;3477.965220347815;512315.43842280924;525064;525825;526554;527036;70647;70774.29225707677;71452;71647.28352716407;71663;75074.24925750673;75241.24758752451;75477.62261188768;75543.62228188934;75555.24444755592;17506.912465437592;17729.37947171843;17735.46793596194;17735.55661108457;17767.46697599074;12059.698507537207;12262.570810021607;13101.148425352321;13230.470781168839;13386.799198012037;19852.70220946687;20693.27573534919;21114;21309.68035479469;21325.36023919285;7477.813054673567;8773.517456539952;8834.83546531091;8836.602352894135;8959.5968181432;914.881065461499;982.5824024789435;999.7900440907491;1037.6835065305154;1057.9418132002659;29838.10485685433;30516;30566.54150187749;31443.05670829833;31482.212944676565;1083.978320433587;1124.820028795389;1127.7067962329854;1171.0455978377595;1187.7624475105013;14270.643233919027;15766.76349854753;15927.283272252776;16038;16068.919655401882;688962;695683;698496;701451;701816;59235.815283694086;59758;59883.40116598865;60028.09817361858;60172.69913650404;1260.2060701757835;1276.7255040166408;1384.2801743093535;1427.4432971141264;1483.0508474576063;28702.425951480436;32413.83793081114;32530.186745331557;32555.34889302248;33035.00894973155;51074.74462627663;51955.48044519506;52153.7392313036;52224.738876305375;52311.215331769294;567231;650092;659467;660519;662496;43638;44069.33895991627;45724.314135287335;45966.540334597565;46028;42679;48632;49596.76008099826;49737;50488.24267635915;39783.00542486461;40390.79804600958;40466;40797.79601102094;42017.57982420137;41129.588704112575;41371.793141034104;41386.79306603448;41716.791416042724;42265.15469690527;38911.221775564896;39250.82247532579;39577;40150.79924600358;40630.1873962525;35555.28889422249;35593.1101722459;36078.09804754902;36379.636203638685;37026.814865925495;24112.51774964526;25711.614325785504;26465.427167332164;26482.602760958987;26841 12:42:48 INFO - PROCESS | 1930 | |2;jquery.html;363.8762820640982;385.88809245318856;396.30844176911285;397.93434083376246;418.6713429957484;15100.424864827086;16400.26198821053;21944.122235110575;22055;22072.44818879529;21585.244516441926;22245.44386390341;22401.887990560066;22456.77543224567;22553.278709012993;5867;5991.88016239676;6120.846978825521;6122;6122.448979591836;3542.9114272143274;4418.845340413086;6027.487663548603;6157.90763138551;6502.9349706503035;6788.524803263749;6793.5584187027935;6799.796006119825;6809.965950170267;6812.59124452532;6017.886690962164;6761.087253220801;6763.289854565249;6767.560108592926;6768.69540870662;3774.735768496206;5543.362513310981;5880.7353669085;5885.588008839383;5898.970505147447;27566.862165689243;27640;27818.443631127266;27927.441451171067;27941.88232470699;2231.2190733243233;2255.1430456426615;2365.7279412867397;2549.8087643426816;2742.369255071345;15486.380544778196;16104;16107.919460402623;16257.918710406491;16446.895946377314;1836.4398858348331;1853.5183537097735;1858.3124244029796;1911.9713204301947;2105.26315789474;125272.74727252702;130436;130536.34731826375;132599;134201.3289933554;11499.137564682514;11723.59666268413;11822.231554948901;11988.580399685969;12003.699907502383;14736.484223052355;14810.555683329518;14817.962742607913;14828;14831.035982661308;8501.702440414556;8761.737147885446;8786.780330491662;8854.203121719076;8854.981677107215;8743.825123497494;8745.108426750163;8757.168069033432;8859.335549833788;8880.51157186361;4573.565511276423;4632.444106707217;4667.649926255544;4693.22561777303;4700.566327270215;1650.8856521847704;1870.532366908273;1935.380678182968;1945.5038965064102;1947.3768394114031;872.3806097670725;888.626776753765;951.6526467839327;958.2717134977468;981.568110031598;21305.573888522144;22204.111835526397;22272.888635557043;22287.442813929458;22329.665055023863;901.3195037746459;912.4798864647088;952.852307892264;963.8216929867997;1015.2791518022287;11188.720281993015;11636.534538618529;11674.88325116755;11692.47383867728;11710.297382157098;595928;606753;614774;621406;622427;93573;108115.37826932603;129789;132773;133095;863.3395452478859;876.3120950054305;972.4651441707033;990.9603615855285;1000.6347683095763;19386.515337116685;19668.50828729293;20100.899495502428;20103.39689809308;20141;44428.55571444244;58247;58701.412985869596;59941.100883487656;60454.69772651108;84652.57673711592;84756;85369;86445.56777216074;86851.56574217089;8206.343492520464;8226.424150309422;8353.122922093336;8518.23335899772;8528.530930798866;12719.364031798557;13349.59829217957;13416.195028298333;13489.325533723471;13737.107088039245;11567;11612.88387116118;11661.125415594033;11759.294442333487;11784.292942423483;11346.94326528362;11480.94259528697;11534.94232528832;11694.824577631513;11712.238704484182;11185.32888026721;11325.71685707864;11503.712407189887;11586.594469193538;11690.181687281809;8181.79545511367;8234.917650823416;8247.463914845515;8284.46151000183;8319.209675080858;5696.572757043321;8255.339572834278;10848.294860834021;11007.669769906915;11363.659090227306 12:42:48 INFO - PROCESS | 1930 | |3;mootools.html;1567.1302427152932;1590.2128446419022;1787.955301117472;1891.678414669506;1899.3447260695054;6981.790546283613;8687.044425113249;8858.291336693064;8872.556372181389;8891.644334226632;7781.182975787546;7997.360211183106;8424.241818236358;8646.048934617203;8650.48097114172;2329.4525786440217;2351.447409858682;2356.6111591587455;2356.6229403295474;2361.3034154924253;2356.8350215484925;2357.387079359362;2396.676448679423;2398.59223931932;2400.099962514057;2100.6008858316945;2169.945751356213;2301.9424514387106;2361.8700971446565;2363.7045369328835;2345.495718420531;2353.223436266028;2373.8694371809543;2374.679418278536;2385.6779334789753;2351.2828587280874;2366.432056306491;2373.2880135959144;2375.8099352051795;2379.1316169598144;8849.292056635453;8921.286297096216;8927.508987005713;8929.241014513775;8933.106689331014;5181.1969144782615;5313.787448502056;5356.758945847446;5366.114591092465;5372.355317361941;3566.233259849145;3578.7494875358734;3637.217998130415;3638.727095467824;3653.598104208535;1085.3325204998948;1090.7763908398838;1093.185576745322;1111.7554138089608;1112.1881026850363;11478.426078696115;11495.597654082067;11501.884981150166;11506.94246528762;11533.961943425049;4046.6560342371226;4538.546145385501;4630.86107416778;4772.785224664898;4783.091212669582;9914.652987145415;9916.603335866484;9920;9948.40309581413;9954.800903982024;5367.812126575551;5371.140617501181;5371.704556249366;5379.865503362447;5390.54180394663;4733.266343716729;4911.705297682151;4918.1884988977135;4918.55732984026;4931.432885218246;3776.150366167645;3780.773153610792;3818.1790914953417;3861.1119442528375;3875.3024455597697;1102.063246240705;1112.8887111288968;1164.1559869094838;1172.9941350293193;1173.5892437646926;829.5022986208347;916.6241840845222;923.3490389275545;925.9814803703888;956.31623389277;5538.529225015838;5736.397678243724;6027.9095813562835;6155.10750941111;6250.218722659668;862.8533149364623;950.9096635819586;959.4387283439266;960.7982323711959;988.4859872866069;3981.6217459341315;4084.4281800547947;4087.0599762054894;4103.815328310233;4107.958920410817;10407.94796026015;10548.736281592868;10655.946720266349;10722.73193170177;10763.408012559385;9801.901980980241;10250.641227557;10640.148788096934;10679.038886500251;10699.144068474503;7953.5625540594665;8308.875366869503;8310.916890831015;8316.792080197922;8319.500829950222;6003.129546215861;6130.448259656652;6236.189295391751;6277.089821975879;6357.364263573791;9615.759606009906;9862.704118876445;10078.697639070839;10092.697219083439;10102.494875256354;9918.156956658477;10260.384376937407;10337.9483102584;10348;10354.860965293963;7342;7629.54222746637;7633.580153091633;7638.656260468403;7638.732644357421;8129.918700813153;8276.917230827856;8280;8281.627326770189;8287.254147126787;7215.639218039182;7549.0563679540055;7554.622268886644;7591.278828511283;7683.846323073619;7343.559386436617;7438.44211684115;7456.030716006884;7563.394928405824;7595.696172153052;7032.8593428130125;7090.149182098181;7104.289571042855;7116.679749411185;7136.964315178391;4960.801567937242;5069.391672999263;5083.644144909968;5148.124818780741;5151.072806894795;5107.642465027412;5177.948220517746;5241.580673546031;5254.290670759398;5283.1546952487415 12:42:48 INFO - PROCESS | 1930 | |4;prototype.html;303.2872749039756;303.6599009109797;311.5700333539715;319.61646024770283;321.45834269256306;4679.7426141562155;5295.443978382272;5333.479958211906;5426.403095659479;5436.456354364562;4850.975745121278;5334.546563542103;5354.625176237665;5379.5427388671915;5407.945450637128;3593.9038593229056;3993.7603743775467;4119.134981653856;4127.545969943311;4141.772202528859;3442.862285508577;3684.6683798458134;3789.2800367930145;4032.5967403259733;4103.548609652936;3622.1125824173114;3710.8701195458166;3980.781057041876;4050.736702114353;4074.449949256857;4071.2875246831836;4085.5097388313443;4108.876733697994;4119.96061949334;4145.937810932838;3589.246258285763;3592.676659100667;3614.5120408744747;3629.727770417203;3634.527511423498;4387.319965405367;4996.200607902718;5297.47025297471;5335.733213339317;5356.35723713153;3660.3411385950517;4352.934705979413;4617.3304870793545;4639.628829693616;4711;5135.845924622267;5509.641873278224;5570.359408668014;5644.576656750719;5645.774169033234;3553.3959226931224;3842.3660096084113;3967.543732470745;3968.742031767955;3976.70174736893;4536.410266665345;5104.4640312767;5215.973920130375;5809.738561764731;5875.618084824516;3716.4611131386337;3865.2849222893856;3866.845326186977;3881.6700580450415;3894.532656081288;3775.86784462548;3952.9011774705864;3954;3961.168154687548;3981.681465482812;4243.618074373321;4374.934375984363;4600.711800695811;4606.493285738566;4621.399218101626;5312.599936040937;5660.632058916157;5704.201411802351;5743.770249190069;5746.42535746422;1229.969250768738;1313.843817440646;1336.7393358295233;1345.2332170662628;1350.365328295719;818.3453237410048;822.391430341555;831.7587899509195;834.1825011488775;840.82342708032;3980.2636512245326;4161.729487583298;4256.425382573375;4287.228298906164;4403.493598236244;4223;4330;4361.953131248478;4719.5044520325555;4769.833055843098;3734.645208705223;3766.303233901737;4212.915741685149;4285.46431696038;4335.6965012449455;2093.769685334612;2095.706601075851;2293.621552443828;2421.2373102472666;2428.8542687438458;3959.624225464756;4349.975777976442;4475.373447717322;4515.716611564478;4907.950920490821;4486.469183226245;4921.753912304371;4940.357753492095;4940.629452790983;4976.975115124401;4365.650747940157;4870.172070748053;4886.975565122151;4887.877803054881;4898.804047838118;4027.859024934113;4031.4277431801033;4664.883377915579;4776.259679749644;4799.80812759511;3791.526059242595;5309.628326017141;5415.187721841755;5497.697626630574;5720.11338242573;5050.595952323879;6038.849028774316;6059.545534084873;6062.393760624079;6063.393660634078;3493.703035241931;3825.9669889130337;3842.7886466244627;3888.416737489399;3903.882883513499;4805.615550756001;5098.139179199639;5450.454954504677;5566.548739781361;5697.515711164598;4916.901661966812;5629.915551266652;5636.239107720406;5660.424506849291;5720.740307578566;4838.371011768448;5081.618878584195;5089.414717307409;5176.9741151294;5618.101103823368;4849.539293767087;5577.888442231214;5652.869426114711;5669.5180909621495;5680.744366503434;5796.072628379439;5804.6807425592;5809.344336863974;5814.360420353673;5841.707914604168;4961.7271050092595;5241.240020197126;5443.959098085791;5475.099785691704;5660.377358490645 12:42:48 INFO - PROCESS | 1930 | |5;yui.html;748.6144635512712;749.3555542233681;758.4577027425391;851.1828644501278;857.7363133277876;15147.394104235831;16093.275802588882;16100;16117.516474505768;16130.75803862943;14465.927670361634;14638;14692.853071469282;14727.042742221749;14739.705205895902;702.7715992302496;728.359044041243;885.7032893980492;914.4102054175053;914.9268058555333;893.0667452512108;904.6155383961823;906.8775715278452;907.4509921497498;907.7231444409452;959.1463597398321;959.9088086631759;960.1790469148882;960.231814548362;961.293449314753;736.1865139021385;925.7500474871814;954.4988880837547;956.9760755981087;961.0293603460485;716.5521549031855;717.3149642091789;717.4869967972925;719.4604046964777;720.4524561333425;14774.26128693561;14828.925855370655;14846.777298340425;14861.405543778345;14900.403983840633;10867.836982445191;13218.66953326165;13982.650433739136;13986.30068496571;14007.789883151761;12458.688532786753;12545.498180072878;12617.179883307648;12685.682857928625;13074.346282685923;4525.275955847082;4607.470140933802;4697.78859951303;4699.459562150362;4708.693934894221;103860.48069759602;105775;111100;111140.4442977788;111330.99999999919;10546.472676366151;10886.455677216107;11073.058790002779;11210.831837522444;11248.550057997752;21446.78553214479;21467.785322146578;21469;21480.785192148192;21481.78518214829;7828.334591559667;7914.44598878073;7928.207179282025;7982.241687039722;7986.880196797053;4438.511763705989;4456.554344565582;4478.149151661174;4512.165249428866;4645.9303110453375;2867.283179205199;3260.9673903261137;3350.2629421527226;3458.3083383323437;3461.5153878457036;546.4671944853815;637.37537213531;655.4363247607054;666.1140682891743;670.9094272273278;569.5614376929824;671.8824205763972;673.7170388436869;698.6017969757189;711.1430725975188;9020.594073266719;9286.767830804149;9429.245660347156;9508.052503723868;9636.807263854684;660.5079215984123;680.5406350713249;686.3582550315471;692.5775277080977;697.1459961547064;4304.289792184317;4408.140412619572;4432.3794668746405;4505.662075344297;4554.316852472156;149149.50850491357;165198.17400912917;167246;172470;174407.25592744164;151271;151736;151780.48219517883;152537.23731381272;154459;123261.38369308096;123676.38161809313;125345.37327313305;126343;129347.70652293545;6797;8493.32053435736;8497.007502318043;8509.361797865062;8634.5251011195;67863.64272714617;68223;68967.65516172387;69815.30184698087;70337.64831176016;65113.02330465142;91950;106561.27035553718;106722.39916401105;109459.45270273597;11589.884101159218;13812.930935345259;13823.632929652324;13843.930780346436;13863.4454621814;15302.23488825532;15310.31103600363;15604.765928511308;15610.843891561393;16055.919720401324;13686.178829270275;14038.298085095908;14268.928655356656;14575.562733117598;14696.632584185481;14148.929255353658;14320.57038288853;14410.42358305656;14659.193744344164;14894.478693245685;13852.86147138556;13870.514531991736;14235;14241.64395890111;14833.258337083318;11253.943730281297;11331.206815523165;11347.659570212907;11451.312921224753;11999.220050696676;9656.855147172939;11188.328700278009;11286.15353848481;11493.597724079951;11553.191276610556 12:42:48 INFO - PROCESS | 1930 | __end_tp_report 12:42:48 INFO - PROCESS | 1930 | __start_cc_report 12:42:48 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1262 12:42:48 INFO - PROCESS | 1930 | __end_cc_report 12:42:48 INFO - PROCESS | 1930 | __startTimestamp1467747768278__endTimestamp 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | ------- Summary: start ------- 12:42:48 INFO - PROCESS | 1930 | Number of tests: 6 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5794.01 Median:6047.64 stddev:629.39 (10.4%) stddev-sans-first:600.89 12:42:48 INFO - PROCESS | 1930 | Values: 3329.4 3331.3 3340.8 3367.2 3410.2 5736.9 5768.1 5828.1 5923.2 5935.1 5178.7 5606.3 5851.7 5871.3 5896.3 5218.1 5232.4 5262.2 5285.2 5348.4 5254.8 5254.9 5256.2 5296.5 5303.7 5240.2 5306.4 5318.2 5330.9 5346.7 5161.9 5317.8 5328.5 5336.0 5362.5 5217.3 5231.0 5231.4 5260.9 5272.7 5713.4 5816.6 5869.6 5871.5 5986.9 6111.2 6153.0 6168.7 6214.8 6234.7 6237.3 6243.8 6247.7 6265.5 6290.8 5953.3 5955.2 5972.3 6006.7 6055.0 6195.4 6227.3 6244.5 6287.8 6317.5 6238.7 6257.4 6259.9 6262.1 6263.3 6233.5 6284.3 6332.6 6350.8 6351.3 6091.1 6210.7 6239.6 6266.8 6330.1 6339.1 6339.3 6346.8 6347.2 6362.3 5033.4 5045.1 5049.9 5050.9 5087.4 4438.5 4489.4 4491.4 4494.6 4526.0 5788.2 5807.2 5824.7 5832.7 5859.6 6151.2 6229.8 6297.0 6304.6 6312.1 5735.6 5738.3 5738.5 5803.6 5827.7 5474.4 5477.6 5484.2 5489.4 5489.8 6195.4 6197.6 6280.3 6316.0 6317.7 6251.1 6313.2 6316.7 6323.8 6326.3 5571.2 6265.7 6281.8 6303.0 6316.1 6083.8 6098.9 6099.6 6242.1 6243.9 5704.5 6060.2 6245.3 6290.8 6296.8 6056.2 6140.0 6142.9 6145.4 6146.5 5975.4 6098.2 6108.0 6137.7 6139.2 6047.6 6069.3 6080.5 6100.5 6106.5 6144.7 6158.2 6159.2 6159.5 6161.1 5876.0 5899.5 5931.5 6000.9 6015.0 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:76595.56 Median:39966.90 stddev:166988.22 (417.8%) stddev-sans-first:167358.89 12:42:48 INFO - PROCESS | 1930 | Values: 486.1 504.9 513.7 517.6 535.6 31035.0 31611.7 40924.0 42134.0 42299.8 43776.3 44075.8 44092.3 44118.0 44139.0 9845.0 10028.8 10037.3 10041.0 10050.5 8761.5 8987.1 12194.6 12439.3 12529.2 12178.9 12212.1 12228.3 12328.8 12358.4 8779.0 8957.4 12230.3 12299.9 12309.6 8861.7 8921.0 11762.4 12070.3 12088.6 41949.8 42129.4 42147.2 42180.8 42191.8 3543.6 3548.3 3675.4 3881.4 3886.3 32460.0 32623.7 32994.7 34069.0 34449.8 3034.2 3074.7 3292.2 3426.7 3478.0 512315.4 525064.0 525825.0 526554.0 527036.0 70647.0 70774.3 71452.0 71647.3 71663.0 75074.2 75241.2 75477.6 75543.6 75555.2 17506.9 17729.4 17735.5 17735.6 17767.5 12059.7 12262.6 13101.1 13230.5 13386.8 19852.7 20693.3 21114.0 21309.7 21325.4 7477.8 8773.5 8834.8 8836.6 8959.6 914.9 982.6 999.8 1037.7 1057.9 29838.1 30516.0 30566.5 31443.1 31482.2 1084.0 1124.8 1127.7 1171.0 1187.8 14270.6 15766.8 15927.3 16038.0 16068.9 688962.0 695683.0 698496.0 701451.0 701816.0 59235.8 59758.0 59883.4 60028.1 60172.7 1260.2 1276.7 1384.3 1427.4 1483.1 28702.4 32413.8 32530.2 32555.3 33035.0 51074.7 51955.5 52153.7 52224.7 52311.2 567231.0 650092.0 659467.0 660519.0 662496.0 43638.0 44069.3 45724.3 45966.5 46028.0 42679.0 48632.0 49596.8 49737.0 50488.2 39783.0 40390.8 40466.0 40797.8 42017.6 41129.6 41371.8 41386.8 41716.8 42265.2 38911.2 39250.8 39577.0 40150.8 40630.2 35555.3 35593.1 36078.1 36379.6 37026.8 24112.5 25711.6 26465.4 26482.6 26841.0 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:36277.28 Median:27729.22 stddev:102237.23 (368.7%) stddev-sans-first:102488.47 12:42:48 INFO - PROCESS | 1930 | Values: 363.9 385.9 396.3 397.9 418.7 15100.4 16400.3 21944.1 22055.0 22072.4 21585.2 22245.4 22401.9 22456.8 22553.3 5867.0 5991.9 6120.8 6122.0 6122.4 3542.9 4418.8 6027.5 6157.9 6502.9 6788.5 6793.6 6799.8 6810.0 6812.6 6017.9 6761.1 6763.3 6767.6 6768.7 3774.7 5543.4 5880.7 5885.6 5899.0 27566.9 27640.0 27818.4 27927.4 27941.9 2231.2 2255.1 2365.7 2549.8 2742.4 15486.4 16104.0 16107.9 16257.9 16446.9 1836.4 1853.5 1858.3 1912.0 2105.3 125272.7 130436.0 130536.3 132599.0 134201.3 11499.1 11723.6 11822.2 11988.6 12003.7 14736.5 14810.6 14818.0 14828.0 14831.0 8501.7 8761.7 8786.8 8854.2 8855.0 8743.8 8745.1 8757.2 8859.3 8880.5 4573.6 4632.4 4667.6 4693.2 4700.6 1650.9 1870.5 1935.4 1945.5 1947.4 872.4 888.6 951.7 958.3 981.6 21305.6 22204.1 22272.9 22287.4 22329.7 901.3 912.5 952.9 963.8 1015.3 11188.7 11636.5 11674.9 11692.5 11710.3 595928.0 606753.0 614774.0 621406.0 622427.0 93573.0 108115.4 129789.0 132773.0 133095.0 863.3 876.3 972.5 991.0 1000.6 19386.5 19668.5 20100.9 20103.4 20141.0 44428.6 58247.0 58701.4 59941.1 60454.7 84652.6 84756.0 85369.0 86445.6 86851.6 8206.3 8226.4 8353.1 8518.2 8528.5 12719.4 13349.6 13416.2 13489.3 13737.1 11567.0 11612.9 11661.1 11759.3 11784.3 11346.9 11480.9 11534.9 11694.8 11712.2 11185.3 11325.7 11503.7 11586.6 11690.2 8181.8 8234.9 8247.5 8284.5 8319.2 5696.6 8255.3 10848.3 11007.7 11363.7 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5726.41 Median:5149.60 stddev:3173.35 (61.6%) stddev-sans-first:3166.85 12:42:48 INFO - PROCESS | 1930 | Values: 1567.1 1590.2 1788.0 1891.7 1899.3 6981.8 8687.0 8858.3 8872.6 8891.6 7781.2 7997.4 8424.2 8646.0 8650.5 2329.5 2351.4 2356.6 2356.6 2361.3 2356.8 2357.4 2396.7 2398.6 2400.1 2100.6 2169.9 2301.9 2361.9 2363.7 2345.5 2353.2 2373.9 2374.7 2385.7 2351.3 2366.4 2373.3 2375.8 2379.1 8849.3 8921.3 8927.5 8929.2 8933.1 5181.2 5313.8 5356.8 5366.1 5372.4 3566.2 3578.7 3637.2 3638.7 3653.6 1085.3 1090.8 1093.2 1111.8 1112.2 11478.4 11495.6 11501.9 11506.9 11534.0 4046.7 4538.5 4630.9 4772.8 4783.1 9914.7 9916.6 9920.0 9948.4 9954.8 5367.8 5371.1 5371.7 5379.9 5390.5 4733.3 4911.7 4918.2 4918.6 4931.4 3776.2 3780.8 3818.2 3861.1 3875.3 1102.1 1112.9 1164.2 1173.0 1173.6 829.5 916.6 923.3 926.0 956.3 5538.5 5736.4 6027.9 6155.1 6250.2 862.9 950.9 959.4 960.8 988.5 3981.6 4084.4 4087.1 4103.8 4108.0 10407.9 10548.7 10655.9 10722.7 10763.4 9801.9 10250.6 10640.1 10679.0 10699.1 7953.6 8308.9 8310.9 8316.8 8319.5 6003.1 6130.4 6236.2 6277.1 6357.4 9615.8 9862.7 10078.7 10092.7 10102.5 9918.2 10260.4 10337.9 10348.0 10354.9 7342.0 7629.5 7633.6 7638.7 7638.7 8129.9 8276.9 8280.0 8281.6 8287.3 7215.6 7549.1 7554.6 7591.3 7683.8 7343.6 7438.4 7456.0 7563.4 7595.7 7032.9 7090.1 7104.3 7116.7 7137.0 4960.8 5069.4 5083.6 5148.1 5151.1 5107.6 5177.9 5241.6 5254.3 5283.2 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4317.20 Median:4619.36 stddev:1335.16 (28.9%) stddev-sans-first:1304.47 12:42:48 INFO - PROCESS | 1930 | Values: 303.3 303.7 311.6 319.6 321.5 4679.7 5295.4 5333.5 5426.4 5436.5 4851.0 5334.5 5354.6 5379.5 5407.9 3593.9 3993.8 4119.1 4127.5 4141.8 3442.9 3684.7 3789.3 4032.6 4103.5 3622.1 3710.9 3980.8 4050.7 4074.4 4071.3 4085.5 4108.9 4120.0 4145.9 3589.2 3592.7 3614.5 3629.7 3634.5 4387.3 4996.2 5297.5 5335.7 5356.4 3660.3 4352.9 4617.3 4639.6 4711.0 5135.8 5509.6 5570.4 5644.6 5645.8 3553.4 3842.4 3967.5 3968.7 3976.7 4536.4 5104.5 5216.0 5809.7 5875.6 3716.5 3865.3 3866.8 3881.7 3894.5 3775.9 3952.9 3954.0 3961.2 3981.7 4243.6 4374.9 4600.7 4606.5 4621.4 5312.6 5660.6 5704.2 5743.8 5746.4 1230.0 1313.8 1336.7 1345.2 1350.4 818.3 822.4 831.8 834.2 840.8 3980.3 4161.7 4256.4 4287.2 4403.5 4223.0 4330.0 4362.0 4719.5 4769.8 3734.6 3766.3 4212.9 4285.5 4335.7 2093.8 2095.7 2293.6 2421.2 2428.9 3959.6 4350.0 4475.4 4515.7 4908.0 4486.5 4921.8 4940.4 4940.6 4977.0 4365.7 4870.2 4887.0 4887.9 4898.8 4027.9 4031.4 4664.9 4776.3 4799.8 3791.5 5309.6 5415.2 5497.7 5720.1 5050.6 6038.8 6059.5 6062.4 6063.4 3493.7 3826.0 3842.8 3888.4 3903.9 4805.6 5098.1 5450.5 5566.5 5697.5 4916.9 5629.9 5636.2 5660.4 5720.7 4838.4 5081.6 5089.4 5177.0 5618.1 4849.5 5577.9 5652.9 5669.5 5680.7 5796.1 5804.7 5809.3 5814.4 5841.7 4961.7 5241.2 5444.0 5475.1 5660.4 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:26803.79 Median:21481.29 stddev:44194.64 (205.7%) stddev-sans-first:44275.32 12:42:48 INFO - PROCESS | 1930 | Values: 748.6 749.4 758.5 851.2 857.7 15147.4 16093.3 16100.0 16117.5 16130.8 14465.9 14638.0 14692.9 14727.0 14739.7 702.8 728.4 885.7 914.4 914.9 893.1 904.6 906.9 907.5 907.7 959.1 959.9 960.2 960.2 961.3 736.2 925.8 954.5 957.0 961.0 716.6 717.3 717.5 719.5 720.5 14774.3 14828.9 14846.8 14861.4 14900.4 10867.8 13218.7 13982.7 13986.3 14007.8 12458.7 12545.5 12617.2 12685.7 13074.3 4525.3 4607.5 4697.8 4699.5 4708.7 103860.5 105775.0 111100.0 111140.4 111331.0 10546.5 10886.5 11073.1 11210.8 11248.6 21446.8 21467.8 21469.0 21480.8 21481.8 7828.3 7914.4 7928.2 7982.2 7986.9 4438.5 4456.6 4478.1 4512.2 4645.9 2867.3 3261.0 3350.3 3458.3 3461.5 546.5 637.4 655.4 666.1 670.9 569.6 671.9 673.7 698.6 711.1 9020.6 9286.8 9429.2 9508.1 9636.8 660.5 680.5 686.4 692.6 697.1 4304.3 4408.1 4432.4 4505.7 4554.3 149149.5 165198.2 167246.0 172470.0 174407.3 151271.0 151736.0 151780.5 152537.2 154459.0 123261.4 123676.4 125345.4 126343.0 129347.7 6797.0 8493.3 8497.0 8509.4 8634.5 67863.6 68223.0 68967.7 69815.3 70337.6 65113.0 91950.0 106561.3 106722.4 109459.5 11589.9 13812.9 13823.6 13843.9 13863.4 15302.2 15310.3 15604.8 15610.8 16055.9 13686.2 14038.3 14268.9 14575.6 14696.6 14148.9 14320.6 14410.4 14659.2 14894.5 13852.9 13870.5 14235.0 14241.6 14833.3 11253.9 11331.2 11347.7 11451.3 11999.2 9656.9 11188.3 11286.2 11493.6 11553.2 12:42:48 INFO - PROCESS | 1930 | -------- Summary: end -------- 12:42:48 INFO - PROCESS | 1930 | 12:42:48 INFO - TEST-INFO | 1930: exit 0 12:42:48 INFO - TEST-OK | dromaeo_css | took 1116647ms 12:42:48 INFO - TEST-START | kraken 12:42:48 INFO - operating with platform_type : mac_ 12:42:48 INFO - Initialising browser for kraken test... 12:42:48 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpvw3Rdn/profile http://localhost:49651/getInfo.html) 12:42:51 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 12:42:51 INFO - PROCESS | 1936 | colorDepth:24 12:42:51 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 12:42:51 INFO - PROCESS | 1936 | __metrics 12:42:51 INFO - PROCESS | 1936 | 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:42:51 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:42:51 INFO - TEST-INFO | 1936: exit 0 12:42:51 INFO - Browser initialized. 12:42:51 INFO - Running cycle 1/1 for kraken test... 12:42:51 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpvw3Rdn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:03 INFO - PROCESS | 1937 | RSS: Main: 243630080 12:43:03 INFO - PROCESS | 1937 | 12:43:06 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49651/tests/kraken/driver.html?testName=audio-beat-detection) 12:43:07 INFO - PROCESS | 1937 | RSS: Main: 294686720 12:43:07 INFO - PROCESS | 1937 | 12:43:09 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49651/tests/kraken/driver.html?testName=audio-dft) 12:43:10 INFO - PROCESS | 1937 | RSS: Main: 304451584 12:43:10 INFO - PROCESS | 1937 | 12:43:13 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49651/tests/kraken/driver.html?testName=audio-fft) 12:43:14 INFO - PROCESS | 1937 | RSS: Main: 389271552 12:43:14 INFO - PROCESS | 1937 | 12:43:16 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49651/tests/kraken/driver.html?testName=audio-oscillator) 12:43:17 INFO - PROCESS | 1937 | RSS: Main: 289980416 12:43:17 INFO - PROCESS | 1937 | 12:43:19 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49651/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:43:19 INFO - PROCESS | 1937 | RSS: Main: 294383616 12:43:19 INFO - PROCESS | 1937 | 12:43:22 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49651/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:23 INFO - PROCESS | 1937 | RSS: Main: 330096640 12:43:23 INFO - PROCESS | 1937 | 12:43:26 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49651/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:26 INFO - PROCESS | 1937 | RSS: Main: 332300288 12:43:26 INFO - PROCESS | 1937 | 12:43:29 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49651/tests/kraken/driver.html?testName=json-parse-financial) 12:43:30 INFO - PROCESS | 1937 | RSS: Main: 332808192 12:43:30 INFO - PROCESS | 1937 | 12:43:31 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49651/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:32 INFO - PROCESS | 1937 | RSS: Main: 312070144 12:43:32 INFO - PROCESS | 1937 | 12:43:34 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:34 INFO - PROCESS | 1937 | RSS: Main: 318091264 12:43:34 INFO - PROCESS | 1937 | 12:43:37 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:37 INFO - PROCESS | 1937 | RSS: Main: 316715008 12:43:37 INFO - PROCESS | 1937 | 12:43:40 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:41 INFO - PROCESS | 1937 | RSS: Main: 325033984 12:43:41 INFO - PROCESS | 1937 | 12:43:44 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:44 INFO - PROCESS | 1937 | RSS: Main: 326393856 12:43:44 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:49651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49651/tests/kraken/driver.html?testName=ai-astar) 12:43:46 INFO - PROCESS | 1937 | RSS: Main: 370110464 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | __start_tp_report 12:43:46 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 12:43:46 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 12:43:46 INFO - PROCESS | 1937 | |i|pagename|runs| 12:43:46 INFO - PROCESS | 1937 | |0;ai-astar;101;109;112;112;114;113;114;112;114;114 12:43:46 INFO - PROCESS | 1937 | |1;audio-beat-detection;142;108;106;157;104;127;130;105;145;106 12:43:46 INFO - PROCESS | 1937 | |2;audio-dft;184;187;181;185;184;184;183;183;186;184 12:43:46 INFO - PROCESS | 1937 | |3;audio-fft;81;79;57;112;57;57;59;67;98;58 12:43:46 INFO - PROCESS | 1937 | |4;audio-oscillator;84;87;92;70;70;67;97;69;67;101 12:43:46 INFO - PROCESS | 1937 | |5;imaging-gaussian-blur;90;87;90;88;90;86;93;129;118;87 12:43:46 INFO - PROCESS | 1937 | |6;imaging-darkroom;94;95;95;96;93;95;96;95;94;94 12:43:46 INFO - PROCESS | 1937 | |7;imaging-desaturate;86;87;85;86;86;85;84;84;84;81 12:43:46 INFO - PROCESS | 1937 | |8;json-parse-financial;56;56;55;53;53;45;45;73;74;82 12:43:46 INFO - PROCESS | 1937 | |9;json-stringify-tinderbox;65;70;70;78;72;70;70;71;71;70 12:43:46 INFO - PROCESS | 1937 | |10;stanford-crypto-aes;81;81;84;80;80;82;85;82;85;85 12:43:46 INFO - PROCESS | 1937 | |11;stanford-crypto-ccm;160;149;124;152;143;149;150;137;181;136 12:43:46 INFO - PROCESS | 1937 | |12;stanford-crypto-pbkdf2;163;162;163;225;163;161;163;165;167;162 12:43:46 INFO - PROCESS | 1937 | |13;stanford-crypto-sha256-iterative;64;66;66;67;66;67;65;66;66;62 12:43:46 INFO - PROCESS | 1937 | __end_tp_report 12:43:46 INFO - PROCESS | 1937 | __start_cc_report 12:43:46 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,2001 12:43:46 INFO - PROCESS | 1937 | __end_cc_report 12:43:46 INFO - PROCESS | 1937 | __startTimestamp1467747826656__endTimestamp 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | ------- Summary: start ------- 12:43:46 INFO - PROCESS | 1937 | Number of tests: 14 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.50 stddev:4.01 (3.5%) stddev-sans-first:1.66 12:43:46 INFO - PROCESS | 1937 | Values: 101.0 109.0 112.0 112.0 114.0 113.0 114.0 112.0 114.0 114.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:128.50 stddev:19.87 (15.5%) stddev-sans-first:19.85 12:43:46 INFO - PROCESS | 1937 | Values: 142.0 108.0 106.0 157.0 104.0 127.0 130.0 105.0 145.0 106.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 12:43:46 INFO - PROCESS | 1937 | Values: 184.0 187.0 181.0 185.0 184.0 184.0 183.0 183.0 186.0 184.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#3] audio-fft Cycles:10 Average:72.50 Median:63.00 stddev:19.63 (31.2%) stddev-sans-first:20.58 12:43:46 INFO - PROCESS | 1937 | Values: 81.0 79.0 57.0 112.0 57.0 57.0 59.0 67.0 98.0 58.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:77.00 stddev:13.32 (17.3%) stddev-sans-first:14.06 12:43:46 INFO - PROCESS | 1937 | Values: 84.0 87.0 92.0 70.0 70.0 67.0 97.0 69.0 67.0 101.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:89.00 stddev:14.97 (16.8%) stddev-sans-first:15.73 12:43:46 INFO - PROCESS | 1937 | Values: 90.0 87.0 90.0 88.0 90.0 86.0 93.0 129.0 118.0 87.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.95 (1.0%) stddev-sans-first:0.97 12:43:46 INFO - PROCESS | 1937 | Values: 94.0 95.0 95.0 96.0 93.0 95.0 96.0 95.0 94.0 94.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.69 (2.0%) stddev-sans-first:1.73 12:43:46 INFO - PROCESS | 1937 | Values: 86.0 87.0 85.0 86.0 86.0 85.0 84.0 84.0 84.0 81.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:56.00 stddev:12.68 (22.6%) stddev-sans-first:13.40 12:43:46 INFO - PROCESS | 1937 | Values: 56.0 56.0 55.0 53.0 53.0 45.0 45.0 73.0 74.0 82.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:3.16 (4.5%) stddev-sans-first:2.60 12:43:46 INFO - PROCESS | 1937 | Values: 65.0 70.0 70.0 78.0 72.0 70.0 70.0 71.0 71.0 70.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:2.07 (2.5%) stddev-sans-first:2.12 12:43:46 INFO - PROCESS | 1937 | Values: 81.0 81.0 84.0 80.0 80.0 82.0 85.0 82.0 85.0 85.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:149.50 stddev:15.35 (10.3%) stddev-sans-first:15.67 12:43:46 INFO - PROCESS | 1937 | Values: 160.0 149.0 124.0 152.0 143.0 149.0 150.0 137.0 181.0 136.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:163.00 stddev:19.61 (12.0%) stddev-sans-first:20.66 12:43:46 INFO - PROCESS | 1937 | Values: 163.0 162.0 163.0 225.0 163.0 161.0 163.0 165.0 167.0 162.0 12:43:46 INFO - PROCESS | 1937 | 12:43:46 INFO - PROCESS | 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.51 (2.3%) stddev-sans-first:1.50 12:43:46 INFO - PROCESS | 1937 | Values: 64.0 66.0 66.0 67.0 66.0 67.0 65.0 66.0 66.0 62.0 12:43:46 INFO - PROCESS | 1937 | -------- Summary: end -------- 12:43:46 INFO - PROCESS | 1937 | 12:43:47 INFO - TEST-INFO | 1937: exit 0 12:43:47 INFO - TEST-OK | kraken | took 58565ms 12:43:47 INFO - SUITE-END | took 1175s 12:43:47 INFO - Completed test suite (00:19:35) 12:43:47 INFO - javascript benchmark 12:43:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.384063948168, 3331.2671212333285, 3340.816255105969, 3367.1918739502526, 3410.249745056087, 5736.885262294752, 5768.134779783035, 5828.125781132827, 5923.229980102581, 5935.109733539965, 5178.731210853337, 5606.299212598426, 5851.7074146292625, 5871.29544454666, 5896.321922978858, 5218.086834803894, 5232.372115346164, 5262.236975638531, 5285.180796976475, 5348.438413966536, 5254.842354729345, 5254.894902101955, 5256.237845512398, 5296.496832800878, 5303.734813259341, 5240.161574148137, 5306.3632364116165, 5318.202269659562, 5330.92003619946, 5346.705931173784, 5161.870953226199, 5317.787288508455, 5328.5470734987575, 5336.039512887679, 5362.49056339651, 5217.321748172713, 5231.0, 5231.424543300234, 5260.8684782880355, 5272.657277276965, 5713.400092990218, 5816.621919575214, 5869.559783016248, 5871.4715675589405, 5986.910196347058, 6111.166351794787, 6153.046277826899, 6168.722407491674, 6214.8135555933395, 6234.657093859836, 6237.313895471494, 6243.8126856194385, 6247.687615619201, 6265.530085243625, 6290.811275661737, 5953.3451320354725, 5955.196048533479, 5972.343042265347, 6006.729697163638, 6054.96972515139, 6195.380461953859, 6227.314995350508, 6244.500439964792, 6287.779927702509, 6317.46301564363, 6238.65687387189, 6257.405546473078, 6259.937400625936, 6262.12330273762, 6263.31103578606, 6233.470155036782, 6284.340144284875, 6332.620042797447, 6350.809475715736, 6351.269603995507, 6091.08883416005, 6210.689465526705, 6239.594426362272, 6266.780662676784, 6330.05049242608, 6339.144215530937, 6339.2709838368255, 6346.809595712044, 6347.206599175103, 6362.300146983828, 5033.421156567042, 5045.066662667418, 5049.9495005049475, 5050.873728156752, 5087.414947281036, 4438.511763705989, 4489.393931819228, 4491.371208030879, 4494.550544945545, 4525.959029423185, 5788.218590490314, 5807.157962095473, 5824.6796426196115, 5832.679202643895, 5859.589828711949, 6151.169592105098, 6229.813105606839, 6297.0, 6304.621722696653, 6312.084747711556, 5735.569832262597, 5738.282714660668, 5738.512226460798, 5803.593748437654, 5827.737751801179, 5474.425185355481, 5477.616566840283, 5484.2047903053835, 5489.39616642169, 5489.835304940778, 6195.4424101831055, 6197.628142311476, 6280.2777680566405, 6316.021016742412, 6317.652529110943, 6251.093591429127, 6313.179286692701, 6316.7157477914525, 6323.810285691437, 6326.304106548184, 5571.164325351229, 6265.655388953652, 6281.842953926189, 6303.0, 6316.084167795736, 6083.8174854754425, 6098.939010609837, 6099.551912188739, 6242.094896240111, 6243.937560624518, 5704.5453409382, 6060.212172417734, 6245.344238855037, 6290.779822706183, 6296.842578935563, 6056.182415373868, 6139.969300153471, 6142.938570614237, 6145.35473775265, 6146.508279337731, 5975.37258587842, 6098.207233059851, 6108.0, 6137.662428566472, 6139.185504090546, 6047.637141771508, 6069.3354524685665, 6080.543959202989, 6100.481459076028, 6106.480949119375, 6144.6620435874665, 6158.230221222348, 6159.199304090619, 6159.476444502267, 6161.137440758387, 5875.970620146872, 5899.498542623754, 5931.466168044896, 6000.8799824004145, 6014.977453832947], "value": 5753.638361906526, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.0885839051778, 504.94445610982797, 513.7354262554783, 517.6402400331771, 535.5661913849781, 31035.0, 31611.68388316116, 40923.99999999996, 42134.0, 42299.77002299768, 43776.343354849625, 44075.77962110193, 44092.33861492072, 44118.0, 44139.0, 9844.950775246114, 10028.799424011515, 10037.34757240777, 10041.0, 10050.497475126214, 8761.51811650359, 8987.101289871025, 12194.63416097514, 12439.315837628927, 12529.248245105277, 12178.939105304506, 12212.145149839514, 12228.266304021743, 12328.81506777399, 12358.44387002587, 8779.0, 8957.417767845069, 12230.266184028942, 12299.877001230006, 12309.630711078593, 8861.73414797557, 8921.01868794432, 11762.353070581115, 12070.27578345302, 12088.576899808464, 41949.79025104886, 42129.368059478824, 42147.157056858996, 42180.78909605432, 42191.789041054595, 3543.5924868640177, 3548.3258180945536, 3675.4119340905595, 3881.359575670011, 3886.261610294035, 32460.0, 32623.673763262537, 32994.6700532994, 34069.0, 34449.82775086134, 3034.1656044587694, 3074.738647214989, 3292.242784159657, 3426.7429942754393, 3477.965220347815, 512315.43842280924, 525064.0, 525825.0, 526554.0, 527036.0, 70647.0, 70774.29225707677, 71452.0, 71647.28352716407, 71663.0, 75074.24925750673, 75241.24758752451, 75477.62261188768, 75543.62228188934, 75555.24444755592, 17506.912465437592, 17729.37947171843, 17735.46793596194, 17735.55661108457, 17767.46697599074, 12059.698507537207, 12262.570810021607, 13101.148425352321, 13230.470781168839, 13386.799198012037, 19852.70220946687, 20693.27573534919, 21114.0, 21309.68035479469, 21325.36023919285, 7477.813054673567, 8773.517456539952, 8834.83546531091, 8836.602352894135, 8959.5968181432, 914.881065461499, 982.5824024789435, 999.7900440907491, 1037.6835065305154, 1057.9418132002659, 29838.10485685433, 30516.0, 30566.54150187749, 31443.05670829833, 31482.212944676565, 1083.978320433587, 1124.820028795389, 1127.7067962329854, 1171.0455978377595, 1187.7624475105013, 14270.643233919027, 15766.76349854753, 15927.283272252776, 16038.0, 16068.919655401882, 688962.0, 695683.0, 698496.0, 701451.0, 701816.0, 59235.815283694086, 59758.0, 59883.40116598865, 60028.09817361858, 60172.69913650404, 1260.2060701757835, 1276.7255040166408, 1384.2801743093535, 1427.4432971141264, 1483.0508474576063, 28702.425951480436, 32413.83793081114, 32530.186745331557, 32555.34889302248, 33035.00894973155, 51074.74462627663, 51955.48044519506, 52153.7392313036, 52224.738876305375, 52311.215331769294, 567231.0, 650092.0, 659467.0, 660519.0, 662496.0, 43638.0, 44069.33895991627, 45724.314135287335, 45966.540334597565, 46028.0, 42679.0, 48632.0, 49596.76008099826, 49737.0, 50488.24267635915, 39783.00542486461, 40390.79804600958, 40466.0, 40797.79601102094, 42017.57982420137, 41129.588704112575, 41371.793141034104, 41386.79306603448, 41716.791416042724, 42265.15469690527, 38911.221775564896, 39250.82247532579, 39577.0, 40150.79924600358, 40630.1873962525, 35555.28889422249, 35593.1101722459, 36078.09804754902, 36379.636203638685, 37026.814865925495, 24112.51774964526, 25711.614325785504, 26465.427167332164, 26482.602760958987, 26841.0], "value": 21639.23427996172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.8762820640982, 385.88809245318856, 396.30844176911285, 397.93434083376246, 418.6713429957484, 15100.424864827086, 16400.26198821053, 21944.122235110575, 22055.0, 22072.44818879529, 21585.244516441926, 22245.44386390341, 22401.887990560066, 22456.77543224567, 22553.278709012993, 5867.0, 5991.88016239676, 6120.846978825521, 6122.0, 6122.448979591836, 3542.9114272143274, 4418.845340413086, 6027.487663548603, 6157.90763138551, 6502.9349706503035, 6788.524803263749, 6793.5584187027935, 6799.796006119825, 6809.965950170267, 6812.59124452532, 6017.886690962164, 6761.087253220801, 6763.289854565249, 6767.560108592926, 6768.69540870662, 3774.735768496206, 5543.362513310981, 5880.7353669085, 5885.588008839383, 5898.970505147447, 27566.862165689243, 27640.0, 27818.443631127266, 27927.441451171067, 27941.88232470699, 2231.2190733243233, 2255.1430456426615, 2365.7279412867397, 2549.8087643426816, 2742.369255071345, 15486.380544778196, 16104.0, 16107.919460402623, 16257.918710406491, 16446.895946377314, 1836.4398858348331, 1853.5183537097735, 1858.3124244029796, 1911.9713204301947, 2105.26315789474, 125272.74727252702, 130436.0, 130536.34731826375, 132599.0, 134201.3289933554, 11499.137564682514, 11723.59666268413, 11822.231554948901, 11988.580399685969, 12003.699907502383, 14736.484223052355, 14810.555683329518, 14817.962742607913, 14828.0, 14831.035982661308, 8501.702440414556, 8761.737147885446, 8786.780330491662, 8854.203121719076, 8854.981677107215, 8743.825123497494, 8745.108426750163, 8757.168069033432, 8859.335549833788, 8880.51157186361, 4573.565511276423, 4632.444106707217, 4667.649926255544, 4693.22561777303, 4700.566327270215, 1650.8856521847704, 1870.532366908273, 1935.380678182968, 1945.5038965064102, 1947.3768394114031, 872.3806097670725, 888.626776753765, 951.6526467839327, 958.2717134977468, 981.568110031598, 21305.573888522144, 22204.111835526397, 22272.888635557043, 22287.442813929458, 22329.665055023863, 901.3195037746459, 912.4798864647088, 952.852307892264, 963.8216929867997, 1015.2791518022287, 11188.720281993015, 11636.534538618529, 11674.88325116755, 11692.47383867728, 11710.297382157098, 595928.0, 606753.0, 614774.0, 621406.0, 622427.0, 93573.0, 108115.37826932603, 129789.0, 132773.0, 133095.0, 863.3395452478859, 876.3120950054305, 972.4651441707033, 990.9603615855285, 1000.6347683095763, 19386.515337116685, 19668.50828729293, 20100.899495502428, 20103.39689809308, 20141.0, 44428.55571444244, 58247.0, 58701.412985869596, 59941.100883487656, 60454.69772651108, 84652.57673711592, 84756.0, 85369.0, 86445.56777216074, 86851.56574217089, 8206.343492520464, 8226.424150309422, 8353.122922093336, 8518.23335899772, 8528.530930798866, 12719.364031798557, 13349.59829217957, 13416.195028298333, 13489.325533723471, 13737.107088039245, 11567.0, 11612.88387116118, 11661.125415594033, 11759.294442333487, 11784.292942423483, 11346.94326528362, 11480.94259528697, 11534.94232528832, 11694.824577631513, 11712.238704484182, 11185.32888026721, 11325.71685707864, 11503.712407189887, 11586.594469193538, 11690.181687281809, 8181.79545511367, 8234.917650823416, 8247.463914845515, 8284.46151000183, 8319.209675080858, 5696.572757043321, 8255.339572834278, 10848.294860834021, 11007.669769906915, 11363.659090227306], "value": 9955.940855289224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1567.1302427152932, 1590.2128446419022, 1787.955301117472, 1891.678414669506, 1899.3447260695054, 6981.790546283613, 8687.044425113249, 8858.291336693064, 8872.556372181389, 8891.644334226632, 7781.182975787546, 7997.360211183106, 8424.241818236358, 8646.048934617203, 8650.48097114172, 2329.4525786440217, 2351.447409858682, 2356.6111591587455, 2356.6229403295474, 2361.3034154924253, 2356.8350215484925, 2357.387079359362, 2396.676448679423, 2398.59223931932, 2400.099962514057, 2100.6008858316945, 2169.945751356213, 2301.9424514387106, 2361.8700971446565, 2363.7045369328835, 2345.495718420531, 2353.223436266028, 2373.8694371809543, 2374.679418278536, 2385.6779334789753, 2351.2828587280874, 2366.432056306491, 2373.2880135959144, 2375.8099352051795, 2379.1316169598144, 8849.292056635453, 8921.286297096216, 8927.508987005713, 8929.241014513775, 8933.106689331014, 5181.1969144782615, 5313.787448502056, 5356.758945847446, 5366.114591092465, 5372.355317361941, 3566.233259849145, 3578.7494875358734, 3637.217998130415, 3638.727095467824, 3653.598104208535, 1085.3325204998948, 1090.7763908398838, 1093.185576745322, 1111.7554138089608, 1112.1881026850363, 11478.426078696115, 11495.597654082067, 11501.884981150166, 11506.94246528762, 11533.961943425049, 4046.6560342371226, 4538.546145385501, 4630.86107416778, 4772.785224664898, 4783.091212669582, 9914.652987145415, 9916.603335866484, 9920.0, 9948.40309581413, 9954.800903982024, 5367.812126575551, 5371.140617501181, 5371.704556249366, 5379.865503362447, 5390.54180394663, 4733.266343716729, 4911.705297682151, 4918.1884988977135, 4918.55732984026, 4931.432885218246, 3776.150366167645, 3780.773153610792, 3818.1790914953417, 3861.1119442528375, 3875.3024455597697, 1102.063246240705, 1112.8887111288968, 1164.1559869094838, 1172.9941350293193, 1173.5892437646926, 829.5022986208347, 916.6241840845222, 923.3490389275545, 925.9814803703888, 956.31623389277, 5538.529225015838, 5736.397678243724, 6027.9095813562835, 6155.10750941111, 6250.218722659668, 862.8533149364623, 950.9096635819586, 959.4387283439266, 960.7982323711959, 988.4859872866069, 3981.6217459341315, 4084.4281800547947, 4087.0599762054894, 4103.815328310233, 4107.958920410817, 10407.94796026015, 10548.736281592868, 10655.946720266349, 10722.73193170177, 10763.408012559385, 9801.901980980241, 10250.641227557, 10640.148788096934, 10679.038886500251, 10699.144068474503, 7953.5625540594665, 8308.875366869503, 8310.916890831015, 8316.792080197922, 8319.500829950222, 6003.129546215861, 6130.448259656652, 6236.189295391751, 6277.089821975879, 6357.364263573791, 9615.759606009906, 9862.704118876445, 10078.697639070839, 10092.697219083439, 10102.494875256354, 9918.156956658477, 10260.384376937407, 10337.9483102584, 10348.0, 10354.860965293963, 7342.0, 7629.54222746637, 7633.580153091633, 7638.656260468403, 7638.732644357421, 8129.918700813153, 8276.917230827856, 8280.0, 8281.627326770189, 8287.254147126787, 7215.639218039182, 7549.0563679540055, 7554.622268886644, 7591.278828511283, 7683.846323073619, 7343.559386436617, 7438.44211684115, 7456.030716006884, 7563.394928405824, 7595.696172153052, 7032.8593428130125, 7090.149182098181, 7104.289571042855, 7116.679749411185, 7136.964315178391, 4960.801567937242, 5069.391672999263, 5083.644144909968, 5148.124818780741, 5151.072806894795, 5107.642465027412, 5177.948220517746, 5241.580673546031, 5254.290670759398, 5283.1546952487415], "value": 4609.369253756531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.2872749039756, 303.6599009109797, 311.5700333539715, 319.61646024770283, 321.45834269256306, 4679.7426141562155, 5295.443978382272, 5333.479958211906, 5426.403095659479, 5436.456354364562, 4850.975745121278, 5334.546563542103, 5354.625176237665, 5379.5427388671915, 5407.945450637128, 3593.9038593229056, 3993.7603743775467, 4119.134981653856, 4127.545969943311, 4141.772202528859, 3442.862285508577, 3684.6683798458134, 3789.2800367930145, 4032.5967403259733, 4103.548609652936, 3622.1125824173114, 3710.8701195458166, 3980.781057041876, 4050.736702114353, 4074.449949256857, 4071.2875246831836, 4085.5097388313443, 4108.876733697994, 4119.96061949334, 4145.937810932838, 3589.246258285763, 3592.676659100667, 3614.5120408744747, 3629.727770417203, 3634.527511423498, 4387.319965405367, 4996.200607902718, 5297.47025297471, 5335.733213339317, 5356.35723713153, 3660.3411385950517, 4352.934705979413, 4617.3304870793545, 4639.628829693616, 4711.0, 5135.845924622267, 5509.641873278224, 5570.359408668014, 5644.576656750719, 5645.774169033234, 3553.3959226931224, 3842.3660096084113, 3967.543732470745, 3968.742031767955, 3976.70174736893, 4536.410266665345, 5104.4640312767, 5215.973920130375, 5809.738561764731, 5875.618084824516, 3716.4611131386337, 3865.2849222893856, 3866.845326186977, 3881.6700580450415, 3894.532656081288, 3775.86784462548, 3952.9011774705864, 3954.0, 3961.168154687548, 3981.681465482812, 4243.618074373321, 4374.934375984363, 4600.711800695811, 4606.493285738566, 4621.399218101626, 5312.599936040937, 5660.632058916157, 5704.201411802351, 5743.770249190069, 5746.42535746422, 1229.969250768738, 1313.843817440646, 1336.7393358295233, 1345.2332170662628, 1350.365328295719, 818.3453237410048, 822.391430341555, 831.7587899509195, 834.1825011488775, 840.82342708032, 3980.2636512245326, 4161.729487583298, 4256.425382573375, 4287.228298906164, 4403.493598236244, 4223.0, 4330.0, 4361.953131248478, 4719.5044520325555, 4769.833055843098, 3734.645208705223, 3766.303233901737, 4212.915741685149, 4285.46431696038, 4335.6965012449455, 2093.769685334612, 2095.706601075851, 2293.621552443828, 2421.2373102472666, 2428.8542687438458, 3959.624225464756, 4349.975777976442, 4475.373447717322, 4515.716611564478, 4907.950920490821, 4486.469183226245, 4921.753912304371, 4940.357753492095, 4940.629452790983, 4976.975115124401, 4365.650747940157, 4870.172070748053, 4886.975565122151, 4887.877803054881, 4898.804047838118, 4027.859024934113, 4031.4277431801033, 4664.883377915579, 4776.259679749644, 4799.80812759511, 3791.526059242595, 5309.628326017141, 5415.187721841755, 5497.697626630574, 5720.11338242573, 5050.595952323879, 6038.849028774316, 6059.545534084873, 6062.393760624079, 6063.393660634078, 3493.703035241931, 3825.9669889130337, 3842.7886466244627, 3888.416737489399, 3903.882883513499, 4805.615550756001, 5098.139179199639, 5450.454954504677, 5566.548739781361, 5697.515711164598, 4916.901661966812, 5629.915551266652, 5636.239107720406, 5660.424506849291, 5720.740307578566, 4838.371011768448, 5081.618878584195, 5089.414717307409, 5176.9741151294, 5618.101103823368, 4849.539293767087, 5577.888442231214, 5652.869426114711, 5669.5180909621495, 5680.744366503434, 5796.072628379439, 5804.6807425592, 5809.344336863974, 5814.360420353673, 5841.707914604168, 4961.7271050092595, 5241.240020197126, 5443.959098085791, 5475.099785691704, 5660.377358490645], "value": 3898.8666543831764, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.6144635512712, 749.3555542233681, 758.4577027425391, 851.1828644501278, 857.7363133277876, 15147.394104235831, 16093.275802588882, 16100.0, 16117.516474505768, 16130.75803862943, 14465.927670361634, 14638.0, 14692.853071469282, 14727.042742221749, 14739.705205895902, 702.7715992302496, 728.359044041243, 885.7032893980492, 914.4102054175053, 914.9268058555333, 893.0667452512108, 904.6155383961823, 906.8775715278452, 907.4509921497498, 907.7231444409452, 959.1463597398321, 959.9088086631759, 960.1790469148882, 960.231814548362, 961.293449314753, 736.1865139021385, 925.7500474871814, 954.4988880837547, 956.9760755981087, 961.0293603460485, 716.5521549031855, 717.3149642091789, 717.4869967972925, 719.4604046964777, 720.4524561333425, 14774.26128693561, 14828.925855370655, 14846.777298340425, 14861.405543778345, 14900.403983840633, 10867.836982445191, 13218.66953326165, 13982.650433739136, 13986.30068496571, 14007.789883151761, 12458.688532786753, 12545.498180072878, 12617.179883307648, 12685.682857928625, 13074.346282685923, 4525.275955847082, 4607.470140933802, 4697.78859951303, 4699.459562150362, 4708.693934894221, 103860.48069759602, 105775.0, 111100.0, 111140.4442977788, 111330.99999999919, 10546.472676366151, 10886.455677216107, 11073.058790002779, 11210.831837522444, 11248.550057997752, 21446.78553214479, 21467.785322146578, 21469.0, 21480.785192148192, 21481.78518214829, 7828.334591559667, 7914.44598878073, 7928.207179282025, 7982.241687039722, 7986.880196797053, 4438.511763705989, 4456.554344565582, 4478.149151661174, 4512.165249428866, 4645.9303110453375, 2867.283179205199, 3260.9673903261137, 3350.2629421527226, 3458.3083383323437, 3461.5153878457036, 546.4671944853815, 637.37537213531, 655.4363247607054, 666.1140682891743, 670.9094272273278, 569.5614376929824, 671.8824205763972, 673.7170388436869, 698.6017969757189, 711.1430725975188, 9020.594073266719, 9286.767830804149, 9429.245660347156, 9508.052503723868, 9636.807263854684, 660.5079215984123, 680.5406350713249, 686.3582550315471, 692.5775277080977, 697.1459961547064, 4304.289792184317, 4408.140412619572, 4432.3794668746405, 4505.662075344297, 4554.316852472156, 149149.50850491357, 165198.17400912917, 167246.0, 172470.0, 174407.25592744164, 151271.0, 151736.0, 151780.48219517883, 152537.23731381272, 154459.0, 123261.38369308096, 123676.38161809313, 125345.37327313305, 126343.0, 129347.70652293545, 6797.0, 8493.32053435736, 8497.007502318043, 8509.361797865062, 8634.5251011195, 67863.64272714617, 68223.0, 68967.65516172387, 69815.30184698087, 70337.64831176016, 65113.02330465142, 91950.0, 106561.27035553718, 106722.39916401105, 109459.45270273597, 11589.884101159218, 13812.930935345259, 13823.632929652324, 13843.930780346436, 13863.4454621814, 15302.23488825532, 15310.31103600363, 15604.765928511308, 15610.843891561393, 16055.919720401324, 13686.178829270275, 14038.298085095908, 14268.928655356656, 14575.562733117598, 14696.632584185481, 14148.929255353658, 14320.57038288853, 14410.42358305656, 14659.193744344164, 14894.478693245685, 13852.86147138556, 13870.514531991736, 14235.0, 14241.64395890111, 14833.258337083318, 11253.943730281297, 11331.206815523165, 11347.659570212907, 11451.312921224753, 11999.220050696676, 9656.855147172939, 11188.328700278009, 11286.15353848481, 11493.597724079951, 11553.191276610556], "value": 8090.578749843038, "unit": "score"}], "name": "dromaeo_css", "value": 7515.87697724528, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 112.0, 112.0, 114.0, 113.0, 114.0, 112.0, 114.0, 114.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 106.0, 157.0, 104.0, 127.0, 130.0, 105.0, 145.0, 106.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 181.0, 185.0, 184.0, 184.0, 183.0, 183.0, 186.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 57.0, 112.0, 57.0, 57.0, 59.0, 67.0, 98.0, 58.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 92.0, 70.0, 70.0, 67.0, 97.0, 69.0, 67.0, 101.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 90.0, 86.0, 93.0, 129.0, 118.0, 87.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 96.0, 93.0, 95.0, 96.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 86.0, 86.0, 85.0, 84.0, 84.0, 84.0, 81.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 53.0, 53.0, 45.0, 45.0, 73.0, 74.0, 82.0], "value": 59.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 70.0, 78.0, 72.0, 70.0, 70.0, 71.0, 71.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 84.0, 80.0, 80.0, 82.0, 85.0, 82.0, 85.0, 85.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 149.0, 124.0, 152.0, 143.0, 149.0, 150.0, 137.0, 181.0, 136.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 225.0, 163.0, 161.0, 163.0, 165.0, 167.0, 162.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 67.0, 66.0, 67.0, 65.0, 66.0, 66.0, 62.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1442.2, "alertThreshold": 2.0}]} 12:43:47 INFO - Return code: 0 12:43:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:43:47 INFO - # TBPL SUCCESS # 12:43:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:43:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:43:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:43:47 INFO - 2016-07-05 12:43:47.960 screenresolution[1941:12168] starting screenresolution argv=screenresolution get 12:43:47 INFO - 2016-07-05 12:43:47.963 screenresolution[1941:12168] Display 0: 1600x1200x32@60 12:43:47 INFO - 2016-07-05 12:43:47.969 screenresolution[1942:12171] starting screenresolution argv=screenresolution list 12:43:47 INFO - Available Modes on Display 0 12:43:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:43:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:43:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:43:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:43:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:43:48 INFO - Intel Iris: 12:43:48 INFO - Chipset Model: Intel Iris 12:43:48 INFO - Type: GPU 12:43:48 INFO - Bus: Built-In 12:43:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:43:48 INFO - Vendor: Intel (0x8086) 12:43:48 INFO - Device ID: 0x0a2e 12:43:48 INFO - Revision ID: 0x0009 12:43:48 INFO - Displays: 12:43:48 INFO - Display: 12:43:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:43:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:43:48 INFO - Display Serial Number: 1600x1200 60 12:43:48 INFO - Main Display: Yes 12:43:48 INFO - Mirror: Off 12:43:48 INFO - Online: Yes 12:43:48 INFO - Rotation: Supported 12:43:48 INFO - Return code: 0 12:43:48 INFO - Running post-action listener: _resource_record_post_action 12:43:48 INFO - ##### 12:43:48 INFO - ##### Finished run-tests step (success) 12:43:48 INFO - ##### 12:43:48 INFO - Running post-run listener: _resource_record_post_run 12:43:48 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 184320; Write bytes: 704083456; Read time: 7; Write time: 1910 12:43:48 INFO - TinderboxPrint: CPU usage
53.7% 12:43:48 INFO - TinderboxPrint: I/O read bytes / time
184,320 / 7 12:43:48 INFO - TinderboxPrint: I/O write bytes / time
704,083,456 / 1,910 12:43:48 INFO - TinderboxPrint: CPU idle
2,245.7 (46.3%) 12:43:48 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 12:43:48 INFO - TinderboxPrint: CPU user
1,631.2 (33.7%) 12:43:48 INFO - TinderboxPrint: Swap in / out
407,474,176 / 0 12:43:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404754944; Write bytes: 399955968; Read time: 31034; Write time: 733 12:43:48 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 184320; Write bytes: 292310528; Read time: 7; Write time: 1141 12:43:48 INFO - Running post-run listener: _upload_blobber_files 12:43:48 INFO - Blob upload gear active. 12:43:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:48 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:43:48 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:43:48 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:43:49 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:43:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:50 INFO - (blobuploader) - INFO - Done attempting. 12:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:51 INFO - (blobuploader) - INFO - Done attempting. 12:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:53 INFO - (blobuploader) - INFO - Done attempting. 12:43:53 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:53 INFO - Return code: 0 12:43:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6747304ad07e9b9b4d589227069bdda338159cfa3b2fc4bb3815a3ed21a97c8edacef6f85cd4e9d7db1b696036ce0e5738ea0c61d34a7267abd19f10b78ed4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da04db555eab99808e7a594a831df943d950d7149d4867f5a957500c105e640302c1d188c254d426f6ec587a08828174ea470d9bbcf6594cfa4603a0ebdb63b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/519bf4d086404bd0da4ac433cbf9090d4acea5ea280be4168e6e5cac9d0f95d7beebb734cabc6275a1125009f0e069e1c30ebe16d0c8702e5dcb0e1ae6a2ee58"} 12:43:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:53 INFO - Contents: 12:43:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6747304ad07e9b9b4d589227069bdda338159cfa3b2fc4bb3815a3ed21a97c8edacef6f85cd4e9d7db1b696036ce0e5738ea0c61d34a7267abd19f10b78ed4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da04db555eab99808e7a594a831df943d950d7149d4867f5a957500c105e640302c1d188c254d426f6ec587a08828174ea470d9bbcf6594cfa4603a0ebdb63b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/519bf4d086404bd0da4ac433cbf9090d4acea5ea280be4168e6e5cac9d0f95d7beebb734cabc6275a1125009f0e069e1c30ebe16d0c8702e5dcb0e1ae6a2ee58"} 12:43:53 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:53 INFO - Copying logs to upload dir... 12:43:53 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.396403 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-05 12:43:53.528507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:43:53.532116) ========= 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/c6747304ad07e9b9b4d589227069bdda338159cfa3b2fc4bb3815a3ed21a97c8edacef6f85cd4e9d7db1b696036ce0e5738ea0c61d34a7267abd19f10b78ed4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da04db555eab99808e7a594a831df943d950d7149d4867f5a957500c105e640302c1d188c254d426f6ec587a08828174ea470d9bbcf6594cfa4603a0ebdb63b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/519bf4d086404bd0da4ac433cbf9090d4acea5ea280be4168e6e5cac9d0f95d7beebb734cabc6275a1125009f0e069e1c30ebe16d0c8702e5dcb0e1ae6a2ee58"} build_url:https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011031 build_url: 'https://queue.taskcluster.net/v1/task/Zb9Wb-AVTBS8eCkgS845Jg/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/c6747304ad07e9b9b4d589227069bdda338159cfa3b2fc4bb3815a3ed21a97c8edacef6f85cd4e9d7db1b696036ce0e5738ea0c61d34a7267abd19f10b78ed4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da04db555eab99808e7a594a831df943d950d7149d4867f5a957500c105e640302c1d188c254d426f6ec587a08828174ea470d9bbcf6594cfa4603a0ebdb63b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/519bf4d086404bd0da4ac433cbf9090d4acea5ea280be4168e6e5cac9d0f95d7beebb734cabc6275a1125009f0e069e1c30ebe16d0c8702e5dcb0e1ae6a2ee58"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 12:43:53.564057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:43:53.564384) ========= 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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 12:43:53.613597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:43:53.613919) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 12:43:53.614261) ========= ========= Total master_lag: 0.17 =========