builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1467983670.34 results: success (0) buildid: 20160708025627 builduid: 0bdbcf4df44a43109c9ba7cedc3e32ff revision: be469964755162110602a8690cc9e7034fd3981b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.345334) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.345781) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.346090) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.368585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.368927) ========= 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.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-08 06:14:30-- 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.55M=0.001s 2016-07-08 06:14:30 (9.55 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.510532) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.510848) ========= 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.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.019520 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:30.560555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 06:14:30.561137) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev be469964755162110602a8690cc9e7034fd3981b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev be469964755162110602a8690cc9e7034fd3981b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-08 06:14:30,636 truncating revision to first 12 chars 2016-07-08 06:14:30,636 Setting DEBUG logging. 2016-07-08 06:14:30,636 attempt 1/10 2016-07-08 06:14:30,636 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/be4699647551?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 06:14:31,423 unpacking tar archive at: mozilla-central-be4699647551/testing/mozharness/ program finished with exit code 0 elapsedTime=1.055732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 06:14:31.640180) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:31.640532) ========= script_repo_revision: be469964755162110602a8690cc9e7034fd3981b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:31.640947) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:31.641229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 06:14:31.661078) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-08 06:14:31.661496) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:14:31 INFO - MultiFileLogger online at 20160708 06:14:31 in /builds/slave/test 06:14:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:14:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:31 INFO - {'append_to_log': False, 06:14:31 INFO - 'base_work_dir': '/builds/slave/test', 06:14:31 INFO - 'blob_upload_branch': 'Firefox', 06:14:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:31 INFO - 'branch': 'Firefox', 06:14:31 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:31 INFO - 'config_files': ('talos/mac_config.py',), 06:14:31 INFO - 'default_actions': ('clobber', 06:14:31 INFO - 'read-buildbot-config', 06:14:31 INFO - 'download-and-extract', 06:14:31 INFO - 'populate-webroot', 06:14:31 INFO - 'create-virtualenv', 06:14:31 INFO - 'install', 06:14:31 INFO - 'run-tests'), 06:14:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:31 INFO - 'download_minidump_stackwalk': True, 06:14:31 INFO - 'download_symbols': 'ondemand', 06:14:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:31 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:31 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:31 INFO - 'installer_path': 'installer.exe', 06:14:31 INFO - 'log_level': 'info', 06:14:31 INFO - 'log_name': 'talos', 06:14:31 INFO - 'log_to_console': True, 06:14:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:14:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:14:31 INFO - 'opt_config_files': (), 06:14:31 INFO - 'pip_index': False, 06:14:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:31 INFO - 'cmd': ('bash', 06:14:31 INFO - '-c', 06:14:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:31 INFO - 'enabled': True, 06:14:31 INFO - 'halt_on_failure': False, 06:14:31 INFO - 'name': 'check_screen_resolution'},), 06:14:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:31 INFO - 'cmd': ('bash', 06:14:31 INFO - '-c', 06:14:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:31 INFO - 'enabled': True, 06:14:31 INFO - 'halt_on_failure': False, 06:14:31 INFO - 'name': 'check_screen_resolution'},), 06:14:31 INFO - 'run_cmd_checks_enabled': True, 06:14:31 INFO - 'sps_profile': False, 06:14:31 INFO - 'sps_profile_interval': 0, 06:14:31 INFO - 'suite': 'dromaeojs', 06:14:31 INFO - 'system_bits': '32', 06:14:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:31 INFO - 'title': 't-yosemite-r7-0330', 06:14:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:31 INFO - 'use_talos_json': True, 06:14:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:31 INFO - 'work_dir': 'build'} 06:14:31 INFO - ##### 06:14:31 INFO - ##### Running clobber step. 06:14:31 INFO - ##### 06:14:31 INFO - Running pre-action listener: _resource_record_pre_action 06:14:31 INFO - Running main action method: clobber 06:14:31 INFO - rmtree: /builds/slave/test/build 06:14:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:34 INFO - Running post-action listener: _resource_record_post_action 06:14:34 INFO - ##### 06:14:34 INFO - ##### Finished clobber step (success) 06:14:34 INFO - ##### 06:14:34 INFO - ##### 06:14:34 INFO - ##### Running read-buildbot-config step. 06:14:34 INFO - ##### 06:14:34 INFO - Running pre-action listener: _resource_record_pre_action 06:14:34 INFO - Running main action method: read_buildbot_config 06:14:34 INFO - Using buildbot properties: 06:14:34 INFO - { 06:14:34 INFO - "project": "", 06:14:34 INFO - "product": "firefox", 06:14:34 INFO - "script_repo_revision": "production", 06:14:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:14:34 INFO - "repository": "", 06:14:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:14:34 INFO - "buildid": "20160708025627", 06:14:34 INFO - "pgo_build": "False", 06:14:34 INFO - "basedir": "/builds/slave/test", 06:14:34 INFO - "buildnumber": 287, 06:14:34 INFO - "slavename": "t-yosemite-r7-0330", 06:14:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:14:34 INFO - "platform": "yosemite_r7", 06:14:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:14:34 INFO - "branch": "mozilla-central", 06:14:34 INFO - "repo_path": "mozilla-central", 06:14:34 INFO - "revision": "be469964755162110602a8690cc9e7034fd3981b", 06:14:34 INFO - "stage_platform": "macosx64", 06:14:34 INFO - "builduid": "0bdbcf4df44a43109c9ba7cedc3e32ff", 06:14:34 INFO - "slavebuilddir": "test" 06:14:34 INFO - } 06:14:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 06:14:34 INFO - Running post-action listener: _resource_record_post_action 06:14:34 INFO - ##### 06:14:34 INFO - ##### Finished read-buildbot-config step (success) 06:14:34 INFO - ##### 06:14:34 INFO - ##### 06:14:34 INFO - ##### Running download-and-extract step. 06:14:34 INFO - ##### 06:14:34 INFO - Running pre-action listener: _resource_record_pre_action 06:14:34 INFO - Running main action method: download_and_extract 06:14:34 INFO - mkdir: /builds/slave/test/build/tests 06:14:34 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')]} 06:14:34 INFO - https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:14:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:14:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:14:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 06:14:37 INFO - Downloaded 1199 bytes. 06:14:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:14:37 INFO - Using the following test package requirements: 06:14:37 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:14:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:14:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:14:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'jsshell-mac.zip'], 06:14:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:14:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:14:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:14:37 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:14:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:14:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:14:37 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:14:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:37 INFO - https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:14:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:38 INFO - Downloaded 18806423 bytes. 06:14:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:14:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:14:41 INFO - Return code: 0 06:14:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:14: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')]} 06:14:41 INFO - https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:14:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:14:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:43 INFO - Downloaded 18806423 bytes. 06:14:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:14:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:14:47 INFO - Return code: 0 06:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:47 INFO - https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:14:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:14:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:14:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:14:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:14:47 INFO - Downloaded 14403333 bytes. 06:14:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:14:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:14:48 INFO - Return code: 0 06:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:48 INFO - https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:14:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:14:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:14:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:14:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 06:14:51 INFO - Downloaded 112444526 bytes. 06:14:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:14:51 INFO - mkdir: /builds/slave/test/properties 06:14:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:51 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:51 INFO - Contents: 06:14:51 INFO - build_url:https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:14:52 INFO - Running post-action listener: _resource_record_post_action 06:14:52 INFO - Running post-action listener: set_extra_try_arguments 06:14:52 INFO - ##### 06:14:52 INFO - ##### Finished download-and-extract step (success) 06:14:52 INFO - ##### 06:14:52 INFO - ##### 06:14:52 INFO - ##### Running populate-webroot step. 06:14:52 INFO - ##### 06:14:52 INFO - Running pre-action listener: _resource_record_pre_action 06:14:52 INFO - Running main action method: populate_webroot 06:14:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:52 INFO - '--tppagecycles', 06:14:52 INFO - '7'], 06:14:52 INFO - 'tests': ['tsvgm']}}, 06:14:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['damp', 'tps']}, 06:14:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'tests': ['damp', 'tps']}, 06:14:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['dromaeo_dom']}, 06:14:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['basic_compositor_video']}, 06:14:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['a11yr', 06:14:52 INFO - 'ts_paint', 06:14:52 INFO - 'tpaint', 06:14:52 INFO - 'sessionrestore', 06:14:52 INFO - 'sessionrestore_no_auto_restore', 06:14:52 INFO - 'tabpaint']}, 06:14:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['tsvgx', 06:14:52 INFO - 'tsvgr_opacity', 06:14:52 INFO - 'tart', 06:14:52 INFO - 'tscrollx', 06:14:52 INFO - 'cart']}, 06:14:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:52 INFO - 'tsvgr_opacity', 06:14:52 INFO - 'tart', 06:14:52 INFO - 'tscrollx', 06:14:52 INFO - 'cart']}, 06:14:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'talos_options': ['--disable-e10s'], 06:14:52 INFO - 'tests': ['tp5o']}, 06:14:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'tests': ['tp5o']}, 06:14:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'talos_options': ['--disable-e10s', 06:14:52 INFO - '--xperf_path', 06:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:52 INFO - 'tests': ['tp5n']}, 06:14:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:14:52 INFO - 'talos_options': ['--xperf_path', 06:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:52 INFO - 'tests': ['tp5n']}}, 06:14:52 INFO - 'talos.zip': {'path': '', 06:14:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:52 INFO - Running post-action listener: _resource_record_post_action 06:14:52 INFO - ##### 06:14:52 INFO - ##### Finished populate-webroot step (success) 06:14:52 INFO - ##### 06:14:52 INFO - ##### 06:14:52 INFO - ##### Running create-virtualenv step. 06:14:52 INFO - ##### 06:14:52 INFO - Running pre-action listener: _resource_record_pre_action 06:14:52 INFO - Running main action method: create_virtualenv 06:14:52 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:52 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 06:14:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:52 INFO - Using real prefix '/tools/python27' 06:14:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:53 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:56 INFO - Installing pip.................done. 06:14:56 INFO - Return code: 0 06:14:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 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 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:14:56 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 06:14:56 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 06:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:56 INFO - 'HOME': '/Users/cltbld', 06:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:56 INFO - 'LOGNAME': 'cltbld', 06:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:56 INFO - 'MOZ_NO_REMOTE': '1', 06:14:56 INFO - 'NO_EM_RESTART': '1', 06:14:56 INFO - 'PAGER': '/bin/cat', 06:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:56 INFO - 'PWD': '/builds/slave/test', 06:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:56 INFO - 'SHELL': '/bin/bash', 06:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:14:56 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:56 INFO - 'USER': 'cltbld', 06:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:56 INFO - 'XPC_FLAGS': '0x0', 06:14:56 INFO - 'XPC_SERVICE_NAME': '0', 06:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:56 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 06:14:56 INFO - Cleaning up... 06:14:56 INFO - Return code: 0 06:14:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:56 INFO - 'HOME': '/Users/cltbld', 06:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:56 INFO - 'LOGNAME': 'cltbld', 06:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:56 INFO - 'MOZ_NO_REMOTE': '1', 06:14:56 INFO - 'NO_EM_RESTART': '1', 06:14:56 INFO - 'PAGER': '/bin/cat', 06:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:56 INFO - 'PWD': '/builds/slave/test', 06:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:56 INFO - 'SHELL': '/bin/bash', 06:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:14:56 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:56 INFO - 'USER': 'cltbld', 06:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:56 INFO - 'XPC_FLAGS': '0x0', 06:14:56 INFO - 'XPC_SERVICE_NAME': '0', 06:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:56 INFO - Downloading/unpacking psutil>=3.1.1 06:14:56 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 06:14:56 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 06:14:56 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 06:14:56 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 06:14:56 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 06:14:56 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 06:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:59 INFO - Installing collected packages: psutil 06:14:59 INFO - Running setup.py install for psutil 06:14:59 INFO - building 'psutil._psutil_osx' extension 06:14:59 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 06:14:59 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 06:14:59 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 06:15:00 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 06:15:00 INFO - building 'psutil._psutil_posix' extension 06:15:00 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 06:15:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:15:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:15:00 INFO - ^ 06:15:00 INFO - 1 warning generated. 06:15:00 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 06:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:15:00 INFO - Successfully installed psutil 06:15:00 INFO - Cleaning up... 06:15:00 INFO - Return code: 0 06:15:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:15:00 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')]} 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:00 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')]} 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:00 INFO - 'HOME': '/Users/cltbld', 06:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:00 INFO - 'LOGNAME': 'cltbld', 06:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:00 INFO - 'MOZ_NO_REMOTE': '1', 06:15:00 INFO - 'NO_EM_RESTART': '1', 06:15:00 INFO - 'PAGER': '/bin/cat', 06:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:00 INFO - 'PWD': '/builds/slave/test', 06:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:00 INFO - 'SHELL': '/bin/bash', 06:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:00 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:00 INFO - 'USER': 'cltbld', 06:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:00 INFO - 'XPC_FLAGS': '0x0', 06:15:00 INFO - 'XPC_SERVICE_NAME': '0', 06:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:15:03 INFO - Installing collected packages: mozsystemmonitor 06:15:03 INFO - Running setup.py install for mozsystemmonitor 06:15:03 INFO - Successfully installed mozsystemmonitor 06:15:03 INFO - Cleaning up... 06:15:03 INFO - Return code: 0 06:15:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:15:03 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')]} 06:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:03 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')]} 06:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:03 INFO - 'HOME': '/Users/cltbld', 06:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:03 INFO - 'LOGNAME': 'cltbld', 06:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:03 INFO - 'MOZ_NO_REMOTE': '1', 06:15:03 INFO - 'NO_EM_RESTART': '1', 06:15:03 INFO - 'PAGER': '/bin/cat', 06:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:03 INFO - 'PWD': '/builds/slave/test', 06:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:03 INFO - 'SHELL': '/bin/bash', 06:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:03 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:03 INFO - 'USER': 'cltbld', 06:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:03 INFO - 'XPC_FLAGS': '0x0', 06:15:03 INFO - 'XPC_SERVICE_NAME': '0', 06:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:03 INFO - Downloading/unpacking jsonschema==2.5.1 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:03 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 06:15:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:15:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:15:06 INFO - warning: no files found matching '*.txt' 06:15:06 INFO - no previously-included directories found matching 'build' 06:15:06 INFO - no previously-included directories found matching 'dist' 06:15:06 INFO - no previously-included directories found matching '.git*' 06:15:06 INFO - Installing collected packages: jsonschema, functools32 06:15:06 INFO - Running setup.py install for functools32 06:15:06 INFO - warning: no files found matching '*.txt' 06:15:06 INFO - no previously-included directories found matching 'build' 06:15:06 INFO - no previously-included directories found matching 'dist' 06:15:06 INFO - no previously-included directories found matching '.git*' 06:15:06 INFO - Successfully installed jsonschema functools32 06:15:06 INFO - Cleaning up... 06:15:06 INFO - Return code: 0 06:15:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:15: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')]} 06:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15: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')]} 06:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15: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', '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 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:15: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 blobuploader==1.2.4 06:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:06 INFO - 'HOME': '/Users/cltbld', 06:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:06 INFO - 'LOGNAME': 'cltbld', 06:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:06 INFO - 'MOZ_NO_REMOTE': '1', 06:15:06 INFO - 'NO_EM_RESTART': '1', 06:15:06 INFO - 'PAGER': '/bin/cat', 06:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:06 INFO - 'PWD': '/builds/slave/test', 06:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:06 INFO - 'SHELL': '/bin/bash', 06:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:06 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:06 INFO - 'USER': 'cltbld', 06:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:06 INFO - 'XPC_FLAGS': '0x0', 06:15:06 INFO - 'XPC_SERVICE_NAME': '0', 06:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:09 INFO - Downloading blobuploader-1.2.4.tar.gz 06:15:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:15:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:15:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:10 INFO - Downloading docopt-0.6.1.tar.gz 06:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:15:10 INFO - Installing collected packages: blobuploader, requests, docopt 06:15:10 INFO - Running setup.py install for blobuploader 06:15:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:15:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:15:10 INFO - Running setup.py install for requests 06:15:11 INFO - Running setup.py install for docopt 06:15:11 INFO - Successfully installed blobuploader requests docopt 06:15:11 INFO - Cleaning up... 06:15:11 INFO - Return code: 0 06:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:11 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 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:11 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 06:15:11 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 06:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:11 INFO - 'HOME': '/Users/cltbld', 06:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:11 INFO - 'LOGNAME': 'cltbld', 06:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:11 INFO - 'MOZ_NO_REMOTE': '1', 06:15:11 INFO - 'NO_EM_RESTART': '1', 06:15:11 INFO - 'PAGER': '/bin/cat', 06:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:11 INFO - 'PWD': '/builds/slave/test', 06:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:11 INFO - 'SHELL': '/bin/bash', 06:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:11 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:11 INFO - 'USER': 'cltbld', 06:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:11 INFO - 'XPC_FLAGS': '0x0', 06:15:11 INFO - 'XPC_SERVICE_NAME': '0', 06:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:11 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-C3q5oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PaOQIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-DsPonl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-80ReKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-UBJVb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-lMaX08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-NG_XZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-AThIsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-8S0jIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:12 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dKzr9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-z0dLwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ITxWB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XWpYFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dimxJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-qJS7Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CqACok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:13 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-b_kaSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:15:13 INFO - Running setup.py install for manifestparser 06:15:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:15:13 INFO - Running setup.py install for mozcrash 06:15:14 INFO - Running setup.py install for mozdebug 06:15:14 INFO - Running setup.py install for mozdevice 06:15:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozfile 06:15:14 INFO - Running setup.py install for mozhttpd 06:15:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozinfo 06:15:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozInstall 06:15:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:15:14 INFO - Running setup.py install for mozleak 06:15:15 INFO - Running setup.py install for mozlog 06:15:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for moznetwork 06:15:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozprocess 06:15:15 INFO - Running setup.py install for mozprofile 06:15:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozrunner 06:15:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:15:15 INFO - Running setup.py install for mozscreenshot 06:15:15 INFO - Running setup.py install for moztest 06:15:16 INFO - Running setup.py install for mozversion 06:15:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:15:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:15:16 INFO - Cleaning up... 06:15:16 INFO - Return code: 0 06:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:16 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')]} 06:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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')]} 06:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:16 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 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:16 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 06:15:16 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 06:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:16 INFO - 'HOME': '/Users/cltbld', 06:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:16 INFO - 'LOGNAME': 'cltbld', 06:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:16 INFO - 'MOZ_NO_REMOTE': '1', 06:15:16 INFO - 'NO_EM_RESTART': '1', 06:15:16 INFO - 'PAGER': '/bin/cat', 06:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:16 INFO - 'PWD': '/builds/slave/test', 06:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:16 INFO - 'SHELL': '/bin/bash', 06:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:16 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:16 INFO - 'USER': 'cltbld', 06:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:16 INFO - 'XPC_FLAGS': '0x0', 06:15:16 INFO - 'XPC_SERVICE_NAME': '0', 06:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:16 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ChSgsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:16 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)) 06:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:16 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-lb2MnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:16 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)) 06:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:16 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-vY8NUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:16 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)) 06:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:16 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-YO_s_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-LvhSAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wQXxLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-RM7hk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-V0aezy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-rn6qnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XoxLkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ehSfeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-oTuUj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-s4WwjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:17 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)) 06:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:17 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Ly8iKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:18 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cwhtZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:18 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9GR5vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:18 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)) 06:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:18 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-cEyGuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:18 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)) 06:15:18 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)) 06:15:18 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)) 06:15:18 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)) 06:15:18 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)) 06:15:18 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)) 06:15:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:20 INFO - Downloading blessings-1.6.tar.gz 06:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:15:20 INFO - Installing collected packages: blessings 06:15:20 INFO - Running setup.py install for blessings 06:15:21 INFO - Successfully installed blessings 06:15:21 INFO - Cleaning up... 06:15:21 INFO - Return code: 0 06:15:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:15:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:21 INFO - Reading from file tmpfile_stdout 06:15:21 INFO - Current package versions: 06:15:21 INFO - blessings == 1.6 06:15:21 INFO - blobuploader == 1.2.4 06:15:21 INFO - docopt == 0.6.1 06:15:21 INFO - functools32 == 3.2.3-2 06:15:21 INFO - jsonschema == 2.5.1 06:15:21 INFO - manifestparser == 1.1 06:15:21 INFO - mozInstall == 1.12 06:15:21 INFO - mozcrash == 0.17 06:15:21 INFO - mozdebug == 0.1 06:15:21 INFO - mozdevice == 0.48 06:15:21 INFO - mozfile == 1.2 06:15:21 INFO - mozhttpd == 0.7 06:15:21 INFO - mozinfo == 0.9 06:15:21 INFO - mozleak == 0.1 06:15:21 INFO - mozlog == 3.2 06:15:21 INFO - moznetwork == 0.27 06:15:21 INFO - mozprocess == 0.23 06:15:21 INFO - mozprofile == 0.28 06:15:21 INFO - mozrunner == 6.12 06:15:21 INFO - mozscreenshot == 0.1 06:15:21 INFO - mozsystemmonitor == 0.3 06:15:21 INFO - moztest == 0.7 06:15:21 INFO - mozversion == 1.4 06:15:21 INFO - psutil == 3.1.1 06:15:21 INFO - requests == 1.2.3 06:15:21 INFO - wsgiref == 0.1.2 06:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:21 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 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:21 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 06:15:21 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 06:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:21 INFO - 'HOME': '/Users/cltbld', 06:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:21 INFO - 'LOGNAME': 'cltbld', 06:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:21 INFO - 'MOZ_NO_REMOTE': '1', 06:15:21 INFO - 'NO_EM_RESTART': '1', 06:15:21 INFO - 'PAGER': '/bin/cat', 06:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:21 INFO - 'PWD': '/builds/slave/test', 06:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:21 INFO - 'SHELL': '/bin/bash', 06:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:21 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:21 INFO - 'USER': 'cltbld', 06:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:21 INFO - 'XPC_FLAGS': '0x0', 06:15:21 INFO - 'XPC_SERVICE_NAME': '0', 06:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 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)) 06:15:21 INFO - Cleaning up... 06:15:21 INFO - Return code: 0 06:15:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10674ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067e0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a1ca6860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, '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.wRxUFdhJES/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.6Ssoys5lv9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 06:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:21 INFO - 'HOME': '/Users/cltbld', 06:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:21 INFO - 'LOGNAME': 'cltbld', 06:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:21 INFO - 'MOZ_NO_REMOTE': '1', 06:15:21 INFO - 'NO_EM_RESTART': '1', 06:15:21 INFO - 'PAGER': '/bin/cat', 06:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:21 INFO - 'PWD': '/builds/slave/test', 06:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:21 INFO - 'SHELL': '/bin/bash', 06:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:21 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:21 INFO - 'USER': 'cltbld', 06:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:21 INFO - 'XPC_FLAGS': '0x0', 06:15:21 INFO - 'XPC_SERVICE_NAME': '0', 06:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:15:21 INFO - Cleaning up... 06:15:21 INFO - Return code: 0 06:15:21 INFO - Running post-action listener: _resource_record_post_action 06:15:21 INFO - Running post-action listener: _start_resource_monitoring 06:15:21 INFO - Starting resource monitoring. 06:15:21 INFO - ##### 06:15:21 INFO - ##### Finished create-virtualenv step (success) 06:15:21 INFO - ##### 06:15:21 INFO - ##### 06:15:21 INFO - ##### Running install step. 06:15:21 INFO - ##### 06:15:21 INFO - Running pre-action listener: _resource_record_pre_action 06:15:21 INFO - Running main action method: install 06:15:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:22 INFO - Reading from file tmpfile_stdout 06:15:22 INFO - Detecting whether we're running mozinstall >=1.0... 06:15:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:15:22 INFO - Reading from file tmpfile_stdout 06:15:22 INFO - Output received: 06:15:22 INFO - Usage: mozinstall [options] installer 06:15:22 INFO - Options: 06:15:22 INFO - -h, --help show this help message and exit 06:15:22 INFO - -d DEST, --destination=DEST 06:15:22 INFO - Directory to install application into. [default: 06:15:22 INFO - "/builds/slave/test"] 06:15:22 INFO - --app=APP Application being installed. [default: firefox] 06:15:22 INFO - mkdir: /builds/slave/test/build/application 06:15:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:15:56 INFO - Reading from file tmpfile_stdout 06:15:56 INFO - Output received: 06:15:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:15:56 INFO - Running post-action listener: _resource_record_post_action 06:15:56 INFO - ##### 06:15:56 INFO - ##### Finished install step (success) 06:15:56 INFO - ##### 06:15:56 INFO - ##### 06:15:56 INFO - ##### Running run-tests step. 06:15:56 INFO - ##### 06:15:56 INFO - Running pre-action listener: _resource_record_pre_action 06:15:56 INFO - Running main action method: run_tests 06:15:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:56 INFO - 2016-07-08 06:15:56.519 screenresolution[1944:66062] starting screenresolution argv=screenresolution get 06:15:56 INFO - 2016-07-08 06:15:56.521 screenresolution[1944:66062] Display 0: 1600x1200x32@60 06:15:56 INFO - 2016-07-08 06:15:56.527 screenresolution[1945:66069] starting screenresolution argv=screenresolution list 06:15:56 INFO - Available Modes on Display 0 06:15:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:56 INFO - Intel Iris: 06:15:56 INFO - Chipset Model: Intel Iris 06:15:56 INFO - Type: GPU 06:15:56 INFO - Bus: Built-In 06:15:56 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:56 INFO - Vendor: Intel (0x8086) 06:15:56 INFO - Device ID: 0x0a2e 06:15:56 INFO - Revision ID: 0x0009 06:15:56 INFO - Displays: 06:15:56 INFO - Display: 06:15:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:56 INFO - Display Serial Number: 1600x1200 60 06:15:56 INFO - Main Display: Yes 06:15:56 INFO - Mirror: Off 06:15:56 INFO - Online: Yes 06:15:56 INFO - Rotation: Supported 06:15:56 INFO - Return code: 0 06:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:56 INFO - Python 2.7.3 06:15:56 INFO - Return code: 0 06:15:56 INFO - grabbing minidump binary from tooltool 06:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:15:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066afd30>, '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 0x106414db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066a7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:56 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 06:15:56 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 06:15:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:15:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:56 INFO - Return code: 0 06:15:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:15:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:15:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 06:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --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 06:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wRxUFdhJES/Render', 06:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:56 INFO - 'HOME': '/Users/cltbld', 06:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:56 INFO - 'LOGNAME': 'cltbld', 06:15:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:56 INFO - 'MOZ_NO_REMOTE': '1', 06:15:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:56 INFO - 'NO_EM_RESTART': '1', 06:15:56 INFO - 'PAGER': '/bin/cat', 06:15:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:56 INFO - 'PWD': '/builds/slave/test', 06:15:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:56 INFO - 'SHELL': '/bin/bash', 06:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6Ssoys5lv9/Listeners', 06:15:56 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 06:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:56 INFO - 'USER': 'cltbld', 06:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:56 INFO - 'XPC_FLAGS': '0x0', 06:15:56 INFO - 'XPC_SERVICE_NAME': '0', 06:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 06:15:57 INFO - mozversion application_buildid: 20160708025627 06:15:57 INFO - mozversion application_changeset: be469964755162110602a8690cc9e7034fd3981b 06:15:57 INFO - mozversion application_display_name: Nightly 06:15:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:57 INFO - mozversion application_name: Firefox 06:15:57 INFO - mozversion application_remotingname: firefox 06:15:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:57 INFO - mozversion application_vendor: Mozilla 06:15:57 INFO - mozversion application_version: 50.0a1 06:15:57 INFO - mozversion platform_buildid: 20160708025627 06:15:57 INFO - mozversion platform_changeset: be469964755162110602a8690cc9e7034fd3981b 06:15:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:57 INFO - mozversion platform_version: 50.0a1 06:15:57 INFO - using testdate: 1467983757 06:15:57 INFO - actual date: 1467983757 06:15:57 INFO - starting webserver on 'localhost:55139' 06:15:57 INFO - SUITE-START | Running 2 tests 06:15:57 INFO - TEST-START | dromaeo_css 06:15:57 INFO - operating with platform_type : mac_ 06:15:57 INFO - Initialising browser for dromaeo_css test... 06:15:57 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp5fcyGk/profile http://localhost:55139/getInfo.html) 06:15:59 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 06:15:59 INFO - PROCESS | 1955 | colorDepth:24 06:15:59 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 06:15:59 INFO - PROCESS | 1955 | __metrics 06:15:59 INFO - PROCESS | 1955 | 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" 06:15:59 INFO - PROCESS | 1955 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:16:00 INFO - TEST-INFO | 1955: exit 0 06:16:00 INFO - Browser initialized. 06:16:00 INFO - Running cycle 1/1 for dromaeo_css test... 06:16:00 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmp5fcyGk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:12 INFO - PROCESS | 1957 | RSS: Main: 243798016 06:16:12 INFO - PROCESS | 1957 | 06:17:03 INFO - PROCESS | 1957 | 1467983823753 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] 06:19:13 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55139/tests/dromaeo/cssquery-ext.html) 06:19:15 INFO - PROCESS | 1957 | RSS: Main: 376934400 06:19:15 INFO - PROCESS | 1957 | 06:22:18 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-ext.html (next: http://localhost:55139/tests/dromaeo/cssquery-jquery.html) 06:22:19 INFO - PROCESS | 1957 | RSS: Main: 380510208 06:22:19 INFO - PROCESS | 1957 | 06:25:21 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55139/tests/dromaeo/cssquery-mootools.html) 06:25:21 INFO - PROCESS | 1957 | RSS: Main: 372260864 06:25:21 INFO - PROCESS | 1957 | 06:28:24 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55139/tests/dromaeo/cssquery-prototype.html) 06:28:24 INFO - PROCESS | 1957 | RSS: Main: 371236864 06:28:24 INFO - PROCESS | 1957 | 06:31:28 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55139/tests/dromaeo/cssquery-yui.html) 06:31:28 INFO - PROCESS | 1957 | RSS: Main: 334925824 06:31:28 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:55139/tests/dromaeo/cssquery-yui.html (next: http://localhost:55139/tests/dromaeo/cssquery-dojo.html) 06:34:31 INFO - PROCESS | 1957 | RSS: Main: 390582272 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | __start_tp_report 06:34:31 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 06:34:31 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 06:34:31 INFO - PROCESS | 1957 | |i|pagename|runs| 06:34:31 INFO - PROCESS | 1957 | |0;dojo.html;3365.4951757236413;3366.528685983962;3400.370931377694;3405.7956522608647;3430.9485357719623;4990.877052663153;5716.685582292972;5832.912506312404;5835.766569337221;5885.146653735206;5891.705414729267;5895.882082358361;5949.583529152961;6010.8797824043495;6017.217761690974;5258.342707161605;5286.233496143057;5329.520343169114;5348.812791552296;5417.59368047396;5393;5436.238926550286;5441.319835020622;5452.40023597404;5482.945170548303;4241.575842415749;4704.98842748809;5291.894162116774;5320.813771517978;5373.113436283009;4589.623113066067;5243.213517972296;5288.576913846883;5318.042752304584;5373.059714549938;5268.28878101459;5303.920441193385;5355.223492593591;5389.676619402849;5397.676139431646;5686.829395118153;5803.7098145093;5927;5938;6037.30570984338;6188.319284878705;6219.222597175353;6227.782027629057;6234;6240.375962403768;6238.033104868752;6270.31026587075;6294.402031806971;6294.87410251797;6310.905336419957;5842.620229685057;5952.255968003999;6021.096835474714;6029.78895738647;6069.332373438918;6344.206974128234;6369.363063693593;6379.393957574023;6383.425491705769;6391.105245265667;6226.159468471789;6226.439620434183;6229.719662615194;6242.937570624326;6259.061140828912;6159.5688301818445;6192.380761923771;6240.687965601702;6243.531735119885;6300.842478938063;6180.567360284737;6231.003039513656;6242.843928901723;6243.781467648701;6251.531135164881;6168.444839964424;6188.4739797116845;6219.626822390671;6238.469730072904;6272.8118156455375;5042.294078828967;5046.545810877039;5078.238264260391;5087.618428617869;5093.770780314895;4552.499225085238;4553.817847286137;4557.931631025537;4570.54294570547;4579.4046773919845;5777.075667893201;5945.940540594539;5952;5965.28416590012;5993.250843644544;6158.876822463526;6160.7535698571555;6216.316205217422;6229.283632382243;6264.467520260829;5920.467157955719;5954.583179177503;5961.195238642815;5972.343042265347;6031.065184896348;5564.304461942257;5577.9959607271085;5578.804741834017;5584.357798853184;5680.8863822723315;5393.757280922289;6304.621722696653;6316.494680425463;6365.745370185141;6497.057926600711;6208.348123447154;6255.78104766331;6265.56141070139;6287.937120628735;6304.432601065926;6165.136880836776;6444.097826304415;6451.806445806634;6458.22501299847;6459.773907913201;6125.143885071339;6145.0782382642965;6201.658908759881;6225.159603453476;6229.065640154013;6139.140520327247;6207.975684012087;6216.813495595139;6227.408396202353;6235.719392627252;6118.051701986199;6141.170941922784;6141.301904043321;6153.753849846135;6219.378062193925;6142.069075791586;6228.314885362516;6231.781887633911;6239.376062393724;6244.406781355764;6136.263648362046;6138.693065346625;6153.2616086068165;6153.384661533811;6157.1072194532435;5399.4060653327315;6078.845019446291;6094.878102437838;6127.158986117617;6152.261728592597;6108.593909311147;6109.890546867989;6118.010103775335;6132.447291485866;6150.35421280777 06:34:31 INFO - PROCESS | 1957 | |1;ext.html;491.8671958571186;495.5564769531267;508.83984515305116;534.973251337433;535.8901425207832;30244.546331805013;42433.93915152123;42924.78537607315;43120.56879431205;43218.783906080425;42227.73316800493;42575.72272831812;44624.330635040504;44824.3276350855;44847.55152448475;8803.735887923373;9825.312228144032;10011.74970625733;10034.749131271703;10039.494075888602;12042.397880106004;12235.32705701186;12298.139130260884;12471.127021108527;12476.563320283793;12277.69305767354;12369.010479161645;12369.4433750481;12390.442430090667;12393.442295096742;12147.331896745676;12290.324032178227;12296.385180741017;12298.508059677602;12307.815382769266;12130.332831694253;12203.755924881541;12224.083193760503;12229.633111006684;12230.938845305805;41885.58114418847;42125.36811947823;42147.789261053804;42148.57851421477;42169;3548.8580456781697;3576.141725985745;3596.7841929484316;4111.362738775465;4474.463064432289;31728.841355793073;32295.0622962623;32570.837145814356;33231.3353732924;33302.8344007961;3316.2538428853313;3325.7505687073567;3339.9833000835083;3341.9498707519406;3418.8290585470627;501728;502626.99999999633;502980;503125;503804;64038.03942940859;64289.67855160694;64818;64921.350786492476;65082.67458662677;67165;67252.663736681;67427.32572674309;67453.66273168701;67488.6625566869;16630.251638676287;17186.96878187313;17191.57021074458;17209.106998230185;17216.827831721774;13124.14693044949;13198.934005329911;13204.669883252804;13215.537456188988;13219;15144.772828407582;17742.556436088944;18029.368972085915;18191.45425637233;18251.54371140732;6358.777442789573;6519.7392104315295;6878;6897.55165914224;6974.965125174342;873.2533683700472;878.7583414561046;1022.325265324882;1094.9890501095047;1100.3837850803575;27844.582331265046;28928;30914.22714432112;30923.536146957813;31193.8440307797;1036.7770929250248;1049.6746008737316;1052.6263176572359;1073.3345325897992;1099.3898386395626;15928.832862941326;16176.75734863978;16178.59553511148;16328.693704503612;16677.916610417113;176386.23613763956;177394;177575;628161;633554.8322258359;63140.368596314365;63296;63362.68318658469;63618;66069.66965165143;1325.4168166006925;1358.3615700620692;1447.949321773733;1466.633341664584;1484.46559238674;28114.2971425716;31480.685193148693;32213.03360899177;34019.31961360709;34094.97715068552;59354.406455934884;60141;61118;61140.69429653001;61149.69425152846;635134;635442;639864;640739;641057;37503;42619.50831720875;43042.35436468518;44484.33273500836;45229.9646515901;42675.3598696026;49729.00541989212;50905.74547127241;51365.22952155795;51594.48405516047;39660.008499787735;42018.57981420147;42095.36856947209;43187.784061079496;43232.35151472667;41112.1777564453;41768.373474397304;42098.15756035637;42180;42634.360484592136;31840.52239216367;38620.78619386347;39098.609013909496;39985.80007099946;40139.996500087735;34631.65368346284;35117.824410878806;35254.471182932786;35356;36083.458748118275;19678.80321196827;25533.616995744705;25550.23349299524;25755.484890302465;26184 06:34:31 INFO - PROCESS | 1957 | |2;jquery.html;357.15176455917197;379.19042843529064;388.935825588778;392.1783862807419;412.6080223787401;15419.997700149497;17113;21139.894300528475;21494.032768525416;21503;20540.38378848633;21288.57422851542;21396;21399.57200855986;21402.357929262107;5953.3748956359605;6064.9393506065035;6094.9390506094815;6133.264008318985;6183.81448556544;5914.526837852962;6010.549208809335;6042.848928776793;6089.2692876854835;6104.633721976674;5879.941200588003;5936.139259807325;6029.065494848283;6049.818505444844;6061.575689701723;5879.67661778602;6010.789622363218;6025.487833534155;6031.819045428644;6039.6980150992495;4577.9771101144615;5213.32226810516;5254.7635356409055;5254.973725131388;5256.789728410859;24620.138295159584;25297.241082767545;25302.74697253022;25357.112501062373;25589.232323030337;2272.0116749214144;2285.6914316567218;2292.667563203343;2366.491204391047;2419.4314336130997;15801.288941997638;15817.367305307775;15905.920470397574;15940.20298985046;16196.595085122848;1858.7304840798008;1862.1695023564241;1894.5737209127835;1936.5352315444334;1945.601151763885;77357.83963240556;100812.49593751984;115822.42088789502;116044.41977790142;117567.23649145356;10595.609404990484;10604.94697526523;11047.889521104848;11050.115990720884;11234.3259404436;13722.725545489035;13804.516841910485;13822;13861.86138138626;13864.237466939416;8479.364047696448;8548.700795472252;8563.314934805201;8709.869351959725;8736.388452808242;8721.869171962298;8873.467591944504;8900.46597204157;8959;8966.68616598416;6680.933190668128;6811.4550835933005;6820.386165245052;6821.3519715626935;6822.215445223764;1851.9814801852078;1875.8124187581407;1879.7180422936397;1904.64764018657;1921.8846869187894;785.9135495095535;960.2030314838668;1009.8232809258351;1038.4496217004998;1053.7207639975413;22016.559668806534;22303;22374.328770136923;22377.216797412013;22389.440263993205;800.0479429478902;955.4744890310302;979.2459805949379;980.4950450517989;1040.625374865048;11058.22592418539;11491.138164637685;12012.759744805055;12015.699607509881;12024.759504809856;592123;596482;597135;615391;616016;89854;116542.41728791471;117830;125167;128366;1008.1581879131007;1011.8330475471612;1014.3710899242517;1028;1062.9574817007388;18344.541386465444;19275.8072419274;19540.022998849716;19609.803901960797;19631.509212269808;55823.441765581825;56136.43863561312;56914.715426422605;57024.71487642535;58363.708181458824;79019.20980790119;85489;85839.14160858312;85864;86418.83952901227;7846.725364612211;8251.546164960984;8405.159484051546;8409.453385530165;8602.311815054905;9339.766505837408;13069.738605227652;13513.324333783468;13533.255670938193;13848.169109853441;10736.08743256832;11027.842076582165;11514.251573647685;11734.943855053087;11797.17419780607;11486.966173044466;11508.36703981289;11771.646850594496;11867;11868.525258989544;11745.88254117482;11993.100517461051;12005.39973001329;12062.215955962834;12151.939240304095;7146.606936618536;7579.355754760907;7624.504407213735;8189.79525511867;8657.783555411164;6180;8668.566571671518;11442.37066961325;11634.476448559666;11911.463984120563 06:34:31 INFO - PROCESS | 1957 | |3;mootools.html;1451.7967484552164;1502.6919481506293;1578.8263291037983;1630.893991890527;1874.109797846024;8646.308295336374;8848.77878053049;9059.773505662346;9072.138146876052;9086.772830679229;7912.010998625172;8098.959505202466;8616.655333786657;8710.695125670603;8788.25299849512;2329.37106981115;2367.443650742083;2367.6093444581625;2368.1237941961413;2373.133806160756;1691.8561922236624;2342.847714898535;2348.9295321140394;2374.8575085494926;2378.6907701998803;2123.532822778986;2129.7231359923267;2133.7012818205462;2134.754503232125;2304.101400453824;2343.496148328109;2353.9293821185393;2355.74086850448;2355.799757020638;2356.198892376583;2068.98619676359;2272.3864556569797;2340.6137987232087;2358.5636657218297;2366.7396586375485;7888.723894663716;8063.516189028677;8108.391870609727;8953.865692014626;9039.728808135766;4886.218205087204;5231.895362092775;5364.436734142937;5382.865428364283;5410.215518749798;3202.6637203093806;3557.964420355789;3640.927181456356;3641.4719865619336;3657.8536858525626;1092.126298960836;1097.358045543358;1101.6915263726169;1107.5071593141056;1115.1524841120724;11641.35972521511;11778.175527713063;11782.528698852035;11792;11815.291082535075;4613.815447382067;4623;4835.879103022382;4837.927431088537;4842.201036828954;10227.335223210617;10234.33476824004;10238.33450825694;10251.487425628839;10251.794964100676;5370.355557333145;5380.192971054373;5386.596005299618;5391.083515802245;5393.164059570773;4789.4971028041555;4918.188498897642;4925.630577706686;4927.236278376858;4960.776765045582;3792.810359482015;3795.400150896645;3849.40249796337;3878.903027424337;3883.165119499321;1161.3960740414936;1169.1114752787942;1173.143605168232;1174.0959461214989;1174.2972689600115;824.0194168938943;867.6529388244627;954.7753733800367;962.9936716131659;1016.2581315639625;6005.819825405245;6120.204373431515;6126.846828829226;6158.692065396713;6216.222972128484;434.65227817745676;442.9313456414261;447.18835313905157;453.9614132798683;458.48191543555566;4078.816453259551;4181;4222;4244;4267.466566679165;10243.7439064024;10609.575616975235;10753.462326883624;10756.193285503463;10789.406582637874;10707.196960227859;10714;10714.19643526723;10714.517846696917;10727.678169654922;7894.328982036591;8128.065272493621;8245.711400100967;8261.8347633047;8274.213949674771;5436.6194366393975;5442.972785136049;5483.341998960151;5526.972365138148;5527.5577953764405;9595.28035397334;9999.000099989942;10078;10110.090091891765;10113.89886101159;10196.38821670702;10270.178385729274;10278.280520363503;10285.382877027403;10292;7027.859442811217;7088.184858741311;7089.858202835811;7101;7104.538205016657;8107.756767296991;8370.916290837014;8489.787755306166;8525.44584601999;8535.743927682179;7452.850942981218;7557.773266802004;7611.3530349920875;7618.923810761821;7737.032870891138;7477.775666730006;7482.925170748223;7531.623418828927;7571.46999710037;7595.962020189863;6990.510664253454;7116.928830711627;7132.108486439195;7133.357997780225;7134.072570565793;5022.070916880389;5123.718195499283;5178.326817513699;5266.499682530153;5270.683758974474;4994.525520075587;5308.973455132699;5452.700101494456;5537.695426751407;5564.666120032811 06:34:31 INFO - PROCESS | 1957 | |4;prototype.html;304.83843562911653;310.5590062111801;316.0943895738708;317.8966835778371;320.20109825984173;4555.9316610250835;5504.532114770249;5550.16747487876;5618.634788738729;5632.57755668325;5422.322209723785;5455.727213639312;5460.262864513289;5507.642003269794;5511.807086751965;3962.8811135665833;3981.6217459341387;4150.792460376984;4204.789760511962;4225.661947044229;3458.8789392371273;3671.723531410516;3930.567637559866;4166.520850102247;4199.496060472748;4137.482814648169;4157.230912281222;4160.566068849726;4189.245935731567;4193.706440549163;3828.5979972102805;3862.575116737157;3979.7413168143976;4070.3080476319096;4188.392683060939;3590.389633762267;3593.568771747407;3597.7587732232337;3621.3662609043577;3653.94519082214;4406.471223453174;4437.733735975852;5377.118008696924;5393.379761327458;5423.891522169574;4466.682328713037;4606.203580603534;4769.833055843063;4821.566059054702;4838.879028024327;4688.413948256468;4792.520747925215;5708.942910570882;5728.42715728428;5752.338481074688;3994.520657521087;4000.239954408653;4036.0536808129937;4041.717079804415;4067.532233793122;5311.840644780624;5944.613600115978;5968.582199246011;6003.3996600339615;6042.6978651067275;3573.9821300893327;3753.8498460061837;3974.5230572331507;4034.293998550301;4066.43814396928;3384.864605415805;3729.601399475197;3950.6246906543834;4135.214309281227;4155.3766934959995;4395.813130454823;4504.945549005921;4759.571638552477;4777.832775852898;4792.568668819823;4856.697445736932;4909.386326709161;5039.823606173766;5796.493121661334;5884.087966365135;1384.8822850057657;1411.4565892131602;1411.5765270418833;1412.533863824956;1412.9364178611986;797.3780451248036;817.712103437089;858.9441686290371;863.6268333349879;873.7902903303161;4811.3745213122775;4917.459079501324;5112.747376892685;5230.790768369223;5318.388385335658;4529.139463501924;5525.060739674265;5761.337446193657;5914.964881145868;5916.911246331309;4889.731064791399;4997.30037794709;5328.440513746001;5405.540529055074;5463.262459567907;2672.997625890291;2991.010977699849;3051.2524431513975;3093.8917137900517;3108;5906.822795316148;6012.909806352908;6048.546359023003;6078.513718902477;6099.664518451439;5132.692038477703;5742.454466825645;5745.885082298414;6017.548683848641;6304.684765761694;5542.806001789918;5835.79574714883;6029.306629737636;6262.874742505124;6338.292099122065;5092.923606145837;5260.921086183786;5796.246487956707;6205.696803671099;6218.502519798496;5655.604107712421;5969.91045134332;5977.296470505885;6033.396660333931;6046.818595442144;5553.139263414178;6132.41742034506;6301.8109456716375;6325.240971083499;6396.520260980538;3237.1437315509743;3824.655780979725;3928.1947200824334;3960.5841386653988;3974.880753577397;4919.901601968012;6008.8798224036145;6009.45914867664;6033.366496517802;6037.275526936795;5773.653580785166;5876.911846322223;6019.277686677627;6022.337542870368;6042.607230530001;4869.829555965524;5803.852574547444;5826.271716035496;5905.704714764159;5938.910916336172;4718.41019872516;5206.479352064764;5712.285964254468;5862.736176871966;5877;5546.722663866871;5646.689432081275;5686.303902463432;5787.39232380594;5907.17299578068;4435.117173837032;4741.9051618968115;5694.943050569441;5752.9712351437975;5778.393268706725 06:34:31 INFO - PROCESS | 1957 | |5;yui.html;615.6183166436808;758.0978635423849;762.5005621318037;792.8176519400538;793.456482309618;14939.402423903044;15745.291461884213;16345.937514061592;16347.918260408696;16683.749743753855;14981.101133931972;15099.773503397459;15130.319135638923;15132.319045642946;15162.924185379085;686.989695154573;709.5529816215777;713.6717110129347;848.6732607945954;864.2481041493909;702.8313204830855;705.0974752317043;705.347553513001;871.5119533061505;904.4076130134772;925.5927391947555;930.7393929699695;930.8231436027133;931.0689310689311;931.3945935142178;924.8612708093773;925.181214625055;926.3839546701436;926.536731634183;928.614624930653;543.6140340358351;678.1726293921408;687.2783577243874;710.882704353781;711.2425267090583;15343.923280383638;15363.385464581515;15411;15414.99802512833;15435.30541125652;13643.931780341034;14015.369308381147;14031.017828751992;14032.43870245189;14204.360803763855;12586.874131258754;12701.555445559454;12742.36288185587;12797.168184068007;12945.676358091028;4527.547245275479;4542.909141817178;4611.423572053493;4696.037312350994;4701.200795864711;106081.46959265231;108050;113222;114549.4272528632;119261.21108183471;11253.493592788343;11327.207095503236;11379.37413442252;11527.020203282815;11564.248323858923;22495.775042249366;22872.428189295402;22912.656310155362;22917.54164916726;22927;7879.803004924923;7966.920330796734;8060;8069.3544516439715;8193.057798353146;4322.265214913472;4354;4361.869143925687;4368.257396242647;4523.11799199153;3538.469229615578;3647.398179300386;3655.5247817784048;3660.9450858237146;3665.0287673766475;711.1572786248312;744.1591002167517;744.8454894912863;759.6847308367077;767.7236194969806;545.7189547383101;685.9485538584527;687.5904395988205;695.4823538789614;700.9614471204133;9427.198688111588;9515.143637072697;9683.515824208762;9765.218782497383;9793;555.2032832884823;617.117521943616;629.2511910826103;680.8195828105556;703.3353480960443;4575.496695363508;4788.904221915612;4805.255185446262;4805.831795887128;4815.6388270879825;96782.5160874191;97083;97136;97900.25196976408;97938;114638.42680786543;115702;124206;127140;138860;105942;106150;106151.46924265329;106434.46782766037;106653.9334606644;8967.013628500728;9024.503652299187;9103;9156.542172891195;9211.493367864832;66484.33515664781;67254.3274567248;67378.31554210988;67755;68333.24167170853;82270;95364.09271814664;96829.51585242029;98527.50736246273;99432;11937.701557461132;12045;12087.274763514217;12314.507419703112;12363.196392234477;12210;12647.241165530098;12754.87245127574;12889.419976101286;14118.082324648865;10546.261761676917;10718.35689858611;12207.938960305142;12663.430145643653;14477.493287734878;13812.516561920285;14241.287935603386;14251.144931304156;14520.346584403938;14538.345774439964;13786.862131378557;14114.647133821736;14256.073355231876;14284.642883927985;14624.122552646875;9817.4600396976;11530.308181509135;11605.89743974321;11673.824892626448;11903.702407439883;9618.326717129734;10920.907909209016;11296.717582060513;11623.88376116228;11925.82111268349 06:34:31 INFO - PROCESS | 1957 | __end_tp_report 06:34:31 INFO - PROCESS | 1957 | __start_cc_report 06:34:31 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1434 06:34:31 INFO - PROCESS | 1957 | __end_cc_report 06:34:31 INFO - PROCESS | 1957 | __startTimestamp1467984871923__endTimestamp 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | ------- Summary: start ------- 06:34:31 INFO - PROCESS | 1957 | Number of tests: 6 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#0] dojo.html Cycles:165 Average:5820.66 Median:6118.05 stddev:642.87 (10.5%) stddev-sans-first:615.32 06:34:31 INFO - PROCESS | 1957 | Values: 3365.5 3366.5 3400.4 3405.8 3430.9 4990.9 5716.7 5832.9 5835.8 5885.1 5891.7 5895.9 5949.6 6010.9 6017.2 5258.3 5286.2 5329.5 5348.8 5417.6 5393.0 5436.2 5441.3 5452.4 5482.9 4241.6 4705.0 5291.9 5320.8 5373.1 4589.6 5243.2 5288.6 5318.0 5373.1 5268.3 5303.9 5355.2 5389.7 5397.7 5686.8 5803.7 5927.0 5938.0 6037.3 6188.3 6219.2 6227.8 6234.0 6240.4 6238.0 6270.3 6294.4 6294.9 6310.9 5842.6 5952.3 6021.1 6029.8 6069.3 6344.2 6369.4 6379.4 6383.4 6391.1 6226.2 6226.4 6229.7 6242.9 6259.1 6159.6 6192.4 6240.7 6243.5 6300.8 6180.6 6231.0 6242.8 6243.8 6251.5 6168.4 6188.5 6219.6 6238.5 6272.8 5042.3 5046.5 5078.2 5087.6 5093.8 4552.5 4553.8 4557.9 4570.5 4579.4 5777.1 5945.9 5952.0 5965.3 5993.3 6158.9 6160.8 6216.3 6229.3 6264.5 5920.5 5954.6 5961.2 5972.3 6031.1 5564.3 5578.0 5578.8 5584.4 5680.9 5393.8 6304.6 6316.5 6365.7 6497.1 6208.3 6255.8 6265.6 6287.9 6304.4 6165.1 6444.1 6451.8 6458.2 6459.8 6125.1 6145.1 6201.7 6225.2 6229.1 6139.1 6208.0 6216.8 6227.4 6235.7 6118.1 6141.2 6141.3 6153.8 6219.4 6142.1 6228.3 6231.8 6239.4 6244.4 6136.3 6138.7 6153.3 6153.4 6157.1 5399.4 6078.8 6094.9 6127.2 6152.3 6108.6 6109.9 6118.0 6132.4 6150.4 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#1] ext.html Cycles:180 Average:66434.98 Median:3562.50 stddev:141552.08 (3973.4%) stddev-sans-first:141862.59 06:34:31 INFO - PROCESS | 1957 | Values: 491.9 495.6 508.8 535.0 535.9 30244.5 42433.9 42924.8 43120.6 43218.8 42227.7 42575.7 44624.3 44824.3 44847.6 8803.7 9825.3 10011.7 10034.7 10039.5 12042.4 12235.3 12298.1 12471.1 12476.6 12277.7 12369.0 12369.4 12390.4 12393.4 12147.3 12290.3 12296.4 12298.5 12307.8 12130.3 12203.8 12224.1 12229.6 12230.9 41885.6 42125.4 42147.8 42148.6 42169.0 3548.9 3576.1 3596.8 4111.4 4474.5 31728.8 32295.1 32570.8 33231.3 33302.8 3316.3 3325.8 3340.0 3341.9 3418.8 501728.0 502627.0 502980.0 503125.0 503804.0 64038.0 64289.7 64818.0 64921.4 65082.7 67165.0 67252.7 67427.3 67453.7 67488.7 16630.3 17187.0 17191.6 17209.1 17216.8 13124.1 13198.9 13204.7 13215.5 13219.0 15144.8 17742.6 18029.4 18191.5 18251.5 6358.8 6519.7 6878.0 6897.6 6975.0 873.3 878.8 1022.3 1095.0 1100.4 27844.6 28928.0 30914.2 30923.5 31193.8 1036.8 1049.7 1052.6 1073.3 1099.4 15928.8 16176.8 16178.6 16328.7 16677.9 176386.2 177394.0 177575.0 628161.0 633554.8 63140.4 63296.0 63362.7 63618.0 66069.7 1325.4 1358.4 1447.9 1466.6 1484.5 28114.3 31480.7 32213.0 34019.3 34095.0 59354.4 60141.0 61118.0 61140.7 61149.7 635134.0 635442.0 639864.0 640739.0 641057.0 37503.0 42619.5 43042.4 44484.3 45230.0 42675.4 49729.0 50905.7 51365.2 51594.5 39660.0 42018.6 42095.4 43187.8 43232.4 41112.2 41768.4 42098.2 42180.0 42634.4 31840.5 38620.8 39098.6 39985.8 40140.0 34631.7 35117.8 35254.5 35356.0 36083.5 19678.8 25533.6 25550.2 25755.5 26184.0 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#2] jquery.html Cycles:180 Average:35065.91 Median:2289.18 stddev:100207.64 (4377.4%) stddev-sans-first:100454.85 06:34:31 INFO - PROCESS | 1957 | Values: 357.2 379.2 388.9 392.2 412.6 15420.0 17113.0 21139.9 21494.0 21503.0 20540.4 21288.6 21396.0 21399.6 21402.4 5953.4 6064.9 6094.9 6133.3 6183.8 5914.5 6010.5 6042.8 6089.3 6104.6 5879.9 5936.1 6029.1 6049.8 6061.6 5879.7 6010.8 6025.5 6031.8 6039.7 4578.0 5213.3 5254.8 5255.0 5256.8 24620.1 25297.2 25302.7 25357.1 25589.2 2272.0 2285.7 2292.7 2366.5 2419.4 15801.3 15817.4 15905.9 15940.2 16196.6 1858.7 1862.2 1894.6 1936.5 1945.6 77357.8 100812.5 115822.4 116044.4 117567.2 10595.6 10604.9 11047.9 11050.1 11234.3 13722.7 13804.5 13822.0 13861.9 13864.2 8479.4 8548.7 8563.3 8709.9 8736.4 8721.9 8873.5 8900.5 8959.0 8966.7 6680.9 6811.5 6820.4 6821.4 6822.2 1852.0 1875.8 1879.7 1904.6 1921.9 785.9 960.2 1009.8 1038.4 1053.7 22016.6 22303.0 22374.3 22377.2 22389.4 800.0 955.5 979.2 980.5 1040.6 11058.2 11491.1 12012.8 12015.7 12024.8 592123.0 596482.0 597135.0 615391.0 616016.0 89854.0 116542.4 117830.0 125167.0 128366.0 1008.2 1011.8 1014.4 1028.0 1063.0 18344.5 19275.8 19540.0 19609.8 19631.5 55823.4 56136.4 56914.7 57024.7 58363.7 79019.2 85489.0 85839.1 85864.0 86418.8 7846.7 8251.5 8405.2 8409.5 8602.3 9339.8 13069.7 13513.3 13533.3 13848.2 10736.1 11027.8 11514.3 11734.9 11797.2 11487.0 11508.4 11771.6 11867.0 11868.5 11745.9 11993.1 12005.4 12062.2 12151.9 7146.6 7579.4 7624.5 8189.8 8657.8 6180.0 8668.6 11442.4 11634.5 11911.5 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#3] mootools.html Cycles:180 Average:5719.91 Median:4706.25 stddev:3242.76 (68.9%) stddev-sans-first:3235.99 06:34:31 INFO - PROCESS | 1957 | Values: 1451.8 1502.7 1578.8 1630.9 1874.1 8646.3 8848.8 9059.8 9072.1 9086.8 7912.0 8099.0 8616.7 8710.7 8788.3 2329.4 2367.4 2367.6 2368.1 2373.1 1691.9 2342.8 2348.9 2374.9 2378.7 2123.5 2129.7 2133.7 2134.8 2304.1 2343.5 2353.9 2355.7 2355.8 2356.2 2069.0 2272.4 2340.6 2358.6 2366.7 7888.7 8063.5 8108.4 8953.9 9039.7 4886.2 5231.9 5364.4 5382.9 5410.2 3202.7 3558.0 3640.9 3641.5 3657.9 1092.1 1097.4 1101.7 1107.5 1115.2 11641.4 11778.2 11782.5 11792.0 11815.3 4613.8 4623.0 4835.9 4837.9 4842.2 10227.3 10234.3 10238.3 10251.5 10251.8 5370.4 5380.2 5386.6 5391.1 5393.2 4789.5 4918.2 4925.6 4927.2 4960.8 3792.8 3795.4 3849.4 3878.9 3883.2 1161.4 1169.1 1173.1 1174.1 1174.3 824.0 867.7 954.8 963.0 1016.3 6005.8 6120.2 6126.8 6158.7 6216.2 434.7 442.9 447.2 454.0 458.5 4078.8 4181.0 4222.0 4244.0 4267.5 10243.7 10609.6 10753.5 10756.2 10789.4 10707.2 10714.0 10714.2 10714.5 10727.7 7894.3 8128.1 8245.7 8261.8 8274.2 5436.6 5443.0 5483.3 5527.0 5527.6 9595.3 9999.0 10078.0 10110.1 10113.9 10196.4 10270.2 10278.3 10285.4 10292.0 7027.9 7088.2 7089.9 7101.0 7104.5 8107.8 8370.9 8489.8 8525.4 8535.7 7452.9 7557.8 7611.4 7618.9 7737.0 7477.8 7482.9 7531.6 7571.5 7596.0 6990.5 7116.9 7132.1 7133.4 7134.1 5022.1 5123.7 5178.3 5266.5 5270.7 4994.5 5309.0 5452.7 5537.7 5564.7 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#4] prototype.html Cycles:180 Average:4663.25 Median:5169.59 stddev:1456.47 (28.2%) stddev-sans-first:1423.34 06:34:31 INFO - PROCESS | 1957 | Values: 304.8 310.6 316.1 317.9 320.2 4555.9 5504.5 5550.2 5618.6 5632.6 5422.3 5455.7 5460.3 5507.6 5511.8 3962.9 3981.6 4150.8 4204.8 4225.7 3458.9 3671.7 3930.6 4166.5 4199.5 4137.5 4157.2 4160.6 4189.2 4193.7 3828.6 3862.6 3979.7 4070.3 4188.4 3590.4 3593.6 3597.8 3621.4 3653.9 4406.5 4437.7 5377.1 5393.4 5423.9 4466.7 4606.2 4769.8 4821.6 4838.9 4688.4 4792.5 5708.9 5728.4 5752.3 3994.5 4000.2 4036.1 4041.7 4067.5 5311.8 5944.6 5968.6 6003.4 6042.7 3574.0 3753.8 3974.5 4034.3 4066.4 3384.9 3729.6 3950.6 4135.2 4155.4 4395.8 4504.9 4759.6 4777.8 4792.6 4856.7 4909.4 5039.8 5796.5 5884.1 1384.9 1411.5 1411.6 1412.5 1412.9 797.4 817.7 858.9 863.6 873.8 4811.4 4917.5 5112.7 5230.8 5318.4 4529.1 5525.1 5761.3 5915.0 5916.9 4889.7 4997.3 5328.4 5405.5 5463.3 2673.0 2991.0 3051.3 3093.9 3108.0 5906.8 6012.9 6048.5 6078.5 6099.7 5132.7 5742.5 5745.9 6017.5 6304.7 5542.8 5835.8 6029.3 6262.9 6338.3 5092.9 5260.9 5796.2 6205.7 6218.5 5655.6 5969.9 5977.3 6033.4 6046.8 5553.1 6132.4 6301.8 6325.2 6396.5 3237.1 3824.7 3928.2 3960.6 3974.9 4919.9 6008.9 6009.5 6033.4 6037.3 5773.7 5876.9 6019.3 6022.3 6042.6 4869.8 5803.9 5826.3 5905.7 5938.9 4718.4 5206.5 5712.3 5862.7 5877.0 5546.7 5646.7 5686.3 5787.4 5907.2 4435.1 4741.9 5694.9 5753.0 5778.4 06:34:31 INFO - PROCESS | 1957 | 06:34:31 INFO - PROCESS | 1957 | [#5] yui.html Cycles:180 Average:23568.40 Median:4365.06 stddev:35676.07 (817.3%) stddev-sans-first:35734.52 06:34:31 INFO - PROCESS | 1957 | Values: 615.6 758.1 762.5 792.8 793.5 14939.4 15745.3 16345.9 16347.9 16683.7 14981.1 15099.8 15130.3 15132.3 15162.9 687.0 709.6 713.7 848.7 864.2 702.8 705.1 705.3 871.5 904.4 925.6 930.7 930.8 931.1 931.4 924.9 925.2 926.4 926.5 928.6 543.6 678.2 687.3 710.9 711.2 15343.9 15363.4 15411.0 15415.0 15435.3 13643.9 14015.4 14031.0 14032.4 14204.4 12586.9 12701.6 12742.4 12797.2 12945.7 4527.5 4542.9 4611.4 4696.0 4701.2 106081.5 108050.0 113222.0 114549.4 119261.2 11253.5 11327.2 11379.4 11527.0 11564.2 22495.8 22872.4 22912.7 22917.5 22927.0 7879.8 7966.9 8060.0 8069.4 8193.1 4322.3 4354.0 4361.9 4368.3 4523.1 3538.5 3647.4 3655.5 3660.9 3665.0 711.2 744.2 744.8 759.7 767.7 545.7 685.9 687.6 695.5 701.0 9427.2 9515.1 9683.5 9765.2 9793.0 555.2 617.1 629.3 680.8 703.3 4575.5 4788.9 4805.3 4805.8 4815.6 96782.5 97083.0 97136.0 97900.3 97938.0 114638.4 115702.0 124206.0 127140.0 138860.0 105942.0 106150.0 106151.5 106434.5 106653.9 8967.0 9024.5 9103.0 9156.5 9211.5 66484.3 67254.3 67378.3 67755.0 68333.2 82270.0 95364.1 96829.5 98527.5 99432.0 11937.7 12045.0 12087.3 12314.5 12363.2 12210.0 12647.2 12754.9 12889.4 14118.1 10546.3 10718.4 12207.9 12663.4 14477.5 13812.5 14241.3 14251.1 14520.3 14538.3 13786.9 14114.6 14256.1 14284.6 14624.1 9817.5 11530.3 11605.9 11673.8 11903.7 9618.3 10920.9 11296.7 11623.9 11925.8 06:34:31 INFO - PROCESS | 1957 | -------- Summary: end -------- 06:34:31 INFO - PROCESS | 1957 | 06:34:32 INFO - TEST-INFO | 1957: exit 0 06:34:32 INFO - TEST-OK | dromaeo_css | took 1115421ms 06:34:32 INFO - TEST-START | kraken 06:34:32 INFO - operating with platform_type : mac_ 06:34:32 INFO - Initialising browser for kraken test... 06:34:32 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpu2Fw5w/profile http://localhost:55139/getInfo.html) 06:34:34 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 06:34:34 INFO - PROCESS | 1960 | colorDepth:24 06:34:34 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 06:34:34 INFO - PROCESS | 1960 | __metrics 06:34:34 INFO - PROCESS | 1960 | 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" 06:34:34 INFO - PROCESS | 1960 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:34:35 INFO - TEST-INFO | 1960: exit 0 06:34:35 INFO - Browser initialized. 06:34:35 INFO - Running cycle 1/1 for kraken test... 06:34:35 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpu2Fw5w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:47 INFO - PROCESS | 1961 | RSS: Main: 243769344 06:34:47 INFO - PROCESS | 1961 | 06:34:49 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55139/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:50 INFO - PROCESS | 1961 | RSS: Main: 294387712 06:34:50 INFO - PROCESS | 1961 | 06:34:53 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55139/tests/kraken/driver.html?testName=audio-dft) 06:34:54 INFO - PROCESS | 1961 | RSS: Main: 304717824 06:34:54 INFO - PROCESS | 1961 | 06:34:57 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55139/tests/kraken/driver.html?testName=audio-fft) 06:34:58 INFO - PROCESS | 1961 | RSS: Main: 388374528 06:34:58 INFO - PROCESS | 1961 | 06:35:00 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55139/tests/kraken/driver.html?testName=audio-oscillator) 06:35:00 INFO - PROCESS | 1961 | RSS: Main: 290566144 06:35:00 INFO - PROCESS | 1961 | 06:35:02 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55139/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:35:03 INFO - PROCESS | 1961 | RSS: Main: 294535168 06:35:03 INFO - PROCESS | 1961 | 06:35:06 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55139/tests/kraken/driver.html?testName=imaging-darkroom) 06:35:06 INFO - PROCESS | 1961 | RSS: Main: 329445376 06:35:06 INFO - PROCESS | 1961 | 06:35:10 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55139/tests/kraken/driver.html?testName=imaging-desaturate) 06:35:10 INFO - PROCESS | 1961 | RSS: Main: 330833920 06:35:10 INFO - PROCESS | 1961 | 06:35:13 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55139/tests/kraken/driver.html?testName=json-parse-financial) 06:35:14 INFO - PROCESS | 1961 | RSS: Main: 332853248 06:35:14 INFO - PROCESS | 1961 | 06:35:15 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55139/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:35:16 INFO - PROCESS | 1961 | RSS: Main: 312074240 06:35:16 INFO - PROCESS | 1961 | 06:35:18 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:35:18 INFO - PROCESS | 1961 | RSS: Main: 319528960 06:35:18 INFO - PROCESS | 1961 | 06:35:21 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:21 INFO - PROCESS | 1961 | RSS: Main: 316358656 06:35:21 INFO - PROCESS | 1961 | 06:35:24 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:25 INFO - PROCESS | 1961 | RSS: Main: 327090176 06:35:25 INFO - PROCESS | 1961 | 06:35:28 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:28 INFO - PROCESS | 1961 | RSS: Main: 329113600 06:35:28 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:55139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55139/tests/kraken/driver.html?testName=ai-astar) 06:35:30 INFO - PROCESS | 1961 | RSS: Main: 372449280 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | __start_tp_report 06:35:30 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 06:35:30 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 06:35:30 INFO - PROCESS | 1961 | |i|pagename|runs| 06:35:30 INFO - PROCESS | 1961 | |0;ai-astar;102;112;113;112;113;111;112;114;111;112 06:35:30 INFO - PROCESS | 1961 | |1;audio-beat-detection;145;109;106;169;135;138;107;131;145;113 06:35:30 INFO - PROCESS | 1961 | |2;audio-dft;185;186;186;183;184;183;182;184;179;184 06:35:30 INFO - PROCESS | 1961 | |3;audio-fft;79;77;57;108;57;57;62;65;99;74 06:35:30 INFO - PROCESS | 1961 | |4;audio-oscillator;86;87;93;71;71;68;96;71;67;101 06:35:30 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;88;85;88;85;87;85;89;86;88;84 06:35:30 INFO - PROCESS | 1961 | |6;imaging-darkroom;93;139;140;95;93;93;93;94;130;150 06:35:30 INFO - PROCESS | 1961 | |7;imaging-desaturate;86;84;86;85;84;84;86;85;87;86 06:35:30 INFO - PROCESS | 1961 | |8;json-parse-financial;55;56;54;54;54;54;53;54;54;56 06:35:30 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;65;79;98;115;91;77;75;72;70;72 06:35:30 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;86;86;97;85;80;82;82;82;85;85 06:35:30 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;137;136;155;147;131;142;119;137;236;151 06:35:30 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;161;160;167;159;161;164;161;163;160;158 06:35:30 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;68;66;67;68;66;66;67;67;66;77 06:35:30 INFO - PROCESS | 1961 | __end_tp_report 06:35:30 INFO - PROCESS | 1961 | __start_cc_report 06:35:30 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,2021 06:35:30 INFO - PROCESS | 1961 | __end_cc_report 06:35:30 INFO - PROCESS | 1961 | __startTimestamp1467984930753__endTimestamp 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | ------- Summary: start ------- 06:35:30 INFO - PROCESS | 1961 | Number of tests: 14 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.36 (3.0%) stddev-sans-first:0.97 06:35:30 INFO - PROCESS | 1961 | Values: 102.0 112.0 113.0 112.0 113.0 111.0 112.0 114.0 111.0 112.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:136.50 stddev:20.80 (15.2%) stddev-sans-first:21.33 06:35:30 INFO - PROCESS | 1961 | Values: 145.0 109.0 106.0 169.0 135.0 138.0 107.0 131.0 145.0 113.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:2.07 (1.1%) stddev-sans-first:2.13 06:35:30 INFO - PROCESS | 1961 | Values: 185.0 186.0 186.0 183.0 184.0 183.0 182.0 184.0 179.0 184.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:73.50 Median:69.50 stddev:17.96 (25.8%) stddev-sans-first:18.94 06:35:30 INFO - PROCESS | 1961 | Values: 79.0 77.0 57.0 108.0 57.0 57.0 62.0 65.0 99.0 74.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:78.50 stddev:12.89 (16.4%) stddev-sans-first:13.55 06:35:30 INFO - PROCESS | 1961 | Values: 86.0 87.0 93.0 71.0 71.0 68.0 96.0 71.0 67.0 101.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.72 (2.0%) stddev-sans-first:1.73 06:35:30 INFO - PROCESS | 1961 | Values: 88.0 85.0 88.0 85.0 87.0 85.0 89.0 86.0 88.0 84.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:93.00 stddev:24.35 (26.2%) stddev-sans-first:24.84 06:35:30 INFO - PROCESS | 1961 | Values: 93.0 139.0 140.0 95.0 93.0 93.0 93.0 94.0 130.0 150.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.09 06:35:30 INFO - PROCESS | 1961 | Values: 86.0 84.0 86.0 85.0 84.0 84.0 86.0 85.0 87.0 86.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:1.00 06:35:30 INFO - PROCESS | 1961 | Values: 55.0 56.0 54.0 54.0 54.0 54.0 53.0 54.0 54.0 56.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:76.00 stddev:15.41 (20.3%) stddev-sans-first:15.16 06:35:30 INFO - PROCESS | 1961 | Values: 65.0 79.0 98.0 115.0 91.0 77.0 75.0 72.0 70.0 72.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:4.69 (5.5%) stddev-sans-first:4.96 06:35:30 INFO - PROCESS | 1961 | Values: 86.0 86.0 97.0 85.0 80.0 82.0 82.0 82.0 85.0 85.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:144.50 stddev:32.22 (22.3%) stddev-sans-first:33.88 06:35:30 INFO - PROCESS | 1961 | Values: 137.0 136.0 155.0 147.0 131.0 142.0 119.0 137.0 236.0 151.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:161.00 stddev:2.63 (1.6%) stddev-sans-first:2.79 06:35:30 INFO - PROCESS | 1961 | Values: 161.0 160.0 167.0 159.0 161.0 164.0 161.0 163.0 160.0 158.0 06:35:30 INFO - PROCESS | 1961 | 06:35:30 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.00 stddev:3.33 (5.0%) stddev-sans-first:3.53 06:35:30 INFO - PROCESS | 1961 | Values: 68.0 66.0 67.0 68.0 66.0 66.0 67.0 67.0 66.0 77.0 06:35:30 INFO - PROCESS | 1961 | -------- Summary: end -------- 06:35:30 INFO - PROCESS | 1961 | 06:35:31 INFO - TEST-INFO | 1961: exit 0 06:35:31 INFO - TEST-OK | kraken | took 59205ms 06:35:31 INFO - SUITE-END | took 1174s 06:35:31 INFO - Completed test suite (00:19:34) 06:35:31 INFO - javascript benchmark 06:35:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3365.4951757236413, 3366.528685983962, 3400.370931377694, 3405.7956522608647, 3430.9485357719623, 4990.877052663153, 5716.685582292972, 5832.912506312404, 5835.766569337221, 5885.146653735206, 5891.705414729267, 5895.882082358361, 5949.583529152961, 6010.8797824043495, 6017.217761690974, 5258.342707161605, 5286.233496143057, 5329.520343169114, 5348.812791552296, 5417.59368047396, 5393.0, 5436.238926550286, 5441.319835020622, 5452.40023597404, 5482.945170548303, 4241.575842415749, 4704.98842748809, 5291.894162116774, 5320.813771517978, 5373.113436283009, 4589.623113066067, 5243.213517972296, 5288.576913846883, 5318.042752304584, 5373.059714549938, 5268.28878101459, 5303.920441193385, 5355.223492593591, 5389.676619402849, 5397.676139431646, 5686.829395118153, 5803.7098145093, 5927.0, 5938.0, 6037.30570984338, 6188.319284878705, 6219.222597175353, 6227.782027629057, 6234.0, 6240.375962403768, 6238.033104868752, 6270.31026587075, 6294.402031806971, 6294.87410251797, 6310.905336419957, 5842.620229685057, 5952.255968003999, 6021.096835474714, 6029.78895738647, 6069.332373438918, 6344.206974128234, 6369.363063693593, 6379.393957574023, 6383.425491705769, 6391.105245265667, 6226.159468471789, 6226.439620434183, 6229.719662615194, 6242.937570624326, 6259.061140828912, 6159.5688301818445, 6192.380761923771, 6240.687965601702, 6243.531735119885, 6300.842478938063, 6180.567360284737, 6231.003039513656, 6242.843928901723, 6243.781467648701, 6251.531135164881, 6168.444839964424, 6188.4739797116845, 6219.626822390671, 6238.469730072904, 6272.8118156455375, 5042.294078828967, 5046.545810877039, 5078.238264260391, 5087.618428617869, 5093.770780314895, 4552.499225085238, 4553.817847286137, 4557.931631025537, 4570.54294570547, 4579.4046773919845, 5777.075667893201, 5945.940540594539, 5952.0, 5965.28416590012, 5993.250843644544, 6158.876822463526, 6160.7535698571555, 6216.316205217422, 6229.283632382243, 6264.467520260829, 5920.467157955719, 5954.583179177503, 5961.195238642815, 5972.343042265347, 6031.065184896348, 5564.304461942257, 5577.9959607271085, 5578.804741834017, 5584.357798853184, 5680.8863822723315, 5393.757280922289, 6304.621722696653, 6316.494680425463, 6365.745370185141, 6497.057926600711, 6208.348123447154, 6255.78104766331, 6265.56141070139, 6287.937120628735, 6304.432601065926, 6165.136880836776, 6444.097826304415, 6451.806445806634, 6458.22501299847, 6459.773907913201, 6125.143885071339, 6145.0782382642965, 6201.658908759881, 6225.159603453476, 6229.065640154013, 6139.140520327247, 6207.975684012087, 6216.813495595139, 6227.408396202353, 6235.719392627252, 6118.051701986199, 6141.170941922784, 6141.301904043321, 6153.753849846135, 6219.378062193925, 6142.069075791586, 6228.314885362516, 6231.781887633911, 6239.376062393724, 6244.406781355764, 6136.263648362046, 6138.693065346625, 6153.2616086068165, 6153.384661533811, 6157.1072194532435, 5399.4060653327315, 6078.845019446291, 6094.878102437838, 6127.158986117617, 6152.261728592597, 6108.593909311147, 6109.890546867989, 6118.010103775335, 6132.447291485866, 6150.35421280777], "value": 5780.192865994847, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.8671958571186, 495.5564769531267, 508.83984515305116, 534.973251337433, 535.8901425207832, 30244.546331805013, 42433.93915152123, 42924.78537607315, 43120.56879431205, 43218.783906080425, 42227.73316800493, 42575.72272831812, 44624.330635040504, 44824.3276350855, 44847.55152448475, 8803.735887923373, 9825.312228144032, 10011.74970625733, 10034.749131271703, 10039.494075888602, 12042.397880106004, 12235.32705701186, 12298.139130260884, 12471.127021108527, 12476.563320283793, 12277.69305767354, 12369.010479161645, 12369.4433750481, 12390.442430090667, 12393.442295096742, 12147.331896745676, 12290.324032178227, 12296.385180741017, 12298.508059677602, 12307.815382769266, 12130.332831694253, 12203.755924881541, 12224.083193760503, 12229.633111006684, 12230.938845305805, 41885.58114418847, 42125.36811947823, 42147.789261053804, 42148.57851421477, 42169.0, 3548.8580456781697, 3576.141725985745, 3596.7841929484316, 4111.362738775465, 4474.463064432289, 31728.841355793073, 32295.0622962623, 32570.837145814356, 33231.3353732924, 33302.8344007961, 3316.2538428853313, 3325.7505687073567, 3339.9833000835083, 3341.9498707519406, 3418.8290585470627, 501728.0, 502626.99999999633, 502980.0, 503125.0, 503804.0, 64038.03942940859, 64289.67855160694, 64818.0, 64921.350786492476, 65082.67458662677, 67165.0, 67252.663736681, 67427.32572674309, 67453.66273168701, 67488.6625566869, 16630.251638676287, 17186.96878187313, 17191.57021074458, 17209.106998230185, 17216.827831721774, 13124.14693044949, 13198.934005329911, 13204.669883252804, 13215.537456188988, 13219.0, 15144.772828407582, 17742.556436088944, 18029.368972085915, 18191.45425637233, 18251.54371140732, 6358.777442789573, 6519.7392104315295, 6878.0, 6897.55165914224, 6974.965125174342, 873.2533683700472, 878.7583414561046, 1022.325265324882, 1094.9890501095047, 1100.3837850803575, 27844.582331265046, 28928.0, 30914.22714432112, 30923.536146957813, 31193.8440307797, 1036.7770929250248, 1049.6746008737316, 1052.6263176572359, 1073.3345325897992, 1099.3898386395626, 15928.832862941326, 16176.75734863978, 16178.59553511148, 16328.693704503612, 16677.916610417113, 176386.23613763956, 177394.0, 177575.0, 628161.0, 633554.8322258359, 63140.368596314365, 63296.0, 63362.68318658469, 63618.0, 66069.66965165143, 1325.4168166006925, 1358.3615700620692, 1447.949321773733, 1466.633341664584, 1484.46559238674, 28114.2971425716, 31480.685193148693, 32213.03360899177, 34019.31961360709, 34094.97715068552, 59354.406455934884, 60141.0, 61118.0, 61140.69429653001, 61149.69425152846, 635134.0, 635442.0, 639864.0, 640739.0, 641057.0, 37503.0, 42619.50831720875, 43042.35436468518, 44484.33273500836, 45229.9646515901, 42675.3598696026, 49729.00541989212, 50905.74547127241, 51365.22952155795, 51594.48405516047, 39660.008499787735, 42018.57981420147, 42095.36856947209, 43187.784061079496, 43232.35151472667, 41112.1777564453, 41768.373474397304, 42098.15756035637, 42180.0, 42634.360484592136, 31840.52239216367, 38620.78619386347, 39098.609013909496, 39985.80007099946, 40139.996500087735, 34631.65368346284, 35117.824410878806, 35254.471182932786, 35356.0, 36083.458748118275, 19678.80321196827, 25533.616995744705, 25550.23349299524, 25755.484890302465, 26184.0], "value": 21160.470915825448, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.15176455917197, 379.19042843529064, 388.935825588778, 392.1783862807419, 412.6080223787401, 15419.997700149497, 17113.0, 21139.894300528475, 21494.032768525416, 21503.0, 20540.38378848633, 21288.57422851542, 21396.0, 21399.57200855986, 21402.357929262107, 5953.3748956359605, 6064.9393506065035, 6094.9390506094815, 6133.264008318985, 6183.81448556544, 5914.526837852962, 6010.549208809335, 6042.848928776793, 6089.2692876854835, 6104.633721976674, 5879.941200588003, 5936.139259807325, 6029.065494848283, 6049.818505444844, 6061.575689701723, 5879.67661778602, 6010.789622363218, 6025.487833534155, 6031.819045428644, 6039.6980150992495, 4577.9771101144615, 5213.32226810516, 5254.7635356409055, 5254.973725131388, 5256.789728410859, 24620.138295159584, 25297.241082767545, 25302.74697253022, 25357.112501062373, 25589.232323030337, 2272.0116749214144, 2285.6914316567218, 2292.667563203343, 2366.491204391047, 2419.4314336130997, 15801.288941997638, 15817.367305307775, 15905.920470397574, 15940.20298985046, 16196.595085122848, 1858.7304840798008, 1862.1695023564241, 1894.5737209127835, 1936.5352315444334, 1945.601151763885, 77357.83963240556, 100812.49593751984, 115822.42088789502, 116044.41977790142, 117567.23649145356, 10595.609404990484, 10604.94697526523, 11047.889521104848, 11050.115990720884, 11234.3259404436, 13722.725545489035, 13804.516841910485, 13822.0, 13861.86138138626, 13864.237466939416, 8479.364047696448, 8548.700795472252, 8563.314934805201, 8709.869351959725, 8736.388452808242, 8721.869171962298, 8873.467591944504, 8900.46597204157, 8959.0, 8966.68616598416, 6680.933190668128, 6811.4550835933005, 6820.386165245052, 6821.3519715626935, 6822.215445223764, 1851.9814801852078, 1875.8124187581407, 1879.7180422936397, 1904.64764018657, 1921.8846869187894, 785.9135495095535, 960.2030314838668, 1009.8232809258351, 1038.4496217004998, 1053.7207639975413, 22016.559668806534, 22303.0, 22374.328770136923, 22377.216797412013, 22389.440263993205, 800.0479429478902, 955.4744890310302, 979.2459805949379, 980.4950450517989, 1040.625374865048, 11058.22592418539, 11491.138164637685, 12012.759744805055, 12015.699607509881, 12024.759504809856, 592123.0, 596482.0, 597135.0, 615391.0, 616016.0, 89854.0, 116542.41728791471, 117830.0, 125167.0, 128366.0, 1008.1581879131007, 1011.8330475471612, 1014.3710899242517, 1028.0, 1062.9574817007388, 18344.541386465444, 19275.8072419274, 19540.022998849716, 19609.803901960797, 19631.509212269808, 55823.441765581825, 56136.43863561312, 56914.715426422605, 57024.71487642535, 58363.708181458824, 79019.20980790119, 85489.0, 85839.14160858312, 85864.0, 86418.83952901227, 7846.725364612211, 8251.546164960984, 8405.159484051546, 8409.453385530165, 8602.311815054905, 9339.766505837408, 13069.738605227652, 13513.324333783468, 13533.255670938193, 13848.169109853441, 10736.08743256832, 11027.842076582165, 11514.251573647685, 11734.943855053087, 11797.17419780607, 11486.966173044466, 11508.36703981289, 11771.646850594496, 11867.0, 11868.525258989544, 11745.88254117482, 11993.100517461051, 12005.39973001329, 12062.215955962834, 12151.939240304095, 7146.606936618536, 7579.355754760907, 7624.504407213735, 8189.79525511867, 8657.783555411164, 6180.0, 8668.566571671518, 11442.37066961325, 11634.476448559666, 11911.463984120563], "value": 9868.62204328541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.7967484552164, 1502.6919481506293, 1578.8263291037983, 1630.893991890527, 1874.109797846024, 8646.308295336374, 8848.77878053049, 9059.773505662346, 9072.138146876052, 9086.772830679229, 7912.010998625172, 8098.959505202466, 8616.655333786657, 8710.695125670603, 8788.25299849512, 2329.37106981115, 2367.443650742083, 2367.6093444581625, 2368.1237941961413, 2373.133806160756, 1691.8561922236624, 2342.847714898535, 2348.9295321140394, 2374.8575085494926, 2378.6907701998803, 2123.532822778986, 2129.7231359923267, 2133.7012818205462, 2134.754503232125, 2304.101400453824, 2343.496148328109, 2353.9293821185393, 2355.74086850448, 2355.799757020638, 2356.198892376583, 2068.98619676359, 2272.3864556569797, 2340.6137987232087, 2358.5636657218297, 2366.7396586375485, 7888.723894663716, 8063.516189028677, 8108.391870609727, 8953.865692014626, 9039.728808135766, 4886.218205087204, 5231.895362092775, 5364.436734142937, 5382.865428364283, 5410.215518749798, 3202.6637203093806, 3557.964420355789, 3640.927181456356, 3641.4719865619336, 3657.8536858525626, 1092.126298960836, 1097.358045543358, 1101.6915263726169, 1107.5071593141056, 1115.1524841120724, 11641.35972521511, 11778.175527713063, 11782.528698852035, 11792.0, 11815.291082535075, 4613.815447382067, 4623.0, 4835.879103022382, 4837.927431088537, 4842.201036828954, 10227.335223210617, 10234.33476824004, 10238.33450825694, 10251.487425628839, 10251.794964100676, 5370.355557333145, 5380.192971054373, 5386.596005299618, 5391.083515802245, 5393.164059570773, 4789.4971028041555, 4918.188498897642, 4925.630577706686, 4927.236278376858, 4960.776765045582, 3792.810359482015, 3795.400150896645, 3849.40249796337, 3878.903027424337, 3883.165119499321, 1161.3960740414936, 1169.1114752787942, 1173.143605168232, 1174.0959461214989, 1174.2972689600115, 824.0194168938943, 867.6529388244627, 954.7753733800367, 962.9936716131659, 1016.2581315639625, 6005.819825405245, 6120.204373431515, 6126.846828829226, 6158.692065396713, 6216.222972128484, 434.65227817745676, 442.9313456414261, 447.18835313905157, 453.9614132798683, 458.48191543555566, 4078.816453259551, 4181.0, 4222.0, 4244.0, 4267.466566679165, 10243.7439064024, 10609.575616975235, 10753.462326883624, 10756.193285503463, 10789.406582637874, 10707.196960227859, 10714.0, 10714.19643526723, 10714.517846696917, 10727.678169654922, 7894.328982036591, 8128.065272493621, 8245.711400100967, 8261.8347633047, 8274.213949674771, 5436.6194366393975, 5442.972785136049, 5483.341998960151, 5526.972365138148, 5527.5577953764405, 9595.28035397334, 9999.000099989942, 10078.0, 10110.090091891765, 10113.89886101159, 10196.38821670702, 10270.178385729274, 10278.280520363503, 10285.382877027403, 10292.0, 7027.859442811217, 7088.184858741311, 7089.858202835811, 7101.0, 7104.538205016657, 8107.756767296991, 8370.916290837014, 8489.787755306166, 8525.44584601999, 8535.743927682179, 7452.850942981218, 7557.773266802004, 7611.3530349920875, 7618.923810761821, 7737.032870891138, 7477.775666730006, 7482.925170748223, 7531.623418828927, 7571.46999710037, 7595.962020189863, 6990.510664253454, 7116.928830711627, 7132.108486439195, 7133.357997780225, 7134.072570565793, 5022.070916880389, 5123.718195499283, 5178.326817513699, 5266.499682530153, 5270.683758974474, 4994.525520075587, 5308.973455132699, 5452.700101494456, 5537.695426751407, 5564.666120032811], "value": 4499.065094436848, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.83843562911653, 310.5590062111801, 316.0943895738708, 317.8966835778371, 320.20109825984173, 4555.9316610250835, 5504.532114770249, 5550.16747487876, 5618.634788738729, 5632.57755668325, 5422.322209723785, 5455.727213639312, 5460.262864513289, 5507.642003269794, 5511.807086751965, 3962.8811135665833, 3981.6217459341387, 4150.792460376984, 4204.789760511962, 4225.661947044229, 3458.8789392371273, 3671.723531410516, 3930.567637559866, 4166.520850102247, 4199.496060472748, 4137.482814648169, 4157.230912281222, 4160.566068849726, 4189.245935731567, 4193.706440549163, 3828.5979972102805, 3862.575116737157, 3979.7413168143976, 4070.3080476319096, 4188.392683060939, 3590.389633762267, 3593.568771747407, 3597.7587732232337, 3621.3662609043577, 3653.94519082214, 4406.471223453174, 4437.733735975852, 5377.118008696924, 5393.379761327458, 5423.891522169574, 4466.682328713037, 4606.203580603534, 4769.833055843063, 4821.566059054702, 4838.879028024327, 4688.413948256468, 4792.520747925215, 5708.942910570882, 5728.42715728428, 5752.338481074688, 3994.520657521087, 4000.239954408653, 4036.0536808129937, 4041.717079804415, 4067.532233793122, 5311.840644780624, 5944.613600115978, 5968.582199246011, 6003.3996600339615, 6042.6978651067275, 3573.9821300893327, 3753.8498460061837, 3974.5230572331507, 4034.293998550301, 4066.43814396928, 3384.864605415805, 3729.601399475197, 3950.6246906543834, 4135.214309281227, 4155.3766934959995, 4395.813130454823, 4504.945549005921, 4759.571638552477, 4777.832775852898, 4792.568668819823, 4856.697445736932, 4909.386326709161, 5039.823606173766, 5796.493121661334, 5884.087966365135, 1384.8822850057657, 1411.4565892131602, 1411.5765270418833, 1412.533863824956, 1412.9364178611986, 797.3780451248036, 817.712103437089, 858.9441686290371, 863.6268333349879, 873.7902903303161, 4811.3745213122775, 4917.459079501324, 5112.747376892685, 5230.790768369223, 5318.388385335658, 4529.139463501924, 5525.060739674265, 5761.337446193657, 5914.964881145868, 5916.911246331309, 4889.731064791399, 4997.30037794709, 5328.440513746001, 5405.540529055074, 5463.262459567907, 2672.997625890291, 2991.010977699849, 3051.2524431513975, 3093.8917137900517, 3108.0, 5906.822795316148, 6012.909806352908, 6048.546359023003, 6078.513718902477, 6099.664518451439, 5132.692038477703, 5742.454466825645, 5745.885082298414, 6017.548683848641, 6304.684765761694, 5542.806001789918, 5835.79574714883, 6029.306629737636, 6262.874742505124, 6338.292099122065, 5092.923606145837, 5260.921086183786, 5796.246487956707, 6205.696803671099, 6218.502519798496, 5655.604107712421, 5969.91045134332, 5977.296470505885, 6033.396660333931, 6046.818595442144, 5553.139263414178, 6132.41742034506, 6301.8109456716375, 6325.240971083499, 6396.520260980538, 3237.1437315509743, 3824.655780979725, 3928.1947200824334, 3960.5841386653988, 3974.880753577397, 4919.901601968012, 6008.8798224036145, 6009.45914867664, 6033.366496517802, 6037.275526936795, 5773.653580785166, 5876.911846322223, 6019.277686677627, 6022.337542870368, 6042.607230530001, 4869.829555965524, 5803.852574547444, 5826.271716035496, 5905.704714764159, 5938.910916336172, 4718.41019872516, 5206.479352064764, 5712.285964254468, 5862.736176871966, 5877.0, 5546.722663866871, 5646.689432081275, 5686.303902463432, 5787.39232380594, 5907.17299578068, 4435.117173837032, 4741.9051618968115, 5694.943050569441, 5752.9712351437975, 5778.393268706725], "value": 4197.69356247567, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.6183166436808, 758.0978635423849, 762.5005621318037, 792.8176519400538, 793.456482309618, 14939.402423903044, 15745.291461884213, 16345.937514061592, 16347.918260408696, 16683.749743753855, 14981.101133931972, 15099.773503397459, 15130.319135638923, 15132.319045642946, 15162.924185379085, 686.989695154573, 709.5529816215777, 713.6717110129347, 848.6732607945954, 864.2481041493909, 702.8313204830855, 705.0974752317043, 705.347553513001, 871.5119533061505, 904.4076130134772, 925.5927391947555, 930.7393929699695, 930.8231436027133, 931.0689310689311, 931.3945935142178, 924.8612708093773, 925.181214625055, 926.3839546701436, 926.536731634183, 928.614624930653, 543.6140340358351, 678.1726293921408, 687.2783577243874, 710.882704353781, 711.2425267090583, 15343.923280383638, 15363.385464581515, 15411.0, 15414.99802512833, 15435.30541125652, 13643.931780341034, 14015.369308381147, 14031.017828751992, 14032.43870245189, 14204.360803763855, 12586.874131258754, 12701.555445559454, 12742.36288185587, 12797.168184068007, 12945.676358091028, 4527.547245275479, 4542.909141817178, 4611.423572053493, 4696.037312350994, 4701.200795864711, 106081.46959265231, 108050.0, 113222.0, 114549.4272528632, 119261.21108183471, 11253.493592788343, 11327.207095503236, 11379.37413442252, 11527.020203282815, 11564.248323858923, 22495.775042249366, 22872.428189295402, 22912.656310155362, 22917.54164916726, 22927.0, 7879.803004924923, 7966.920330796734, 8060.0, 8069.3544516439715, 8193.057798353146, 4322.265214913472, 4354.0, 4361.869143925687, 4368.257396242647, 4523.11799199153, 3538.469229615578, 3647.398179300386, 3655.5247817784048, 3660.9450858237146, 3665.0287673766475, 711.1572786248312, 744.1591002167517, 744.8454894912863, 759.6847308367077, 767.7236194969806, 545.7189547383101, 685.9485538584527, 687.5904395988205, 695.4823538789614, 700.9614471204133, 9427.198688111588, 9515.143637072697, 9683.515824208762, 9765.218782497383, 9793.0, 555.2032832884823, 617.117521943616, 629.2511910826103, 680.8195828105556, 703.3353480960443, 4575.496695363508, 4788.904221915612, 4805.255185446262, 4805.831795887128, 4815.6388270879825, 96782.5160874191, 97083.0, 97136.0, 97900.25196976408, 97938.0, 114638.42680786543, 115702.0, 124206.0, 127140.0, 138860.0, 105942.0, 106150.0, 106151.46924265329, 106434.46782766037, 106653.9334606644, 8967.013628500728, 9024.503652299187, 9103.0, 9156.542172891195, 9211.493367864832, 66484.33515664781, 67254.3274567248, 67378.31554210988, 67755.0, 68333.24167170853, 82270.0, 95364.09271814664, 96829.51585242029, 98527.50736246273, 99432.0, 11937.701557461132, 12045.0, 12087.274763514217, 12314.507419703112, 12363.196392234477, 12210.0, 12647.241165530098, 12754.87245127574, 12889.419976101286, 14118.082324648865, 10546.261761676917, 10718.35689858611, 12207.938960305142, 12663.430145643653, 14477.493287734878, 13812.516561920285, 14241.287935603386, 14251.144931304156, 14520.346584403938, 14538.345774439964, 13786.862131378557, 14114.647133821736, 14256.073355231876, 14284.642883927985, 14624.122552646875, 9817.4600396976, 11530.308181509135, 11605.89743974321, 11673.824892626448, 11903.702407439883, 9618.326717129734, 10920.907909209016, 11296.717582060513, 11623.88376116228, 11925.82111268349], "value": 7834.592445922422, "unit": "score"}], "name": "dromaeo_css", "value": 7504.473702484718, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 112.0, 113.0, 111.0, 112.0, 114.0, 111.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 106.0, 169.0, 135.0, 138.0, 107.0, 131.0, 145.0, 113.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 183.0, 184.0, 183.0, 182.0, 184.0, 179.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 57.0, 108.0, 57.0, 57.0, 62.0, 65.0, 99.0, 74.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 93.0, 71.0, 71.0, 68.0, 96.0, 71.0, 67.0, 101.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 139.0, 140.0, 95.0, 93.0, 93.0, 93.0, 94.0, 130.0, 150.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 85.0, 84.0, 84.0, 86.0, 85.0, 87.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 79.0, 98.0, 115.0, 91.0, 77.0, 75.0, 72.0, 70.0, 72.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 97.0, 85.0, 80.0, 82.0, 82.0, 82.0, 85.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 155.0, 147.0, 131.0, 142.0, 119.0, 137.0, 236.0, 151.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 167.0, 159.0, 161.0, 164.0, 161.0, 163.0, 160.0, 158.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 66.0, 66.0, 67.0, 67.0, 66.0, 77.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1462.1, "alertThreshold": 2.0}]} 06:35:31 INFO - Return code: 0 06:35:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:32 INFO - # TBPL SUCCESS # 06:35:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:35:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:35:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:35:32 INFO - 2016-07-08 06:35:32.056 screenresolution[1965:67968] starting screenresolution argv=screenresolution get 06:35:32 INFO - 2016-07-08 06:35:32.060 screenresolution[1965:67968] Display 0: 1600x1200x32@60 06:35:32 INFO - 2016-07-08 06:35:32.066 screenresolution[1966:67971] starting screenresolution argv=screenresolution list 06:35:32 INFO - Available Modes on Display 0 06:35:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:35:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:35:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:35:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:35:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:35:32 INFO - Intel Iris: 06:35:32 INFO - Chipset Model: Intel Iris 06:35:32 INFO - Type: GPU 06:35:32 INFO - Bus: Built-In 06:35:32 INFO - VRAM (Dynamic, Max): 1536 MB 06:35:32 INFO - Vendor: Intel (0x8086) 06:35:32 INFO - Device ID: 0x0a2e 06:35:32 INFO - Revision ID: 0x0009 06:35:32 INFO - Displays: 06:35:32 INFO - Display: 06:35:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:35:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:35:32 INFO - Display Serial Number: 1600x1200 60 06:35:32 INFO - Main Display: Yes 06:35:32 INFO - Mirror: Off 06:35:32 INFO - Online: Yes 06:35:32 INFO - Rotation: Supported 06:35:32 INFO - Return code: 0 06:35:32 INFO - Running post-action listener: _resource_record_post_action 06:35:32 INFO - ##### 06:35:32 INFO - ##### Finished run-tests step (success) 06:35:32 INFO - ##### 06:35:32 INFO - Running post-run listener: _resource_record_post_run 06:35:32 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 32768; Write bytes: 702874112; Read time: 2; Write time: 1924 06:35:32 INFO - TinderboxPrint: CPU usage
53.4% 06:35:32 INFO - TinderboxPrint: I/O read bytes / time
32,768 / 2 06:35:32 INFO - TinderboxPrint: I/O write bytes / time
702,874,112 / 1,924 06:35:32 INFO - TinderboxPrint: CPU idle
2,256.1 (46.6%) 06:35:32 INFO - TinderboxPrint: CPU system
961.1 (19.9%) 06:35:32 INFO - TinderboxPrint: CPU user
1,620.5 (33.5%) 06:35:32 INFO - TinderboxPrint: Swap in / out
406,347,776 / 0 06:35:32 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398557696; Write bytes: 394118656; Read time: 29479; Write time: 802 06:35:32 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 32768; Write bytes: 291171328; Read time: 2; Write time: 1077 06:35:32 INFO - Running post-run listener: _upload_blobber_files 06:35:32 INFO - Blob upload gear active. 06:35:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:32 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 06:35:32 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:35:32 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:35:33 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:35:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:33 INFO - (blobuploader) - INFO - Done attempting. 06:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:35:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:34 INFO - (blobuploader) - INFO - Done attempting. 06:35:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:37 INFO - (blobuploader) - INFO - Done attempting. 06:35:37 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:37 INFO - Return code: 0 06:35:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea99356f4347876c51dc4525938e5f442c68936266d6f3e5ce189fddc69a6dc54aa5c0a392fb9d40afae4c541e7764099297be2c3864db18bffe2e0483996d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/027cd407579f222452bf20f3fc52a499257e26a414d7711bdf5a55eae89883783d5bd17c42ad15acc113b225f1eafa56892075342668110e83f3753d83ac7090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b5ce7ac6c9533e0e5770a19687cff61afbca1df047c7645bbb7d0c022e6dc684dac10aa400cdfaccd000872267d610e15c7e18335376b6723ee26464939c6af"} 06:35:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:37 INFO - Contents: 06:35:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea99356f4347876c51dc4525938e5f442c68936266d6f3e5ce189fddc69a6dc54aa5c0a392fb9d40afae4c541e7764099297be2c3864db18bffe2e0483996d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/027cd407579f222452bf20f3fc52a499257e26a414d7711bdf5a55eae89883783d5bd17c42ad15acc113b225f1eafa56892075342668110e83f3753d83ac7090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b5ce7ac6c9533e0e5770a19687cff61afbca1df047c7645bbb7d0c022e6dc684dac10aa400cdfaccd000872267d610e15c7e18335376b6723ee26464939c6af"} 06:35:37 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:37 INFO - Copying logs to upload dir... 06:35:37 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.444963 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-08 06:35:37.143990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 06:35:37.147537) ========= 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.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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/Firefox/sha512/3ea99356f4347876c51dc4525938e5f442c68936266d6f3e5ce189fddc69a6dc54aa5c0a392fb9d40afae4c541e7764099297be2c3864db18bffe2e0483996d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/027cd407579f222452bf20f3fc52a499257e26a414d7711bdf5a55eae89883783d5bd17c42ad15acc113b225f1eafa56892075342668110e83f3753d83ac7090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b5ce7ac6c9533e0e5770a19687cff61afbca1df047c7645bbb7d0c022e6dc684dac10aa400cdfaccd000872267d610e15c7e18335376b6723ee26464939c6af"} build_url:https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010835 build_url: 'https://queue.taskcluster.net/v1/task/GnRI77BgSXiD4wyr1Hx8YA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea99356f4347876c51dc4525938e5f442c68936266d6f3e5ce189fddc69a6dc54aa5c0a392fb9d40afae4c541e7764099297be2c3864db18bffe2e0483996d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/027cd407579f222452bf20f3fc52a499257e26a414d7711bdf5a55eae89883783d5bd17c42ad15acc113b225f1eafa56892075342668110e83f3753d83ac7090", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b5ce7ac6c9533e0e5770a19687cff61afbca1df047c7645bbb7d0c022e6dc684dac10aa400cdfaccd000872267d610e15c7e18335376b6723ee26464939c6af"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 06:35:37.180300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:35:37.180632) ========= 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.wRxUFdhJES/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.6Ssoys5lv9/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005040 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 06:35:37.231768) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 06:35:37.232306) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 06:35:37.232918) ========= ========= Total master_lag: 0.18 =========