builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0134 starttime: 1462786238.05 results: success (0) buildid: 20160509005545 builduid: ea7a7eff58c544d79701ff562c295d6e revision: fc51f034e4aca08b76934a832d7dc7db1954b9d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.052136) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.053099) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.053772) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.074529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.075154) ========= 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.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-05-09 02:30:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.71M=0.001s 2016-05-09 02:30:38 (9.71 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108584 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.209125) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.209524) ========= 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.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.026173 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:38.259575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 02:30:38.259999) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fc51f034e4aca08b76934a832d7dc7db1954b9d2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fc51f034e4aca08b76934a832d7dc7db1954b9d2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-05-09 02:30:38,333 truncating revision to first 12 chars 2016-05-09 02:30:38,333 Setting DEBUG logging. 2016-05-09 02:30:38,333 attempt 1/10 2016-05-09 02:30:38,333 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fc51f034e4ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 02:30:39,547 unpacking tar archive at: mozilla-beta-fc51f034e4ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456314 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 02:30:39.736431) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:39.737164) ========= script_repo_revision: fc51f034e4aca08b76934a832d7dc7db1954b9d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:39.738045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:39.738595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 02:30:39.753608) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-09 02:30:39.754216) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:30:39 INFO - MultiFileLogger online at 20160509 02:30:39 in /builds/slave/test 02:30:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 02:30:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:30:39 INFO - {'append_to_log': False, 02:30:39 INFO - 'base_work_dir': '/builds/slave/test', 02:30:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:30:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:30:39 INFO - 'branch': 'Mozilla-Beta', 02:30:39 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:39 INFO - 'config_files': ('talos/mac_config.py',), 02:30:39 INFO - 'default_actions': ('clobber', 02:30:39 INFO - 'read-buildbot-config', 02:30:39 INFO - 'download-and-extract', 02:30:39 INFO - 'populate-webroot', 02:30:39 INFO - 'create-virtualenv', 02:30:39 INFO - 'install', 02:30:39 INFO - 'run-tests'), 02:30:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:39 INFO - 'download_minidump_stackwalk': True, 02:30:39 INFO - 'download_symbols': 'ondemand', 02:30:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:30:39 INFO - 'tooltool.py': '/tools/tooltool.py', 02:30:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:30:39 INFO - '/tools/misc-python/virtualenv.py')}, 02:30:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:39 INFO - 'installer_path': 'installer.exe', 02:30:39 INFO - 'log_level': 'info', 02:30:39 INFO - 'log_name': 'talos', 02:30:39 INFO - 'log_to_console': True, 02:30:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:30:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:30:39 INFO - 'opt_config_files': (), 02:30:39 INFO - 'pip_index': False, 02:30:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:39 INFO - 'cmd': ('bash', 02:30:39 INFO - '-c', 02:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:39 INFO - 'enabled': True, 02:30:39 INFO - 'halt_on_failure': False, 02:30:39 INFO - 'name': 'check_screen_resolution'},), 02:30:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:39 INFO - 'cmd': ('bash', 02:30:39 INFO - '-c', 02:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:39 INFO - 'enabled': True, 02:30:39 INFO - 'halt_on_failure': False, 02:30:39 INFO - 'name': 'check_screen_resolution'},), 02:30:39 INFO - 'run_cmd_checks_enabled': True, 02:30:39 INFO - 'sps_profile': False, 02:30:39 INFO - 'sps_profile_interval': 0, 02:30:39 INFO - 'suite': 'dromaeojs', 02:30:39 INFO - 'system_bits': '32', 02:30:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:39 INFO - 'title': 't-yosemite-r7-0134', 02:30:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:30:39 INFO - 'use_talos_json': True, 02:30:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:30:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:39 INFO - 'work_dir': 'build'} 02:30:39 INFO - ##### 02:30:39 INFO - ##### Running clobber step. 02:30:39 INFO - ##### 02:30:39 INFO - Running pre-action listener: _resource_record_pre_action 02:30:39 INFO - Running main action method: clobber 02:30:39 INFO - rmtree: /builds/slave/test/build 02:30:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:30:41 INFO - Running post-action listener: _resource_record_post_action 02:30:41 INFO - ##### 02:30:41 INFO - ##### Running read-buildbot-config step. 02:30:41 INFO - ##### 02:30:41 INFO - Running pre-action listener: _resource_record_pre_action 02:30:41 INFO - Running main action method: read_buildbot_config 02:30:41 INFO - Using buildbot properties: 02:30:41 INFO - { 02:30:41 INFO - "project": "", 02:30:41 INFO - "product": "firefox", 02:30:41 INFO - "script_repo_revision": "production", 02:30:41 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 02:30:41 INFO - "repository": "", 02:30:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 02:30:41 INFO - "buildid": "20160509005545", 02:30:41 INFO - "pgo_build": "False", 02:30:41 INFO - "basedir": "/builds/slave/test", 02:30:41 INFO - "buildnumber": 59, 02:30:41 INFO - "slavename": "t-yosemite-r7-0134", 02:30:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:30:41 INFO - "platform": "yosemite_r7", 02:30:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 02:30:41 INFO - "branch": "mozilla-beta", 02:30:41 INFO - "repo_path": "releases/mozilla-beta", 02:30:41 INFO - "revision": "fc51f034e4aca08b76934a832d7dc7db1954b9d2", 02:30:41 INFO - "stage_platform": "macosx64", 02:30:41 INFO - "builduid": "ea7a7eff58c544d79701ff562c295d6e", 02:30:41 INFO - "slavebuilddir": "test" 02:30:41 INFO - } 02:30:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 02:30:41 INFO - Running post-action listener: _resource_record_post_action 02:30:41 INFO - ##### 02:30:41 INFO - ##### Running download-and-extract step. 02:30:41 INFO - ##### 02:30:41 INFO - Running pre-action listener: _resource_record_pre_action 02:30:41 INFO - Running main action method: download_and_extract 02:30:41 INFO - mkdir: /builds/slave/test/build/tests 02:30:41 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')]} 02:30:41 INFO - https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 02:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 02:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 02:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 02:30:42 INFO - Downloaded 1241 bytes. 02:30:42 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 02:30:42 INFO - Using the following test package requirements: 02:30:42 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:30:42 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 02:30:42 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 02:30:42 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:30:42 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 02:30:42 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:30:42 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 02:30:42 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 02:30:42 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 02:30:42 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:30:42 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:30:42 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 02:30:42 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 02:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:42 INFO - https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:30:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 02:30:43 INFO - Downloaded 17747440 bytes. 02:30:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:30:46 INFO - Return code: 0 02:30:46 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 02:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:46 INFO - https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:30:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 02:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 02:30:49 INFO - Downloaded 17747440 bytes. 02:30:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:30:53 INFO - Return code: 0 02:30:53 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')]} 02:30:53 INFO - https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:30:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 02:30:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 02:30:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 02:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 02:30:54 INFO - Downloaded 10845194 bytes. 02:30:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:30:54 INFO - Return code: 0 02:30: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')]} 02:30:54 INFO - https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 02:30:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:30:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:30:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 02:30:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 02:30:56 INFO - Downloaded 88341384 bytes. 02:30:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:30:56 INFO - mkdir: /builds/slave/test/properties 02:30:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:30:56 INFO - Writing to file /builds/slave/test/properties/build_url 02:30:56 INFO - Contents: 02:30:56 INFO - build_url:https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:30:57 INFO - Running post-action listener: _resource_record_post_action 02:30:57 INFO - Running post-action listener: set_extra_try_arguments 02:30:57 INFO - ##### 02:30:57 INFO - ##### Running populate-webroot step. 02:30:57 INFO - ##### 02:30:57 INFO - Running pre-action listener: _resource_record_pre_action 02:30:57 INFO - Running main action method: populate_webroot 02:30:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:30:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:30:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:30:58 INFO - '--tppagecycles', 02:30:58 INFO - '7'], 02:30:58 INFO - 'tests': ['tsvgm']}}, 02:30:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:30:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:30:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 02:30:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:30:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'tests': ['damp', 'tps']}, 02:30:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['damp', 'tps']}, 02:30:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:30:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:30:58 INFO - 'other': {'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'other_l64': {'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'other_nol64': {'tests': ['a11yr', 02:30:58 INFO - 'ts_paint', 02:30:58 INFO - 'tpaint', 02:30:58 INFO - 'sessionrestore', 02:30:58 INFO - 'sessionrestore_no_auto_restore', 02:30:58 INFO - 'tabpaint']}, 02:30:58 INFO - 'svgr': {'tests': ['tsvgx', 02:30:58 INFO - 'tsvgr_opacity', 02:30:58 INFO - 'tart', 02:30:58 INFO - 'tscrollx', 02:30:58 INFO - 'cart']}, 02:30:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['tsvgx', 02:30:58 INFO - 'tsvgr_opacity', 02:30:58 INFO - 'tart', 02:30:58 INFO - 'tscrollx', 02:30:58 INFO - 'cart']}, 02:30:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'tests': ['tp5o']}, 02:30:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'talos_options': ['--e10s'], 02:30:58 INFO - 'tests': ['tp5o']}, 02:30:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'talos_options': ['--xperf_path', 02:30:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:58 INFO - 'tests': ['tp5n']}, 02:30:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:58 INFO - 'talos_options': ['--e10s', 02:30:58 INFO - '--xperf_path', 02:30:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:58 INFO - 'tests': ['tp5n']}}, 02:30:58 INFO - 'talos.zip': {'path': '', 02:30:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:30:58 INFO - Running post-action listener: _resource_record_post_action 02:30:58 INFO - ##### 02:30:58 INFO - ##### Running create-virtualenv step. 02:30:58 INFO - ##### 02:30:58 INFO - Running pre-action listener: _resource_record_pre_action 02:30:58 INFO - Running main action method: create_virtualenv 02:30:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:30:58 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 02:30:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:30:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:30:58 INFO - Using real prefix '/tools/python27' 02:30:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:30:58 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:01 INFO - Installing pip.................done. 02:31:01 INFO - Return code: 0 02:31:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:01 INFO - 'HOME': '/Users/cltbld', 02:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:01 INFO - 'LOGNAME': 'cltbld', 02:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:01 INFO - 'MOZ_NO_REMOTE': '1', 02:31:01 INFO - 'NO_EM_RESTART': '1', 02:31:01 INFO - 'PAGER': '/bin/cat', 02:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:01 INFO - 'PWD': '/builds/slave/test', 02:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:01 INFO - 'SHELL': '/bin/bash', 02:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:01 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:01 INFO - 'USER': 'cltbld', 02:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:01 INFO - 'XPC_FLAGS': '0x0', 02:31:01 INFO - 'XPC_SERVICE_NAME': '0', 02:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:31:01 INFO - Cleaning up... 02:31:02 INFO - Return code: 0 02:31:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:02 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:02 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>=0.7.1'] in /builds/slave/test/build 02:31:02 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>=0.7.1 02:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:02 INFO - 'HOME': '/Users/cltbld', 02:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:02 INFO - 'LOGNAME': 'cltbld', 02:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:02 INFO - 'MOZ_NO_REMOTE': '1', 02:31:02 INFO - 'NO_EM_RESTART': '1', 02:31:02 INFO - 'PAGER': '/bin/cat', 02:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:02 INFO - 'PWD': '/builds/slave/test', 02:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:02 INFO - 'SHELL': '/bin/bash', 02:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:02 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:02 INFO - 'USER': 'cltbld', 02:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:02 INFO - 'XPC_FLAGS': '0x0', 02:31:02 INFO - 'XPC_SERVICE_NAME': '0', 02:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:02 INFO - Downloading/unpacking psutil>=0.7.1 02:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:05 INFO - Installing collected packages: psutil 02:31:05 INFO - Running setup.py install for psutil 02:31:05 INFO - building 'psutil._psutil_osx' extension 02:31:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:31:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:31:06 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 02:31:06 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 02:31:06 INFO - building 'psutil._psutil_posix' extension 02:31:06 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 02:31:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:31:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:31:06 INFO - ^ 02:31:06 INFO - 1 warning generated. 02:31:06 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 02:31:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:06 INFO - Successfully installed psutil 02:31:06 INFO - Cleaning up... 02:31:06 INFO - Return code: 0 02:31:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:31: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')]} 02:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31: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')]} 02:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:06 INFO - 'HOME': '/Users/cltbld', 02:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:06 INFO - 'LOGNAME': 'cltbld', 02:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:06 INFO - 'MOZ_NO_REMOTE': '1', 02:31:06 INFO - 'NO_EM_RESTART': '1', 02:31:06 INFO - 'PAGER': '/bin/cat', 02:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:06 INFO - 'PWD': '/builds/slave/test', 02:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:06 INFO - 'SHELL': '/bin/bash', 02:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:06 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:06 INFO - 'USER': 'cltbld', 02:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:06 INFO - 'XPC_FLAGS': '0x0', 02:31:06 INFO - 'XPC_SERVICE_NAME': '0', 02:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:31:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:31:09 INFO - Installing collected packages: mozsystemmonitor 02:31:09 INFO - Running setup.py install for mozsystemmonitor 02:31:09 INFO - Successfully installed mozsystemmonitor 02:31:09 INFO - Cleaning up... 02:31:09 INFO - Return code: 0 02:31:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:31: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')]} 02:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31: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')]} 02:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:09 INFO - 'HOME': '/Users/cltbld', 02:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:09 INFO - 'LOGNAME': 'cltbld', 02:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:09 INFO - 'MOZ_NO_REMOTE': '1', 02:31:09 INFO - 'NO_EM_RESTART': '1', 02:31:09 INFO - 'PAGER': '/bin/cat', 02:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:09 INFO - 'PWD': '/builds/slave/test', 02:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:09 INFO - 'SHELL': '/bin/bash', 02:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:09 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:09 INFO - 'USER': 'cltbld', 02:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:09 INFO - 'XPC_FLAGS': '0x0', 02:31:09 INFO - 'XPC_SERVICE_NAME': '0', 02:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:09 INFO - Downloading/unpacking blobuploader==1.2.4 02:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:12 INFO - Downloading blobuploader-1.2.4.tar.gz 02:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:31:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:31:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:13 INFO - Downloading docopt-0.6.1.tar.gz 02:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:31:13 INFO - Installing collected packages: blobuploader, requests, docopt 02:31:13 INFO - Running setup.py install for blobuploader 02:31:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:31:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:31:13 INFO - Running setup.py install for requests 02:31:14 INFO - Running setup.py install for docopt 02:31:14 INFO - Successfully installed blobuploader requests docopt 02:31:14 INFO - Cleaning up... 02:31:14 INFO - Return code: 0 02:31:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31: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')]} 02:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31: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')]} 02:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:14 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 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:14 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 02:31:14 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 02:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:14 INFO - 'HOME': '/Users/cltbld', 02:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:14 INFO - 'LOGNAME': 'cltbld', 02:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:14 INFO - 'MOZ_NO_REMOTE': '1', 02:31:14 INFO - 'NO_EM_RESTART': '1', 02:31:14 INFO - 'PAGER': '/bin/cat', 02:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:14 INFO - 'PWD': '/builds/slave/test', 02:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:14 INFO - 'SHELL': '/bin/bash', 02:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:14 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:14 INFO - 'USER': 'cltbld', 02:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:14 INFO - 'XPC_FLAGS': '0x0', 02:31:14 INFO - 'XPC_SERVICE_NAME': '0', 02:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:14 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-PEVukv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:14 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-FrUjFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:14 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-2BCB5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:14 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7Xf3Vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ePPkAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-l3Mwyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Dg7pv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Bh7gXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Hj6v77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-OpIHHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-soblKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-C6BcCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:15 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rIapBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-6_V3fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-zjp8it-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-64jQdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-OkO8As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:31:16 INFO - Running setup.py install for manifestparser 02:31:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:31:16 INFO - Running setup.py install for mozcrash 02:31:16 INFO - Running setup.py install for mozdebug 02:31:16 INFO - Running setup.py install for mozdevice 02:31:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Running setup.py install for mozfile 02:31:17 INFO - Running setup.py install for mozhttpd 02:31:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Running setup.py install for mozinfo 02:31:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Running setup.py install for mozInstall 02:31:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Running setup.py install for mozleak 02:31:17 INFO - Running setup.py install for mozlog 02:31:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:31:17 INFO - Running setup.py install for moznetwork 02:31:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Running setup.py install for mozprocess 02:31:18 INFO - Running setup.py install for mozprofile 02:31:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Running setup.py install for mozrunner 02:31:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Running setup.py install for mozscreenshot 02:31:18 INFO - Running setup.py install for moztest 02:31:18 INFO - Running setup.py install for mozversion 02:31:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:31:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:31:18 INFO - Cleaning up... 02:31:18 INFO - Return code: 0 02:31:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:19 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')]} 02:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:19 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')]} 02:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:19 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 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:19 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 02:31:19 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 02:31:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:19 INFO - 'HOME': '/Users/cltbld', 02:31:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:19 INFO - 'LOGNAME': 'cltbld', 02:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:19 INFO - 'MOZ_NO_REMOTE': '1', 02:31:19 INFO - 'NO_EM_RESTART': '1', 02:31:19 INFO - 'PAGER': '/bin/cat', 02:31:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:19 INFO - 'PWD': '/builds/slave/test', 02:31:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:19 INFO - 'SHELL': '/bin/bash', 02:31:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:19 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:19 INFO - 'USER': 'cltbld', 02:31:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:19 INFO - 'XPC_FLAGS': '0x0', 02:31:19 INFO - 'XPC_SERVICE_NAME': '0', 02:31:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-V0f9Ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-85PXJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-bd62_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-lcrR9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-T3rNF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Atf2qQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:19 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)) 02:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:19 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-5TjmXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-mIxaKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-MgoTiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-F3HVHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-UxWsM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-E0bVHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wlnERb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-GswUL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wPO43x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-BYrXat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:31:20 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)) 02:31:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:31:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-U9u1Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:31:21 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)) 02:31:21 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)) 02:31:21 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)) 02:31:21 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)) 02:31:21 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)) 02:31:21 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)) 02:31:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:23 INFO - Downloading blessings-1.6.tar.gz 02:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:31:23 INFO - Installing collected packages: blessings 02:31:23 INFO - Running setup.py install for blessings 02:31:23 INFO - Successfully installed blessings 02:31:23 INFO - Cleaning up... 02:31:23 INFO - Return code: 0 02:31:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:31:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:24 INFO - Reading from file tmpfile_stdout 02:31:24 INFO - Current package versions: 02:31:24 INFO - blessings == 1.6 02:31:24 INFO - blobuploader == 1.2.4 02:31:24 INFO - docopt == 0.6.1 02:31:24 INFO - manifestparser == 1.1 02:31:24 INFO - mozInstall == 1.12 02:31:24 INFO - mozcrash == 0.17 02:31:24 INFO - mozdebug == 0.1 02:31:24 INFO - mozdevice == 0.48 02:31:24 INFO - mozfile == 1.2 02:31:24 INFO - mozhttpd == 0.7 02:31:24 INFO - mozinfo == 0.9 02:31:24 INFO - mozleak == 0.1 02:31:24 INFO - mozlog == 3.1 02:31:24 INFO - moznetwork == 0.27 02:31:24 INFO - mozprocess == 0.22 02:31:24 INFO - mozprofile == 0.28 02:31:24 INFO - mozrunner == 6.11 02:31:24 INFO - mozscreenshot == 0.1 02:31:24 INFO - mozsystemmonitor == 0.0 02:31:24 INFO - moztest == 0.7 02:31:24 INFO - mozversion == 1.4 02:31:24 INFO - psutil == 3.1.1 02:31:24 INFO - requests == 1.2.3 02:31:24 INFO - wsgiref == 0.1.2 02:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:24 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')]} 02:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:24 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')]} 02:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:24 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 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:24 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 02:31:24 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 02:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:24 INFO - 'HOME': '/Users/cltbld', 02:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:24 INFO - 'LOGNAME': 'cltbld', 02:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:24 INFO - 'MOZ_NO_REMOTE': '1', 02:31:24 INFO - 'NO_EM_RESTART': '1', 02:31:24 INFO - 'PAGER': '/bin/cat', 02:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:24 INFO - 'PWD': '/builds/slave/test', 02:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:24 INFO - 'SHELL': '/bin/bash', 02:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:24 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:24 INFO - 'USER': 'cltbld', 02:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:24 INFO - 'XPC_FLAGS': '0x0', 02:31:24 INFO - 'XPC_SERVICE_NAME': '0', 02:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 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)) 02:31:24 INFO - Cleaning up... 02:31:24 INFO - Return code: 0 02:31:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:31:24 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')]} 02:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:24 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')]} 02:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:24 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 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:24 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 02:31:24 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 02:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:24 INFO - 'HOME': '/Users/cltbld', 02:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:24 INFO - 'LOGNAME': 'cltbld', 02:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:24 INFO - 'MOZ_NO_REMOTE': '1', 02:31:24 INFO - 'NO_EM_RESTART': '1', 02:31:24 INFO - 'PAGER': '/bin/cat', 02:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:24 INFO - 'PWD': '/builds/slave/test', 02:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:24 INFO - 'SHELL': '/bin/bash', 02:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:24 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:24 INFO - 'USER': 'cltbld', 02:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:24 INFO - 'XPC_FLAGS': '0x0', 02:31:24 INFO - 'XPC_SERVICE_NAME': '0', 02:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:24 INFO - Downloading/unpacking jsonschema 02:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:31:27 INFO - Downloading/unpacking functools32 (from jsonschema) 02:31:27 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 02:31:27 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 02:31:27 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 02:31:27 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 02:31:27 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 02:31:27 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 02:31:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:31:27 INFO - warning: no files found matching '*.txt' 02:31:27 INFO - no previously-included directories found matching 'build' 02:31:27 INFO - no previously-included directories found matching 'dist' 02:31:27 INFO - no previously-included directories found matching '.git*' 02:31:27 INFO - Installing collected packages: jsonschema, functools32 02:31:27 INFO - Running setup.py install for functools32 02:31:27 INFO - warning: no files found matching '*.txt' 02:31:27 INFO - no previously-included directories found matching 'build' 02:31:27 INFO - no previously-included directories found matching 'dist' 02:31:27 INFO - no previously-included directories found matching '.git*' 02:31:27 INFO - Successfully installed jsonschema functools32 02:31:27 INFO - Cleaning up... 02:31:27 INFO - Return code: 0 02:31:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea42584e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, '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.cLgYiu1TvV/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.kWmcc94bij/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 02:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:27 INFO - 'HOME': '/Users/cltbld', 02:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:27 INFO - 'LOGNAME': 'cltbld', 02:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:27 INFO - 'MOZ_NO_REMOTE': '1', 02:31:27 INFO - 'NO_EM_RESTART': '1', 02:31:27 INFO - 'PAGER': '/bin/cat', 02:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:27 INFO - 'PWD': '/builds/slave/test', 02:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:27 INFO - 'SHELL': '/bin/bash', 02:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:27 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:27 INFO - 'USER': 'cltbld', 02:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:27 INFO - 'XPC_FLAGS': '0x0', 02:31:27 INFO - 'XPC_SERVICE_NAME': '0', 02:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:28 INFO - Downloading/unpacking flake8 02:31:28 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 02:31:28 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 02:31:28 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 02:31:28 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 02:31:28 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 02:31:28 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 02:31:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:31:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:31:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31: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 02:31:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 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 02:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:31:31 INFO - no previously-included directories found matching 'docs/_build' 02:31:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:31:31 INFO - Running setup.py install for pep8 02:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:31:31 INFO - no previously-included directories found matching 'docs/_build' 02:31:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:31:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:31:32 INFO - Cleaning up... 02:31:32 INFO - Return code: 0 02:31:32 INFO - Running post-action listener: _resource_record_post_action 02:31:32 INFO - Running post-action listener: _start_resource_monitoring 02:31:32 INFO - Starting resource monitoring. 02:31:32 INFO - ##### 02:31:32 INFO - ##### Running install step. 02:31:32 INFO - ##### 02:31:32 INFO - Running pre-action listener: _resource_record_pre_action 02:31:32 INFO - Running main action method: install 02:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:31:32 INFO - Reading from file tmpfile_stdout 02:31:32 INFO - Detecting whether we're running mozinstall >=1.0... 02:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:31:32 INFO - Reading from file tmpfile_stdout 02:31:32 INFO - Output received: 02:31:32 INFO - Usage: mozinstall [options] installer 02:31:32 INFO - Options: 02:31:32 INFO - -h, --help show this help message and exit 02:31:32 INFO - -d DEST, --destination=DEST 02:31:32 INFO - Directory to install application into. [default: 02:31:32 INFO - "/builds/slave/test"] 02:31:32 INFO - --app=APP Application being installed. [default: firefox] 02:31:32 INFO - mkdir: /builds/slave/test/build/application 02:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 02:31:54 INFO - Reading from file tmpfile_stdout 02:31:54 INFO - Output received: 02:31:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:31:54 INFO - Running post-action listener: _resource_record_post_action 02:31:54 INFO - ##### 02:31:54 INFO - ##### Running run-tests step. 02:31:54 INFO - ##### 02:31:54 INFO - Running pre-action listener: _resource_record_pre_action 02:31:54 INFO - Running main action method: run_tests 02:31:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:31:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:31:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:31:54 INFO - 2016-05-09 02:31:54.916 screenresolution[1639:7453] starting screenresolution argv=screenresolution get 02:31:54 INFO - 2016-05-09 02:31:54.918 screenresolution[1639:7453] Display 0: 1600x1200x32@60 02:31:54 INFO - 2016-05-09 02:31:54.924 screenresolution[1640:7455] starting screenresolution argv=screenresolution list 02:31:54 INFO - Available Modes on Display 0 02:31:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:31:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:31:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:31:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:31:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:31:55 INFO - Intel Iris: 02:31:55 INFO - Chipset Model: Intel Iris 02:31:55 INFO - Type: GPU 02:31:55 INFO - Bus: Built-In 02:31:55 INFO - VRAM (Dynamic, Max): 1536 MB 02:31:55 INFO - Vendor: Intel (0x8086) 02:31:55 INFO - Device ID: 0x0a2e 02:31:55 INFO - Revision ID: 0x0009 02:31:55 INFO - Displays: 02:31:55 INFO - Display: 02:31:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:31:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:31:55 INFO - Display Serial Number: 1600x1200 60 02:31:55 INFO - Main Display: Yes 02:31:55 INFO - Mirror: Off 02:31:55 INFO - Online: Yes 02:31:55 INFO - Rotation: Supported 02:31:55 INFO - Return code: 0 02:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:31:55 INFO - Python 2.7.3 02:31:55 INFO - Return code: 0 02:31:55 INFO - grabbing minidump binary from tooltool 02:31: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')]} 02:31:55 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dccd30>, '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 0x108c30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dc3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:31:55 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 02:31:55 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 02:31:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:31:55 INFO - Return code: 0 02:31:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:31:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:31:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:31:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:31:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:31:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:31:56 INFO - Return code: 0 02:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 02:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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 02:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cLgYiu1TvV/Render', 02:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:56 INFO - 'HOME': '/Users/cltbld', 02:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:56 INFO - 'LOGNAME': 'cltbld', 02:31:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:31:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:56 INFO - 'MOZ_NO_REMOTE': '1', 02:31:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:31:56 INFO - 'NO_EM_RESTART': '1', 02:31:56 INFO - 'PAGER': '/bin/cat', 02:31:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:56 INFO - 'PWD': '/builds/slave/test', 02:31:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:56 INFO - 'SHELL': '/bin/bash', 02:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kWmcc94bij/Listeners', 02:31:56 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 02:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:56 INFO - 'USER': 'cltbld', 02:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:56 INFO - 'XPC_FLAGS': '0x0', 02:31:56 INFO - 'XPC_SERVICE_NAME': '0', 02:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 02:31:56 INFO - mozversion application_buildid: 20160509005545 02:31:56 INFO - mozversion application_changeset: fc51f034e4aca08b76934a832d7dc7db1954b9d2 02:31:56 INFO - mozversion application_display_name: Firefox 02:31:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:31:56 INFO - mozversion application_name: Firefox 02:31:56 INFO - mozversion application_remotingname: firefox 02:31:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:31:56 INFO - mozversion application_vendor: Mozilla 02:31:56 INFO - mozversion application_version: 47.0 02:31:56 INFO - mozversion platform_buildid: 20160509005545 02:31:56 INFO - mozversion platform_changeset: fc51f034e4aca08b76934a832d7dc7db1954b9d2 02:31:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:31:56 INFO - mozversion platform_version: 47.0 02:31:56 INFO - using testdate: 1462786316 02:31:56 INFO - actual date: 1462786316 02:31:56 INFO - starting webserver on 'localhost:49275' 02:31:56 INFO - SUITE-START | Running 2 tests 02:31:56 INFO - TEST-START | dromaeo_css 02:31:56 INFO - operating with platform_type : mac_ 02:31:56 INFO - Initialising browser for dromaeo_css test... 02:31:56 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmp99af44/profile http://localhost:49275/getInfo.html) 02:31:59 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 02:31:59 INFO - PROCESS | 1658 | colorDepth:24 02:31:59 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 02:31:59 INFO - PROCESS | 1658 | __metrics 02:31:59 INFO - PROCESS | 1658 | 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" 02:31:59 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:31:59 INFO - TEST-INFO | 1658: exit 0 02:31:59 INFO - Browser initialized. 02:31:59 INFO - Running cycle 1/1 for dromaeo_css test... 02:31:59 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmp99af44/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:11 INFO - PROCESS | 1661 | RSS: Main: 157245440 02:32:11 INFO - PROCESS | 1661 | 02:33:02 INFO - PROCESS | 1661 | 1462786382537 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] 02:35:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 02:35:13 INFO - PROCESS | 1661 | RSS: Main: 296136704 02:35:13 INFO - PROCESS | 1661 | 02:38:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 02:38:16 INFO - PROCESS | 1661 | RSS: Main: 292188160 02:38:16 INFO - PROCESS | 1661 | 02:41:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 02:41:19 INFO - PROCESS | 1661 | RSS: Main: 293392384 02:41:19 INFO - PROCESS | 1661 | 02:44:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 02:44:21 INFO - PROCESS | 1661 | RSS: Main: 254226432 02:44:21 INFO - PROCESS | 1661 | 02:47:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 02:47:25 INFO - PROCESS | 1661 | RSS: Main: 253652992 02:47:25 INFO - PROCESS | 1661 | 02:50:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 02:50:28 INFO - PROCESS | 1661 | RSS: Main: 299614208 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | __start_tp_report 02:50:28 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 02:50:28 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 02:50:28 INFO - PROCESS | 1661 | |i|pagename|runs| 02:50:28 INFO - PROCESS | 1661 | |0;dojo.html;3564.3762341590223;3617.83719732612;3618.149734812317;3637.054365864876;3679.595244523102;4011.438398624195;5681.892031053946;5807.070868661015;5843.328017278009;5862.912056319154;5831.3293971193325;5837.50381217597;5865.706714664271;5873.11903214518;5881.85295367616;5185.222216667492;5271.5782737381105;5289.761960711778;5297.973510132444;5402.081646120149;5419.214213938976;5422.9728851355885;5423.349198096214;5427.213054107152;5434.809781657643;5377.462253774611;5421.241026256327;5440.047991601474;5441.863953401157;5470.234167216593;5450.400455949843;5469.535089517396;5481.808136715196;5488.506034456878;5534.114541673353;5239.318888544503;5374.408815030343;5399.757010934517;5411.242426060355;5413.26920865682;5734.197212390269;5757.395473475309;5797.710114494258;5829.213056237396;5870.6477611343025;6252.562320637558;6326.398992095744;6328.905066424008;6329.303776584572;6333.429991300759;6264.59280146789;6302.9369706302805;6304.464120549759;6310.2427708674795;6330.461910737593;5966.731497082642;6078.6048906820915;6084.176559636672;6093.756249750005;6107.511399088063;6377.96811015942;6380.361963803675;6400.167978162809;6404.295527491972;6409.615423074631;6145.354737752561;6164.260288765377;6267.96866015667;6331.620102793847;6337.429631333202;6334.588251763621;6339.11252424661;6340.841478963062;6351.841203969938;6354.142190804182;6273.309935907047;6318.62088274705;6329.050642403677;6337.017762246859;6344.143540622049;6256.343083976117;6260.467860231931;6324.209473815773;6340.809775706736;6368.84077898047;5054.368203974504;5068.619853511002;5068.797248110034;5069.847904562869;5077.796888124508;4538.795754191069;4575.794089265991;4575.81696732127;4609.7695115244105;4646.372739680168;6100;6102.176206212104;6125.081237814364;6145.723442445101;6154;6247.0629405589525;6268.843278917972;6298.496120310364;6346.746130154742;6350.269718982377;6122.173506576644;6124.869201255686;6125.75438176821;6137.048757442603;6144.385561443909;5558.194061861008;5571.693556854331;5572.0527510323345;5574.944250557523;5584.329880414295;6325.209348831396;6412.711427985845;6436.678166091677;6472.5145614080075;6493.740250390026;6300.464460520907;6315.873682526232;6342.270638876404;6350.079238510482;6368.554201205871;6492.285848556749;6514.478841692748;6515.706793194412;6524.217093948757;6531.183602049744;5270.78916843322;6225.159603453476;6239.625622462667;6328.208973878265;6354.4281014708895;6308;6312.968435157794;6316.526260530387;6323.7786677466065;6325.1144839721555;6272.466840318624;6322.3045464997895;6335.081413195153;6336.524760642878;6351.96824015877;6218.9378106218355;6301.653409062362;6306.369363063657;6308.179936608211;6322.810315690536;6220.595661282002;6223.502119830312;6223.657698826608;6224.564280500322;6230.06549017651;6020.096985452217;6118.192813661829;6243.500519958483;6296.150019747275;6323.810285691437;6078.422549857756;6112.824594567669;6125.84596676928;6162.352952940056;6214.036824292061 02:50:28 INFO - PROCESS | 1661 | |1;ext.html;483.7436158835814;589.911513273009;632.6082618239872;637.8182218067849;681.8465845184834;32537.51193732093;41051.38422923659;41520;41653.375199372036;41755.95610109745;44730.32904506435;44997.7750111249;45162;45212.32181517272;45259.094818103615;9687.128158465754;9944.701658950207;9949.602015919354;9958.651447199349;9959.850602240973;9831.90168098317;9933.304668673196;9991.800163996715;10007.649732259373;10007.849882251772;9929.255305852053;10127.139193168552;10134.493275336241;10146.036126568;10170.38977661339;10055.899441005606;10112.292139550234;10119.544620492023;10122.64570740028;10129.088382045651;9883.357581757235;10043.296969212159;10045.447500387476;10051.849222261671;10052;43921;44289.33565996512;44390.556094438965;44429.33355999662;44447;3421.5209870618132;3494.8427320770625;3558.0215440754;3606.314800187956;3607.4047782115917;27568.035118770946;28524.572131418045;29294.12117636453;30379;31613.525797113063;2825.675047369538;2875.7986940914143;2876.1084063940248;2900.1589539033653;2942.0144251675715;414133;415929;416828;417246;418086;49005.26492102621;49574;71601.28398716051;72255;72326.63836680783;47869.5213047872;47975.76012119987;48206;48303;48332.27501587479;17290.740638890427;17461.38885139014;17465.38871139504;17478.650426991644;17511.299548017938;13692.726145477034;13797.172169669651;13869.959753018564;13896.722065558632;13912.721745565032;15559.377624895104;15594.9220253898;15596.376144954302;15605.765913511306;15634.765478517831;6167.321594624587;6196.056872985386;6605.438537724252;6658.766943156967;6720.764773232913;996.2080146283722;1017.9643712470028;1061.984070238947;1064.935064935065;1072.9946350268356;28567.428651426853;29276.853615731787;29381.85309073441;29522.96669616553;29719.702802972126;1187.0444292344573;1192.3442106841203;1260.936953152357;1270.1807334269345;1285.4858056777364;12315.384230788424;16335.754963675554;16506.669866602602;17290.394836180672;17391.043492608027;612818;786383;796946.01526992;797687;800656;62716.05925911115;62990;63491;63529.36470635327;63529.682351587944;1554.968900622004;1582.548973542535;1626.8617167540892;1629.4541328655034;1672.3310675729563;25245.99016039338;30069.384735358188;31150.75396984186;31747.20631984219;31808.68191318057;50309;50692.986140277724;50821;50863.49136508587;50914.23628645647;362503;363092;363104;363242;364742.17628911685;31874.20314492156;33292.667073329925;35162.64837351696;36245.45631815472;36423.81788091043;47788;47894;47969.04061918812;49638.7734797237;49727;39079.41380879346;39189.608103918596;39562.604373955895;39565.40651890282;40671;38716.80641596774;38923.22153556856;39222.41166382564;39786;40890.59109408987;38888.80555597317;39173.804130979166;39486.80256598699;39830;39918;33294.16764580905;33470.497942531365;33590.83204583961;33847;34140.82929585435;22503.662445063666;26110.60834087452;26292.8685356572;26373.362663734242;26412.603810943234 02:50:28 INFO - PROCESS | 1661 | |2;jquery.html;375.1091158498566;377.89041178058363;378.1736904862876;380.8095952023988;384.70185606155246;16723.49829505115;22156;22355.10579576817;22512.66231006532;22850.85745712712;12273.815892761615;12578.99368050556;21463.57072858542;22266.55466890661;22322.883855807228;5280.656757310762;5431.511163995239;5464.398916119224;5470.5350045246205;5482.314710661168;4162.146759914225;4428.6014258716705;5450.727463626803;6648.567843090208;6707.966460167692;6462.709178086998;6650.1022361980995;6673.59958402495;6675.532712710112;6686.83282917926;5905.350411454737;6067.878642427171;6107.144999700045;6151.784687535937;6640;5784.219130417382;5794.362620111784;5811.564132690065;5818.243628328332;5826.388229235954;20021.8998905006;25832;26433;27158.86420567904;27239;2394.6767186429784;2504.962425563618;2670.9332266693295;2685.140754958414;2907.665618453884;10315.020073093045;10334.431606261653;10428.009339112772;10558.78882422355;10620.150387968944;1809.8914065156132;1824.2611742244412;1854.8237917397826;1868.2807119259182;1875.7655293088364;102789.48605257;107231;107242;107727.99999999921;108343.70734780261;11292.887071129348;11550.480228389742;11668.591599293984;11676.941615292038;11743.768683950022;13318.400671969785;14478.565643030515;14511.492097776527;14527.055741376776;14530.418783248551;8887.689200807707;9021.548922553846;9042.683506077256;9100.271978241857;9179.082091790768;9115.991722731482;9367.62529498826;9390.436573805593;9453.054694530492;9469.857952130724;3906.277338692351;4598.816047358135;4600.700954437951;4610.815567377267;4682.929756053662;1922.6250881078056;1963.2834015584494;1965.2826718248023;1971.69438736996;1979.5644958109192;941.7975135345933;966.6085235479642;1040.9063184313297;1065.7229120428744;1074.237291523012;20591;21138.260160894293;21149.471263218544;21168.682469762967;21196;998.8002399519979;1008.2438171371472;1012.7974405119006;1047.9633212837666;1068.6526878764432;10816.134709223243;11257.380844053492;11284.548618055185;11306.94346528262;11505.654830355103;682892;683678;693959;697038;698179;83576.32847343141;101108;122191.77808221981;127276;128705;1013.6908242985812;1017.3793985668736;1032.3754128751998;1042.5621239079453;1042.8852826189113;18198.72701909446;18631.34790282388;18974.43076707701;18974.620507589494;19074.80925190786;50447.24329134992;53518.73240633772;54124.729376352865;55171.13802033819;59075.70462147834;71416.92874606988;76982.61508692421;77690.80039189738;77862.61068694621;78482;7800.492967957065;8340.124286949784;8384.66461341564;8450.788730281793;8684;12943.352832358532;13471.393787279792;13605.455781768618;13790.724185516434;13842.307884605529;11617.128715346551;11751.529938802352;11927.642170734893;11981.400929953641;12111.091668125102;11634;11648;11652.650420487398;11977.161598688008;12193.634190974284;9988.900110998797;11906.76186476283;11908.345041022827;12043.591281743862;12185.573504927284;8305.626246818787;8342.624581893708;8390.328773698211;8398.244158025807;8426.915730842613;11287.153463490435;11924.224925379822;12106.636800895987;12125.454354553889;12188.817167742314 02:50:28 INFO - PROCESS | 1661 | |3;mootools.html;1513.6064623197965;1634.836516348365;1668.349343755935;1992.8804271743697;2066.0805941356093;8337.124601916803;8952.149545793156;9132.041135680758;9145.542722863855;9165.220956218713;8827.955860220705;9077.72766816995;9083.364164508486;9107.316951228651;9116.726498205047;2119.3430036688637;2580.7935365170742;2584.2118153963033;2585.392432778305;2586.5344238037146;2517.7356377580363;2596.3379338268714;2600.1029644772525;2601.947961040778;2607.960880586793;2417.8065754739578;2590.1970389179414;2613.124603257911;2616.6467526883816;2618.93452663684;2146.4419250994774;2570.768630823235;2607.504574130909;2607.765301122889;2611.4385407137556;2577.6391305217285;2592.235290589281;2592.80553958452;2593.0405749872666;2620.462805124964;9237.769055773591;9300.441973481613;9304.953475232649;9321.766955826091;9338.066193380675;5743.339515955677;5818.970905145447;5877.85305367365;5886.764529418818;5889.882202355929;3716.5540135183683;3803.2963901678277;3861.4207868819904;3866.903327416809;3867.7292589518743;1072.5012869015945;1090.2204923479671;1091.9781604367868;1107.3355986408171;1132.5186795611833;12443.564475243324;12448.315342656151;12466.314352710597;12481.625551233477;12483.063770217179;4234.788260586958;4966.975165124151;5001.42483614389;5115.232715092766;5119.0529751996;10524;10555.894441055645;10559.04968552834;10565.894341056644;10576.312539684895;5681.687507187145;5689.402612725687;5690.402507736628;5696.458836410535;5696.629719068247;4343.391925130421;4605.585497305258;4784.904301913943;4978.054169707744;5139.460356662571;4121.349756856127;4141.772202528829;4171.891940504343;4214.494260688737;4215.620594146541;1334.7063645997864;1370.205280937054;1380.5582213691723;1381.302442266649;1384.2248340928911;1216.0757824053605;1229.9139060265695;1242.1801610936739;1442.6104951662992;1484.1169504145016;8545.914540854512;8653.783655408664;8668.176523230282;8669.133086691407;8673.609687564074;490.70557665400474;503.8060346766448;523.5079025715814;531.1713726586537;535.568867062011;6431.903521447182;6434.388733070351;6497.350264973559;6502.512311576651;6515.54391192612;10902.291351062155;11377.658670239905;11427.828582571268;11447.885521144848;11449.026832719146;10711.94644026775;11196.440177991068;11210.495527701272;11210.887891120985;11264.493097810619;8524.190201930807;8735.781605459788;8772.692955746517;8790.780230494289;8811.559422029;8214.219649133323;8285.005799304123;8314.043884953307;8384.706535271236;8389.203025712548;9636.614535418505;9871.50642467888;9988.550515226687;10084.69745907624;10268.743281418025;9914.900850991398;10257.58969641206;10411.635592754521;10435.426051567236;10438.530266138194;6713.295104014203;7721.343685786771;7737.1102323233545;7949;7950.125486196629;8541.188587084218;8568.785780355542;8595.52724600153;8597.312215022908;8631.309495240492;7599.88600171009;7748.457607967388;7757.573333466487;7848.372130229682;7877.2122787723065;7626.580538070238;7688.615569221628;7708;7808.5314881107315;7837.49056311338;8118.75643730689;8185;8238.629261683118;8256.917430825615;8271.048829384697;7356.227596102332;7450.478466507292;7458.21688722698;7460.664270107966;7481.663325150273;7441.218672039358;7479.813004674927;7563.8109047272055;7579.886301705368;7580.431467639839 02:50:28 INFO - PROCESS | 1661 | |4;prototype.html;334.7706820827733;336.50533715438314;343.99656003439964;351.9120219945014;353.9433690609503;6744.459158941552;6824.283450237728;6832.453403727703;6833.760818371358;6846;6723.562968407063;6729.831754206135;6764.4588432925375;6765.086713293704;6768.593884366941;4026.7382620129742;4842.92735608967;4934.876628084308;4957.182064959277;5063.670861394016;4985.326980857592;5018.146915024437;5042.369703787027;5048.1670524363435;5072.492750724917;4787.1622466068475;4980.676256043345;5022.773975171126;5032.949670503284;5034;4877.658563900528;4976.527229913152;4986.975065124651;5006.499350065001;5017.19724844025;4287.121140166259;4297.484301883763;4301.720388174758;4313.223619748444;4316.244657185011;6438.066480360369;6455.386738259858;6485.902711459332;6492.642904640243;6555.6394398308075;5966.343702192756;5978.2228310319815;5979.820605381846;5984.371640977722;5998.910016349758;5563.805266815642;7135.928640713579;7161.534500257519;7169.856602867913;7175.533590316665;4815.398075240595;4886.267059941002;4886.731229782361;4891.706497610155;4899.020195960822;7457.925420745777;7531.736389226406;7534.698612055566;7537.510061845963;7550.735724249625;4926.211806111005;4934.087193869145;4941.555260026615;4953.72754498506;4954.628402869727;4382.474103107647;4807.471178170398;5022.723750193774;5023.974880125576;5028.622853285945;5725.169850371756;5939.10913362999;5943.3462319144855;5962.612430192024;5970.13433052205;7154.356107950205;7204.53170543913;7210.927890721131;7211.927880721126;7217.133943926762;1731.826817318258;1754.8771585989114;1757.7539144519778;1758.6043140293332;1762.647470505904;831.9168083191754;1104.7569534702352;1121.2039451989017;1134.3364131982717;1157.5022740221616;6731.49513786468;6765.289644587346;6767.526273160832;6776.661166941633;6809.693563789641;7426.14599321085;7436.776896693108;7438.516496427715;7441.293077157661;7445.553266804009;6816.863662726817;6817.761378351734;6822.761203357859;6828.487863410165;6831.11195544586;3807.124361396894;3902.1220225449047;3933.645971862546;3954.367301231847;3992.2215168042535;7652.80867978305;7696.960910277041;7697.923020769833;7701.691932322757;7704.422168337397;7607.581583012988;7621;7626.199249078878;7627.237276272439;7628.580428076398;7628.588711088355;7662.846743065219;7666.425018123663;7675.385969122569;7679.884801728091;7073.575585464889;7376;7410.5183163094225;7416.851662966603;7430.182679905314;7206.855862882609;7268.854622907618;7270.636468176675;7274.199838017919;7277.526960747535;7658.5787781670315;7666.118396384264;7668.041494813148;7670.926070350267;7689.038870141232;4996.675216110941;4997.525235102659;4998.050370429607;4999.325091112653;5007.424146223231;6019.939800601938;7089.929100708927;7341.972123902765;7354.742584009534;7370.520916140434;5777.74000169985;7079.044329015517;7107.82230444243;7135.286471352823;7136.0723105995885;7107.964460177666;7121.181064177478;7141.428685705235;7145.606991615511;7153.642317884189;6948.5483443576;6956.478264130109;6963.7562685305775;6970.268121847336;6975.476839237179;6843.418309443753;7148.64256787169;7149.392301654418;7151.177614574391;7151.32062454066;5507.642003269774;6929.7228110875;6958.304169583002;6964.582125072512;6965.721371145098 02:50:28 INFO - PROCESS | 1661 | |5;yui.html;723.9493235473517;739.8557281330139;749.6776386153955;760.046142091675;825.8678611422174;15515.069095854256;15717.764233536505;15755.291011904463;15756.60608484787;15845.920770396146;13446.596602101952;14534.418623255056;14611.92694036531;16254.51236462905;16488.83511164891;815.6533473273864;832.3923535818856;980.308882238024;998.1515711645117;1000.7548150703088;977.4770497783687;995.2535598301274;996.2976101848216;997.7505623594102;1001.1790331927822;859.7506723050308;1048.035807057505;1051.995344446056;1054.072416273682;1057.8730552333732;852.5822347049963;861.4529773593782;1031.6079889641962;1052.5894900988621;1055.4353420919772;790.6718711734624;791.4815795653856;793.8610743119989;794.7854079398588;798.744401791427;15633.140177290245;15689.529314120595;15711.68576628461;15712.921435392864;15715.921420392824;9844.852327215098;10886.836697449544;12824.845763881201;13099.41052652633;13428.32858357088;8065.435419520577;10376.118029967462;10444.425556594384;10718.785624287471;10729.409882456463;3649.799261040642;3650.2152037312107;3656.012876523324;3727.1986522897437;3878.0111071676824;85092.57453712754;86938;87339.99999999936;88363;88468.5576572113;9731.708048758548;9988.450635214987;10012.899871001342;10023.548940297558;10226.795464090825;22750.431239218822;22790.31629051131;22810.543789124124;22847.885760571422;22878.771212287666;7436.776896693108;7493.812654683677;7613.809654758676;7673.3477654398885;7763.340116090196;4182.519010313853;4242.618164365222;4259.42497762798;4266.701330906807;4524.864254072383;3689.94465083024;3766.0584853786554;3771.453139294787;3772.4152756322815;3773;591.4765432592101;675.422513750736;679.5820570349299;684.4182444921777;690.7582346178898;612.4212619075021;621.6767281013938;630.5239544144233;632.8291361332507;640.2828831708516;8046.396520261003;8307.875381869157;8375.120612335739;8590.570471476527;8617.482951022834;571.4799532425473;573.5669569475019;611.1382950040422;622.707327556048;623.1027320658237;3693.1634756264443;4001.8394665546666;4028.5165780106577;4048;4228.23891699497;98705.02589948161;98740.50629746949;100123;100663;102411.4879425598;78254.21745782469;78510;79151.60424197842;79548;82268;72375.91436128358;72553;73255.26744732485;74560.1143901189;75762;8702.564871756513;8886.955565222133;8993.640254389751;9163.26693864476;9202.815943680955;51989;52096.73951630218;52910.73544632252;52995.94008119739;54044.45955540394;69868;73375.63312183405;74547;74716.62641686757;75170;11155.72110697239;11214.887851121384;11346.319220846775;11498.597549086076;11620.360880151673;9715.46564938935;12200.084993625336;12231.69420764488;12704.36478176069;13370.130941488773;10980.231383803057;11637.825432618687;11778;11921.165518413627;12234.204776689487;11688.70778230551;11859.466324015613;11888.702782430162;11951.701207469883;12265.362946966088;11405.315681059163;11544.711382215512;11660.650180494598;12191.451384687532;12283.01735861111;9722;9942.15491683215;10187.490625468845;10196.643117490852;10562.8415573768;9889.752756181153;10126.696199114038;10138.49307534635;10150.289479736643;10172.79654406893 02:50:28 INFO - PROCESS | 1661 | __end_tp_report 02:50:28 INFO - PROCESS | 1661 | __start_cc_report 02:50:28 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1133 02:50:28 INFO - PROCESS | 1661 | __end_cc_report 02:50:28 INFO - PROCESS | 1661 | __startTimestamp1462787428096__endTimestamp 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | ------- Summary: start ------- 02:50:28 INFO - PROCESS | 1661 | Number of tests: 6 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5898.97 Median:6162.35 stddev:632.23 (10.3%) stddev-sans-first:607.07 02:50:28 INFO - PROCESS | 1661 | Values: 3564.4 3617.8 3618.1 3637.1 3679.6 4011.4 5681.9 5807.1 5843.3 5862.9 5831.3 5837.5 5865.7 5873.1 5881.9 5185.2 5271.6 5289.8 5298.0 5402.1 5419.2 5423.0 5423.3 5427.2 5434.8 5377.5 5421.2 5440.0 5441.9 5470.2 5450.4 5469.5 5481.8 5488.5 5534.1 5239.3 5374.4 5399.8 5411.2 5413.3 5734.2 5757.4 5797.7 5829.2 5870.6 6252.6 6326.4 6328.9 6329.3 6333.4 6264.6 6302.9 6304.5 6310.2 6330.5 5966.7 6078.6 6084.2 6093.8 6107.5 6378.0 6380.4 6400.2 6404.3 6409.6 6145.4 6164.3 6268.0 6331.6 6337.4 6334.6 6339.1 6340.8 6351.8 6354.1 6273.3 6318.6 6329.1 6337.0 6344.1 6256.3 6260.5 6324.2 6340.8 6368.8 5054.4 5068.6 5068.8 5069.8 5077.8 4538.8 4575.8 4575.8 4609.8 4646.4 6100.0 6102.2 6125.1 6145.7 6154.0 6247.1 6268.8 6298.5 6346.7 6350.3 6122.2 6124.9 6125.8 6137.0 6144.4 5558.2 5571.7 5572.1 5574.9 5584.3 6325.2 6412.7 6436.7 6472.5 6493.7 6300.5 6315.9 6342.3 6350.1 6368.6 6492.3 6514.5 6515.7 6524.2 6531.2 5270.8 6225.2 6239.6 6328.2 6354.4 6308.0 6313.0 6316.5 6323.8 6325.1 6272.5 6322.3 6335.1 6336.5 6352.0 6218.9 6301.7 6306.4 6308.2 6322.8 6220.6 6223.5 6223.7 6224.6 6230.1 6020.1 6118.2 6243.5 6296.2 6323.8 6078.4 6112.8 6125.8 6162.4 6214.0 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:65913.58 Median:363098.00 stddev:146001.09 (40.2%) stddev-sans-first:146328.02 02:50:28 INFO - PROCESS | 1661 | Values: 483.7 589.9 632.6 637.8 681.8 32537.5 41051.4 41520.0 41653.4 41756.0 44730.3 44997.8 45162.0 45212.3 45259.1 9687.1 9944.7 9949.6 9958.7 9959.9 9831.9 9933.3 9991.8 10007.6 10007.8 9929.3 10127.1 10134.5 10146.0 10170.4 10055.9 10112.3 10119.5 10122.6 10129.1 9883.4 10043.3 10045.4 10051.8 10052.0 43921.0 44289.3 44390.6 44429.3 44447.0 3421.5 3494.8 3558.0 3606.3 3607.4 27568.0 28524.6 29294.1 30379.0 31613.5 2825.7 2875.8 2876.1 2900.2 2942.0 414133.0 415929.0 416828.0 417246.0 418086.0 49005.3 49574.0 71601.3 72255.0 72326.6 47869.5 47975.8 48206.0 48303.0 48332.3 17290.7 17461.4 17465.4 17478.7 17511.3 13692.7 13797.2 13870.0 13896.7 13912.7 15559.4 15594.9 15596.4 15605.8 15634.8 6167.3 6196.1 6605.4 6658.8 6720.8 996.2 1018.0 1062.0 1064.9 1073.0 28567.4 29276.9 29381.9 29523.0 29719.7 1187.0 1192.3 1260.9 1270.2 1285.5 12315.4 16335.8 16506.7 17290.4 17391.0 612818.0 786383.0 796946.0 797687.0 800656.0 62716.1 62990.0 63491.0 63529.4 63529.7 1555.0 1582.5 1626.9 1629.5 1672.3 25246.0 30069.4 31150.8 31747.2 31808.7 50309.0 50693.0 50821.0 50863.5 50914.2 362503.0 363092.0 363104.0 363242.0 364742.2 31874.2 33292.7 35162.6 36245.5 36423.8 47788.0 47894.0 47969.0 49638.8 49727.0 39079.4 39189.6 39562.6 39565.4 40671.0 38716.8 38923.2 39222.4 39786.0 40890.6 38888.8 39173.8 39486.8 39830.0 39918.0 33294.2 33470.5 33590.8 33847.0 34140.8 22503.7 26110.6 26292.9 26373.4 26412.6 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:37036.33 Median:22211.28 stddev:114046.87 (513.5%) stddev-sans-first:114333.58 02:50:28 INFO - PROCESS | 1661 | Values: 375.1 377.9 378.2 380.8 384.7 16723.5 22156.0 22355.1 22512.7 22850.9 12273.8 12579.0 21463.6 22266.6 22322.9 5280.7 5431.5 5464.4 5470.5 5482.3 4162.1 4428.6 5450.7 6648.6 6708.0 6462.7 6650.1 6673.6 6675.5 6686.8 5905.4 6067.9 6107.1 6151.8 6640.0 5784.2 5794.4 5811.6 5818.2 5826.4 20021.9 25832.0 26433.0 27158.9 27239.0 2394.7 2505.0 2670.9 2685.1 2907.7 10315.0 10334.4 10428.0 10558.8 10620.2 1809.9 1824.3 1854.8 1868.3 1875.8 102789.5 107231.0 107242.0 107728.0 108343.7 11292.9 11550.5 11668.6 11676.9 11743.8 13318.4 14478.6 14511.5 14527.1 14530.4 8887.7 9021.5 9042.7 9100.3 9179.1 9116.0 9367.6 9390.4 9453.1 9469.9 3906.3 4598.8 4600.7 4610.8 4682.9 1922.6 1963.3 1965.3 1971.7 1979.6 941.8 966.6 1040.9 1065.7 1074.2 20591.0 21138.3 21149.5 21168.7 21196.0 998.8 1008.2 1012.8 1048.0 1068.7 10816.1 11257.4 11284.5 11306.9 11505.7 682892.0 683678.0 693959.0 697038.0 698179.0 83576.3 101108.0 122191.8 127276.0 128705.0 1013.7 1017.4 1032.4 1042.6 1042.9 18198.7 18631.3 18974.4 18974.6 19074.8 50447.2 53518.7 54124.7 55171.1 59075.7 71416.9 76982.6 77690.8 77862.6 78482.0 7800.5 8340.1 8384.7 8450.8 8684.0 12943.4 13471.4 13605.5 13790.7 13842.3 11617.1 11751.5 11927.6 11981.4 12111.1 11634.0 11648.0 11652.7 11977.2 12193.6 9988.9 11906.8 11908.3 12043.6 12185.6 8305.6 8342.6 8390.3 8398.2 8426.9 11287.2 11924.2 12106.6 12125.5 12188.8 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:6335.68 Median:5117.14 stddev:3373.20 (65.9%) stddev-sans-first:3363.19 02:50:28 INFO - PROCESS | 1661 | Values: 1513.6 1634.8 1668.3 1992.9 2066.1 8337.1 8952.1 9132.0 9145.5 9165.2 8828.0 9077.7 9083.4 9107.3 9116.7 2119.3 2580.8 2584.2 2585.4 2586.5 2517.7 2596.3 2600.1 2601.9 2608.0 2417.8 2590.2 2613.1 2616.6 2618.9 2146.4 2570.8 2607.5 2607.8 2611.4 2577.6 2592.2 2592.8 2593.0 2620.5 9237.8 9300.4 9305.0 9321.8 9338.1 5743.3 5819.0 5877.9 5886.8 5889.9 3716.6 3803.3 3861.4 3866.9 3867.7 1072.5 1090.2 1092.0 1107.3 1132.5 12443.6 12448.3 12466.3 12481.6 12483.1 4234.8 4967.0 5001.4 5115.2 5119.1 10524.0 10555.9 10559.0 10565.9 10576.3 5681.7 5689.4 5690.4 5696.5 5696.6 4343.4 4605.6 4784.9 4978.1 5139.5 4121.3 4141.8 4171.9 4214.5 4215.6 1334.7 1370.2 1380.6 1381.3 1384.2 1216.1 1229.9 1242.2 1442.6 1484.1 8545.9 8653.8 8668.2 8669.1 8673.6 490.7 503.8 523.5 531.2 535.6 6431.9 6434.4 6497.4 6502.5 6515.5 10902.3 11377.7 11427.8 11447.9 11449.0 10711.9 11196.4 11210.5 11210.9 11264.5 8524.2 8735.8 8772.7 8790.8 8811.6 8214.2 8285.0 8314.0 8384.7 8389.2 9636.6 9871.5 9988.6 10084.7 10268.7 9914.9 10257.6 10411.6 10435.4 10438.5 6713.3 7721.3 7737.1 7949.0 7950.1 8541.2 8568.8 8595.5 8597.3 8631.3 7599.9 7748.5 7757.6 7848.4 7877.2 7626.6 7688.6 7708.0 7808.5 7837.5 8118.8 8185.0 8238.6 8256.9 8271.0 7356.2 7450.5 7458.2 7460.7 7481.7 7441.2 7479.8 7563.8 7579.9 7580.4 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:5897.75 Median:6768.06 stddev:1841.18 (27.2%) stddev-sans-first:1798.38 02:50:28 INFO - PROCESS | 1661 | Values: 334.8 336.5 344.0 351.9 353.9 6744.5 6824.3 6832.5 6833.8 6846.0 6723.6 6729.8 6764.5 6765.1 6768.6 4026.7 4842.9 4934.9 4957.2 5063.7 4985.3 5018.1 5042.4 5048.2 5072.5 4787.2 4980.7 5022.8 5032.9 5034.0 4877.7 4976.5 4987.0 5006.5 5017.2 4287.1 4297.5 4301.7 4313.2 4316.2 6438.1 6455.4 6485.9 6492.6 6555.6 5966.3 5978.2 5979.8 5984.4 5998.9 5563.8 7135.9 7161.5 7169.9 7175.5 4815.4 4886.3 4886.7 4891.7 4899.0 7457.9 7531.7 7534.7 7537.5 7550.7 4926.2 4934.1 4941.6 4953.7 4954.6 4382.5 4807.5 5022.7 5024.0 5028.6 5725.2 5939.1 5943.3 5962.6 5970.1 7154.4 7204.5 7210.9 7211.9 7217.1 1731.8 1754.9 1757.8 1758.6 1762.6 831.9 1104.8 1121.2 1134.3 1157.5 6731.5 6765.3 6767.5 6776.7 6809.7 7426.1 7436.8 7438.5 7441.3 7445.6 6816.9 6817.8 6822.8 6828.5 6831.1 3807.1 3902.1 3933.6 3954.4 3992.2 7652.8 7697.0 7697.9 7701.7 7704.4 7607.6 7621.0 7626.2 7627.2 7628.6 7628.6 7662.8 7666.4 7675.4 7679.9 7073.6 7376.0 7410.5 7416.9 7430.2 7206.9 7268.9 7270.6 7274.2 7277.5 7658.6 7666.1 7668.0 7670.9 7689.0 4996.7 4997.5 4998.1 4999.3 5007.4 6019.9 7089.9 7342.0 7354.7 7370.5 5777.7 7079.0 7107.8 7135.3 7136.1 7108.0 7121.2 7141.4 7145.6 7153.6 6948.5 6956.5 6963.8 6970.3 6975.5 6843.4 7148.6 7149.4 7151.2 7151.3 5507.6 6929.7 6958.3 6964.6 6965.7 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:19319.63 Median:4251.02 stddev:27429.39 (645.2%) stddev-sans-first:27470.80 02:50:28 INFO - PROCESS | 1661 | Values: 723.9 739.9 749.7 760.0 825.9 15515.1 15717.8 15755.3 15756.6 15845.9 13446.6 14534.4 14611.9 16254.5 16488.8 815.7 832.4 980.3 998.2 1000.8 977.5 995.3 996.3 997.8 1001.2 859.8 1048.0 1052.0 1054.1 1057.9 852.6 861.5 1031.6 1052.6 1055.4 790.7 791.5 793.9 794.8 798.7 15633.1 15689.5 15711.7 15712.9 15715.9 9844.9 10886.8 12824.8 13099.4 13428.3 8065.4 10376.1 10444.4 10718.8 10729.4 3649.8 3650.2 3656.0 3727.2 3878.0 85092.6 86938.0 87340.0 88363.0 88468.6 9731.7 9988.5 10012.9 10023.5 10226.8 22750.4 22790.3 22810.5 22847.9 22878.8 7436.8 7493.8 7613.8 7673.3 7763.3 4182.5 4242.6 4259.4 4266.7 4524.9 3689.9 3766.1 3771.5 3772.4 3773.0 591.5 675.4 679.6 684.4 690.8 612.4 621.7 630.5 632.8 640.3 8046.4 8307.9 8375.1 8590.6 8617.5 571.5 573.6 611.1 622.7 623.1 3693.2 4001.8 4028.5 4048.0 4228.2 98705.0 98740.5 100123.0 100663.0 102411.5 78254.2 78510.0 79151.6 79548.0 82268.0 72375.9 72553.0 73255.3 74560.1 75762.0 8702.6 8887.0 8993.6 9163.3 9202.8 51989.0 52096.7 52910.7 52995.9 54044.5 69868.0 73375.6 74547.0 74716.6 75170.0 11155.7 11214.9 11346.3 11498.6 11620.4 9715.5 12200.1 12231.7 12704.4 13370.1 10980.2 11637.8 11778.0 11921.2 12234.2 11688.7 11859.5 11888.7 11951.7 12265.4 11405.3 11544.7 11660.7 12191.5 12283.0 9722.0 9942.2 10187.5 10196.6 10562.8 9889.8 10126.7 10138.5 10150.3 10172.8 02:50:28 INFO - PROCESS | 1661 | -------- Summary: end -------- 02:50:28 INFO - PROCESS | 1661 | 02:50:28 INFO - TEST-INFO | 1661: exit 0 02:50:28 INFO - TEST-OK | dromaeo_css | took 1111890ms 02:50:28 INFO - TEST-START | kraken 02:50:28 INFO - operating with platform_type : mac_ 02:50:28 INFO - Initialising browser for kraken test... 02:50:28 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpkLe0Hb/profile http://localhost:49275/getInfo.html) 02:50:30 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 02:50:30 INFO - PROCESS | 1670 | colorDepth:24 02:50:30 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 02:50:30 INFO - PROCESS | 1670 | __metrics 02:50:30 INFO - PROCESS | 1670 | 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" 02:50:30 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:50:31 INFO - TEST-INFO | 1670: exit 0 02:50:31 INFO - Browser initialized. 02:50:31 INFO - Running cycle 1/1 for kraken test... 02:50:31 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpkLe0Hb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:43 INFO - PROCESS | 1673 | RSS: Main: 157224960 02:50:43 INFO - PROCESS | 1673 | 02:50:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 02:50:46 INFO - PROCESS | 1673 | RSS: Main: 213020672 02:50:46 INFO - PROCESS | 1673 | 02:50:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 02:50:49 INFO - PROCESS | 1673 | RSS: Main: 223563776 02:50:49 INFO - PROCESS | 1673 | 02:50:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 02:50:53 INFO - PROCESS | 1673 | RSS: Main: 214044672 02:50:53 INFO - PROCESS | 1673 | 02:50:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 02:50:55 INFO - PROCESS | 1673 | RSS: Main: 216514560 02:50:55 INFO - PROCESS | 1673 | 02:50:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:50:58 INFO - PROCESS | 1673 | RSS: Main: 216379392 02:50:58 INFO - PROCESS | 1673 | 02:51:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:01 INFO - PROCESS | 1673 | RSS: Main: 216236032 02:51:01 INFO - PROCESS | 1673 | 02:51:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:05 INFO - PROCESS | 1673 | RSS: Main: 216367104 02:51:05 INFO - PROCESS | 1673 | 02:51:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 02:51:08 INFO - PROCESS | 1673 | RSS: Main: 216850432 02:51:08 INFO - PROCESS | 1673 | 02:51:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:10 INFO - PROCESS | 1673 | RSS: Main: 250613760 02:51:10 INFO - PROCESS | 1673 | 02:51:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:51:13 INFO - PROCESS | 1673 | RSS: Main: 251539456 02:51:13 INFO - PROCESS | 1673 | 02:51:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:51:15 INFO - PROCESS | 1673 | RSS: Main: 256118784 02:51:15 INFO - PROCESS | 1673 | 02:51:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:51:18 INFO - PROCESS | 1673 | RSS: Main: 257732608 02:51:18 INFO - PROCESS | 1673 | 02:51:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:51:21 INFO - PROCESS | 1673 | RSS: Main: 257945600 02:51:21 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 02:51:23 INFO - PROCESS | 1673 | RSS: Main: 321138688 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | __start_tp_report 02:51:23 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 02:51:23 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 02:51:23 INFO - PROCESS | 1673 | |i|pagename|runs| 02:51:23 INFO - PROCESS | 1673 | |0;ai-astar;114;108;111;112;113;113;121;112;110;111 02:51:23 INFO - PROCESS | 1673 | |1;audio-beat-detection;169;157;157;167;145;141;120;152;152;149 02:51:23 INFO - PROCESS | 1673 | |2;audio-dft;184;184;183;183;184;185;185;184;184;187 02:51:23 INFO - PROCESS | 1673 | |3;audio-fft;73;71;76;115;55;57;61;57;119;54 02:51:23 INFO - PROCESS | 1673 | |4;audio-oscillator;91;91;126;85;113;120;133;76;77;120 02:51:23 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;84;90;127;86;83;87;83;87;83 02:51:23 INFO - PROCESS | 1673 | |6;imaging-darkroom;92;93;92;93;92;105;150;92;92;91 02:51:23 INFO - PROCESS | 1673 | |7;imaging-desaturate;87;85;87;85;87;96;140;142;83;84 02:51:23 INFO - PROCESS | 1673 | |8;json-parse-financial;54;57;57;56;57;57;58;56;54;52 02:51:23 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;62;68;71;73;73;66;65;67;65 02:51:23 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;81;79;80;74;81;79;79;79;84 02:51:23 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;128;118;121;129;129;127;112;117;126;132 02:51:23 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;133;122;135;145;166;162;133;132;132;132 02:51:23 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;64;67;64;65;66;66;65;65;63 02:51:23 INFO - PROCESS | 1673 | __end_tp_report 02:51:23 INFO - PROCESS | 1673 | __start_cc_report 02:51:23 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1188 02:51:23 INFO - PROCESS | 1673 | __end_cc_report 02:51:23 INFO - PROCESS | 1673 | __startTimestamp1462787483863__endTimestamp 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | ------- Summary: start ------- 02:51:23 INFO - PROCESS | 1673 | Number of tests: 14 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.50 stddev:3.44 (3.1%) stddev-sans-first:3.61 02:51:23 INFO - PROCESS | 1673 | Values: 114.0 108.0 111.0 112.0 113.0 113.0 121.0 112.0 110.0 111.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:154.50 stddev:13.96 (9.0%) stddev-sans-first:13.19 02:51:23 INFO - PROCESS | 1673 | Values: 169.0 157.0 157.0 167.0 145.0 141.0 120.0 152.0 152.0 149.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:1.16 (0.6%) stddev-sans-first:1.22 02:51:23 INFO - PROCESS | 1673 | Values: 184.0 184.0 183.0 183.0 184.0 185.0 185.0 184.0 184.0 187.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:73.80 Median:59.00 stddev:24.10 (40.8%) stddev-sans-first:25.56 02:51:23 INFO - PROCESS | 1673 | Values: 73.0 71.0 76.0 115.0 55.0 57.0 61.0 57.0 119.0 54.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:76.50 stddev:21.41 (28.0%) stddev-sans-first:22.24 02:51:23 INFO - PROCESS | 1673 | Values: 91.0 91.0 126.0 85.0 113.0 120.0 133.0 76.0 77.0 120.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:86.50 stddev:13.31 (15.4%) stddev-sans-first:14.08 02:51:23 INFO - PROCESS | 1673 | Values: 87.0 84.0 90.0 127.0 86.0 83.0 87.0 83.0 87.0 83.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:92.00 stddev:18.31 (19.9%) stddev-sans-first:19.24 02:51:23 INFO - PROCESS | 1673 | Values: 92.0 93.0 92.0 93.0 92.0 105.0 150.0 92.0 92.0 91.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:86.00 stddev:23.15 (26.9%) stddev-sans-first:24.24 02:51:23 INFO - PROCESS | 1673 | Values: 87.0 85.0 87.0 85.0 87.0 96.0 140.0 142.0 83.0 84.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:1.87 (3.3%) stddev-sans-first:1.87 02:51:23 INFO - PROCESS | 1673 | Values: 54.0 57.0 57.0 56.0 57.0 57.0 58.0 56.0 54.0 52.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:67.50 stddev:4.05 (6.0%) stddev-sans-first:3.83 02:51:23 INFO - PROCESS | 1673 | Values: 62.0 62.0 68.0 71.0 73.0 73.0 66.0 65.0 67.0 65.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.00 stddev:2.50 (3.1%) stddev-sans-first:2.65 02:51:23 INFO - PROCESS | 1673 | Values: 80.0 81.0 79.0 80.0 74.0 81.0 79.0 79.0 79.0 84.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:127.50 stddev:6.51 (5.1%) stddev-sans-first:6.73 02:51:23 INFO - PROCESS | 1673 | Values: 128.0 118.0 121.0 129.0 129.0 127.0 112.0 117.0 126.0 132.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.20 Median:134.00 stddev:14.21 (10.6%) stddev-sans-first:14.90 02:51:23 INFO - PROCESS | 1673 | Values: 133.0 122.0 135.0 145.0 166.0 162.0 133.0 132.0 132.0 132.0 02:51:23 INFO - PROCESS | 1673 | 02:51:23 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 02:51:23 INFO - PROCESS | 1673 | Values: 66.0 64.0 67.0 64.0 65.0 66.0 66.0 65.0 65.0 63.0 02:51:23 INFO - PROCESS | 1673 | -------- Summary: end -------- 02:51:23 INFO - PROCESS | 1673 | 02:51:24 INFO - TEST-INFO | 1673: exit 0 02:51:24 INFO - TEST-OK | kraken | took 55931ms 02:51:24 INFO - SUITE-END | took 1167s 02:51:25 INFO - Completed test suite (00:19:28) 02:51:25 INFO - javascript benchmark 02:51:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3564.3762341590223, 3617.83719732612, 3618.149734812317, 3637.054365864876, 3679.595244523102, 4011.438398624195, 5681.892031053946, 5807.070868661015, 5843.328017278009, 5862.912056319154, 5831.3293971193325, 5837.50381217597, 5865.706714664271, 5873.11903214518, 5881.85295367616, 5185.222216667492, 5271.5782737381105, 5289.761960711778, 5297.973510132444, 5402.081646120149, 5419.214213938976, 5422.9728851355885, 5423.349198096214, 5427.213054107152, 5434.809781657643, 5377.462253774611, 5421.241026256327, 5440.047991601474, 5441.863953401157, 5470.234167216593, 5450.400455949843, 5469.535089517396, 5481.808136715196, 5488.506034456878, 5534.114541673353, 5239.318888544503, 5374.408815030343, 5399.757010934517, 5411.242426060355, 5413.26920865682, 5734.197212390269, 5757.395473475309, 5797.710114494258, 5829.213056237396, 5870.6477611343025, 6252.562320637558, 6326.398992095744, 6328.905066424008, 6329.303776584572, 6333.429991300759, 6264.59280146789, 6302.9369706302805, 6304.464120549759, 6310.2427708674795, 6330.461910737593, 5966.731497082642, 6078.6048906820915, 6084.176559636672, 6093.756249750005, 6107.511399088063, 6377.96811015942, 6380.361963803675, 6400.167978162809, 6404.295527491972, 6409.615423074631, 6145.354737752561, 6164.260288765377, 6267.96866015667, 6331.620102793847, 6337.429631333202, 6334.588251763621, 6339.11252424661, 6340.841478963062, 6351.841203969938, 6354.142190804182, 6273.309935907047, 6318.62088274705, 6329.050642403677, 6337.017762246859, 6344.143540622049, 6256.343083976117, 6260.467860231931, 6324.209473815773, 6340.809775706736, 6368.84077898047, 5054.368203974504, 5068.619853511002, 5068.797248110034, 5069.847904562869, 5077.796888124508, 4538.795754191069, 4575.794089265991, 4575.81696732127, 4609.7695115244105, 4646.372739680168, 6100.0, 6102.176206212104, 6125.081237814364, 6145.723442445101, 6154.0, 6247.0629405589525, 6268.843278917972, 6298.496120310364, 6346.746130154742, 6350.269718982377, 6122.173506576644, 6124.869201255686, 6125.75438176821, 6137.048757442603, 6144.385561443909, 5558.194061861008, 5571.693556854331, 5572.0527510323345, 5574.944250557523, 5584.329880414295, 6325.209348831396, 6412.711427985845, 6436.678166091677, 6472.5145614080075, 6493.740250390026, 6300.464460520907, 6315.873682526232, 6342.270638876404, 6350.079238510482, 6368.554201205871, 6492.285848556749, 6514.478841692748, 6515.706793194412, 6524.217093948757, 6531.183602049744, 5270.78916843322, 6225.159603453476, 6239.625622462667, 6328.208973878265, 6354.4281014708895, 6308.0, 6312.968435157794, 6316.526260530387, 6323.7786677466065, 6325.1144839721555, 6272.466840318624, 6322.3045464997895, 6335.081413195153, 6336.524760642878, 6351.96824015877, 6218.9378106218355, 6301.653409062362, 6306.369363063657, 6308.179936608211, 6322.810315690536, 6220.595661282002, 6223.502119830312, 6223.657698826608, 6224.564280500322, 6230.06549017651, 6020.096985452217, 6118.192813661829, 6243.500519958483, 6296.150019747275, 6323.810285691437, 6078.422549857756, 6112.824594567669, 6125.84596676928, 6162.352952940056, 6214.036824292061], "unit": "score", "name": "dojo.html", "value": 5861.617937425014}, {"lowerIsBetter": false, "replicates": [483.7436158835814, 589.911513273009, 632.6082618239872, 637.8182218067849, 681.8465845184834, 32537.51193732093, 41051.38422923659, 41520.0, 41653.375199372036, 41755.95610109745, 44730.32904506435, 44997.7750111249, 45162.0, 45212.32181517272, 45259.094818103615, 9687.128158465754, 9944.701658950207, 9949.602015919354, 9958.651447199349, 9959.850602240973, 9831.90168098317, 9933.304668673196, 9991.800163996715, 10007.649732259373, 10007.849882251772, 9929.255305852053, 10127.139193168552, 10134.493275336241, 10146.036126568, 10170.38977661339, 10055.899441005606, 10112.292139550234, 10119.544620492023, 10122.64570740028, 10129.088382045651, 9883.357581757235, 10043.296969212159, 10045.447500387476, 10051.849222261671, 10052.0, 43921.0, 44289.33565996512, 44390.556094438965, 44429.33355999662, 44447.0, 3421.5209870618132, 3494.8427320770625, 3558.0215440754, 3606.314800187956, 3607.4047782115917, 27568.035118770946, 28524.572131418045, 29294.12117636453, 30379.0, 31613.525797113063, 2825.675047369538, 2875.7986940914143, 2876.1084063940248, 2900.1589539033653, 2942.0144251675715, 414133.0, 415929.0, 416828.0, 417246.0, 418086.0, 49005.26492102621, 49574.0, 71601.28398716051, 72255.0, 72326.63836680783, 47869.5213047872, 47975.76012119987, 48206.0, 48303.0, 48332.27501587479, 17290.740638890427, 17461.38885139014, 17465.38871139504, 17478.650426991644, 17511.299548017938, 13692.726145477034, 13797.172169669651, 13869.959753018564, 13896.722065558632, 13912.721745565032, 15559.377624895104, 15594.9220253898, 15596.376144954302, 15605.765913511306, 15634.765478517831, 6167.321594624587, 6196.056872985386, 6605.438537724252, 6658.766943156967, 6720.764773232913, 996.2080146283722, 1017.9643712470028, 1061.984070238947, 1064.935064935065, 1072.9946350268356, 28567.428651426853, 29276.853615731787, 29381.85309073441, 29522.96669616553, 29719.702802972126, 1187.0444292344573, 1192.3442106841203, 1260.936953152357, 1270.1807334269345, 1285.4858056777364, 12315.384230788424, 16335.754963675554, 16506.669866602602, 17290.394836180672, 17391.043492608027, 612818.0, 786383.0, 796946.01526992, 797687.0, 800656.0, 62716.05925911115, 62990.0, 63491.0, 63529.36470635327, 63529.682351587944, 1554.968900622004, 1582.548973542535, 1626.8617167540892, 1629.4541328655034, 1672.3310675729563, 25245.99016039338, 30069.384735358188, 31150.75396984186, 31747.20631984219, 31808.68191318057, 50309.0, 50692.986140277724, 50821.0, 50863.49136508587, 50914.23628645647, 362503.0, 363092.0, 363104.0, 363242.0, 364742.17628911685, 31874.20314492156, 33292.667073329925, 35162.64837351696, 36245.45631815472, 36423.81788091043, 47788.0, 47894.0, 47969.04061918812, 49638.7734797237, 49727.0, 39079.41380879346, 39189.608103918596, 39562.604373955895, 39565.40651890282, 40671.0, 38716.80641596774, 38923.22153556856, 39222.41166382564, 39786.0, 40890.59109408987, 38888.80555597317, 39173.804130979166, 39486.80256598699, 39830.0, 39918.0, 33294.16764580905, 33470.497942531365, 33590.83204583961, 33847.0, 34140.82929585435, 22503.662445063666, 26110.60834087452, 26292.8685356572, 26373.362663734242, 26412.603810943234], "unit": "score", "name": "ext.html", "value": 20229.836425942816}, {"lowerIsBetter": false, "replicates": [375.1091158498566, 377.89041178058363, 378.1736904862876, 380.8095952023988, 384.70185606155246, 16723.49829505115, 22156.0, 22355.10579576817, 22512.66231006532, 22850.85745712712, 12273.815892761615, 12578.99368050556, 21463.57072858542, 22266.55466890661, 22322.883855807228, 5280.656757310762, 5431.511163995239, 5464.398916119224, 5470.5350045246205, 5482.314710661168, 4162.146759914225, 4428.6014258716705, 5450.727463626803, 6648.567843090208, 6707.966460167692, 6462.709178086998, 6650.1022361980995, 6673.59958402495, 6675.532712710112, 6686.83282917926, 5905.350411454737, 6067.878642427171, 6107.144999700045, 6151.784687535937, 6640.0, 5784.219130417382, 5794.362620111784, 5811.564132690065, 5818.243628328332, 5826.388229235954, 20021.8998905006, 25832.0, 26433.0, 27158.86420567904, 27239.0, 2394.6767186429784, 2504.962425563618, 2670.9332266693295, 2685.140754958414, 2907.665618453884, 10315.020073093045, 10334.431606261653, 10428.009339112772, 10558.78882422355, 10620.150387968944, 1809.8914065156132, 1824.2611742244412, 1854.8237917397826, 1868.2807119259182, 1875.7655293088364, 102789.48605257, 107231.0, 107242.0, 107727.99999999921, 108343.70734780261, 11292.887071129348, 11550.480228389742, 11668.591599293984, 11676.941615292038, 11743.768683950022, 13318.400671969785, 14478.565643030515, 14511.492097776527, 14527.055741376776, 14530.418783248551, 8887.689200807707, 9021.548922553846, 9042.683506077256, 9100.271978241857, 9179.082091790768, 9115.991722731482, 9367.62529498826, 9390.436573805593, 9453.054694530492, 9469.857952130724, 3906.277338692351, 4598.816047358135, 4600.700954437951, 4610.815567377267, 4682.929756053662, 1922.6250881078056, 1963.2834015584494, 1965.2826718248023, 1971.69438736996, 1979.5644958109192, 941.7975135345933, 966.6085235479642, 1040.9063184313297, 1065.7229120428744, 1074.237291523012, 20591.0, 21138.260160894293, 21149.471263218544, 21168.682469762967, 21196.0, 998.8002399519979, 1008.2438171371472, 1012.7974405119006, 1047.9633212837666, 1068.6526878764432, 10816.134709223243, 11257.380844053492, 11284.548618055185, 11306.94346528262, 11505.654830355103, 682892.0, 683678.0, 693959.0, 697038.0, 698179.0, 83576.32847343141, 101108.0, 122191.77808221981, 127276.0, 128705.0, 1013.6908242985812, 1017.3793985668736, 1032.3754128751998, 1042.5621239079453, 1042.8852826189113, 18198.72701909446, 18631.34790282388, 18974.43076707701, 18974.620507589494, 19074.80925190786, 50447.24329134992, 53518.73240633772, 54124.729376352865, 55171.13802033819, 59075.70462147834, 71416.92874606988, 76982.61508692421, 77690.80039189738, 77862.61068694621, 78482.0, 7800.492967957065, 8340.124286949784, 8384.66461341564, 8450.788730281793, 8684.0, 12943.352832358532, 13471.393787279792, 13605.455781768618, 13790.724185516434, 13842.307884605529, 11617.128715346551, 11751.529938802352, 11927.642170734893, 11981.400929953641, 12111.091668125102, 11634.0, 11648.0, 11652.650420487398, 11977.161598688008, 12193.634190974284, 9988.900110998797, 11906.76186476283, 11908.345041022827, 12043.591281743862, 12185.573504927284, 8305.626246818787, 8342.624581893708, 8390.328773698211, 8398.244158025807, 8426.915730842613, 11287.153463490435, 11924.224925379822, 12106.636800895987, 12125.454354553889, 12188.817167742314], "unit": "score", "name": "jquery.html", "value": 9810.224129449525}, {"lowerIsBetter": false, "replicates": [1513.6064623197965, 1634.836516348365, 1668.349343755935, 1992.8804271743697, 2066.0805941356093, 8337.124601916803, 8952.149545793156, 9132.041135680758, 9145.542722863855, 9165.220956218713, 8827.955860220705, 9077.72766816995, 9083.364164508486, 9107.316951228651, 9116.726498205047, 2119.3430036688637, 2580.7935365170742, 2584.2118153963033, 2585.392432778305, 2586.5344238037146, 2517.7356377580363, 2596.3379338268714, 2600.1029644772525, 2601.947961040778, 2607.960880586793, 2417.8065754739578, 2590.1970389179414, 2613.124603257911, 2616.6467526883816, 2618.93452663684, 2146.4419250994774, 2570.768630823235, 2607.504574130909, 2607.765301122889, 2611.4385407137556, 2577.6391305217285, 2592.235290589281, 2592.80553958452, 2593.0405749872666, 2620.462805124964, 9237.769055773591, 9300.441973481613, 9304.953475232649, 9321.766955826091, 9338.066193380675, 5743.339515955677, 5818.970905145447, 5877.85305367365, 5886.764529418818, 5889.882202355929, 3716.5540135183683, 3803.2963901678277, 3861.4207868819904, 3866.903327416809, 3867.7292589518743, 1072.5012869015945, 1090.2204923479671, 1091.9781604367868, 1107.3355986408171, 1132.5186795611833, 12443.564475243324, 12448.315342656151, 12466.314352710597, 12481.625551233477, 12483.063770217179, 4234.788260586958, 4966.975165124151, 5001.42483614389, 5115.232715092766, 5119.0529751996, 10524.0, 10555.894441055645, 10559.04968552834, 10565.894341056644, 10576.312539684895, 5681.687507187145, 5689.402612725687, 5690.402507736628, 5696.458836410535, 5696.629719068247, 4343.391925130421, 4605.585497305258, 4784.904301913943, 4978.054169707744, 5139.460356662571, 4121.349756856127, 4141.772202528829, 4171.891940504343, 4214.494260688737, 4215.620594146541, 1334.7063645997864, 1370.205280937054, 1380.5582213691723, 1381.302442266649, 1384.2248340928911, 1216.0757824053605, 1229.9139060265695, 1242.1801610936739, 1442.6104951662992, 1484.1169504145016, 8545.914540854512, 8653.783655408664, 8668.176523230282, 8669.133086691407, 8673.609687564074, 490.70557665400474, 503.8060346766448, 523.5079025715814, 531.1713726586537, 535.568867062011, 6431.903521447182, 6434.388733070351, 6497.350264973559, 6502.512311576651, 6515.54391192612, 10902.291351062155, 11377.658670239905, 11427.828582571268, 11447.885521144848, 11449.026832719146, 10711.94644026775, 11196.440177991068, 11210.495527701272, 11210.887891120985, 11264.493097810619, 8524.190201930807, 8735.781605459788, 8772.692955746517, 8790.780230494289, 8811.559422029, 8214.219649133323, 8285.005799304123, 8314.043884953307, 8384.706535271236, 8389.203025712548, 9636.614535418505, 9871.50642467888, 9988.550515226687, 10084.69745907624, 10268.743281418025, 9914.900850991398, 10257.58969641206, 10411.635592754521, 10435.426051567236, 10438.530266138194, 6713.295104014203, 7721.343685786771, 7737.1102323233545, 7949.0, 7950.125486196629, 8541.188587084218, 8568.785780355542, 8595.52724600153, 8597.312215022908, 8631.309495240492, 7599.88600171009, 7748.457607967388, 7757.573333466487, 7848.372130229682, 7877.2122787723065, 7626.580538070238, 7688.615569221628, 7708.0, 7808.5314881107315, 7837.49056311338, 8118.75643730689, 8185.0, 8238.629261683118, 8256.917430825615, 8271.048829384697, 7356.227596102332, 7450.478466507292, 7458.21688722698, 7460.664270107966, 7481.663325150273, 7441.218672039358, 7479.813004674927, 7563.8109047272055, 7579.886301705368, 7580.431467639839], "unit": "score", "name": "mootools.html", "value": 5063.748892156622}, {"lowerIsBetter": false, "replicates": [334.7706820827733, 336.50533715438314, 343.99656003439964, 351.9120219945014, 353.9433690609503, 6744.459158941552, 6824.283450237728, 6832.453403727703, 6833.760818371358, 6846.0, 6723.562968407063, 6729.831754206135, 6764.4588432925375, 6765.086713293704, 6768.593884366941, 4026.7382620129742, 4842.92735608967, 4934.876628084308, 4957.182064959277, 5063.670861394016, 4985.326980857592, 5018.146915024437, 5042.369703787027, 5048.1670524363435, 5072.492750724917, 4787.1622466068475, 4980.676256043345, 5022.773975171126, 5032.949670503284, 5034.0, 4877.658563900528, 4976.527229913152, 4986.975065124651, 5006.499350065001, 5017.19724844025, 4287.121140166259, 4297.484301883763, 4301.720388174758, 4313.223619748444, 4316.244657185011, 6438.066480360369, 6455.386738259858, 6485.902711459332, 6492.642904640243, 6555.6394398308075, 5966.343702192756, 5978.2228310319815, 5979.820605381846, 5984.371640977722, 5998.910016349758, 5563.805266815642, 7135.928640713579, 7161.534500257519, 7169.856602867913, 7175.533590316665, 4815.398075240595, 4886.267059941002, 4886.731229782361, 4891.706497610155, 4899.020195960822, 7457.925420745777, 7531.736389226406, 7534.698612055566, 7537.510061845963, 7550.735724249625, 4926.211806111005, 4934.087193869145, 4941.555260026615, 4953.72754498506, 4954.628402869727, 4382.474103107647, 4807.471178170398, 5022.723750193774, 5023.974880125576, 5028.622853285945, 5725.169850371756, 5939.10913362999, 5943.3462319144855, 5962.612430192024, 5970.13433052205, 7154.356107950205, 7204.53170543913, 7210.927890721131, 7211.927880721126, 7217.133943926762, 1731.826817318258, 1754.8771585989114, 1757.7539144519778, 1758.6043140293332, 1762.647470505904, 831.9168083191754, 1104.7569534702352, 1121.2039451989017, 1134.3364131982717, 1157.5022740221616, 6731.49513786468, 6765.289644587346, 6767.526273160832, 6776.661166941633, 6809.693563789641, 7426.14599321085, 7436.776896693108, 7438.516496427715, 7441.293077157661, 7445.553266804009, 6816.863662726817, 6817.761378351734, 6822.761203357859, 6828.487863410165, 6831.11195544586, 3807.124361396894, 3902.1220225449047, 3933.645971862546, 3954.367301231847, 3992.2215168042535, 7652.80867978305, 7696.960910277041, 7697.923020769833, 7701.691932322757, 7704.422168337397, 7607.581583012988, 7621.0, 7626.199249078878, 7627.237276272439, 7628.580428076398, 7628.588711088355, 7662.846743065219, 7666.425018123663, 7675.385969122569, 7679.884801728091, 7073.575585464889, 7376.0, 7410.5183163094225, 7416.851662966603, 7430.182679905314, 7206.855862882609, 7268.854622907618, 7270.636468176675, 7274.199838017919, 7277.526960747535, 7658.5787781670315, 7666.118396384264, 7668.041494813148, 7670.926070350267, 7689.038870141232, 4996.675216110941, 4997.525235102659, 4998.050370429607, 4999.325091112653, 5007.424146223231, 6019.939800601938, 7089.929100708927, 7341.972123902765, 7354.742584009534, 7370.520916140434, 5777.74000169985, 7079.044329015517, 7107.82230444243, 7135.286471352823, 7136.0723105995885, 7107.964460177666, 7121.181064177478, 7141.428685705235, 7145.606991615511, 7153.642317884189, 6948.5483443576, 6956.478264130109, 6963.7562685305775, 6970.268121847336, 6975.476839237179, 6843.418309443753, 7148.64256787169, 7149.392301654418, 7151.177614574391, 7151.32062454066, 5507.642003269774, 6929.7228110875, 6958.304169583002, 6964.582125072512, 6965.721371145098], "unit": "score", "name": "prototype.html", "value": 5282.860554270893}, {"lowerIsBetter": false, "replicates": [723.9493235473517, 739.8557281330139, 749.6776386153955, 760.046142091675, 825.8678611422174, 15515.069095854256, 15717.764233536505, 15755.291011904463, 15756.60608484787, 15845.920770396146, 13446.596602101952, 14534.418623255056, 14611.92694036531, 16254.51236462905, 16488.83511164891, 815.6533473273864, 832.3923535818856, 980.308882238024, 998.1515711645117, 1000.7548150703088, 977.4770497783687, 995.2535598301274, 996.2976101848216, 997.7505623594102, 1001.1790331927822, 859.7506723050308, 1048.035807057505, 1051.995344446056, 1054.072416273682, 1057.8730552333732, 852.5822347049963, 861.4529773593782, 1031.6079889641962, 1052.5894900988621, 1055.4353420919772, 790.6718711734624, 791.4815795653856, 793.8610743119989, 794.7854079398588, 798.744401791427, 15633.140177290245, 15689.529314120595, 15711.68576628461, 15712.921435392864, 15715.921420392824, 9844.852327215098, 10886.836697449544, 12824.845763881201, 13099.41052652633, 13428.32858357088, 8065.435419520577, 10376.118029967462, 10444.425556594384, 10718.785624287471, 10729.409882456463, 3649.799261040642, 3650.2152037312107, 3656.012876523324, 3727.1986522897437, 3878.0111071676824, 85092.57453712754, 86938.0, 87339.99999999936, 88363.0, 88468.5576572113, 9731.708048758548, 9988.450635214987, 10012.899871001342, 10023.548940297558, 10226.795464090825, 22750.431239218822, 22790.31629051131, 22810.543789124124, 22847.885760571422, 22878.771212287666, 7436.776896693108, 7493.812654683677, 7613.809654758676, 7673.3477654398885, 7763.340116090196, 4182.519010313853, 4242.618164365222, 4259.42497762798, 4266.701330906807, 4524.864254072383, 3689.94465083024, 3766.0584853786554, 3771.453139294787, 3772.4152756322815, 3773.0, 591.4765432592101, 675.422513750736, 679.5820570349299, 684.4182444921777, 690.7582346178898, 612.4212619075021, 621.6767281013938, 630.5239544144233, 632.8291361332507, 640.2828831708516, 8046.396520261003, 8307.875381869157, 8375.120612335739, 8590.570471476527, 8617.482951022834, 571.4799532425473, 573.5669569475019, 611.1382950040422, 622.707327556048, 623.1027320658237, 3693.1634756264443, 4001.8394665546666, 4028.5165780106577, 4048.0, 4228.23891699497, 98705.02589948161, 98740.50629746949, 100123.0, 100663.0, 102411.4879425598, 78254.21745782469, 78510.0, 79151.60424197842, 79548.0, 82268.0, 72375.91436128358, 72553.0, 73255.26744732485, 74560.1143901189, 75762.0, 8702.564871756513, 8886.955565222133, 8993.640254389751, 9163.26693864476, 9202.815943680955, 51989.0, 52096.73951630218, 52910.73544632252, 52995.94008119739, 54044.45955540394, 69868.0, 73375.63312183405, 74547.0, 74716.62641686757, 75170.0, 11155.72110697239, 11214.887851121384, 11346.319220846775, 11498.597549086076, 11620.360880151673, 9715.46564938935, 12200.084993625336, 12231.69420764488, 12704.36478176069, 13370.130941488773, 10980.231383803057, 11637.825432618687, 11778.0, 11921.165518413627, 12234.204776689487, 11688.70778230551, 11859.466324015613, 11888.702782430162, 11951.701207469883, 12265.362946966088, 11405.315681059163, 11544.711382215512, 11660.650180494598, 12191.451384687532, 12283.01735861111, 9722.0, 9942.15491683215, 10187.490625468845, 10196.643117490852, 10562.8415573768, 9889.752756181153, 10126.696199114038, 10138.49307534635, 10150.289479736643, 10172.79654406893], "unit": "score", "name": "yui.html", "value": 7239.748799881832}], "name": "dromaeo_css", "value": 7800.657775477444}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 108.0, 111.0, 112.0, 113.0, 113.0, 121.0, 112.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.5}, {"lowerIsBetter": true, "replicates": [169.0, 157.0, 157.0, 167.0, 145.0, 141.0, 120.0, 152.0, 152.0, 149.0], "unit": "score", "name": "audio-beat-detection", "value": 150.9}, {"lowerIsBetter": true, "replicates": [184.0, 184.0, 183.0, 183.0, 184.0, 185.0, 185.0, 184.0, 184.0, 187.0], "unit": "score", "name": "audio-dft", "value": 184.3}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 76.0, 115.0, 55.0, 57.0, 61.0, 57.0, 119.0, 54.0], "unit": "score", "name": "audio-fft", "value": 73.8}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 126.0, 85.0, 113.0, 120.0, 133.0, 76.0, 77.0, 120.0], "unit": "score", "name": "audio-oscillator", "value": 103.2}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 90.0, 127.0, 86.0, 83.0, 87.0, 83.0, 87.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.7}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 92.0, 93.0, 92.0, 105.0, 150.0, 92.0, 92.0, 91.0], "unit": "score", "name": "imaging-darkroom", "value": 99.2}, {"lowerIsBetter": true, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 96.0, 140.0, 142.0, 83.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 97.6}, {"lowerIsBetter": true, "replicates": [54.0, 57.0, 57.0, 56.0, 57.0, 57.0, 58.0, 56.0, 54.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 55.8}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 68.0, 71.0, 73.0, 73.0, 66.0, 65.0, 67.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.2}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 79.0, 80.0, 74.0, 81.0, 79.0, 79.0, 79.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.6}, {"lowerIsBetter": true, "replicates": [128.0, 118.0, 121.0, 129.0, 129.0, 127.0, 112.0, 117.0, 126.0, 132.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.9}, {"lowerIsBetter": true, "replicates": [133.0, 122.0, 135.0, 145.0, 166.0, 162.0, 133.0, 132.0, 132.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 139.2}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 67.0, 64.0, 65.0, 66.0, 66.0, 65.0, 65.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1442.0}]} 02:51:25 INFO - Return code: 0 02:51:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:51:25 INFO - # TBPL SUCCESS # 02:51:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:51:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:51:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:51:25 INFO - 2016-05-09 02:51:25.181 screenresolution[1677:9723] starting screenresolution argv=screenresolution get 02:51:25 INFO - 2016-05-09 02:51:25.184 screenresolution[1677:9723] Display 0: 1600x1200x32@60 02:51:25 INFO - 2016-05-09 02:51:25.190 screenresolution[1678:9726] starting screenresolution argv=screenresolution list 02:51:25 INFO - Available Modes on Display 0 02:51:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:51:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:51:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:51:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:51:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:51:25 INFO - Intel Iris: 02:51:25 INFO - Chipset Model: Intel Iris 02:51:25 INFO - Type: GPU 02:51:25 INFO - Bus: Built-In 02:51:25 INFO - VRAM (Dynamic, Max): 1536 MB 02:51:25 INFO - Vendor: Intel (0x8086) 02:51:25 INFO - Device ID: 0x0a2e 02:51:25 INFO - Revision ID: 0x0009 02:51:25 INFO - Displays: 02:51:25 INFO - Display: 02:51:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:51:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:51:25 INFO - Display Serial Number: 1600x1200 60 02:51:25 INFO - Main Display: Yes 02:51:25 INFO - Mirror: Off 02:51:25 INFO - Online: Yes 02:51:25 INFO - Rotation: Supported 02:51:25 INFO - Return code: 0 02:51:25 INFO - Running post-action listener: _resource_record_post_action 02:51:25 INFO - Running post-run listener: _resource_record_post_run 02:51:26 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 37971456; Write bytes: 507427328; Read time: 501; Write time: 1616 02:51:26 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 197532160; Write bytes: 196190208; Read time: 17839; Write time: 356 02:51:26 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 37049856; Write bytes: 301279744; Read time: 484; Write time: 1230 02:51:26 INFO - Running post-run listener: _upload_blobber_files 02:51:26 INFO - Blob upload gear active. 02:51:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:51:26 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 02:51:26 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:51:26 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:51:26 INFO - (blobuploader) - INFO - Open directory for files ... 02:51:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:51:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:51:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:51:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:27 INFO - (blobuploader) - INFO - Done attempting. 02:51:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:51:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:51:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:51:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:28 INFO - (blobuploader) - INFO - Done attempting. 02:51:28 INFO - (blobuploader) - INFO - Iteration through files over. 02:51:28 INFO - Return code: 0 02:51:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:51:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:51:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d60275462bdb2a0d9a75c74e271c8ce16226d30ae48132e97aa823773c10fb7d07e9a663c8b10918e42955c3d965986c5458280a332ba5863570bd366a44ab52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ebe2633177ba5a0c9f1bef8c47c5e29746a5fd01009f2918d3d5a491a35650da14ea6ac1ba87926db4ca69d9f3e5fe32cdbbc28e2ec75854b17e16828ff43f9"} 02:51:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:51:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:51:28 INFO - Contents: 02:51:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d60275462bdb2a0d9a75c74e271c8ce16226d30ae48132e97aa823773c10fb7d07e9a663c8b10918e42955c3d965986c5458280a332ba5863570bd366a44ab52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ebe2633177ba5a0c9f1bef8c47c5e29746a5fd01009f2918d3d5a491a35650da14ea6ac1ba87926db4ca69d9f3e5fe32cdbbc28e2ec75854b17e16828ff43f9"} 02:51:28 INFO - Running post-run listener: copy_logs_to_upload_dir 02:51:28 INFO - Copying logs to upload dir... 02:51:28 INFO - mkdir: /builds/slave/test/build/upload/logs 02:51:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.987232 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-09 02:51:28.776110) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 02:51:28.779920) ========= 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.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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/Mozilla-Beta/sha512/d60275462bdb2a0d9a75c74e271c8ce16226d30ae48132e97aa823773c10fb7d07e9a663c8b10918e42955c3d965986c5458280a332ba5863570bd366a44ab52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ebe2633177ba5a0c9f1bef8c47c5e29746a5fd01009f2918d3d5a491a35650da14ea6ac1ba87926db4ca69d9f3e5fe32cdbbc28e2ec75854b17e16828ff43f9"} build_url:https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/D_vUHmeKR_2mA7juCS2aMw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d60275462bdb2a0d9a75c74e271c8ce16226d30ae48132e97aa823773c10fb7d07e9a663c8b10918e42955c3d965986c5458280a332ba5863570bd366a44ab52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ebe2633177ba5a0c9f1bef8c47c5e29746a5fd01009f2918d3d5a491a35650da14ea6ac1ba87926db4ca69d9f3e5fe32cdbbc28e2ec75854b17e16828ff43f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 02:51:28.812390) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:51:28.813151) ========= 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.cLgYiu1TvV/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.kWmcc94bij/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005242 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 02:51:28.865015) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 02:51:28.865721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 02:51:28.866518) ========= ========= Total master_lag: 0.17 =========