builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0319 starttime: 1467299041.87 results: success (0) buildid: 20160630061725 builduid: b74caa2562a74220a1cb97cfc5d3c721 revision: de6b42bae9ddb72f09f04173c306b8fad9fd9a8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:01.868220) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:01.868647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:01.869027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:01.895831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:01.896179) ========= 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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-30 08:04:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.71M=0.001s 2016-06-30 08:04:02 (9.71 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.466247 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:02.385002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:02.385378) ========= 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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.020646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:02.434155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 08:04:02.434492) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev de6b42bae9ddb72f09f04173c306b8fad9fd9a8d --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 de6b42bae9ddb72f09f04173c306b8fad9fd9a8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-30 08:04:02,519 truncating revision to first 12 chars 2016-06-30 08:04:02,519 Setting DEBUG logging. 2016-06-30 08:04:02,519 attempt 1/10 2016-06-30 08:04:02,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/de6b42bae9dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 08:04:03,950 unpacking tar archive at: fx-team-de6b42bae9dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.704396 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 08:04:04.286049) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:04.286352) ========= script_repo_revision: de6b42bae9ddb72f09f04173c306b8fad9fd9a8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:04.289771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:04.290063) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 08:04:04.308575) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-30 08:04:04.308978) ========= /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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:04 INFO - MultiFileLogger online at 20160630 08:04:04 in /builds/slave/test 08:04:04 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 08:04:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:04:04 INFO - {'append_to_log': False, 08:04:04 INFO - 'base_work_dir': '/builds/slave/test', 08:04:04 INFO - 'blob_upload_branch': 'Fx-Team', 08:04:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:04:04 INFO - 'branch': 'Fx-Team', 08:04:04 INFO - 'buildbot_json_path': 'buildprops.json', 08:04:04 INFO - 'config_files': ('talos/mac_config.py',), 08:04:04 INFO - 'default_actions': ('clobber', 08:04:04 INFO - 'read-buildbot-config', 08:04:04 INFO - 'download-and-extract', 08:04:04 INFO - 'populate-webroot', 08:04:04 INFO - 'create-virtualenv', 08:04:04 INFO - 'install', 08:04:04 INFO - 'run-tests'), 08:04:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:04:04 INFO - 'download_minidump_stackwalk': True, 08:04:04 INFO - 'download_symbols': 'ondemand', 08:04:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:04:04 INFO - 'tooltool.py': '/tools/tooltool.py', 08:04:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:04:04 INFO - '/tools/misc-python/virtualenv.py')}, 08:04:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:04:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:04:04 INFO - 'installer_path': 'installer.exe', 08:04:04 INFO - 'log_level': 'info', 08:04:04 INFO - 'log_name': 'talos', 08:04:04 INFO - 'log_to_console': True, 08:04:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:04:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:04:04 INFO - 'opt_config_files': (), 08:04:04 INFO - 'pip_index': False, 08:04:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:04 INFO - 'cmd': ('bash', 08:04:04 INFO - '-c', 08:04:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:04 INFO - 'enabled': True, 08:04:04 INFO - 'halt_on_failure': False, 08:04:04 INFO - 'name': 'check_screen_resolution'},), 08:04:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:04 INFO - 'cmd': ('bash', 08:04:04 INFO - '-c', 08:04:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:04 INFO - 'enabled': True, 08:04:04 INFO - 'halt_on_failure': False, 08:04:04 INFO - 'name': 'check_screen_resolution'},), 08:04:04 INFO - 'run_cmd_checks_enabled': True, 08:04:04 INFO - 'sps_profile': False, 08:04:04 INFO - 'sps_profile_interval': 0, 08:04:04 INFO - 'suite': 'dromaeojs', 08:04:04 INFO - 'system_bits': '32', 08:04:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:04:04 INFO - 'title': 't-yosemite-r7-0319', 08:04:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:04:04 INFO - 'use_talos_json': True, 08:04:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:04:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:04:04 INFO - 'work_dir': 'build'} 08:04:04 INFO - ##### 08:04:04 INFO - ##### Running clobber step. 08:04:04 INFO - ##### 08:04:04 INFO - Running pre-action listener: _resource_record_pre_action 08:04:04 INFO - Running main action method: clobber 08:04:04 INFO - rmtree: /builds/slave/test/build 08:04:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:04:06 INFO - Running post-action listener: _resource_record_post_action 08:04:06 INFO - ##### 08:04:06 INFO - ##### Finished clobber step (success) 08:04:06 INFO - ##### 08:04:06 INFO - ##### 08:04:06 INFO - ##### Running read-buildbot-config step. 08:04:06 INFO - ##### 08:04:06 INFO - Running pre-action listener: _resource_record_pre_action 08:04:06 INFO - Running main action method: read_buildbot_config 08:04:06 INFO - Using buildbot properties: 08:04:06 INFO - { 08:04:06 INFO - "project": "", 08:04:06 INFO - "product": "firefox", 08:04:06 INFO - "script_repo_revision": "production", 08:04:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:04:06 INFO - "repository": "", 08:04:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:04:06 INFO - "buildid": "20160630061725", 08:04:06 INFO - "pgo_build": "False", 08:04:06 INFO - "basedir": "/builds/slave/test", 08:04:06 INFO - "buildnumber": 61, 08:04:06 INFO - "slavename": "t-yosemite-r7-0319", 08:04:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:04:06 INFO - "platform": "yosemite_r7", 08:04:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:04:06 INFO - "branch": "fx-team", 08:04:06 INFO - "repo_path": "integration/fx-team", 08:04:06 INFO - "revision": "de6b42bae9ddb72f09f04173c306b8fad9fd9a8d", 08:04:06 INFO - "stage_platform": "macosx64", 08:04:06 INFO - "builduid": "b74caa2562a74220a1cb97cfc5d3c721", 08:04:06 INFO - "slavebuilddir": "test" 08:04:06 INFO - } 08:04:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 08:04:06 INFO - Running post-action listener: _resource_record_post_action 08:04:06 INFO - ##### 08:04:06 INFO - ##### Finished read-buildbot-config step (success) 08:04:06 INFO - ##### 08:04:06 INFO - ##### 08:04:06 INFO - ##### Running download-and-extract step. 08:04:06 INFO - ##### 08:04:06 INFO - Running pre-action listener: _resource_record_pre_action 08:04:06 INFO - Running main action method: download_and_extract 08:04:06 INFO - mkdir: /builds/slave/test/build/tests 08:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:06 INFO - https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:04:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:04:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:04:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:09 INFO - Downloaded 1199 bytes. 08:04:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 08:04:09 INFO - Using the following test package requirements: 08:04:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:04:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 08:04:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 08:04:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'jsshell-mac.zip'], 08:04:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 08:04:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:04:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 08:04:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 08:04:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 08:04:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:04:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 08:04:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:04:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:09 INFO - https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:04:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:04:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:04:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:11 INFO - Downloaded 18788652 bytes. 08:04:11 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'] 08:04:11 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 08:04:14 INFO - Return code: 0 08:04:14 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 08:04: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')]} 08:04:14 INFO - https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:04:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:04:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:04:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:17 INFO - Downloaded 18788652 bytes. 08:04:17 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'] 08:04:17 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 08:04:20 INFO - Return code: 0 08:04:20 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')]} 08:04:20 INFO - https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:04:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:04:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:04:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:21 INFO - Downloaded 14405242 bytes. 08:04:21 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'] 08:04:21 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 08:04:22 INFO - Return code: 0 08:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:22 INFO - https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:04:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:04:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:04:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 08:04:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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 08:04:25 INFO - Downloaded 112418459 bytes. 08:04:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:04:25 INFO - mkdir: /builds/slave/test/properties 08:04:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:04:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:04:25 INFO - Contents: 08:04:25 INFO - build_url:https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:04:25 INFO - Running post-action listener: _resource_record_post_action 08:04:25 INFO - Running post-action listener: set_extra_try_arguments 08:04:25 INFO - ##### 08:04:25 INFO - ##### Finished download-and-extract step (success) 08:04:25 INFO - ##### 08:04:25 INFO - ##### 08:04:25 INFO - ##### Running populate-webroot step. 08:04:25 INFO - ##### 08:04:25 INFO - Running pre-action listener: _resource_record_pre_action 08:04:25 INFO - Running main action method: populate_webroot 08:04:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:04:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:04:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:04:25 INFO - '--tppagecycles', 08:04:25 INFO - '7'], 08:04:25 INFO - 'tests': ['tsvgm']}}, 08:04:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:04:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:04:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:04:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:04:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['damp', 'tps']}, 08:04:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'tests': ['damp', 'tps']}, 08:04:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['dromaeo_dom']}, 08:04:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:04:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['basic_compositor_video']}, 08:04:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:04:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'other-e10s': {'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['a11yr', 08:04:25 INFO - 'ts_paint', 08:04:25 INFO - 'tpaint', 08:04:25 INFO - 'sessionrestore', 08:04:25 INFO - 'sessionrestore_no_auto_restore', 08:04:25 INFO - 'tabpaint']}, 08:04:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['tsvgx', 08:04:25 INFO - 'tsvgr_opacity', 08:04:25 INFO - 'tart', 08:04:25 INFO - 'tscrollx', 08:04:25 INFO - 'cart']}, 08:04:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:04:25 INFO - 'tsvgr_opacity', 08:04:25 INFO - 'tart', 08:04:25 INFO - 'tscrollx', 08:04:25 INFO - 'cart']}, 08:04:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'talos_options': ['--disable-e10s'], 08:04:25 INFO - 'tests': ['tp5o']}, 08:04:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'tests': ['tp5o']}, 08:04:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'talos_options': ['--disable-e10s', 08:04:25 INFO - '--xperf_path', 08:04:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:25 INFO - 'tests': ['tp5n']}, 08:04:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:25 INFO - 'talos_options': ['--xperf_path', 08:04:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:25 INFO - 'tests': ['tp5n']}}, 08:04:25 INFO - 'talos.zip': {'path': '', 08:04:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:04:25 INFO - Running post-action listener: _resource_record_post_action 08:04:25 INFO - ##### 08:04:25 INFO - ##### Finished populate-webroot step (success) 08:04:25 INFO - ##### 08:04:25 INFO - ##### 08:04:25 INFO - ##### Running create-virtualenv step. 08:04:25 INFO - ##### 08:04:25 INFO - Running pre-action listener: _resource_record_pre_action 08:04:25 INFO - Running main action method: create_virtualenv 08:04:25 INFO - Creating virtualenv /builds/slave/test/build/venv 08:04:25 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 08:04:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:04:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:04:25 INFO - Using real prefix '/tools/python27' 08:04:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:04:26 INFO - Installing distribute.............................................................................................................................................................................................done. 08:04:29 INFO - Installing pip.................done. 08:04:29 INFO - Return code: 0 08:04:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:04:29 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')]} 08:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:29 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')]} 08:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:29 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:29 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 08:04:29 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 08:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:29 INFO - 'HOME': '/Users/cltbld', 08:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:29 INFO - 'LOGNAME': 'cltbld', 08:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:29 INFO - 'MOZ_NO_REMOTE': '1', 08:04:29 INFO - 'NO_EM_RESTART': '1', 08:04:29 INFO - 'PAGER': '/bin/cat', 08:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:29 INFO - 'PWD': '/builds/slave/test', 08:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:29 INFO - 'SHELL': '/bin/bash', 08:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:29 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:29 INFO - 'USER': 'cltbld', 08:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:29 INFO - 'XPC_FLAGS': '0x0', 08:04:29 INFO - 'XPC_SERVICE_NAME': '0', 08:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:29 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 08:04:29 INFO - Cleaning up... 08:04:29 INFO - Return code: 0 08:04:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:04:29 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')]} 08:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:29 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')]} 08:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:29 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:29 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 08:04:29 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 08:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:29 INFO - 'HOME': '/Users/cltbld', 08:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:29 INFO - 'LOGNAME': 'cltbld', 08:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:29 INFO - 'MOZ_NO_REMOTE': '1', 08:04:29 INFO - 'NO_EM_RESTART': '1', 08:04:29 INFO - 'PAGER': '/bin/cat', 08:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:29 INFO - 'PWD': '/builds/slave/test', 08:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:29 INFO - 'SHELL': '/bin/bash', 08:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:29 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:29 INFO - 'USER': 'cltbld', 08:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:29 INFO - 'XPC_FLAGS': '0x0', 08:04:29 INFO - 'XPC_SERVICE_NAME': '0', 08:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:30 INFO - Downloading/unpacking psutil>=3.1.1 08:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:04:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:33 INFO - Installing collected packages: psutil 08:04:33 INFO - Running setup.py install for psutil 08:04:33 INFO - building 'psutil._psutil_osx' extension 08:04:33 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 08:04:33 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 08:04:33 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 08:04:33 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 08:04:33 INFO - building 'psutil._psutil_posix' extension 08:04:33 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 08:04:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:04:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:04:33 INFO - ^ 08:04:33 INFO - 1 warning generated. 08:04:33 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 08:04:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:33 INFO - Successfully installed psutil 08:04:33 INFO - Cleaning up... 08:04:33 INFO - Return code: 0 08:04:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:33 INFO - 'HOME': '/Users/cltbld', 08:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:33 INFO - 'LOGNAME': 'cltbld', 08:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:33 INFO - 'MOZ_NO_REMOTE': '1', 08:04:33 INFO - 'NO_EM_RESTART': '1', 08:04:33 INFO - 'PAGER': '/bin/cat', 08:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:33 INFO - 'PWD': '/builds/slave/test', 08:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:33 INFO - 'SHELL': '/bin/bash', 08:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:33 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:33 INFO - 'USER': 'cltbld', 08:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:33 INFO - 'XPC_FLAGS': '0x0', 08:04:33 INFO - 'XPC_SERVICE_NAME': '0', 08:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:04:34 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 08:04:34 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 08:04:34 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 08:04:34 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 08:04:34 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 08:04:34 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 08:04:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:04:36 INFO - Installing collected packages: mozsystemmonitor 08:04:36 INFO - Running setup.py install for mozsystemmonitor 08:04:36 INFO - Successfully installed mozsystemmonitor 08:04:36 INFO - Cleaning up... 08:04:36 INFO - Return code: 0 08:04:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:04: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')]} 08:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04: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')]} 08:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04: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==2.5.1'] in /builds/slave/test/build 08:04: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==2.5.1 08:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:36 INFO - 'HOME': '/Users/cltbld', 08:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:36 INFO - 'LOGNAME': 'cltbld', 08:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:36 INFO - 'MOZ_NO_REMOTE': '1', 08:04:36 INFO - 'NO_EM_RESTART': '1', 08:04:36 INFO - 'PAGER': '/bin/cat', 08:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:36 INFO - 'PWD': '/builds/slave/test', 08:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:36 INFO - 'SHELL': '/bin/bash', 08:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:36 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:36 INFO - 'USER': 'cltbld', 08:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:36 INFO - 'XPC_FLAGS': '0x0', 08:04:36 INFO - 'XPC_SERVICE_NAME': '0', 08:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:04:37 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 08:04:37 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 08:04:37 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 08:04:37 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 08:04:37 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 08:04:37 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 08:04:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:04:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:04:39 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 08:04:39 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 08:04:39 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 08:04:39 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 08:04:39 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 08:04:39 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 08:04:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:04:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:04:40 INFO - warning: no files found matching '*.txt' 08:04:40 INFO - no previously-included directories found matching 'build' 08:04:40 INFO - no previously-included directories found matching 'dist' 08:04:40 INFO - no previously-included directories found matching '.git*' 08:04:40 INFO - Installing collected packages: jsonschema, functools32 08:04:40 INFO - Running setup.py install for functools32 08:04:40 INFO - warning: no files found matching '*.txt' 08:04:40 INFO - no previously-included directories found matching 'build' 08:04:40 INFO - no previously-included directories found matching 'dist' 08:04:40 INFO - no previously-included directories found matching '.git*' 08:04:40 INFO - Successfully installed jsonschema functools32 08:04:40 INFO - Cleaning up... 08:04:40 INFO - Return code: 0 08:04:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:40 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:40 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 08:04:40 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 08:04:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:40 INFO - 'HOME': '/Users/cltbld', 08:04:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:40 INFO - 'LOGNAME': 'cltbld', 08:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:40 INFO - 'MOZ_NO_REMOTE': '1', 08:04:40 INFO - 'NO_EM_RESTART': '1', 08:04:40 INFO - 'PAGER': '/bin/cat', 08:04:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:40 INFO - 'PWD': '/builds/slave/test', 08:04:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:40 INFO - 'SHELL': '/bin/bash', 08:04:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:40 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:40 INFO - 'USER': 'cltbld', 08:04:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:40 INFO - 'XPC_FLAGS': '0x0', 08:04:40 INFO - 'XPC_SERVICE_NAME': '0', 08:04:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:04:40 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 08:04:40 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 08:04:40 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 08:04:40 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 08:04:40 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 08:04:40 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 08:04:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:04:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:04:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:43 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 08:04:44 INFO - Downloading docopt-0.6.1.tar.gz 08:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:04:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:04:44 INFO - Running setup.py install for blobuploader 08:04:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:04:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:04:44 INFO - Running setup.py install for requests 08:04:44 INFO - Running setup.py install for docopt 08:04:45 INFO - Successfully installed blobuploader requests docopt 08:04:45 INFO - Cleaning up... 08:04:45 INFO - Return code: 0 08:04:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:45 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')]} 08:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:45 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')]} 08:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:45 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:45 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 08:04:45 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 08:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:45 INFO - 'HOME': '/Users/cltbld', 08:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:45 INFO - 'LOGNAME': 'cltbld', 08:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:45 INFO - 'MOZ_NO_REMOTE': '1', 08:04:45 INFO - 'NO_EM_RESTART': '1', 08:04:45 INFO - 'PAGER': '/bin/cat', 08:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:45 INFO - 'PWD': '/builds/slave/test', 08:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:45 INFO - 'SHELL': '/bin/bash', 08:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:45 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:45 INFO - 'USER': 'cltbld', 08:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:45 INFO - 'XPC_FLAGS': '0x0', 08:04:45 INFO - 'XPC_SERVICE_NAME': '0', 08:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-5ZnSgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-GJnfRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1D9QT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-raGUNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-yQ70hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:45 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-fZs52L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OSkVuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9QV4QO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-tl0voz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-qocWrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-VKmReZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-zkvhXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-jYipns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-iFNZrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:46 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-c8EEoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:47 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-jAw5JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:47 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-BbbI_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:04:47 INFO - Running setup.py install for manifestparser 08:04:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:04:47 INFO - Running setup.py install for mozcrash 08:04:47 INFO - Running setup.py install for mozdebug 08:04:47 INFO - Running setup.py install for mozdevice 08:04:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:04:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:04:47 INFO - Running setup.py install for mozfile 08:04:47 INFO - Running setup.py install for mozhttpd 08:04:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Running setup.py install for mozinfo 08:04:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Running setup.py install for mozInstall 08:04:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Running setup.py install for mozleak 08:04:48 INFO - Running setup.py install for mozlog 08:04:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Running setup.py install for moznetwork 08:04:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:04:48 INFO - Running setup.py install for mozprocess 08:04:48 INFO - Running setup.py install for mozprofile 08:04:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:04:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:04:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:04:49 INFO - Running setup.py install for mozrunner 08:04:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:04:49 INFO - Running setup.py install for mozscreenshot 08:04:49 INFO - Running setup.py install for moztest 08:04:49 INFO - Running setup.py install for mozversion 08:04:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:04:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:04:49 INFO - Cleaning up... 08:04:49 INFO - Return code: 0 08:04:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:49 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:49 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 08:04:49 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 08:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:49 INFO - 'HOME': '/Users/cltbld', 08:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:49 INFO - 'LOGNAME': 'cltbld', 08:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:49 INFO - 'MOZ_NO_REMOTE': '1', 08:04:49 INFO - 'NO_EM_RESTART': '1', 08:04:49 INFO - 'PAGER': '/bin/cat', 08:04:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:49 INFO - 'PWD': '/builds/slave/test', 08:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:49 INFO - 'SHELL': '/bin/bash', 08:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:49 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:49 INFO - 'USER': 'cltbld', 08:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:49 INFO - 'XPC_FLAGS': '0x0', 08:04:49 INFO - 'XPC_SERVICE_NAME': '0', 08:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-_QSNO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-nky1b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-wcLTh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-0TUjAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OTDjKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-WC0Mok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-sFLRUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-SvA3rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:50 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)) 08:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:50 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-aqgTbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-8PfVUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-nyJm0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9C6n77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-CIhsgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-86aOwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-6zp9Yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-QF0TC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:51 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)) 08:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:51 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-ept6rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:51 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)) 08:04:51 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)) 08:04:51 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)) 08:04:51 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)) 08:04:51 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)) 08:04:51 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)) 08:04:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:54 INFO - Downloading blessings-1.6.tar.gz 08:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:04:54 INFO - Installing collected packages: blessings 08:04:54 INFO - Running setup.py install for blessings 08:04:54 INFO - Successfully installed blessings 08:04:54 INFO - Cleaning up... 08:04:54 INFO - Return code: 0 08:04:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:54 INFO - Reading from file tmpfile_stdout 08:04:54 INFO - Current package versions: 08:04:54 INFO - blessings == 1.6 08:04:54 INFO - blobuploader == 1.2.4 08:04:54 INFO - docopt == 0.6.1 08:04:54 INFO - functools32 == 3.2.3-2 08:04:54 INFO - jsonschema == 2.5.1 08:04:54 INFO - manifestparser == 1.1 08:04:54 INFO - mozInstall == 1.12 08:04:54 INFO - mozcrash == 0.17 08:04:54 INFO - mozdebug == 0.1 08:04:54 INFO - mozdevice == 0.48 08:04:54 INFO - mozfile == 1.2 08:04:54 INFO - mozhttpd == 0.7 08:04:54 INFO - mozinfo == 0.9 08:04:54 INFO - mozleak == 0.1 08:04:54 INFO - mozlog == 3.2 08:04:54 INFO - moznetwork == 0.27 08:04:54 INFO - mozprocess == 0.22 08:04:54 INFO - mozprofile == 0.28 08:04:54 INFO - mozrunner == 6.11 08:04:54 INFO - mozscreenshot == 0.1 08:04:54 INFO - mozsystemmonitor == 0.3 08:04:54 INFO - moztest == 0.7 08:04:54 INFO - mozversion == 1.4 08:04:54 INFO - psutil == 3.1.1 08:04:54 INFO - requests == 1.2.3 08:04:54 INFO - wsgiref == 0.1.2 08:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:54 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:54 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 08:04:54 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 08:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:54 INFO - 'HOME': '/Users/cltbld', 08:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:54 INFO - 'LOGNAME': 'cltbld', 08:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:54 INFO - 'MOZ_NO_REMOTE': '1', 08:04:54 INFO - 'NO_EM_RESTART': '1', 08:04:54 INFO - 'PAGER': '/bin/cat', 08:04:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:54 INFO - 'PWD': '/builds/slave/test', 08:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:54 INFO - 'SHELL': '/bin/bash', 08:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:54 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:54 INFO - 'USER': 'cltbld', 08:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:54 INFO - 'XPC_FLAGS': '0x0', 08:04:54 INFO - 'XPC_SERVICE_NAME': '0', 08:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 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)) 08:04:55 INFO - Cleaning up... 08:04:55 INFO - Return code: 0 08:04:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:55 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 0x107ed5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec69cb5960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, '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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 08:04:55 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 08:04:55 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 08:04:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:04:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:55 INFO - 'HOME': '/Users/cltbld', 08:04:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:55 INFO - 'LOGNAME': 'cltbld', 08:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:55 INFO - 'MOZ_NO_REMOTE': '1', 08:04:55 INFO - 'NO_EM_RESTART': '1', 08:04:55 INFO - 'PAGER': '/bin/cat', 08:04:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:55 INFO - 'PWD': '/builds/slave/test', 08:04:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:55 INFO - 'SHELL': '/bin/bash', 08:04:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:04:55 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:04:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:55 INFO - 'USER': 'cltbld', 08:04:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:55 INFO - 'XPC_FLAGS': '0x0', 08:04:55 INFO - 'XPC_SERVICE_NAME': '0', 08:04:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:04:55 INFO - Cleaning up... 08:04:55 INFO - Return code: 0 08:04:55 INFO - Running post-action listener: _resource_record_post_action 08:04:55 INFO - Running post-action listener: _start_resource_monitoring 08:04:55 INFO - Starting resource monitoring. 08:04:55 INFO - ##### 08:04:55 INFO - ##### Finished create-virtualenv step (success) 08:04:55 INFO - ##### 08:04:55 INFO - ##### 08:04:55 INFO - ##### Running install step. 08:04:55 INFO - ##### 08:04:55 INFO - Running pre-action listener: _resource_record_pre_action 08:04:55 INFO - Running main action method: install 08:04:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:55 INFO - Reading from file tmpfile_stdout 08:04:55 INFO - Detecting whether we're running mozinstall >=1.0... 08:04:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:04:55 INFO - Reading from file tmpfile_stdout 08:04:55 INFO - Output received: 08:04:55 INFO - Usage: mozinstall [options] installer 08:04:55 INFO - Options: 08:04:55 INFO - -h, --help show this help message and exit 08:04:55 INFO - -d DEST, --destination=DEST 08:04:55 INFO - Directory to install application into. [default: 08:04:55 INFO - "/builds/slave/test"] 08:04:55 INFO - --app=APP Application being installed. [default: firefox] 08:04:55 INFO - mkdir: /builds/slave/test/build/application 08:04:55 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'] 08:04:55 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 08:05:30 INFO - Reading from file tmpfile_stdout 08:05:30 INFO - Output received: 08:05:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:05:30 INFO - Running post-action listener: _resource_record_post_action 08:05:30 INFO - ##### 08:05:30 INFO - ##### Finished install step (success) 08:05:30 INFO - ##### 08:05:30 INFO - ##### 08:05:30 INFO - ##### Running run-tests step. 08:05:30 INFO - ##### 08:05:30 INFO - Running pre-action listener: _resource_record_pre_action 08:05:30 INFO - Running main action method: run_tests 08:05:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:30 INFO - 2016-06-30 08:05:30.108 screenresolution[1919:21254] starting screenresolution argv=screenresolution get 08:05:30 INFO - 2016-06-30 08:05:30.111 screenresolution[1919:21254] Display 0: 1600x1200x32@60 08:05:30 INFO - 2016-06-30 08:05:30.117 screenresolution[1920:21256] starting screenresolution argv=screenresolution list 08:05:30 INFO - Available Modes on Display 0 08:05:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:30 INFO - Intel Iris: 08:05:30 INFO - Chipset Model: Intel Iris 08:05:30 INFO - Type: GPU 08:05:30 INFO - Bus: Built-In 08:05:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:30 INFO - Vendor: Intel (0x8086) 08:05:30 INFO - Device ID: 0x0a2e 08:05:30 INFO - Revision ID: 0x0009 08:05:30 INFO - Displays: 08:05:30 INFO - Display: 08:05:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:30 INFO - Display Serial Number: 1600x1200 60 08:05:30 INFO - Main Display: Yes 08:05:30 INFO - Mirror: Off 08:05:30 INFO - Online: Yes 08:05:30 INFO - Rotation: Supported 08:05:30 INFO - Return code: 0 08:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:05:30 INFO - Python 2.7.3 08:05:30 INFO - Return code: 0 08:05:30 INFO - grabbing minidump binary from tooltool 08:05: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')]} 08:05:30 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 0x107e39d30>, '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 0x107b9edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e31b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:05:30 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 08:05:30 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 08:05:30 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 08:05:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:05:30 INFO - Return code: 0 08:05:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:05:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:05:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:05:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:05:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:05:30 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/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 08:05:30 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/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0319 --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 08:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8ujIOzGRa4/Render', 08:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:30 INFO - 'HOME': '/Users/cltbld', 08:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:30 INFO - 'LOGNAME': 'cltbld', 08:05:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:05:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:30 INFO - 'MOZ_NO_REMOTE': '1', 08:05:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:05:30 INFO - 'NO_EM_RESTART': '1', 08:05:30 INFO - 'PAGER': '/bin/cat', 08:05:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:30 INFO - 'PWD': '/builds/slave/test', 08:05:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:30 INFO - 'SHELL': '/bin/bash', 08:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKlTKdC2x6/Listeners', 08:05:30 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 08:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:30 INFO - 'USER': 'cltbld', 08:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:30 INFO - 'XPC_FLAGS': '0x0', 08:05:30 INFO - 'XPC_SERVICE_NAME': '0', 08:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:30 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/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 08:05:30 INFO - mozversion application_buildid: 20160630061725 08:05:30 INFO - mozversion application_changeset: de6b42bae9ddb72f09f04173c306b8fad9fd9a8d 08:05:30 INFO - mozversion application_display_name: Nightly 08:05:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:05:30 INFO - mozversion application_name: Firefox 08:05:30 INFO - mozversion application_remotingname: firefox 08:05:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:05:30 INFO - mozversion application_vendor: Mozilla 08:05:30 INFO - mozversion application_version: 50.0a1 08:05:30 INFO - mozversion platform_buildid: 20160630061725 08:05:30 INFO - mozversion platform_changeset: de6b42bae9ddb72f09f04173c306b8fad9fd9a8d 08:05:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:05:30 INFO - mozversion platform_version: 50.0a1 08:05:30 INFO - using testdate: 1467299130 08:05:30 INFO - actual date: 1467299130 08:05:30 INFO - starting webserver on 'localhost:55977' 08:05:30 INFO - SUITE-START | Running 2 tests 08:05:30 INFO - TEST-START | dromaeo_css 08:05:30 INFO - operating with platform_type : mac_ 08:05:30 INFO - Initialising browser for dromaeo_css test... 08:05:30 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpbWUAK7/profile http://localhost:55977/getInfo.html) 08:05:33 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 08:05:33 INFO - PROCESS | 1930 | colorDepth:24 08:05:33 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 08:05:33 INFO - PROCESS | 1930 | __metrics 08:05:33 INFO - PROCESS | 1930 | 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" 08:05:33 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:33 INFO - TEST-INFO | 1930: exit 0 08:05:33 INFO - Browser initialized. 08:05:33 INFO - Running cycle 1/1 for dromaeo_css test... 08:05:33 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpbWUAK7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:45 INFO - PROCESS | 1932 | RSS: Main: 244928512 08:05:45 INFO - PROCESS | 1932 | 08:06:37 INFO - PROCESS | 1932 | 1467299197423 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] 08:08:49 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55977/tests/dromaeo/cssquery-ext.html) 08:08:50 INFO - PROCESS | 1932 | RSS: Main: 361160704 08:08:50 INFO - PROCESS | 1932 | 08:11:53 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-ext.html (next: http://localhost:55977/tests/dromaeo/cssquery-jquery.html) 08:11:54 INFO - PROCESS | 1932 | RSS: Main: 379981824 08:11:54 INFO - PROCESS | 1932 | 08:14:56 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55977/tests/dromaeo/cssquery-mootools.html) 08:14:57 INFO - PROCESS | 1932 | RSS: Main: 373985280 08:14:57 INFO - PROCESS | 1932 | 08:17:59 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55977/tests/dromaeo/cssquery-prototype.html) 08:18:00 INFO - PROCESS | 1932 | RSS: Main: 375844864 08:18:00 INFO - PROCESS | 1932 | 08:21:03 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55977/tests/dromaeo/cssquery-yui.html) 08:21:04 INFO - PROCESS | 1932 | RSS: Main: 340611072 08:21:04 INFO - PROCESS | 1932 | 08:24:07 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:55977/tests/dromaeo/cssquery-yui.html (next: http://localhost:55977/tests/dromaeo/cssquery-dojo.html) 08:24:07 INFO - PROCESS | 1932 | RSS: Main: 393043968 08:24:07 INFO - PROCESS | 1932 | 08:24:07 INFO - PROCESS | 1932 | __start_tp_report 08:24:07 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 08:24:07 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 08:24:07 INFO - PROCESS | 1932 | |i|pagename|runs| 08:24:07 INFO - PROCESS | 1932 | |0;dojo.html;3352.296017836254;3354.0440974322323;3357.1774915145793;3410.931781364371;3431.571053618298;5614.326280846294;5652.943470565293;5730.713464326788;5741.454561816631;5749.655020698761;4819.325294458778;5671.631343962639;5736.139579063132;5754.568407369443;5776.133579963008;5219.086659834533;5263.947360526403;5274.340707411574;5275.788968441258;5352.464753524655;5354.9196762048405;5363.034653762341;5363.141897296433;5364.034473794715;5365.570754339644;5148.330717006784;5205.531502164805;5215.269862219274;5304;5353.973230133844;5293.920591191135;5311.336082989626;5313.57491400687;5322.988632159916;5355.973220133875;5100.209467532539;5138.97430512845;5188.740562971836;5245.239440281138;5248.134057880445;4701.365315682373;5655.3779084300695;5667.234923285345;5672.773089076432;5695.174199741055;6220.751169953196;6221.191245138103;6269.686515674198;6275.717592708339;6281.089242059921;6103.398280005586;6239.87520249597;6283.214598175228;6320.115183874262;6360.840978975562;5803.88392232153;5804.912926306109;5814.563907706939;5818.650880947157;5883.676397798162;6319.494440444753;6323.36766323364;6330.177076979963;6356.586821856565;6360.0777887206095;5866.823995280149;5934.465898069195;6081.3614570470345;6171.907421388682;6190.133381326618;6223.159873417121;6276.497880169575;6299.023651334051;6300.59046161998;6306.873862522724;6167.660778657128;6223.408776166257;6240.968795155995;6241.001439769615;6246.063090536456;6100.359462256488;6105.247473117015;6247.75008999644;6253.46845518127;6278.340774218733;3979.9801000994808;4902.828401006019;4958.429780575208;4960.677555958851;4971.825986090542;4393.055493068941;4405.03089320349;4409.250427427345;4434.157510073076;4472.9776351118035;5567.109262517978;5594.049011668027;5627.352854421712;5710.914336284876;5729.455701708331;5087.440381558025;6170.876582468325;6182.041783523561;6217;6233.34549872266;5644.181593668895;5671.007573674673;5698.401667824902;5706.9429305707245;5786.189933409326;5436.673799572039;5440.91838622421;5442.2380866678695;5442.945570544323;5460.126379779216;5317.574594032545;6245.3754624539;6302.936970630327;6328.62028278314;6349.936500634934;6114.692773002257;6176.845578860385;6182.165407669907;6209.472194863307;6221.346758590283;6301.243850737941;6325.304216536086;6336.144620476177;6345.777897773556;6358.491320694426;6026.096085587197;6200.472959798468;6201.627902325875;6223.315435302204;6231.501479881508;6125.571219981412;6181.1655426516845;6218.782342617987;6241.063840423973;6315.684215789284;6086.3305036446845;6092.8781424372155;6094.2077529920825;6095.481884039729;6101.542384321105;5876.3536011037895;6100.6705071301185;6102.58014228768;6124.350421260821;6124.594542075475;5112.437631860566;5862.530997520103;5886.911696324473;5903.439173278532;5981.3420523743225;6050.45545900871;6077;6089.147519347206;6094.268687757498;6100.6339619621185;4971.825986090469;5844.94155058461;5921.881562368814;5957.106434034929;5974.133750606051 08:24:07 INFO - PROCESS | 1932 | |1;ext.html;439.824070371851;518.4970578538818;531.5083547718359;537.4061661863641;553.167482938178;29677;32081.19797005076;39845.2030959381;40406;40570.39144412836;42240;42623.14753704924;42651.57348426515;42689.7865510672;42794;9518.19095376892;9676.274279429035;9686.951565242163;9718.416894986289;9721.659741909034;8656.220940115389;8850.911490885106;8858.73423797284;8919.28645708345;8929.241014513775;11707.00490458312;11943.58197463089;11948.283103013804;12143.57497487588;12211.877881221162;11817.881821181807;11916.16586838926;11929.522819087226;11929.642110736691;11931.463084161234;7519.849603007964;7566.697332106709;7578.393728501762;8594.656213751443;11424.086073114215;40093.398599021035;40448.79775601133;40491.7975410124;40525.594744052476;40665;2860.5852151437925;3233;3391.915202119942;3638.9810852961214;3688.1517251032415;30796.538051929238;30902.536461952863;31352;33106;33168;3200.103970888155;3227.7579181561478;3329.7646662305;3377.9493307600405;3390.7456940729544;493892;496434;498096;499279;500632;59559.70220148871;59655.40344596585;59987.70006149941;60144.398556014756;60179;64382;64586;64587;64664.67667661631;64681.67659161768;16439.424620138238;16603.916980415263;16645.50063498097;16658.16709164537;16674.249658765384;12792.744145117045;12847.036472264617;12884.48462061507;12893.48426062947;12894.29081400532;15721.528354149394;16447.342106315853;16539;18421.986790726372;18667.813321866608;7851.803704907423;7921.801954951057;7939.64271607779;8008.39937004727;8019.516389467967;921.8110287391069;992.5483904823288;1001.1340190734962;1032.2206733915846;1041.1150522056191;25693;30037.849810751242;30369.696303036686;30641.84679076635;30690.23274418113;1062.192733522513;1072.3137192197;1092.4428541443922;1116.7096554895786;1151.2574389518713;13723.313834308445;14705.779413308808;15789.842101579066;15994.440194593133;16165.272562734704;707790;714411;715881;718339.4083029551;719021;53478.19782703263;59410.70294648499;59419.40580594139;59454.027298634894;59589;1358.5109360630165;1373.3476598615578;1391.6729568551382;1397.0988712280725;1400.4468235047302;29848.206689651633;29997.4000519984;30146.84926575353;30897.91857285074;30942.38115237728;49700.75149624229;50145.498545015544;50147.498525014285;50161.498385015686;50220.74889625528;490074;505242;508200;508448;509465;26500.07249746327;26798.732012679622;26865.791039402884;26888.462230755667;26982;47425.76287118543;47973;48311;48426.75786621044;49072;39226.80386598049;39576.01059973524;39858;40272.99317517086;40937.181256375305;39358.803205984936;39886.60113398829;39903.28646906067;40326.59673403228;41024;38327.85016449511;38765;38945.8052709746;38972.02569935661;39015.82952511429;33422.83288583639;34172.31655366928;34817.47773783446;34885;35012.47481287732;23606.527869442172;25779.871100644377;25876.353091172117;25901.740982589934;26784 08:24:07 INFO - PROCESS | 1932 | |2;jquery.html;357.9284143171366;379.37782037458567;383.1054487771056;384.67302792626265;405.9208454351401;15088.849111508882;15339.846601533982;20462;20566.383008509732;20569.177232910664;20514.89742551289;20551.794482055175;21100.839453830034;21130.89434552829;21445.785542144575;5805.7387417566315;5965.373635768247;6107.175531303262;6165;6197.752089916398;5915.852103697399;6015.9398406016035;6097.817065488042;6099.542534309922;6101.389861013885;5119.462456442076;5452.945470545304;5900.734466948998;5988.371221021774;6043.879122417549;5640.717964101819;5684.829455116333;5707.74315155819;5718.685472299001;5723.341815691166;5063.8480845574695;5175.482451754794;5180.6373553851245;5194.11700010999;5196.272521846944;25086.498270034495;25963;26087.347816304744;26351.34121646974;26377.868110659325;2200.8239340852692;2263;2482.6027835546224;2514.120057979711;2959.8668059937354;15609.219539023117;16173.4339298124;16239.675206495922;16288.51134465956;16405;1795.425463851568;1811.0944527736133;1824.0970719493935;1869.7662792150982;1923.6056608395386;86819.12840794027;105342.47328763307;108285.91714082837;120667;124919.37540312241;10750.569977200981;10909.34543927367;10978.12175025996;11053.060489858291;11197.552097915992;12264.632061038183;12769.297688627028;12988.67528311781;13693.246871422167;13725.176489410467;8375.329973602098;8403.621837017348;8561.914380856111;8668.0898505656;8725.171108744658;8606.483610983361;8623.78440538979;8641.74694669282;8767.473951562926;8778.736637900873;6506.512011599149;6642.236142843538;6648.601083934883;6655.134832471747;6663.666816659148;1737.9051197745591;1844.6771814932333;1866.7199920012106;1889.9055047247582;1911.4326252472838;799.2247519905682;925.8426067568395;957.9760505987406;976.9560369783235;1048.570086264628;20614.484637883874;21570.245041423474;22231.333060008226;22430.327090187322;22523.774762252167;879.8856148700756;896.6278994217326;983.9212862970945;994.6817018554138;1008.0927165551062;11553;11671.416429178676;11813.173077884465;11872.287662740264;11880.821787673192;558006;592429;613024;619220;624664.8766756137;112044;115536.42231788787;115600.78386900984;115844.8415515834;117386;889.8309321229075;1000.0249756487513;1012.2762225009154;1039.4542864995938;1042.5517027678172;15879.682406351578;16327.918360408123;19346.613067738846;19594.804051959298;19883.60232795307;34960.125996850285;35656.82171589125;41235.35058597622;45219.321710175034;53573.17841394137;72131;72279.27720722725;73799.26200737924;73934.26065739489;74122.62938685271;7955.4828936118965;8031.223155187169;8105.424407087403;8114.959425202836;8237.83524329522;13315.733685326046;13319.200847949154;13445.260510672008;13520.526781562598;13826.239556824068;11532.88467115318;11537.88462115368;11566.826497602711;11686.474108664961;11722.413879306172;11277.774444511228;11352;11398.601048963246;11695.590654327058;11741.47163377633;11850.940745296562;11905.702357441134;12026.518939242333;12061.396930153633;12066.939665301617;6305.621662700252;7067.858642827012;8052.235037671412;8109.432339736162;8294.751157465285;10294.742631434274;11336.036436902956;11343;11412.771744565229;11574.305541667527 08:24:07 INFO - PROCESS | 1932 | |3;mootools.html;1375.2504884837763;1504.4132788212598;1547.9303431345588;1718.2096235731563;1900.2778944001286;7806.843863122741;8542.786430339236;8702;8708.477491350523;8831.249343805785;8486.0665326814;8729.738107856758;8732.95633521833;8733.86899196511;8740.694075707352;2091.602595506849;2169.967450488244;2169.967450488244;2319.1998760427628;2341.672165896776;1938.2150229157144;2163.2320526213202;2187.5843589717983;2418.093215044358;2419.6491508731224;1919.8656094073351;2357.1749887539395;2367.206985659806;2368.3723813189517;2369.5971684813535;2081.8646787958733;2291.2667946257206;2293.1973809166743;2301.1830800065904;2303.366574192094;2333.3933177373833;2359.728631207416;2372.655964885082;2374.0266490738713;2377.5839228135005;8811.206991370807;8880.955595221982;8888.688895888678;8902.376833621649;8912.955435222848;5216.66091704038;5374.973125134349;5384.434634363413;5386.569074474072;5389.353277606712;3519.225770330523;3601.405768048295;3637.963620363789;3640.2173532690335;3642.7267954903223;1056.2922841696081;1084.3385534823751;1093.557109370706;1104.1939384249542;1107.402002918423;11759.17685761997;11761.176717629683;11775.411229438494;11779.88220117805;11787.528498860122;4634.930476042862;4754.215554433549;4754.7147171169845;4811.109944660249;4821.566059054702;8406.957965210135;9794.65718699842;9889.554970026365;9913.900860991298;9927.602895884229;5338.599605029638;5342.2787923630585;5342.893142137135;5344.706041167696;5353.892922141536;4133.21468920904;4801.279808028754;4867.245576935581;4891.706497610084;4899.216125419987;3866.3620502617314;3904.7852368120025;3948.9210215795524;3984.442178095069;4018.015586181404;1254.5295514182183;1262.103906226571;1264.64589914824;1275.6236910111538;1279.6928737103028;891.1445012787667;896.5741272895323;916.5875356089653;938.6328007160924;953.7997020625608;5641.379448260688;5808.970955145197;5865.560082993792;5967.492763115184;6070.848228794315;429.78940319243344;445.1764236163137;446.29931008317277;456.0218331678597;456.83097254015564;3966.286068507696;4025.335819589735;4050.8177132029127;4063.959360406358;4083.265012297815;10402;10652.893471065345;10795.676129716121;10812.783744325227;10914.945425272981;10743.301685390581;10754.892451075546;10755.300905441121;10771.407572583583;10787.946060269649;7888.2506161914525;8234.711785087493;8239.711610093618;8244.752657420288;8260.504369737835;5752.252207213036;5882.205902203148;5893.823185304448;5937;5962.433568810956;8345.916540834756;10113.89886101159;10117.645882394081;10160.593576256866;10182.694519164437;8775.868361974703;9289.442633441744;9900.801983960424;10166.135878450414;10310.07209351162;6956.165260168812;7444.069491313586;7509.812254693677;7523.360514356341;7537.660805263667;8119.4316397853945;8122.837543248984;8125.374925015139;8195.83608327842;8214.83570328602;7373.33639972405;7422.591757453392;7423.109226892808;7513.1359893612935;7573.35626471756;6931.89602155958;7199.460040497089;7252.093488313961;7393.408527317694;7420.220876807857;6975;6977.651117444209;6989.475789315923;6998.580085194904;7074.893876591958;4500.797464114057;4686.179918514178;4774.546418090275;4964.304997300309;4976.676516026447;4824.300476430828;4857.705101439448;4872.220444728827;4904.068227036852;4992.350994370709 08:24:07 INFO - PROCESS | 1932 | |4;prototype.html;300.51466880987203;307.1185697049468;311.24833527032223;312.47348218252233;315.4511150597957;5110.77341438055;5378.435264297246;5417.864553386163;5514.972425137878;5529.562313798411;4435.68950173488;5425.02349577077;5457.645253058548;5467.753951072202;5474.671519708821;3866.729328946975;3948.111674873145;4082.040720430696;4103.589641035903;4166.229247589191;3415.487676848468;4028.97985510072;4117.629413352797;4140.171965606875;4148.94201978495;3855.8843234703004;4075.246079475292;4103.507579090513;4112.856050038249;4123.5876412358675;3544.4151714967;3906.7069969752242;4024.959750402517;4048.271311164004;4159.459270294872;3554.5556805399324;3580.516630254934;3583.677469027774;3586.33652774235;3606.350856845767;4038.6163314485075;5219.530242278213;5261.684298942076;5277.973610131963;5330.013947419739;4213.768242746648;4289.571042895716;4386.583274588909;4706.752340884778;4747.8575642730775;4636.724900652314;5634.239377684042;5666.704984481292;5694.921353705909;5704.548748007479;3937.960620393788;3950.9012274693077;3954.980225098856;3966.6794924968926;3970.9007274818073;5033.622478314104;5204.9329887373;5366.329208848894;5613.607047506635;5958.6424814511265;3847;3886.805659717003;3904.921901561953;3909.5730058528434;3920.000399898008;3968.7420317679257;3992.3612222044335;4014.8193331300163;4034.3948407739076;4035.7174997750462;3886.9204975338057;4046.320776877563;4383.868483945486;4676.789544470507;4715.858524244278;5690.7439165237665;5738.360354237014;5746.569007324384;5751.539876809845;5776.508996735324;1073.0771536478621;1363.9727205456034;1365.5698454986648;1374.673440130279;1380.0891411668451;764.7038270132184;794.3089512124374;828.7845160258377;837.6942416018231;837.7433849226161;4936.5190442867;4968.329275547827;5120.590352771769;5369.704666243316;5373.946260537423;5894.614765530097;5895.35151133375;5896.410358964069;5907.202527658711;5914.822555323347;4448.154850578379;5222.843314700565;5340.572754179734;5350.652207606493;5366.9534440784455;2796.979102627526;2917.897873574457;3091.335362896988;3097.736692381173;3121.453745594512;5611.410801865827;5870.090136028923;6034.732706131674;6097.603655762361;6116.724747386378;5732.598718089777;5791.349465402424;6069.969650151721;6074.605150665193;6093.512518998469;5379.327584051994;5948.345681975065;6069.908951365733;6072.453479186895;6140.925338065731;5072.442031376472;5330.9733451332495;5910.438508341701;5925.555583331181;6012.398760123952;4937;5903.527717782481;5932.288125424977;5950.851228719317;5970.283565972111;4920.360353154067;5889.910366582196;6170.598911070766;6208.751649934133;6243.719032643451;3411;3541.2386336937684;3614.1506745915217;3879.243547508209;3879.534455865314;5851.356350801323;5860.736266867916;5861.912071318849;5870.823875283749;5953.9404605955115;4743.810247590196;5677.51741102011;5684.77260909559;5689.146628005832;5744.357595505947;5447;5791.594588378773;5792.304923409218;5809.680467574324;5811.389804070512;4519.096180763926;5342.8512869733195;5424.528301886875;5560.388357280615;5645.548356131418;4914;5723.456271654186;5777.277840269966;5798.275215598051;5813.447722466359;4886.315915771724;5367.899580585892;5653.236813030353;5691.943080569141;5702.525575447617 08:24:08 INFO - PROCESS | 1932 | |5;yui.html;709.5281637710923;719.8272414620493;740.9555426674403;776.6349815586676;837.2172019162082;15049.924750376247;16053.357865685388;16066.59833504163;16079.276432560533;16083.35666573337;14025.649358766035;14095.295235238249;14175.149491030545;14176.716465670681;14190.006699531037;671.8589096289785;677.288846710954;688.3976520544524;689.8723736108836;831.0526000359595;683.1187767779584;816.2286639126029;825.7976795685065;836.6653338664553;842.7808769719855;893.0266010049045;893.3701740273092;896.4307664632972;901.8016036471965;906.5784410249232;711.4379640084379;864.0754392799707;864.6498168241841;865.3077537969599;866.4238281542766;649.8830210562097;650.1418128070949;654.4829584628138;672.0020769157796;672.3881268046104;15281.235938203044;15296;15305.08169509833;15334.693306133815;15342.539723808304;13645.863541364559;14150.29248537569;14364.138151710826;14421.35103920326;14486.42054317826;12846.35768211595;12913.354332283348;12977.35113244334;13177.407016684272;13435.12671676338;4605.930911036304;4639.30410438437;4668.393108895856;4797.280407938802;4822.445418776815;107981;108223;109500;109740.35389469244;109937.90062099436;11134.109271258441;11236.269642473213;11238.775224495628;11268.83096753533;11750.353730544903;22352.552948941255;22414.663780043313;22451;22457.55084898293;22463.989120489616;7751.612419379121;7771.222877712296;7790.5325680459355;7822.687092516236;7939.960300198462;4327.913441731148;4347.174036932973;4384.649228061748;4405.537418571068;4531.274996000624;3389.779664321811;3503.9124021899656;3512.648735126518;3518.894433167009;3934.1541568562325;713.2475238623302;719.7193094693074;719.851836321067;721.4084450750335;737.6938570493185;541.5748637319685;662.231959631614;677.8852366137498;681.608075356662;699.5627732667083;9165.404248723811;9191.448513089103;9368.110029547182;9495.43027418357;9591.376560523544;562.6005536069437;583.8190161050088;611.3950878942775;626.3798839149209;666.8399584099878;4552.931706024412;4584.839530616479;4704.270838020112;4813.700300918732;4831.227003679394;159871.40128598566;162717;166037;167317;171536;129752.35123824321;132701.33649331692;134600;134911;140525.59474405125;122643;124033.37983310026;128930;129221.35389322993;131714.68285317215;8751.431156974777;10004.499775011365;10067.546960386946;10111.046462476465;10230;62559.6872015637;62744;64488;64685.35314646793;65307.346926530125;67099.32251693551;86885;97369;99466.5026674862;101800.4909975475;12142.757144856876;13410.262435566137;13621;13743.725125497234;14251.358688859234;11004.284721493077;13472.326383680973;15257.313420896307;15314.387424502895;15327.310271038054;14332.641683957985;14340.784888226477;14355.56933292003;14387.712245754818;14458.638534036732;13628.386722597705;13774.65563360924;14041.648958775704;14053.789193162314;14232;14047.578572642837;14130.364133614217;14282.07166534172;14288.928330375056;14592.489262875748;11168.944155279172;11673.824892626788;11973.880261197624;11973.940130299641;12526.248425094524;9998.550065246936;11446.484908178985;11559.537618495166;11800.05599552051;12038.337891416057 08:24:08 INFO - PROCESS | 1932 | __end_tp_report 08:24:08 INFO - PROCESS | 1932 | __start_cc_report 08:24:08 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1249 08:24:08 INFO - PROCESS | 1932 | __end_cc_report 08:24:08 INFO - PROCESS | 1932 | __startTimestamp1467300247988__endTimestamp 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | ------- Summary: start ------- 08:24:08 INFO - PROCESS | 1932 | Number of tests: 6 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5715.63 Median:5883.68 stddev:650.97 (11.1%) stddev-sans-first:626.01 08:24:08 INFO - PROCESS | 1932 | Values: 3352.3 3354.0 3357.2 3410.9 3431.6 5614.3 5652.9 5730.7 5741.5 5749.7 4819.3 5671.6 5736.1 5754.6 5776.1 5219.1 5263.9 5274.3 5275.8 5352.5 5354.9 5363.0 5363.1 5364.0 5365.6 5148.3 5205.5 5215.3 5304.0 5354.0 5293.9 5311.3 5313.6 5323.0 5356.0 5100.2 5139.0 5188.7 5245.2 5248.1 4701.4 5655.4 5667.2 5672.8 5695.2 6220.8 6221.2 6269.7 6275.7 6281.1 6103.4 6239.9 6283.2 6320.1 6360.8 5803.9 5804.9 5814.6 5818.7 5883.7 6319.5 6323.4 6330.2 6356.6 6360.1 5866.8 5934.5 6081.4 6171.9 6190.1 6223.2 6276.5 6299.0 6300.6 6306.9 6167.7 6223.4 6241.0 6241.0 6246.1 6100.4 6105.2 6247.8 6253.5 6278.3 3980.0 4902.8 4958.4 4960.7 4971.8 4393.1 4405.0 4409.3 4434.2 4473.0 5567.1 5594.0 5627.4 5710.9 5729.5 5087.4 6170.9 6182.0 6217.0 6233.3 5644.2 5671.0 5698.4 5706.9 5786.2 5436.7 5440.9 5442.2 5442.9 5460.1 5317.6 6245.4 6302.9 6328.6 6349.9 6114.7 6176.8 6182.2 6209.5 6221.3 6301.2 6325.3 6336.1 6345.8 6358.5 6026.1 6200.5 6201.6 6223.3 6231.5 6125.6 6181.2 6218.8 6241.1 6315.7 6086.3 6092.9 6094.2 6095.5 6101.5 5876.4 6100.7 6102.6 6124.4 6124.6 5112.4 5862.5 5886.9 5903.4 5981.3 6050.5 6077.0 6089.1 6094.3 6100.6 4971.8 5844.9 5921.9 5957.1 5974.1 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:70738.47 Median:39121.32 stddev:155508.00 (397.5%) stddev-sans-first:155854.67 08:24:08 INFO - PROCESS | 1932 | Values: 439.8 518.5 531.5 537.4 553.2 29677.0 32081.2 39845.2 40406.0 40570.4 42240.0 42623.1 42651.6 42689.8 42794.0 9518.2 9676.3 9687.0 9718.4 9721.7 8656.2 8850.9 8858.7 8919.3 8929.2 11707.0 11943.6 11948.3 12143.6 12211.9 11817.9 11916.2 11929.5 11929.6 11931.5 7519.8 7566.7 7578.4 8594.7 11424.1 40093.4 40448.8 40491.8 40525.6 40665.0 2860.6 3233.0 3391.9 3639.0 3688.2 30796.5 30902.5 31352.0 33106.0 33168.0 3200.1 3227.8 3329.8 3377.9 3390.7 493892.0 496434.0 498096.0 499279.0 500632.0 59559.7 59655.4 59987.7 60144.4 60179.0 64382.0 64586.0 64587.0 64664.7 64681.7 16439.4 16603.9 16645.5 16658.2 16674.2 12792.7 12847.0 12884.5 12893.5 12894.3 15721.5 16447.3 16539.0 18422.0 18667.8 7851.8 7921.8 7939.6 8008.4 8019.5 921.8 992.5 1001.1 1032.2 1041.1 25693.0 30037.8 30369.7 30641.8 30690.2 1062.2 1072.3 1092.4 1116.7 1151.3 13723.3 14705.8 15789.8 15994.4 16165.3 707790.0 714411.0 715881.0 718339.4 719021.0 53478.2 59410.7 59419.4 59454.0 59589.0 1358.5 1373.3 1391.7 1397.1 1400.4 29848.2 29997.4 30146.8 30897.9 30942.4 49700.8 50145.5 50147.5 50161.5 50220.7 490074.0 505242.0 508200.0 508448.0 509465.0 26500.1 26798.7 26865.8 26888.5 26982.0 47425.8 47973.0 48311.0 48426.8 49072.0 39226.8 39576.0 39858.0 40273.0 40937.2 39358.8 39886.6 39903.3 40326.6 41024.0 38327.9 38765.0 38945.8 38972.0 39015.8 33422.8 34172.3 34817.5 34885.0 35012.5 23606.5 25779.9 25876.4 25901.7 26784.0 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:34335.87 Median:12393.39 stddev:99815.34 (805.4%) stddev-sans-first:100062.74 08:24:08 INFO - PROCESS | 1932 | Values: 357.9 379.4 383.1 384.7 405.9 15088.8 15339.8 20462.0 20566.4 20569.2 20514.9 20551.8 21100.8 21130.9 21445.8 5805.7 5965.4 6107.2 6165.0 6197.8 5915.9 6015.9 6097.8 6099.5 6101.4 5119.5 5452.9 5900.7 5988.4 6043.9 5640.7 5684.8 5707.7 5718.7 5723.3 5063.8 5175.5 5180.6 5194.1 5196.3 25086.5 25963.0 26087.3 26351.3 26377.9 2200.8 2263.0 2482.6 2514.1 2959.9 15609.2 16173.4 16239.7 16288.5 16405.0 1795.4 1811.1 1824.1 1869.8 1923.6 86819.1 105342.5 108285.9 120667.0 124919.4 10750.6 10909.3 10978.1 11053.1 11197.6 12264.6 12769.3 12988.7 13693.2 13725.2 8375.3 8403.6 8561.9 8668.1 8725.2 8606.5 8623.8 8641.7 8767.5 8778.7 6506.5 6642.2 6648.6 6655.1 6663.7 1737.9 1844.7 1866.7 1889.9 1911.4 799.2 925.8 958.0 977.0 1048.6 20614.5 21570.2 22231.3 22430.3 22523.8 879.9 896.6 983.9 994.7 1008.1 11553.0 11671.4 11813.2 11872.3 11880.8 558006.0 592429.0 613024.0 619220.0 624664.9 112044.0 115536.4 115600.8 115844.8 117386.0 889.8 1000.0 1012.3 1039.5 1042.6 15879.7 16327.9 19346.6 19594.8 19883.6 34960.1 35656.8 41235.4 45219.3 53573.2 72131.0 72279.3 73799.3 73934.3 74122.6 7955.5 8031.2 8105.4 8115.0 8237.8 13315.7 13319.2 13445.3 13520.5 13826.2 11532.9 11537.9 11566.8 11686.5 11722.4 11277.8 11352.0 11398.6 11695.6 11741.5 11850.9 11905.7 12026.5 12061.4 12066.9 6305.6 7067.9 8052.2 8109.4 8294.8 10294.7 11336.0 11343.0 11412.8 11574.3 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5657.65 Median:4822.93 stddev:3202.24 (66.4%) stddev-sans-first:3195.06 08:24:08 INFO - PROCESS | 1932 | Values: 1375.3 1504.4 1547.9 1718.2 1900.3 7806.8 8542.8 8702.0 8708.5 8831.2 8486.1 8729.7 8733.0 8733.9 8740.7 2091.6 2170.0 2170.0 2319.2 2341.7 1938.2 2163.2 2187.6 2418.1 2419.6 1919.9 2357.2 2367.2 2368.4 2369.6 2081.9 2291.3 2293.2 2301.2 2303.4 2333.4 2359.7 2372.7 2374.0 2377.6 8811.2 8881.0 8888.7 8902.4 8913.0 5216.7 5375.0 5384.4 5386.6 5389.4 3519.2 3601.4 3638.0 3640.2 3642.7 1056.3 1084.3 1093.6 1104.2 1107.4 11759.2 11761.2 11775.4 11779.9 11787.5 4634.9 4754.2 4754.7 4811.1 4821.6 8407.0 9794.7 9889.6 9913.9 9927.6 5338.6 5342.3 5342.9 5344.7 5353.9 4133.2 4801.3 4867.2 4891.7 4899.2 3866.4 3904.8 3948.9 3984.4 4018.0 1254.5 1262.1 1264.6 1275.6 1279.7 891.1 896.6 916.6 938.6 953.8 5641.4 5809.0 5865.6 5967.5 6070.8 429.8 445.2 446.3 456.0 456.8 3966.3 4025.3 4050.8 4064.0 4083.3 10402.0 10652.9 10795.7 10812.8 10914.9 10743.3 10754.9 10755.3 10771.4 10787.9 7888.3 8234.7 8239.7 8244.8 8260.5 5752.3 5882.2 5893.8 5937.0 5962.4 8345.9 10113.9 10117.6 10160.6 10182.7 8775.9 9289.4 9900.8 10166.1 10310.1 6956.2 7444.1 7509.8 7523.4 7537.7 8119.4 8122.8 8125.4 8195.8 8214.8 7373.3 7422.6 7423.1 7513.1 7573.4 6931.9 7199.5 7252.1 7393.4 7420.2 6975.0 6977.7 6989.5 6998.6 7074.9 4500.8 4686.2 4774.5 4964.3 4976.7 4824.3 4857.7 4872.2 4904.1 4992.4 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4623.39 Median:5303.99 stddev:1447.32 (27.3%) stddev-sans-first:1414.55 08:24:08 INFO - PROCESS | 1932 | Values: 300.5 307.1 311.2 312.5 315.5 5110.8 5378.4 5417.9 5515.0 5529.6 4435.7 5425.0 5457.6 5467.8 5474.7 3866.7 3948.1 4082.0 4103.6 4166.2 3415.5 4029.0 4117.6 4140.2 4148.9 3855.9 4075.2 4103.5 4112.9 4123.6 3544.4 3906.7 4025.0 4048.3 4159.5 3554.6 3580.5 3583.7 3586.3 3606.4 4038.6 5219.5 5261.7 5278.0 5330.0 4213.8 4289.6 4386.6 4706.8 4747.9 4636.7 5634.2 5666.7 5694.9 5704.5 3938.0 3950.9 3955.0 3966.7 3970.9 5033.6 5204.9 5366.3 5613.6 5958.6 3847.0 3886.8 3904.9 3909.6 3920.0 3968.7 3992.4 4014.8 4034.4 4035.7 3886.9 4046.3 4383.9 4676.8 4715.9 5690.7 5738.4 5746.6 5751.5 5776.5 1073.1 1364.0 1365.6 1374.7 1380.1 764.7 794.3 828.8 837.7 837.7 4936.5 4968.3 5120.6 5369.7 5373.9 5894.6 5895.4 5896.4 5907.2 5914.8 4448.2 5222.8 5340.6 5350.7 5367.0 2797.0 2917.9 3091.3 3097.7 3121.5 5611.4 5870.1 6034.7 6097.6 6116.7 5732.6 5791.3 6070.0 6074.6 6093.5 5379.3 5948.3 6069.9 6072.5 6140.9 5072.4 5331.0 5910.4 5925.6 6012.4 4937.0 5903.5 5932.3 5950.9 5970.3 4920.4 5889.9 6170.6 6208.8 6243.7 3411.0 3541.2 3614.2 3879.2 3879.5 5851.4 5860.7 5861.9 5870.8 5953.9 4743.8 5677.5 5684.8 5689.1 5744.4 5447.0 5791.6 5792.3 5809.7 5811.4 4519.1 5342.9 5424.5 5560.4 5645.5 4914.0 5723.5 5777.3 5798.3 5813.4 4886.3 5367.9 5653.2 5691.9 5702.5 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:26154.75 Median:22432.83 stddev:42519.21 (189.5%) stddev-sans-first:42595.57 08:24:08 INFO - PROCESS | 1932 | Values: 709.5 719.8 741.0 776.6 837.2 15049.9 16053.4 16066.6 16079.3 16083.4 14025.6 14095.3 14175.1 14176.7 14190.0 671.9 677.3 688.4 689.9 831.1 683.1 816.2 825.8 836.7 842.8 893.0 893.4 896.4 901.8 906.6 711.4 864.1 864.6 865.3 866.4 649.9 650.1 654.5 672.0 672.4 15281.2 15296.0 15305.1 15334.7 15342.5 13645.9 14150.3 14364.1 14421.4 14486.4 12846.4 12913.4 12977.4 13177.4 13435.1 4605.9 4639.3 4668.4 4797.3 4822.4 107981.0 108223.0 109500.0 109740.4 109937.9 11134.1 11236.3 11238.8 11268.8 11750.4 22352.6 22414.7 22451.0 22457.6 22464.0 7751.6 7771.2 7790.5 7822.7 7940.0 4327.9 4347.2 4384.6 4405.5 4531.3 3389.8 3503.9 3512.6 3518.9 3934.2 713.2 719.7 719.9 721.4 737.7 541.6 662.2 677.9 681.6 699.6 9165.4 9191.4 9368.1 9495.4 9591.4 562.6 583.8 611.4 626.4 666.8 4552.9 4584.8 4704.3 4813.7 4831.2 159871.4 162717.0 166037.0 167317.0 171536.0 129752.4 132701.3 134600.0 134911.0 140525.6 122643.0 124033.4 128930.0 129221.4 131714.7 8751.4 10004.5 10067.5 10111.0 10230.0 62559.7 62744.0 64488.0 64685.4 65307.3 67099.3 86885.0 97369.0 99466.5 101800.5 12142.8 13410.3 13621.0 13743.7 14251.4 11004.3 13472.3 15257.3 15314.4 15327.3 14332.6 14340.8 14355.6 14387.7 14458.6 13628.4 13774.7 14041.6 14053.8 14232.0 14047.6 14130.4 14282.1 14288.9 14592.5 11168.9 11673.8 11973.9 11973.9 12526.2 9998.6 11446.5 11559.5 11800.1 12038.3 08:24:08 INFO - PROCESS | 1932 | -------- Summary: end -------- 08:24:08 INFO - PROCESS | 1932 | 08:24:08 INFO - TEST-INFO | 1932: exit 0 08:24:08 INFO - TEST-OK | dromaeo_css | took 1117878ms 08:24:08 INFO - TEST-START | kraken 08:24:08 INFO - operating with platform_type : mac_ 08:24:08 INFO - Initialising browser for kraken test... 08:24:08 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpstIfWt/profile http://localhost:55977/getInfo.html) 08:24:10 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 08:24:10 INFO - PROCESS | 1935 | colorDepth:24 08:24:10 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 08:24:10 INFO - PROCESS | 1935 | __metrics 08:24:10 INFO - PROCESS | 1935 | 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" 08:24:10 INFO - PROCESS | 1935 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:11 INFO - TEST-INFO | 1935: exit 0 08:24:11 INFO - Browser initialized. 08:24:11 INFO - Running cycle 1/1 for kraken test... 08:24:11 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpstIfWt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:23 INFO - PROCESS | 1936 | RSS: Main: 244715520 08:24:23 INFO - PROCESS | 1936 | 08:24:26 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55977/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:27 INFO - PROCESS | 1936 | RSS: Main: 294158336 08:24:27 INFO - PROCESS | 1936 | 08:24:30 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55977/tests/kraken/driver.html?testName=audio-dft) 08:24:30 INFO - PROCESS | 1936 | RSS: Main: 304889856 08:24:30 INFO - PROCESS | 1936 | 08:24:34 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55977/tests/kraken/driver.html?testName=audio-fft) 08:24:34 INFO - PROCESS | 1936 | RSS: Main: 390623232 08:24:34 INFO - PROCESS | 1936 | 08:24:36 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55977/tests/kraken/driver.html?testName=audio-oscillator) 08:24:37 INFO - PROCESS | 1936 | RSS: Main: 294293504 08:24:37 INFO - PROCESS | 1936 | 08:24:39 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55977/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:24:40 INFO - PROCESS | 1936 | RSS: Main: 297869312 08:24:40 INFO - PROCESS | 1936 | 08:24:42 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55977/tests/kraken/driver.html?testName=imaging-darkroom) 08:24:43 INFO - PROCESS | 1936 | RSS: Main: 336535552 08:24:43 INFO - PROCESS | 1936 | 08:24:46 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55977/tests/kraken/driver.html?testName=imaging-desaturate) 08:24:47 INFO - PROCESS | 1936 | RSS: Main: 336973824 08:24:47 INFO - PROCESS | 1936 | 08:24:49 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55977/tests/kraken/driver.html?testName=json-parse-financial) 08:24:50 INFO - PROCESS | 1936 | RSS: Main: 334819328 08:24:50 INFO - PROCESS | 1936 | 08:24:51 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55977/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:24:52 INFO - PROCESS | 1936 | RSS: Main: 313856000 08:24:52 INFO - PROCESS | 1936 | 08:24:54 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:24:55 INFO - PROCESS | 1936 | RSS: Main: 321290240 08:24:55 INFO - PROCESS | 1936 | 08:24:57 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:24:57 INFO - PROCESS | 1936 | RSS: Main: 318017536 08:24:57 INFO - PROCESS | 1936 | 08:25:00 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:01 INFO - PROCESS | 1936 | RSS: Main: 328159232 08:25:01 INFO - PROCESS | 1936 | 08:25:04 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:04 INFO - PROCESS | 1936 | RSS: Main: 330412032 08:25:04 INFO - PROCESS | 1936 | 08:25:06 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55977/tests/kraken/driver.html?testName=ai-astar) 08:25:07 INFO - PROCESS | 1936 | RSS: Main: 373493760 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | __start_tp_report 08:25:07 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 08:25:07 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 08:25:07 INFO - PROCESS | 1936 | |i|pagename|runs| 08:25:07 INFO - PROCESS | 1936 | |0;ai-astar;102;112;111;113;112;111;113;117;110;111 08:25:07 INFO - PROCESS | 1936 | |1;audio-beat-detection;143;107;109;159;105;106;128;109;173;134 08:25:07 INFO - PROCESS | 1936 | |2;audio-dft;182;184;182;183;182;182;182;182;183;183 08:25:07 INFO - PROCESS | 1936 | |3;audio-fft;80;76;48;92;73;81;87;88;83;58 08:25:07 INFO - PROCESS | 1936 | |4;audio-oscillator;89;90;93;72;76;70;97;73;68;103 08:25:07 INFO - PROCESS | 1936 | |5;imaging-gaussian-blur;89;85;86;83;87;84;87;85;87;83 08:25:07 INFO - PROCESS | 1936 | |6;imaging-darkroom;92;94;93;95;93;93;94;95;94;92 08:25:07 INFO - PROCESS | 1936 | |7;imaging-desaturate;87;84;87;83;83;84;82;84;85;84 08:25:07 INFO - PROCESS | 1936 | |8;json-parse-financial;55;55;54;53;56;53;54;55;54;55 08:25:07 INFO - PROCESS | 1936 | |9;json-stringify-tinderbox;65;67;73;77;72;71;68;71;69;71 08:25:07 INFO - PROCESS | 1936 | |10;stanford-crypto-aes;85;90;102;85;85;82;84;84;82;85 08:25:07 INFO - PROCESS | 1936 | |11;stanford-crypto-ccm;139;152;135;138;134;139;150;133;249;151 08:25:07 INFO - PROCESS | 1936 | |12;stanford-crypto-pbkdf2;167;166;167;166;165;161;171;163;190;164 08:25:07 INFO - PROCESS | 1936 | |13;stanford-crypto-sha256-iterative;67;68;69;71;83;84;86;80;66;66 08:25:07 INFO - PROCESS | 1936 | __end_tp_report 08:25:07 INFO - PROCESS | 1936 | __start_cc_report 08:25:07 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,2025 08:25:07 INFO - PROCESS | 1936 | __end_cc_report 08:25:07 INFO - PROCESS | 1936 | __startTimestamp1467300307059__endTimestamp 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | ------- Summary: start ------- 08:25:07 INFO - PROCESS | 1936 | Number of tests: 14 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.77 (3.4%) stddev-sans-first:2.05 08:25:07 INFO - PROCESS | 1936 | Values: 102.0 112.0 111.0 113.0 112.0 111.0 113.0 117.0 110.0 111.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:131.00 stddev:24.54 (18.7%) stddev-sans-first:25.36 08:25:07 INFO - PROCESS | 1936 | Values: 143.0 107.0 109.0 159.0 105.0 106.0 128.0 109.0 173.0 134.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#2] audio-dft Cycles:10 Average:182.50 Median:182.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 08:25:07 INFO - PROCESS | 1936 | Values: 182.0 184.0 182.0 183.0 182.0 182.0 182.0 182.0 183.0 183.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#3] audio-fft Cycles:10 Average:76.60 Median:82.00 stddev:13.84 (16.9%) stddev-sans-first:14.63 08:25:07 INFO - PROCESS | 1936 | Values: 80.0 76.0 48.0 92.0 73.0 81.0 87.0 88.0 83.0 58.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:82.50 stddev:12.67 (15.4%) stddev-sans-first:13.26 08:25:07 INFO - PROCESS | 1936 | Values: 89.0 90.0 93.0 72.0 76.0 70.0 97.0 73.0 68.0 103.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.96 (2.3%) stddev-sans-first:1.64 08:25:07 INFO - PROCESS | 1936 | Values: 89.0 85.0 86.0 83.0 87.0 84.0 87.0 85.0 87.0 83.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:1.08 (1.1%) stddev-sans-first:1.00 08:25:07 INFO - PROCESS | 1936 | Values: 92.0 94.0 93.0 95.0 93.0 93.0 94.0 95.0 94.0 92.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:1.64 (1.9%) stddev-sans-first:1.41 08:25:07 INFO - PROCESS | 1936 | Values: 87.0 84.0 87.0 83.0 83.0 84.0 82.0 84.0 85.0 84.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:1.00 08:25:07 INFO - PROCESS | 1936 | Values: 55.0 55.0 54.0 53.0 56.0 53.0 54.0 55.0 54.0 55.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:3.37 (4.8%) stddev-sans-first:2.96 08:25:07 INFO - PROCESS | 1936 | Values: 65.0 67.0 73.0 77.0 72.0 71.0 68.0 71.0 69.0 71.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.00 stddev:5.91 (7.0%) stddev-sans-first:6.25 08:25:07 INFO - PROCESS | 1936 | Values: 85.0 90.0 102.0 85.0 85.0 82.0 84.0 84.0 82.0 85.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:144.50 stddev:34.84 (24.1%) stddev-sans-first:36.63 08:25:07 INFO - PROCESS | 1936 | Values: 139.0 152.0 135.0 138.0 134.0 139.0 150.0 133.0 249.0 151.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:166.50 stddev:8.18 (4.9%) stddev-sans-first:8.67 08:25:07 INFO - PROCESS | 1936 | Values: 167.0 166.0 167.0 166.0 165.0 161.0 171.0 163.0 190.0 164.0 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - PROCESS | 1936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:75.50 stddev:8.22 (10.9%) stddev-sans-first:8.32 08:25:07 INFO - PROCESS | 1936 | Values: 67.0 68.0 69.0 71.0 83.0 84.0 86.0 80.0 66.0 66.0 08:25:07 INFO - PROCESS | 1936 | -------- Summary: end -------- 08:25:07 INFO - PROCESS | 1936 | 08:25:07 INFO - TEST-INFO | 1936: exit 0 08:25:07 INFO - TEST-OK | kraken | took 59306ms 08:25:07 INFO - SUITE-END | took 1177s 08:25:08 INFO - Completed test suite (00:19:37) 08:25:08 INFO - javascript benchmark 08:25:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.296017836254, 3354.0440974322323, 3357.1774915145793, 3410.931781364371, 3431.571053618298, 5614.326280846294, 5652.943470565293, 5730.713464326788, 5741.454561816631, 5749.655020698761, 4819.325294458778, 5671.631343962639, 5736.139579063132, 5754.568407369443, 5776.133579963008, 5219.086659834533, 5263.947360526403, 5274.340707411574, 5275.788968441258, 5352.464753524655, 5354.9196762048405, 5363.034653762341, 5363.141897296433, 5364.034473794715, 5365.570754339644, 5148.330717006784, 5205.531502164805, 5215.269862219274, 5304.0, 5353.973230133844, 5293.920591191135, 5311.336082989626, 5313.57491400687, 5322.988632159916, 5355.973220133875, 5100.209467532539, 5138.97430512845, 5188.740562971836, 5245.239440281138, 5248.134057880445, 4701.365315682373, 5655.3779084300695, 5667.234923285345, 5672.773089076432, 5695.174199741055, 6220.751169953196, 6221.191245138103, 6269.686515674198, 6275.717592708339, 6281.089242059921, 6103.398280005586, 6239.87520249597, 6283.214598175228, 6320.115183874262, 6360.840978975562, 5803.88392232153, 5804.912926306109, 5814.563907706939, 5818.650880947157, 5883.676397798162, 6319.494440444753, 6323.36766323364, 6330.177076979963, 6356.586821856565, 6360.0777887206095, 5866.823995280149, 5934.465898069195, 6081.3614570470345, 6171.907421388682, 6190.133381326618, 6223.159873417121, 6276.497880169575, 6299.023651334051, 6300.59046161998, 6306.873862522724, 6167.660778657128, 6223.408776166257, 6240.968795155995, 6241.001439769615, 6246.063090536456, 6100.359462256488, 6105.247473117015, 6247.75008999644, 6253.46845518127, 6278.340774218733, 3979.9801000994808, 4902.828401006019, 4958.429780575208, 4960.677555958851, 4971.825986090542, 4393.055493068941, 4405.03089320349, 4409.250427427345, 4434.157510073076, 4472.9776351118035, 5567.109262517978, 5594.049011668027, 5627.352854421712, 5710.914336284876, 5729.455701708331, 5087.440381558025, 6170.876582468325, 6182.041783523561, 6217.0, 6233.34549872266, 5644.181593668895, 5671.007573674673, 5698.401667824902, 5706.9429305707245, 5786.189933409326, 5436.673799572039, 5440.91838622421, 5442.2380866678695, 5442.945570544323, 5460.126379779216, 5317.574594032545, 6245.3754624539, 6302.936970630327, 6328.62028278314, 6349.936500634934, 6114.692773002257, 6176.845578860385, 6182.165407669907, 6209.472194863307, 6221.346758590283, 6301.243850737941, 6325.304216536086, 6336.144620476177, 6345.777897773556, 6358.491320694426, 6026.096085587197, 6200.472959798468, 6201.627902325875, 6223.315435302204, 6231.501479881508, 6125.571219981412, 6181.1655426516845, 6218.782342617987, 6241.063840423973, 6315.684215789284, 6086.3305036446845, 6092.8781424372155, 6094.2077529920825, 6095.481884039729, 6101.542384321105, 5876.3536011037895, 6100.6705071301185, 6102.58014228768, 6124.350421260821, 6124.594542075475, 5112.437631860566, 5862.530997520103, 5886.911696324473, 5903.439173278532, 5981.3420523743225, 6050.45545900871, 6077.0, 6089.147519347206, 6094.268687757498, 6100.6339619621185, 4971.825986090469, 5844.94155058461, 5921.881562368814, 5957.106434034929, 5974.133750606051], "value": 5675.414706206712, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.824070371851, 518.4970578538818, 531.5083547718359, 537.4061661863641, 553.167482938178, 29677.0, 32081.19797005076, 39845.2030959381, 40406.0, 40570.39144412836, 42240.0, 42623.14753704924, 42651.57348426515, 42689.7865510672, 42794.0, 9518.19095376892, 9676.274279429035, 9686.951565242163, 9718.416894986289, 9721.659741909034, 8656.220940115389, 8850.911490885106, 8858.73423797284, 8919.28645708345, 8929.241014513775, 11707.00490458312, 11943.58197463089, 11948.283103013804, 12143.57497487588, 12211.877881221162, 11817.881821181807, 11916.16586838926, 11929.522819087226, 11929.642110736691, 11931.463084161234, 7519.849603007964, 7566.697332106709, 7578.393728501762, 8594.656213751443, 11424.086073114215, 40093.398599021035, 40448.79775601133, 40491.7975410124, 40525.594744052476, 40665.0, 2860.5852151437925, 3233.0, 3391.915202119942, 3638.9810852961214, 3688.1517251032415, 30796.538051929238, 30902.536461952863, 31352.0, 33106.0, 33168.0, 3200.103970888155, 3227.7579181561478, 3329.7646662305, 3377.9493307600405, 3390.7456940729544, 493892.0, 496434.0, 498096.0, 499279.0, 500632.0, 59559.70220148871, 59655.40344596585, 59987.70006149941, 60144.398556014756, 60179.0, 64382.0, 64586.0, 64587.0, 64664.67667661631, 64681.67659161768, 16439.424620138238, 16603.916980415263, 16645.50063498097, 16658.16709164537, 16674.249658765384, 12792.744145117045, 12847.036472264617, 12884.48462061507, 12893.48426062947, 12894.29081400532, 15721.528354149394, 16447.342106315853, 16539.0, 18421.986790726372, 18667.813321866608, 7851.803704907423, 7921.801954951057, 7939.64271607779, 8008.39937004727, 8019.516389467967, 921.8110287391069, 992.5483904823288, 1001.1340190734962, 1032.2206733915846, 1041.1150522056191, 25693.0, 30037.849810751242, 30369.696303036686, 30641.84679076635, 30690.23274418113, 1062.192733522513, 1072.3137192197, 1092.4428541443922, 1116.7096554895786, 1151.2574389518713, 13723.313834308445, 14705.779413308808, 15789.842101579066, 15994.440194593133, 16165.272562734704, 707790.0, 714411.0, 715881.0, 718339.4083029551, 719021.0, 53478.19782703263, 59410.70294648499, 59419.40580594139, 59454.027298634894, 59589.0, 1358.5109360630165, 1373.3476598615578, 1391.6729568551382, 1397.0988712280725, 1400.4468235047302, 29848.206689651633, 29997.4000519984, 30146.84926575353, 30897.91857285074, 30942.38115237728, 49700.75149624229, 50145.498545015544, 50147.498525014285, 50161.498385015686, 50220.74889625528, 490074.0, 505242.0, 508200.0, 508448.0, 509465.0, 26500.07249746327, 26798.732012679622, 26865.791039402884, 26888.462230755667, 26982.0, 47425.76287118543, 47973.0, 48311.0, 48426.75786621044, 49072.0, 39226.80386598049, 39576.01059973524, 39858.0, 40272.99317517086, 40937.181256375305, 39358.803205984936, 39886.60113398829, 39903.28646906067, 40326.59673403228, 41024.0, 38327.85016449511, 38765.0, 38945.8052709746, 38972.02569935661, 39015.82952511429, 33422.83288583639, 34172.31655366928, 34817.47773783446, 34885.0, 35012.47481287732, 23606.527869442172, 25779.871100644377, 25876.353091172117, 25901.740982589934, 26784.0], "value": 20234.599549033002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.9284143171366, 379.37782037458567, 383.1054487771056, 384.67302792626265, 405.9208454351401, 15088.849111508882, 15339.846601533982, 20462.0, 20566.383008509732, 20569.177232910664, 20514.89742551289, 20551.794482055175, 21100.839453830034, 21130.89434552829, 21445.785542144575, 5805.7387417566315, 5965.373635768247, 6107.175531303262, 6165.0, 6197.752089916398, 5915.852103697399, 6015.9398406016035, 6097.817065488042, 6099.542534309922, 6101.389861013885, 5119.462456442076, 5452.945470545304, 5900.734466948998, 5988.371221021774, 6043.879122417549, 5640.717964101819, 5684.829455116333, 5707.74315155819, 5718.685472299001, 5723.341815691166, 5063.8480845574695, 5175.482451754794, 5180.6373553851245, 5194.11700010999, 5196.272521846944, 25086.498270034495, 25963.0, 26087.347816304744, 26351.34121646974, 26377.868110659325, 2200.8239340852692, 2263.0, 2482.6027835546224, 2514.120057979711, 2959.8668059937354, 15609.219539023117, 16173.4339298124, 16239.675206495922, 16288.51134465956, 16405.0, 1795.425463851568, 1811.0944527736133, 1824.0970719493935, 1869.7662792150982, 1923.6056608395386, 86819.12840794027, 105342.47328763307, 108285.91714082837, 120667.0, 124919.37540312241, 10750.569977200981, 10909.34543927367, 10978.12175025996, 11053.060489858291, 11197.552097915992, 12264.632061038183, 12769.297688627028, 12988.67528311781, 13693.246871422167, 13725.176489410467, 8375.329973602098, 8403.621837017348, 8561.914380856111, 8668.0898505656, 8725.171108744658, 8606.483610983361, 8623.78440538979, 8641.74694669282, 8767.473951562926, 8778.736637900873, 6506.512011599149, 6642.236142843538, 6648.601083934883, 6655.134832471747, 6663.666816659148, 1737.9051197745591, 1844.6771814932333, 1866.7199920012106, 1889.9055047247582, 1911.4326252472838, 799.2247519905682, 925.8426067568395, 957.9760505987406, 976.9560369783235, 1048.570086264628, 20614.484637883874, 21570.245041423474, 22231.333060008226, 22430.327090187322, 22523.774762252167, 879.8856148700756, 896.6278994217326, 983.9212862970945, 994.6817018554138, 1008.0927165551062, 11553.0, 11671.416429178676, 11813.173077884465, 11872.287662740264, 11880.821787673192, 558006.0, 592429.0, 613024.0, 619220.0, 624664.8766756137, 112044.0, 115536.42231788787, 115600.78386900984, 115844.8415515834, 117386.0, 889.8309321229075, 1000.0249756487513, 1012.2762225009154, 1039.4542864995938, 1042.5517027678172, 15879.682406351578, 16327.918360408123, 19346.613067738846, 19594.804051959298, 19883.60232795307, 34960.125996850285, 35656.82171589125, 41235.35058597622, 45219.321710175034, 53573.17841394137, 72131.0, 72279.27720722725, 73799.26200737924, 73934.26065739489, 74122.62938685271, 7955.4828936118965, 8031.223155187169, 8105.424407087403, 8114.959425202836, 8237.83524329522, 13315.733685326046, 13319.200847949154, 13445.260510672008, 13520.526781562598, 13826.239556824068, 11532.88467115318, 11537.88462115368, 11566.826497602711, 11686.474108664961, 11722.413879306172, 11277.774444511228, 11352.0, 11398.601048963246, 11695.590654327058, 11741.47163377633, 11850.940745296562, 11905.702357441134, 12026.518939242333, 12061.396930153633, 12066.939665301617, 6305.621662700252, 7067.858642827012, 8052.235037671412, 8109.432339736162, 8294.751157465285, 10294.742631434274, 11336.036436902956, 11343.0, 11412.771744565229, 11574.305541667527], "value": 9696.486698572277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.2504884837763, 1504.4132788212598, 1547.9303431345588, 1718.2096235731563, 1900.2778944001286, 7806.843863122741, 8542.786430339236, 8702.0, 8708.477491350523, 8831.249343805785, 8486.0665326814, 8729.738107856758, 8732.95633521833, 8733.86899196511, 8740.694075707352, 2091.602595506849, 2169.967450488244, 2169.967450488244, 2319.1998760427628, 2341.672165896776, 1938.2150229157144, 2163.2320526213202, 2187.5843589717983, 2418.093215044358, 2419.6491508731224, 1919.8656094073351, 2357.1749887539395, 2367.206985659806, 2368.3723813189517, 2369.5971684813535, 2081.8646787958733, 2291.2667946257206, 2293.1973809166743, 2301.1830800065904, 2303.366574192094, 2333.3933177373833, 2359.728631207416, 2372.655964885082, 2374.0266490738713, 2377.5839228135005, 8811.206991370807, 8880.955595221982, 8888.688895888678, 8902.376833621649, 8912.955435222848, 5216.66091704038, 5374.973125134349, 5384.434634363413, 5386.569074474072, 5389.353277606712, 3519.225770330523, 3601.405768048295, 3637.963620363789, 3640.2173532690335, 3642.7267954903223, 1056.2922841696081, 1084.3385534823751, 1093.557109370706, 1104.1939384249542, 1107.402002918423, 11759.17685761997, 11761.176717629683, 11775.411229438494, 11779.88220117805, 11787.528498860122, 4634.930476042862, 4754.215554433549, 4754.7147171169845, 4811.109944660249, 4821.566059054702, 8406.957965210135, 9794.65718699842, 9889.554970026365, 9913.900860991298, 9927.602895884229, 5338.599605029638, 5342.2787923630585, 5342.893142137135, 5344.706041167696, 5353.892922141536, 4133.21468920904, 4801.279808028754, 4867.245576935581, 4891.706497610084, 4899.216125419987, 3866.3620502617314, 3904.7852368120025, 3948.9210215795524, 3984.442178095069, 4018.015586181404, 1254.5295514182183, 1262.103906226571, 1264.64589914824, 1275.6236910111538, 1279.6928737103028, 891.1445012787667, 896.5741272895323, 916.5875356089653, 938.6328007160924, 953.7997020625608, 5641.379448260688, 5808.970955145197, 5865.560082993792, 5967.492763115184, 6070.848228794315, 429.78940319243344, 445.1764236163137, 446.29931008317277, 456.0218331678597, 456.83097254015564, 3966.286068507696, 4025.335819589735, 4050.8177132029127, 4063.959360406358, 4083.265012297815, 10402.0, 10652.893471065345, 10795.676129716121, 10812.783744325227, 10914.945425272981, 10743.301685390581, 10754.892451075546, 10755.300905441121, 10771.407572583583, 10787.946060269649, 7888.2506161914525, 8234.711785087493, 8239.711610093618, 8244.752657420288, 8260.504369737835, 5752.252207213036, 5882.205902203148, 5893.823185304448, 5937.0, 5962.433568810956, 8345.916540834756, 10113.89886101159, 10117.645882394081, 10160.593576256866, 10182.694519164437, 8775.868361974703, 9289.442633441744, 9900.801983960424, 10166.135878450414, 10310.07209351162, 6956.165260168812, 7444.069491313586, 7509.812254693677, 7523.360514356341, 7537.660805263667, 8119.4316397853945, 8122.837543248984, 8125.374925015139, 8195.83608327842, 8214.83570328602, 7373.33639972405, 7422.591757453392, 7423.109226892808, 7513.1359893612935, 7573.35626471756, 6931.89602155958, 7199.460040497089, 7252.093488313961, 7393.408527317694, 7420.220876807857, 6975.0, 6977.651117444209, 6989.475789315923, 6998.580085194904, 7074.893876591958, 4500.797464114057, 4686.179918514178, 4774.546418090275, 4964.304997300309, 4976.676516026447, 4824.300476430828, 4857.705101439448, 4872.220444728827, 4904.068227036852, 4992.350994370709], "value": 4464.673901282109, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.51466880987203, 307.1185697049468, 311.24833527032223, 312.47348218252233, 315.4511150597957, 5110.77341438055, 5378.435264297246, 5417.864553386163, 5514.972425137878, 5529.562313798411, 4435.68950173488, 5425.02349577077, 5457.645253058548, 5467.753951072202, 5474.671519708821, 3866.729328946975, 3948.111674873145, 4082.040720430696, 4103.589641035903, 4166.229247589191, 3415.487676848468, 4028.97985510072, 4117.629413352797, 4140.171965606875, 4148.94201978495, 3855.8843234703004, 4075.246079475292, 4103.507579090513, 4112.856050038249, 4123.5876412358675, 3544.4151714967, 3906.7069969752242, 4024.959750402517, 4048.271311164004, 4159.459270294872, 3554.5556805399324, 3580.516630254934, 3583.677469027774, 3586.33652774235, 3606.350856845767, 4038.6163314485075, 5219.530242278213, 5261.684298942076, 5277.973610131963, 5330.013947419739, 4213.768242746648, 4289.571042895716, 4386.583274588909, 4706.752340884778, 4747.8575642730775, 4636.724900652314, 5634.239377684042, 5666.704984481292, 5694.921353705909, 5704.548748007479, 3937.960620393788, 3950.9012274693077, 3954.980225098856, 3966.6794924968926, 3970.9007274818073, 5033.622478314104, 5204.9329887373, 5366.329208848894, 5613.607047506635, 5958.6424814511265, 3847.0, 3886.805659717003, 3904.921901561953, 3909.5730058528434, 3920.000399898008, 3968.7420317679257, 3992.3612222044335, 4014.8193331300163, 4034.3948407739076, 4035.7174997750462, 3886.9204975338057, 4046.320776877563, 4383.868483945486, 4676.789544470507, 4715.858524244278, 5690.7439165237665, 5738.360354237014, 5746.569007324384, 5751.539876809845, 5776.508996735324, 1073.0771536478621, 1363.9727205456034, 1365.5698454986648, 1374.673440130279, 1380.0891411668451, 764.7038270132184, 794.3089512124374, 828.7845160258377, 837.6942416018231, 837.7433849226161, 4936.5190442867, 4968.329275547827, 5120.590352771769, 5369.704666243316, 5373.946260537423, 5894.614765530097, 5895.35151133375, 5896.410358964069, 5907.202527658711, 5914.822555323347, 4448.154850578379, 5222.843314700565, 5340.572754179734, 5350.652207606493, 5366.9534440784455, 2796.979102627526, 2917.897873574457, 3091.335362896988, 3097.736692381173, 3121.453745594512, 5611.410801865827, 5870.090136028923, 6034.732706131674, 6097.603655762361, 6116.724747386378, 5732.598718089777, 5791.349465402424, 6069.969650151721, 6074.605150665193, 6093.512518998469, 5379.327584051994, 5948.345681975065, 6069.908951365733, 6072.453479186895, 6140.925338065731, 5072.442031376472, 5330.9733451332495, 5910.438508341701, 5925.555583331181, 6012.398760123952, 4937.0, 5903.527717782481, 5932.288125424977, 5950.851228719317, 5970.283565972111, 4920.360353154067, 5889.910366582196, 6170.598911070766, 6208.751649934133, 6243.719032643451, 3411.0, 3541.2386336937684, 3614.1506745915217, 3879.243547508209, 3879.534455865314, 5851.356350801323, 5860.736266867916, 5861.912071318849, 5870.823875283749, 5953.9404605955115, 4743.810247590196, 5677.51741102011, 5684.77260909559, 5689.146628005832, 5744.357595505947, 5447.0, 5791.594588378773, 5792.304923409218, 5809.680467574324, 5811.389804070512, 4519.096180763926, 5342.8512869733195, 5424.528301886875, 5560.388357280615, 5645.548356131418, 4914.0, 5723.456271654186, 5777.277840269966, 5798.275215598051, 5813.447722466359, 4886.315915771724, 5367.899580585892, 5653.236813030353, 5691.943080569141, 5702.525575447617], "value": 4153.308387676197, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5281637710923, 719.8272414620493, 740.9555426674403, 776.6349815586676, 837.2172019162082, 15049.924750376247, 16053.357865685388, 16066.59833504163, 16079.276432560533, 16083.35666573337, 14025.649358766035, 14095.295235238249, 14175.149491030545, 14176.716465670681, 14190.006699531037, 671.8589096289785, 677.288846710954, 688.3976520544524, 689.8723736108836, 831.0526000359595, 683.1187767779584, 816.2286639126029, 825.7976795685065, 836.6653338664553, 842.7808769719855, 893.0266010049045, 893.3701740273092, 896.4307664632972, 901.8016036471965, 906.5784410249232, 711.4379640084379, 864.0754392799707, 864.6498168241841, 865.3077537969599, 866.4238281542766, 649.8830210562097, 650.1418128070949, 654.4829584628138, 672.0020769157796, 672.3881268046104, 15281.235938203044, 15296.0, 15305.08169509833, 15334.693306133815, 15342.539723808304, 13645.863541364559, 14150.29248537569, 14364.138151710826, 14421.35103920326, 14486.42054317826, 12846.35768211595, 12913.354332283348, 12977.35113244334, 13177.407016684272, 13435.12671676338, 4605.930911036304, 4639.30410438437, 4668.393108895856, 4797.280407938802, 4822.445418776815, 107981.0, 108223.0, 109500.0, 109740.35389469244, 109937.90062099436, 11134.109271258441, 11236.269642473213, 11238.775224495628, 11268.83096753533, 11750.353730544903, 22352.552948941255, 22414.663780043313, 22451.0, 22457.55084898293, 22463.989120489616, 7751.612419379121, 7771.222877712296, 7790.5325680459355, 7822.687092516236, 7939.960300198462, 4327.913441731148, 4347.174036932973, 4384.649228061748, 4405.537418571068, 4531.274996000624, 3389.779664321811, 3503.9124021899656, 3512.648735126518, 3518.894433167009, 3934.1541568562325, 713.2475238623302, 719.7193094693074, 719.851836321067, 721.4084450750335, 737.6938570493185, 541.5748637319685, 662.231959631614, 677.8852366137498, 681.608075356662, 699.5627732667083, 9165.404248723811, 9191.448513089103, 9368.110029547182, 9495.43027418357, 9591.376560523544, 562.6005536069437, 583.8190161050088, 611.3950878942775, 626.3798839149209, 666.8399584099878, 4552.931706024412, 4584.839530616479, 4704.270838020112, 4813.700300918732, 4831.227003679394, 159871.40128598566, 162717.0, 166037.0, 167317.0, 171536.0, 129752.35123824321, 132701.33649331692, 134600.0, 134911.0, 140525.59474405125, 122643.0, 124033.37983310026, 128930.0, 129221.35389322993, 131714.68285317215, 8751.431156974777, 10004.499775011365, 10067.546960386946, 10111.046462476465, 10230.0, 62559.6872015637, 62744.0, 64488.0, 64685.35314646793, 65307.346926530125, 67099.32251693551, 86885.0, 97369.0, 99466.5026674862, 101800.4909975475, 12142.757144856876, 13410.262435566137, 13621.0, 13743.725125497234, 14251.358688859234, 11004.284721493077, 13472.326383680973, 15257.313420896307, 15314.387424502895, 15327.310271038054, 14332.641683957985, 14340.784888226477, 14355.56933292003, 14387.712245754818, 14458.638534036732, 13628.386722597705, 13774.65563360924, 14041.648958775704, 14053.789193162314, 14232.0, 14047.578572642837, 14130.364133614217, 14282.07166534172, 14288.928330375056, 14592.489262875748, 11168.944155279172, 11673.824892626788, 11973.880261197624, 11973.940130299641, 12526.248425094524, 9998.550065246936, 11446.484908178985, 11559.537618495166, 11800.05599552051, 12038.337891416057], "value": 8002.179720285042, "unit": "score"}], "name": "dromaeo_css", "value": 7407.829759034248, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 113.0, 112.0, 111.0, 113.0, 117.0, 110.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 109.0, 159.0, 105.0, 106.0, 128.0, 109.0, 173.0, 134.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 182.0, 183.0, 182.0, 182.0, 182.0, 182.0, 183.0, 183.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 48.0, 92.0, 73.0, 81.0, 87.0, 88.0, 83.0, 58.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 93.0, 72.0, 76.0, 70.0, 97.0, 73.0, 68.0, 103.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 86.0, 83.0, 87.0, 84.0, 87.0, 85.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 95.0, 93.0, 93.0, 94.0, 95.0, 94.0, 92.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 83.0, 83.0, 84.0, 82.0, 84.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 53.0, 56.0, 53.0, 54.0, 55.0, 54.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 73.0, 77.0, 72.0, 71.0, 68.0, 71.0, 69.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 102.0, 85.0, 85.0, 82.0, 84.0, 84.0, 82.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 135.0, 138.0, 134.0, 139.0, 150.0, 133.0, 249.0, 151.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 166.0, 165.0, 161.0, 171.0, 163.0, 190.0, 164.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 71.0, 83.0, 84.0, 86.0, 80.0, 66.0, 66.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1449.3, "alertThreshold": 2.0}]} 08:25:08 INFO - Return code: 0 08:25:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:08 INFO - # TBPL SUCCESS # 08:25:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:08 INFO - 2016-06-30 08:25:08.382 screenresolution[1940:23397] starting screenresolution argv=screenresolution get 08:25:08 INFO - 2016-06-30 08:25:08.384 screenresolution[1940:23397] Display 0: 1600x1200x32@60 08:25:08 INFO - 2016-06-30 08:25:08.390 screenresolution[1941:23400] starting screenresolution argv=screenresolution list 08:25:08 INFO - Available Modes on Display 0 08:25:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:08 INFO - Intel Iris: 08:25:08 INFO - Chipset Model: Intel Iris 08:25:08 INFO - Type: GPU 08:25:08 INFO - Bus: Built-In 08:25:08 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:08 INFO - Vendor: Intel (0x8086) 08:25:08 INFO - Device ID: 0x0a2e 08:25:08 INFO - Revision ID: 0x0009 08:25:08 INFO - Displays: 08:25:08 INFO - Display: 08:25:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:08 INFO - Display Serial Number: 1600x1200 60 08:25:08 INFO - Main Display: Yes 08:25:08 INFO - Mirror: Off 08:25:08 INFO - Online: Yes 08:25:08 INFO - Rotation: Supported 08:25:08 INFO - Return code: 0 08:25:08 INFO - Running post-action listener: _resource_record_post_action 08:25:08 INFO - ##### 08:25:08 INFO - ##### Finished run-tests step (success) 08:25:08 INFO - ##### 08:25:08 INFO - Running post-run listener: _resource_record_post_run 08:25:08 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 892928; Write bytes: 704553984; Read time: 5; Write time: 1962 08:25:08 INFO - TinderboxPrint: CPU usage
53.3% 08:25:08 INFO - TinderboxPrint: I/O read bytes / time
892,928 / 5 08:25:08 INFO - TinderboxPrint: I/O write bytes / time
704,553,984 / 1,962 08:25:08 INFO - TinderboxPrint: CPU idle
2,266.1 (46.7%) 08:25:08 INFO - TinderboxPrint: CPU system
965.8 (19.9%) 08:25:08 INFO - TinderboxPrint: CPU user
1,617.8 (33.4%) 08:25:08 INFO - TinderboxPrint: Swap in / out
407,367,680 / 0 08:25:08 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396067328; Write bytes: 389797376; Read time: 29342; Write time: 785 08:25:08 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 892928; Write bytes: 293476352; Read time: 5; Write time: 1119 08:25:09 INFO - Running post-run listener: _upload_blobber_files 08:25:09 INFO - Blob upload gear active. 08:25:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:09 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 08:25:09 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'] 08:25:09 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 08:25:09 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:25:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:10 INFO - (blobuploader) - INFO - Done attempting. 08:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:25:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:11 INFO - (blobuploader) - INFO - Done attempting. 08:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:14 INFO - (blobuploader) - INFO - Done attempting. 08:25:14 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:14 INFO - Return code: 0 08:25:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ba5dbec1031ba050e8b76aca260c578eaef58f48e7271b658f0c2cfa166a444c922856bcb6503e83febd60b9c55dfb67a6e5807efb7d72cce431a1ad8c548c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a85e8690124b2f9f58a7eea3ce780131dafbe60b81a7b46f6a3c6b4f8880a12f8527fbaee917a7f4a3a9ed64e0188c4d0b1002e5723a1f2574069eb6a375d4f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f02ef204aee8476c69851b9c70a32ca1e2b5f8dd054d59c65727e64a353d957f53a7f6a0b771f85d382248fc3b5d594bb1a646f458b77aae3b3173f12ac6c18b"} 08:25:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:14 INFO - Contents: 08:25:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ba5dbec1031ba050e8b76aca260c578eaef58f48e7271b658f0c2cfa166a444c922856bcb6503e83febd60b9c55dfb67a6e5807efb7d72cce431a1ad8c548c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a85e8690124b2f9f58a7eea3ce780131dafbe60b81a7b46f6a3c6b4f8880a12f8527fbaee917a7f4a3a9ed64e0188c4d0b1002e5723a1f2574069eb6a375d4f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f02ef204aee8476c69851b9c70a32ca1e2b5f8dd054d59c65727e64a353d957f53a7f6a0b771f85d382248fc3b5d594bb1a646f458b77aae3b3173f12ac6c18b"} 08:25:14 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:14 INFO - Copying logs to upload dir... 08:25:14 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.724065 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-30 08:25:14.073023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 08:25:14.076179) ========= 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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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/7ba5dbec1031ba050e8b76aca260c578eaef58f48e7271b658f0c2cfa166a444c922856bcb6503e83febd60b9c55dfb67a6e5807efb7d72cce431a1ad8c548c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a85e8690124b2f9f58a7eea3ce780131dafbe60b81a7b46f6a3c6b4f8880a12f8527fbaee917a7f4a3a9ed64e0188c4d0b1002e5723a1f2574069eb6a375d4f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f02ef204aee8476c69851b9c70a32ca1e2b5f8dd054d59c65727e64a353d957f53a7f6a0b771f85d382248fc3b5d594bb1a646f458b77aae3b3173f12ac6c18b"} build_url:https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010584 build_url: 'https://queue.taskcluster.net/v1/task/RQf05H5fQ2Cnh5Wm_ubArw/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/7ba5dbec1031ba050e8b76aca260c578eaef58f48e7271b658f0c2cfa166a444c922856bcb6503e83febd60b9c55dfb67a6e5807efb7d72cce431a1ad8c548c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a85e8690124b2f9f58a7eea3ce780131dafbe60b81a7b46f6a3c6b4f8880a12f8527fbaee917a7f4a3a9ed64e0188c4d0b1002e5723a1f2574069eb6a375d4f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f02ef204aee8476c69851b9c70a32ca1e2b5f8dd054d59c65727e64a353d957f53a7f6a0b771f85d382248fc3b5d594bb1a646f458b77aae3b3173f12ac6c18b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 08:25:14.108644) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:25:14.109072) ========= 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.8ujIOzGRa4/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.CKlTKdC2x6/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.005109 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 08:25:14.157708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 08:25:14.158029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 08:25:14.158457) ========= ========= Total master_lag: 0.30 =========