builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1461531866.85 results: success (0) buildid: 20160424125552 builduid: 29fa51451e6247e79de74546a4441689 revision: 98da47b4d83c576ce17d189942d2655df5a36dc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:26.846434) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 14:04:26.846806) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 mins, 21 secs) (at 2016-04-24 14:04:26.847122) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 mins, 21 secs) (at 2016-04-24 14:05:48.191064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 14:05:48.191572) ========= 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.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-04-24 14:05:48-- 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.75M=0.001s 2016-04-24 14:05:48 (9.75 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.531228 ========= master_lag: 0.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-24 14:05:49.259573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:05:49.259905) ========= 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.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.027630 ========= master_lag: 0.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:05:49.564916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-24 14:05:49.565264) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 98da47b4d83c576ce17d189942d2655df5a36dc1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 98da47b4d83c576ce17d189942d2655df5a36dc1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-04-24 14:05:49,653 truncating revision to first 12 chars 2016-04-24 14:05:49,654 Setting DEBUG logging. 2016-04-24 14:05:49,654 attempt 1/10 2016-04-24 14:05:49,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/98da47b4d83c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 14:05:50,082 unpacking tar archive at: mozilla-aurora-98da47b4d83c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.678363 ========= master_lag: 4.00 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-24 14:05:54.241766) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 14:05:54.245082) ========= script_repo_revision: 98da47b4d83c576ce17d189942d2655df5a36dc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 14:05:54.245537) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 11 secs) (at 2016-04-24 14:05:54.245815) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 11 secs) (at 2016-04-24 14:06:06.104682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-24 14:06:06.104960) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:06 INFO - MultiFileLogger online at 20160424 14:06:06 in /builds/slave/test 14:06:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:06:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:06:06 INFO - {'append_to_log': False, 14:06:06 INFO - 'base_work_dir': '/builds/slave/test', 14:06:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:06:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:06:06 INFO - 'branch': 'Mozilla-Aurora', 14:06:06 INFO - 'buildbot_json_path': 'buildprops.json', 14:06:06 INFO - 'config_files': ('talos/mac_config.py',), 14:06:06 INFO - 'default_actions': ('clobber', 14:06:06 INFO - 'read-buildbot-config', 14:06:06 INFO - 'download-and-extract', 14:06:06 INFO - 'populate-webroot', 14:06:06 INFO - 'create-virtualenv', 14:06:06 INFO - 'install', 14:06:06 INFO - 'run-tests'), 14:06:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:06:06 INFO - 'download_minidump_stackwalk': True, 14:06:06 INFO - 'download_symbols': 'ondemand', 14:06:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:06:06 INFO - 'tooltool.py': '/tools/tooltool.py', 14:06:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:06:06 INFO - '/tools/misc-python/virtualenv.py')}, 14:06:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:06:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:06:06 INFO - 'installer_path': 'installer.exe', 14:06:06 INFO - 'log_level': 'info', 14:06:06 INFO - 'log_name': 'talos', 14:06:06 INFO - 'log_to_console': True, 14:06:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:06:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:06:06 INFO - 'opt_config_files': (), 14:06:06 INFO - 'pip_index': False, 14:06:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:06 INFO - 'cmd': ('bash', 14:06:06 INFO - '-c', 14:06:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:06 INFO - 'enabled': True, 14:06:06 INFO - 'halt_on_failure': False, 14:06:06 INFO - 'name': 'check_screen_resolution'},), 14:06:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:06:06 INFO - 'cmd': ('bash', 14:06:06 INFO - '-c', 14:06:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:06:06 INFO - 'enabled': True, 14:06:06 INFO - 'halt_on_failure': False, 14:06:06 INFO - 'name': 'check_screen_resolution'},), 14:06:06 INFO - 'run_cmd_checks_enabled': True, 14:06:06 INFO - 'sps_profile': False, 14:06:06 INFO - 'sps_profile_interval': 0, 14:06:06 INFO - 'suite': 'dromaeojs', 14:06:06 INFO - 'system_bits': '32', 14:06:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:06:06 INFO - 'title': 't-yosemite-r7-0052', 14:06:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:06:06 INFO - 'use_talos_json': True, 14:06:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:06:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:06:06 INFO - 'work_dir': 'build'} 14:06:06 INFO - ##### 14:06:06 INFO - ##### Running clobber step. 14:06:06 INFO - ##### 14:06:06 INFO - Running pre-action listener: _resource_record_pre_action 14:06:06 INFO - Running main action method: clobber 14:06:06 INFO - rmtree: /builds/slave/test/build 14:06:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:06:07 INFO - Running post-action listener: _resource_record_post_action 14:06:07 INFO - ##### 14:06:07 INFO - ##### Running read-buildbot-config step. 14:06:07 INFO - ##### 14:06:07 INFO - Running pre-action listener: _resource_record_pre_action 14:06:07 INFO - Running main action method: read_buildbot_config 14:06:07 INFO - Using buildbot properties: 14:06:07 INFO - { 14:06:07 INFO - "project": "", 14:06:07 INFO - "product": "firefox", 14:06:07 INFO - "script_repo_revision": "production", 14:06:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:06:07 INFO - "repository": "", 14:06:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:06:07 INFO - "buildid": "20160424125552", 14:06:07 INFO - "pgo_build": "False", 14:06:07 INFO - "basedir": "/builds/slave/test", 14:06:07 INFO - "buildnumber": 319, 14:06:07 INFO - "slavename": "t-yosemite-r7-0052", 14:06:07 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:06:07 INFO - "platform": "yosemite_r7", 14:06:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:06:07 INFO - "branch": "mozilla-aurora", 14:06:07 INFO - "repo_path": "releases/mozilla-aurora", 14:06:07 INFO - "revision": "98da47b4d83c576ce17d189942d2655df5a36dc1", 14:06:07 INFO - "stage_platform": "macosx64", 14:06:07 INFO - "builduid": "29fa51451e6247e79de74546a4441689", 14:06:07 INFO - "slavebuilddir": "test" 14:06:07 INFO - } 14:06:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 14:06:07 INFO - Running post-action listener: _resource_record_post_action 14:06:07 INFO - ##### 14:06:07 INFO - ##### Running download-and-extract step. 14:06:07 INFO - ##### 14:06:07 INFO - Running pre-action listener: _resource_record_pre_action 14:06:07 INFO - Running main action method: download_and_extract 14:06:07 INFO - mkdir: /builds/slave/test/build/tests 14:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:07 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:06:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 14:06:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 14:06:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 14:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 14:06:15 INFO - Downloaded 1277 bytes. 14:06:15 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 14:06:15 INFO - Using the following test package requirements: 14:06:15 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:06:15 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 14:06:15 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 14:06:15 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'jsshell-mac.zip'], 14:06:15 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 14:06:15 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:06:15 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 14:06:15 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 14:06:15 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 14:06:15 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 14:06:15 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 14:06:15 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 14:06:15 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:06:15 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')]} 14:06:15 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:06:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:06:18 INFO - Downloaded 17919003 bytes. 14:06:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:06:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:06:21 INFO - Return code: 0 14:06:21 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:06: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')]} 14:06:21 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:06:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 14:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:06:26 INFO - Downloaded 17919003 bytes. 14:06:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:06:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:06:29 INFO - Return code: 0 14:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:29 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 14:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:06:30 INFO - Downloaded 10845194 bytes. 14:06:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:06:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:06:30 INFO - Return code: 0 14:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:30 INFO - https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 14:06:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:06:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:06:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 14:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 14:06:33 INFO - Downloaded 115764096 bytes. 14:06:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:06:33 INFO - mkdir: /builds/slave/test/properties 14:06:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:06:33 INFO - Writing to file /builds/slave/test/properties/build_url 14:06:33 INFO - Contents: 14:06:33 INFO - build_url:https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 14:06:34 INFO - Running post-action listener: _resource_record_post_action 14:06:34 INFO - Running post-action listener: set_extra_try_arguments 14:06:34 INFO - ##### 14:06:34 INFO - ##### Running populate-webroot step. 14:06:34 INFO - ##### 14:06:34 INFO - Running pre-action listener: _resource_record_pre_action 14:06:34 INFO - Running main action method: populate_webroot 14:06:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:06:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:06:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:06:34 INFO - '--tppagecycles', 14:06:34 INFO - '7'], 14:06:34 INFO - 'tests': ['tsvgm']}}, 14:06:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:06:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:06:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 14:06:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:06:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:06:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'tests': ['damp', 'tps']}, 14:06:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['damp', 'tps']}, 14:06:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:06:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:06:34 INFO - 'other': {'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'other_l64': {'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'other_nol64': {'tests': ['a11yr', 14:06:34 INFO - 'ts_paint', 14:06:34 INFO - 'tpaint', 14:06:34 INFO - 'sessionrestore', 14:06:34 INFO - 'sessionrestore_no_auto_restore', 14:06:34 INFO - 'tabpaint']}, 14:06:34 INFO - 'svgr': {'tests': ['tsvgx', 14:06:34 INFO - 'tsvgr_opacity', 14:06:34 INFO - 'tart', 14:06:34 INFO - 'tscrollx', 14:06:34 INFO - 'cart']}, 14:06:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['tsvgx', 14:06:34 INFO - 'tsvgr_opacity', 14:06:34 INFO - 'tart', 14:06:34 INFO - 'tscrollx', 14:06:34 INFO - 'cart']}, 14:06:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'tests': ['tp5o']}, 14:06:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'talos_options': ['--e10s'], 14:06:34 INFO - 'tests': ['tp5o']}, 14:06:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'talos_options': ['--xperf_path', 14:06:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:34 INFO - 'tests': ['tp5n']}, 14:06:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:06:34 INFO - 'talos_options': ['--e10s', 14:06:34 INFO - '--xperf_path', 14:06:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:06:34 INFO - 'tests': ['tp5n']}}, 14:06:34 INFO - 'talos.zip': {'path': '', 14:06:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:06:34 INFO - Running post-action listener: _resource_record_post_action 14:06:34 INFO - ##### 14:06:34 INFO - ##### Running create-virtualenv step. 14:06:34 INFO - ##### 14:06:34 INFO - Running pre-action listener: _resource_record_pre_action 14:06:34 INFO - Running main action method: create_virtualenv 14:06:34 INFO - Creating virtualenv /builds/slave/test/build/venv 14:06:34 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 14:06:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:06:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:06:34 INFO - Using real prefix '/tools/python27' 14:06:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:06:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:06:38 INFO - Installing pip.................done. 14:06:38 INFO - Return code: 0 14:06:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:06:38 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')]} 14:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:38 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')]} 14:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:38 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 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:38 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 14:06:38 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 14:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:38 INFO - 'HOME': '/Users/cltbld', 14:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:38 INFO - 'LOGNAME': 'cltbld', 14:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:38 INFO - 'MOZ_NO_REMOTE': '1', 14:06:38 INFO - 'NO_EM_RESTART': '1', 14:06:38 INFO - 'PAGER': '/bin/cat', 14:06:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:38 INFO - 'PWD': '/builds/slave/test', 14:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:38 INFO - 'SHELL': '/bin/bash', 14:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:38 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:38 INFO - 'USER': 'cltbld', 14:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:38 INFO - 'XPC_FLAGS': '0x0', 14:06:38 INFO - 'XPC_SERVICE_NAME': '0', 14:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:38 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 14:06:38 INFO - Cleaning up... 14:06:38 INFO - Return code: 0 14:06:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:06:38 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')]} 14:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:38 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')]} 14:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 14:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:38 INFO - 'HOME': '/Users/cltbld', 14:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:38 INFO - 'LOGNAME': 'cltbld', 14:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:38 INFO - 'MOZ_NO_REMOTE': '1', 14:06:38 INFO - 'NO_EM_RESTART': '1', 14:06:38 INFO - 'PAGER': '/bin/cat', 14:06:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:38 INFO - 'PWD': '/builds/slave/test', 14:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:38 INFO - 'SHELL': '/bin/bash', 14:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:38 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:38 INFO - 'USER': 'cltbld', 14:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:38 INFO - 'XPC_FLAGS': '0x0', 14:06:38 INFO - 'XPC_SERVICE_NAME': '0', 14:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:38 INFO - Downloading/unpacking psutil>=0.7.1 14:06:38 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 14:06:38 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 14:06:38 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 14:06:38 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 14:06:38 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 14:06:38 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 14:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:06:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:42 INFO - Installing collected packages: psutil 14:06:42 INFO - Running setup.py install for psutil 14:06:42 INFO - building 'psutil._psutil_osx' extension 14:06:42 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 14:06:42 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 14:06:42 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 14:06:43 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 14:06:43 INFO - building 'psutil._psutil_posix' extension 14:06:43 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 14:06:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:06:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:06:43 INFO - ^ 14:06:43 INFO - 1 warning generated. 14:06:43 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 14:06:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:06:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:06:43 INFO - Successfully installed psutil 14:06:43 INFO - Cleaning up... 14:06:43 INFO - Return code: 0 14:06:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:06:43 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')]} 14:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 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')]} 14:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:06:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:06:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:43 INFO - 'HOME': '/Users/cltbld', 14:06:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:43 INFO - 'LOGNAME': 'cltbld', 14:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:43 INFO - 'MOZ_NO_REMOTE': '1', 14:06:43 INFO - 'NO_EM_RESTART': '1', 14:06:43 INFO - 'PAGER': '/bin/cat', 14:06:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:43 INFO - 'PWD': '/builds/slave/test', 14:06:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:43 INFO - 'SHELL': '/bin/bash', 14:06:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:43 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:43 INFO - 'USER': 'cltbld', 14:06:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:43 INFO - 'XPC_FLAGS': '0x0', 14:06:43 INFO - 'XPC_SERVICE_NAME': '0', 14:06:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:06:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:06:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:06:46 INFO - Installing collected packages: mozsystemmonitor 14:06:46 INFO - Running setup.py install for mozsystemmonitor 14:06:46 INFO - Successfully installed mozsystemmonitor 14:06:46 INFO - Cleaning up... 14:06:46 INFO - Return code: 0 14:06:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:46 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 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:46 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 14:06:46 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 14:06:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:46 INFO - 'HOME': '/Users/cltbld', 14:06:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:46 INFO - 'LOGNAME': 'cltbld', 14:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:46 INFO - 'MOZ_NO_REMOTE': '1', 14:06:46 INFO - 'NO_EM_RESTART': '1', 14:06:46 INFO - 'PAGER': '/bin/cat', 14:06:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:46 INFO - 'PWD': '/builds/slave/test', 14:06:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:46 INFO - 'SHELL': '/bin/bash', 14:06:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:46 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:46 INFO - 'USER': 'cltbld', 14:06:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:46 INFO - 'XPC_FLAGS': '0x0', 14:06:46 INFO - 'XPC_SERVICE_NAME': '0', 14:06:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:46 INFO - Downloading/unpacking blobuploader==1.2.4 14:06:46 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 14:06:46 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 14:06:46 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 14:06:46 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 14:06:46 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 14:06:46 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 14:06:49 INFO - Downloading blobuploader-1.2.4.tar.gz 14:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:06:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:06:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:49 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 14:06:50 INFO - Downloading docopt-0.6.1.tar.gz 14:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:06:50 INFO - Installing collected packages: blobuploader, requests, docopt 14:06:50 INFO - Running setup.py install for blobuploader 14:06:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:06:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:06:50 INFO - Running setup.py install for requests 14:06:51 INFO - Running setup.py install for docopt 14:06:51 INFO - Successfully installed blobuploader requests docopt 14:06:51 INFO - Cleaning up... 14:06:51 INFO - Return code: 0 14:06:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:51 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 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:51 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 14:06:51 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 14:06:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:51 INFO - 'HOME': '/Users/cltbld', 14:06:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:51 INFO - 'LOGNAME': 'cltbld', 14:06:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:51 INFO - 'MOZ_NO_REMOTE': '1', 14:06:51 INFO - 'NO_EM_RESTART': '1', 14:06:51 INFO - 'PAGER': '/bin/cat', 14:06:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:51 INFO - 'PWD': '/builds/slave/test', 14:06:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:51 INFO - 'SHELL': '/bin/bash', 14:06:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:51 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:51 INFO - 'USER': 'cltbld', 14:06:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:51 INFO - 'XPC_FLAGS': '0x0', 14:06:51 INFO - 'XPC_SERVICE_NAME': '0', 14:06:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:06:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-HxnsVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:06:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Bf1WcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:06:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-DtROF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:06:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-HPkiuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:06:51 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vcsXJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-8uYmN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-cbUVmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-FVour8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-15d1fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-W6NE9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-kOks1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-WxEecC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-H1Id6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-UQbL8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:52 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Tp2t_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:53 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-lZRZ7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:53 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-omfFt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:06:53 INFO - Running setup.py install for manifestparser 14:06:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:06:53 INFO - Running setup.py install for mozcrash 14:06:53 INFO - Running setup.py install for mozdebug 14:06:53 INFO - Running setup.py install for mozdevice 14:06:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:06:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:06:53 INFO - Running setup.py install for mozfile 14:06:54 INFO - Running setup.py install for mozhttpd 14:06:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Running setup.py install for mozinfo 14:06:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Running setup.py install for mozInstall 14:06:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Running setup.py install for mozleak 14:06:54 INFO - Running setup.py install for mozlog 14:06:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Running setup.py install for moznetwork 14:06:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:06:54 INFO - Running setup.py install for mozprocess 14:06:55 INFO - Running setup.py install for mozprofile 14:06:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:06:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:06:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:06:55 INFO - Running setup.py install for mozrunner 14:06:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:06:55 INFO - Running setup.py install for mozscreenshot 14:06:55 INFO - Running setup.py install for moztest 14:06:55 INFO - Running setup.py install for mozversion 14:06:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:06:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:06:55 INFO - Cleaning up... 14:06:55 INFO - Return code: 0 14:06:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:55 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 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:06:55 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 14:06:55 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 14:06:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:06:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:06:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:06:55 INFO - 'HOME': '/Users/cltbld', 14:06:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:06:55 INFO - 'LOGNAME': 'cltbld', 14:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:55 INFO - 'MOZ_NO_REMOTE': '1', 14:06:55 INFO - 'NO_EM_RESTART': '1', 14:06:55 INFO - 'PAGER': '/bin/cat', 14:06:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:55 INFO - 'PWD': '/builds/slave/test', 14:06:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:06:55 INFO - 'SHELL': '/bin/bash', 14:06:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:06:55 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:06:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:06:55 INFO - 'USER': 'cltbld', 14:06:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:06:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:55 INFO - 'XPC_FLAGS': '0x0', 14:06:55 INFO - 'XPC_SERVICE_NAME': '0', 14:06:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-mjBcZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-VWHxTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-XFNZCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vtLxmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-NkFwdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Hj_6eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-NEWRlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:06:56 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)) 14:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:06:56 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-zBVQJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-97VUHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3RqNd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Oinalj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-N0qgjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-NATFny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CvuyIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-RV_AB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Gddhbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:57 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)) 14:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:57 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-RFYchm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:57 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)) 14:06:57 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)) 14:06:57 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)) 14:06:57 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)) 14:06:57 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)) 14:06:57 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)) 14:06:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:06:57 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 14:06:57 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 14:06:57 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 14:06:57 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 14:06:57 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 14:06:57 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 14:07:00 INFO - Downloading blessings-1.6.tar.gz 14:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:07:00 INFO - Installing collected packages: blessings 14:07:00 INFO - Running setup.py install for blessings 14:07:00 INFO - Successfully installed blessings 14:07:00 INFO - Cleaning up... 14:07:00 INFO - Return code: 0 14:07:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:07:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:01 INFO - Reading from file tmpfile_stdout 14:07:01 INFO - Current package versions: 14:07:01 INFO - blessings == 1.6 14:07:01 INFO - blobuploader == 1.2.4 14:07:01 INFO - docopt == 0.6.1 14:07:01 INFO - manifestparser == 1.1 14:07:01 INFO - mozInstall == 1.12 14:07:01 INFO - mozcrash == 0.17 14:07:01 INFO - mozdebug == 0.1 14:07:01 INFO - mozdevice == 0.48 14:07:01 INFO - mozfile == 1.2 14:07:01 INFO - mozhttpd == 0.7 14:07:01 INFO - mozinfo == 0.9 14:07:01 INFO - mozleak == 0.1 14:07:01 INFO - mozlog == 3.1 14:07:01 INFO - moznetwork == 0.27 14:07:01 INFO - mozprocess == 0.22 14:07:01 INFO - mozprofile == 0.28 14:07:01 INFO - mozrunner == 6.11 14:07:01 INFO - mozscreenshot == 0.1 14:07:01 INFO - mozsystemmonitor == 0.0 14:07:01 INFO - moztest == 0.7 14:07:01 INFO - mozversion == 1.4 14:07:01 INFO - psutil == 3.1.1 14:07:01 INFO - requests == 1.2.3 14:07:01 INFO - wsgiref == 0.1.2 14:07:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 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 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:07:01 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 14:07:01 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 14:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:01 INFO - 'HOME': '/Users/cltbld', 14:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:01 INFO - 'LOGNAME': 'cltbld', 14:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:01 INFO - 'MOZ_NO_REMOTE': '1', 14:07:01 INFO - 'NO_EM_RESTART': '1', 14:07:01 INFO - 'PAGER': '/bin/cat', 14:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:01 INFO - 'PWD': '/builds/slave/test', 14:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:01 INFO - 'SHELL': '/bin/bash', 14:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:07:01 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:01 INFO - 'USER': 'cltbld', 14:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:01 INFO - 'XPC_FLAGS': '0x0', 14:07:01 INFO - 'XPC_SERVICE_NAME': '0', 14:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 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)) 14:07:01 INFO - Cleaning up... 14:07:01 INFO - Return code: 0 14:07:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:07:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:07:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:01 INFO - 'HOME': '/Users/cltbld', 14:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:01 INFO - 'LOGNAME': 'cltbld', 14:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:01 INFO - 'MOZ_NO_REMOTE': '1', 14:07:01 INFO - 'NO_EM_RESTART': '1', 14:07:01 INFO - 'PAGER': '/bin/cat', 14:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:01 INFO - 'PWD': '/builds/slave/test', 14:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:01 INFO - 'SHELL': '/bin/bash', 14:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:07:01 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:01 INFO - 'USER': 'cltbld', 14:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:01 INFO - 'XPC_FLAGS': '0x0', 14:07:01 INFO - 'XPC_SERVICE_NAME': '0', 14:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:01 INFO - Downloading/unpacking jsonschema 14:07:01 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 14:07:01 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 14:07:01 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 14:07:01 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 14:07:01 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 14:07:01 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 14:07:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:07:03 INFO - Downloading/unpacking functools32 (from jsonschema) 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07:04 INFO - Downloading functools32-3.2.3-2.tar.gz 14:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:07:04 INFO - warning: no files found matching '*.txt' 14:07:04 INFO - no previously-included directories found matching 'build' 14:07:04 INFO - no previously-included directories found matching 'dist' 14:07:04 INFO - no previously-included directories found matching '.git*' 14:07:04 INFO - Installing collected packages: jsonschema, functools32 14:07:04 INFO - Running setup.py install for functools32 14:07:04 INFO - warning: no files found matching '*.txt' 14:07:04 INFO - no previously-included directories found matching 'build' 14:07:04 INFO - no previously-included directories found matching 'dist' 14:07:04 INFO - no previously-included directories found matching '.git*' 14:07:04 INFO - Successfully installed jsonschema functools32 14:07:04 INFO - Cleaning up... 14:07:04 INFO - Return code: 0 14:07:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:07:04 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')]} 14:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:04 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')]} 14:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:07:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1067b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067bf4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89a058eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, '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.V6uFOogb87/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.lyS1BJ9VQj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 14:07:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:04 INFO - 'HOME': '/Users/cltbld', 14:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:04 INFO - 'LOGNAME': 'cltbld', 14:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:04 INFO - 'MOZ_NO_REMOTE': '1', 14:07:04 INFO - 'NO_EM_RESTART': '1', 14:07:04 INFO - 'PAGER': '/bin/cat', 14:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:04 INFO - 'PWD': '/builds/slave/test', 14:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:04 INFO - 'SHELL': '/bin/bash', 14:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:07:04 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:04 INFO - 'USER': 'cltbld', 14:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:04 INFO - 'XPC_FLAGS': '0x0', 14:07:04 INFO - 'XPC_SERVICE_NAME': '0', 14:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:07:04 INFO - Downloading/unpacking flake8 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:04 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 14:07:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:07:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:07:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07: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 14:07:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:07:08 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 14:07:08 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 14:07:08 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 14:07:08 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 14:07:08 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 14:07:08 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 14:07:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:07:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:08 INFO - no previously-included directories found matching 'docs/_build' 14:07:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:07:08 INFO - Running setup.py install for pep8 14:07:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:07:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:07:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:07:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:07:08 INFO - no previously-included directories found matching 'docs/_build' 14:07:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:07:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:07:08 INFO - Cleaning up... 14:07:08 INFO - Return code: 0 14:07:08 INFO - Running post-action listener: _resource_record_post_action 14:07:08 INFO - Running post-action listener: _start_resource_monitoring 14:07:09 INFO - Starting resource monitoring. 14:07:09 INFO - ##### 14:07:09 INFO - ##### Running install step. 14:07:09 INFO - ##### 14:07:09 INFO - Running pre-action listener: _resource_record_pre_action 14:07:09 INFO - Running main action method: install 14:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:07:09 INFO - Reading from file tmpfile_stdout 14:07:09 INFO - Detecting whether we're running mozinstall >=1.0... 14:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:07:09 INFO - Reading from file tmpfile_stdout 14:07:09 INFO - Output received: 14:07:09 INFO - Usage: mozinstall [options] installer 14:07:09 INFO - Options: 14:07:09 INFO - -h, --help show this help message and exit 14:07:09 INFO - -d DEST, --destination=DEST 14:07:09 INFO - Directory to install application into. [default: 14:07:09 INFO - "/builds/slave/test"] 14:07:09 INFO - --app=APP Application being installed. [default: firefox] 14:07:09 INFO - mkdir: /builds/slave/test/build/application 14:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:07:41 INFO - Reading from file tmpfile_stdout 14:07:41 INFO - Output received: 14:07:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:07:41 INFO - Running post-action listener: _resource_record_post_action 14:07:41 INFO - ##### 14:07:41 INFO - ##### Running run-tests step. 14:07:41 INFO - ##### 14:07:41 INFO - Running pre-action listener: _resource_record_pre_action 14:07:41 INFO - Running main action method: run_tests 14:07:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:41 INFO - 2016-04-24 14:07:41.090 screenresolution[1646:7985] starting screenresolution argv=screenresolution get 14:07:41 INFO - 2016-04-24 14:07:41.092 screenresolution[1646:7985] Display 0: 1600x1200x32@60 14:07:41 INFO - 2016-04-24 14:07:41.098 screenresolution[1647:7987] starting screenresolution argv=screenresolution list 14:07:41 INFO - Available Modes on Display 0 14:07:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:07:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:07:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:41 INFO - Intel Iris: 14:07:41 INFO - Chipset Model: Intel Iris 14:07:41 INFO - Type: GPU 14:07:41 INFO - Bus: Built-In 14:07:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:41 INFO - Vendor: Intel (0x8086) 14:07:41 INFO - Device ID: 0x0a2e 14:07:41 INFO - Revision ID: 0x0009 14:07:41 INFO - Displays: 14:07:41 INFO - Display: 14:07:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:41 INFO - Display Serial Number: 1600x1200 60 14:07:41 INFO - Main Display: Yes 14:07:41 INFO - Mirror: Off 14:07:41 INFO - Online: Yes 14:07:41 INFO - Rotation: Supported 14:07:41 INFO - Return code: 0 14:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:07:41 INFO - Python 2.7.3 14:07:41 INFO - Return code: 0 14:07:41 INFO - grabbing minidump binary from tooltool 14:07: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')]} 14:07:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10665cd30>, '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 0x1064c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106653b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:07:41 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 14:07:41 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 14:07:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:07:41 INFO - Return code: 0 14:07:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:07:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:07:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:07:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:07:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:07:42 INFO - Return code: 0 14:07:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 14:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 14:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V6uFOogb87/Render', 14:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:07:42 INFO - 'HOME': '/Users/cltbld', 14:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:07:42 INFO - 'LOGNAME': 'cltbld', 14:07:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:07:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:07:42 INFO - 'MOZ_NO_REMOTE': '1', 14:07:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:07:42 INFO - 'NO_EM_RESTART': '1', 14:07:42 INFO - 'PAGER': '/bin/cat', 14:07:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:07:42 INFO - 'PWD': '/builds/slave/test', 14:07:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:07:42 INFO - 'SHELL': '/bin/bash', 14:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lyS1BJ9VQj/Listeners', 14:07:42 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 14:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:07:42 INFO - 'USER': 'cltbld', 14:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:07:42 INFO - 'XPC_FLAGS': '0x0', 14:07:42 INFO - 'XPC_SERVICE_NAME': '0', 14:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:07:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 14:07:42 INFO - mozversion application_buildid: 20160424125552 14:07:42 INFO - mozversion application_changeset: 98da47b4d83c576ce17d189942d2655df5a36dc1 14:07:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:07:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:07:42 INFO - mozversion application_name: Firefox 14:07:42 INFO - mozversion application_remotingname: firefox-dev 14:07:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:07:42 INFO - mozversion application_vendor: Mozilla 14:07:42 INFO - mozversion application_version: 47.0a2 14:07:42 INFO - mozversion platform_buildid: 20160424125552 14:07:42 INFO - mozversion platform_changeset: 98da47b4d83c576ce17d189942d2655df5a36dc1 14:07:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:07:42 INFO - mozversion platform_version: 47.0a2 14:07:42 INFO - using testdate: 1461532062 14:07:42 INFO - actual date: 1461532062 14:07:42 INFO - starting webserver on 'localhost:49275' 14:07:42 INFO - SUITE-START | Running 2 tests 14:07:42 INFO - TEST-START | dromaeo_css 14:07:42 INFO - operating with platform_type : mac_ 14:07:42 INFO - Initialising browser for dromaeo_css test... 14:07:42 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpG9td55/profile http://localhost:49275/getInfo.html) 14:07:45 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 14:07:45 INFO - PROCESS | 1665 | colorDepth:24 14:07:45 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 14:07:45 INFO - PROCESS | 1665 | __metrics 14:07:45 INFO - PROCESS | 1665 | 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" 14:07:45 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:07:45 INFO - TEST-INFO | 1665: exit 0 14:07:45 INFO - Browser initialized. 14:07:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:07:45 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpG9td55/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:07:57 INFO - PROCESS | 1668 | RSS: Main: 208076800 14:07:57 INFO - PROCESS | 1668 | 14:08:48 INFO - PROCESS | 1668 | 1461532128706 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] 14:10:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:11:00 INFO - PROCESS | 1668 | RSS: Main: 308682752 14:11:00 INFO - PROCESS | 1668 | 14:14:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:14:03 INFO - PROCESS | 1668 | RSS: Main: 343543808 14:14:03 INFO - PROCESS | 1668 | 14:17:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:17:06 INFO - PROCESS | 1668 | RSS: Main: 349134848 14:17:06 INFO - PROCESS | 1668 | 14:20:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:20:08 INFO - PROCESS | 1668 | RSS: Main: 304119808 14:20:08 INFO - PROCESS | 1668 | 14:23:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 14:23:12 INFO - PROCESS | 1668 | RSS: Main: 308101120 14:23:12 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 14:26:15 INFO - PROCESS | 1668 | RSS: Main: 354713600 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | __start_tp_report 14:26:15 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 14:26:15 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 14:26:15 INFO - PROCESS | 1668 | |i|pagename|runs| 14:26:15 INFO - PROCESS | 1668 | |0;dojo.html;3424.143964008998;3484.547008888844;3514.174169070268;3516.2791627716288;3559.8576056957722;5704.229928959599;5706.058500347438;5709.771609135635;5711.8000869969555;5720.628159169656;5701.800436984707;5739.483446489815;5739.713014349287;5747.224124743158;5758.683272420015;5099.796008159669;5137.409197942228;5151.74241287936;5201.063808514466;5229.084910140729;5222.738863056851;5235.450277720842;5244.423113457517;5244.868878278055;5253.684778913258;5184.507471790174;5208.140656791625;5240.528352448279;5243.580513558925;5249.580033597322;5203.609729270301;5241.344831896013;5251.527362537352;5262.500062494057;5295.4704529547125;5020.347354843883;5170.586353091744;5192.350956130484;5194.428612852583;5221.190715439113;5624.240727501776;5630.831075067755;5643.463870932255;5655.632383895075;5666.829995100154;6120.908186377208;6268.717907694165;6277.4664153547;6282.811515654537;6292.716827742717;6245.56281060326;6256.15541901842;6265.624062556216;6315.494760419156;6318.684065796691;5790.913136302959;5812.709364531756;5819.127130930352;5834.766609335579;5863.973804584223;6210.627362358183;6274.9686251569365;6277.52918531112;6290.748370065146;6298.0552917062805;6015.639061656315;6039.234072692226;6071.544634152457;6076.726547305382;6094.603850749687;6214.1921550198185;6225.968870155621;6315.652639104802;6329.272133704683;6335.36646335372;5708.801151758177;6187.81436556904;6216.689165541705;6246.312905580383;6251.78118765841;5650.152477128381;5918.4377484139795;6054.273487181566;6131.724072416752;6275.623462592168;4852.078105160008;4855.6601037927;4857.8299759508245;4869.391326084239;4876.804927802919;4452.510223875371;4459.331100334911;4479.888002799891;4486.75322857246;4491.505934347219;5620.494155526022;5681.488666019995;5702.030654788696;5763.6541807491685;5796.043652797325;6240.282367527701;6265.27949285838;6276.560640755103;6297.842553936188;6302.779402720975;5618.719064046782;5637.098064309773;5647.661140331593;5647.6893770843;5692.772289108472;5270.077736396192;5358.946410535923;5359.598030147755;5420.810271640474;5446.782128714886;6294.622322660655;6312.274088479791;6338.809835704937;6350;6372.267189273293;6336.936630633819;6337.588056776295;6355.872882542416;6362.141110949962;6370.1718776558755;5319.734013299397;6241.438270555672;6290.46531044848;6343.270523889812;6353.809385718436;6212.161358216764;6225.782097626561;6226.50187984951;6244.250689917239;6244.562880598314;6302.401271879165;6320.652364120017;6326.2092238470195;6340.556161068681;6341.968290158519;5943;6071.392860713893;6119.265688117454;6169.2288463942;6185.567010309235;6086.462455517837;6092.969535152296;6101.267847858286;6106.855059071508;6110.991686371699;5726.9713651431475;5869.413058694096;5893.174955506318;5924.407559244041;5929;5158.993996170861;5948.196993405835;6022.156898034191;6052.334243233153;6095;5924.348321684697;5934.43622855828;5949.315828679757;5951.107333899777;5953.9404605955115 14:26:15 INFO - PROCESS | 1668 | |1;ext.html;449.2183600535068;509.12176495545214;629.3989240275537;666.0874601795541;668.3083009085595;31410.371792564136;31938.68061319386;31949.201269968235;31975.68024319756;32045.51931721026;33121.17197070074;41532.754017379455;41889;41988.37017444741;41990.790046049806;9583.616655333779;9798.6080556778;9804.31369804114;9807.90192098077;9817.95091024544;9622.711318660451;9742.9512852436;9764.414135151914;9770.755731106743;9775.022497750238;8280.420370574062;9718.514074296292;9805.705828825112;9819.705408837746;9820.50897455128;9693.612255509772;9927.453990030546;9935.950320248425;9936.155426788731;9945.651902183388;9763.365381250196;9872;9890.109890109925;9890.90109098907;9913.256505762096;42196;42654;42676.14647707059;42845.57154428447;43003.569964300266;3164.6202455705234;3341.331733653279;3403.710684591813;3847.172857835551;3918.8824335269987;28309.292267693472;28316;28353.291167720767;29115.56326655102;29531.261718456997;2555.527227462907;2565.371483986435;2631.1317265302596;2646.827956182842;2648.6159506871395;473739;474623;475342;476971;476991.9999999965;48413;48505.272420913716;48518.75740621345;48683.75658121687;68754.65622671954;47794.28308575374;47838;47881.8029549264;47962.760186198844;48021.03957920892;17234.569135771457;17355.56611084733;17367.913160434116;17392.47822565325;17401.65196696059;13730.65673358174;13770.449182032808;13787.724245515034;13793.448262069405;13893.513727019506;12997.545085921947;15558.766618500731;15592.986455880331;15615.219239038002;15772;5406.864828379322;6617.239017512951;6618.437432818168;6666.366695163952;6674.2658307586125;978.5254151736544;1036.2694300518117;1064.3826580583243;1109.9132041912287;1196.2045239915358;30356;30426.847865760974;30937.91717289929;31068.68931310658;31138;1112.933224006562;1158.1429741990867;1164.057113737875;1212.9150959432754;1252.843394575678;14715.779263311058;14773.40906363755;14901.478448254258;15079.623009424851;15122.319495622723;642799;649830;653687;654710;655030;63248.683756580926;63830;63851;63858.68070659617;64035.719285614956;1418.2128918450153;1517.3627076627843;1529.2124555853745;1557.369265447473;1562.867156291728;29142.97999570005;29678.258043549085;29789.851050744608;30112.548311774903;30516;48393;48498.27252591284;49260;49342.25986610276;49588.752056239486;340627;343909;344040;344107;344438;29654.110376688735;33833.49249761305;34465.96602101941;34628.30743385169;34693.47959780555;41759;42304.15391692211;42557.36163957481;43985;44142.77928610337;34395.4840677385;35446.822765886005;35579.466308004885;35622;36379.63620363762;35384.46923296204;35442;35718.82140589385;36171.819140904125;37643.435348470346;36731.44902826406;36946.261074777816;37172.44241336436;37488.437673434375;38055.238895222494;31076;31889.31821014583;31982.68017319797;32084.5187322195;32173.67826321707;18111.547211319823;18171;24785.50428991446;25104.62343064892;25208.495830082928 14:26:15 INFO - PROCESS | 1668 | |2;jquery.html;308.75454014058823;387.74796382351485;390.7206347461565;395.3930716350401;399.9860004899829;20279.59440811183;20911.16355345784;21045.368638940818;21278.89360553197;21809.345719628396;20171;20867;20923.16307347708;21145.788542114573;21200.19798317141;5812.796552120677;6000.819975400723;6044.24446944132;6044.455998960093;6066.454019138277;4104.466419365478;4286.5284818669925;5681.23303354046;5745.195672605818;5998.310194327664;5959.672218028007;5972.701364931758;5976.760929562813;5983.371745966676;5989.43100405463;5955.731992060368;6190.50475961922;6289.591176573508;6428.421442070213;6490.578112422678;5687.800926967537;5689.630174038674;5689.829305120853;5698.800541981052;5700.800471983503;24621.645809480473;25202.369940751443;26137.73862261391;26482.205533834014;26512.46975060488;1686.7891513560805;2083.9270625528034;2112.926047588327;2188.39819049761;2718.211718601603;10077.496125193784;10270;10524.842127368096;12216.816747748791;15372.923135384362;1758.2703178181039;1762.7003409420302;1802.9819701802944;1830.560665440298;1831.9267229310813;111775.88224117736;111842;114425.28362074493;114861.85138148595;115357.4232128834;11511.48198331077;11587.88412115885;11589.6523104307;11630.302181868945;11727.941360293144;14433.20617366034;14492.130472171704;14535.982481226212;14541.927290363481;14551.490697825526;8634.309255259564;8907.73276801697;8919.1972722454;8929.598168082452;9000.684976025808;9091.454512729126;9092.954535227282;9146.771330716652;9193.540322983825;9232.815343693088;4509.278515437514;4581.679282450196;4597.333386658983;4637.768111594407;4654.37165982588;1818.3363072478714;1839.7554982920815;1870.803565625617;1916.2501809902833;1924.499630096185;880.55972013993;952.8665986761858;992.9453880036523;999.6950929966284;1061.6761887624193;21058.89470552668;21388.572228555342;21500.677489837664;21513.67729484059;21639.56720865574;899.6401439424152;993.5826952679892;1006.7533454303596;1006.9395836249848;1055.9736006599742;11385.544578216779;11621.941890290494;11727.885850844155;11776.470058847372;11819.940900295443;590280;622789.7721022823;633146;636266;639656;105457.47271263595;121397.17904231267;123886;125005.34716799039;125788.59739909059;924.7641851327868;1014.077189757332;1022.6318525330875;1034.2191645307744;1048.5386429970788;13267.137636053625;18778.859929812454;18953.620927581647;19523.70714439256;19654.508637284183;38842.805785970886;50443;51303.743481282356;52138.217926731886;52170.95658086893;69080.6545967267;71930.64034679793;73800.52398951883;73959;73987.63006184934;8045.919540804517;8123.147069557847;8343.582820859054;8403.789905252173;8584.699535516227;9253.814923701622;13211.603651890458;13617.591472255848;13754.65613359634;14076.296185190495;11246.550137994389;11689.824652630048;11701.765964680488;11808.114391420506;11856.940715296369;10284.485775711335;11571.363575003455;11676.182667213214;11690.298582085103;12002.21985570935;11903.642890713292;11985.28088314704;12023.398830058637;12149;12221.450034748636;8018.037835459782;8378.329733621174;8545.871811922701;8593.742187734379;8757.299416046828;10418.791624167627;11728.296302221554;11891.345975971404;11915.880841191478;12089.637310880687 14:26:15 INFO - PROCESS | 1668 | |3;mootools.html;1515.3029606381065;1673.2721266249182;1695.9236834342453;2042.9182832686693;2088.603165398574;8221.05457872344;8818.118188181179;8982.865257021134;8999;9007.59465824038;8843.513606751641;9093.90906090939;9094.408863423872;9099;9108.954455227731;2269.8638081715067;2516.421223118684;2549.031368080127;2574.987125064372;2575.961360579593;2579.3809485723386;2586.948261034778;2588.3011586871626;2590.507803517335;2590.961135582968;2483.962740558884;2533.2526904563197;2535.961960570584;2536.1503896194795;2536.4546622476164;2491.2526242127287;2550.8979640814346;2556.961645575318;2557.194483737626;2557.565213913639;2519.735427780075;2527.684039495063;2540.83484573504;2547.643329933811;2573.794096472278;9056.818863622757;9112.134347237003;9125.863112053257;9136.269098472172;9139.360244782867;5559.916601250984;5724.427557244284;5771.567132465082;5775.075987841925;5778.3066032076;3568.3933731265747;3624.637536246354;3697.3899306614376;3715.702743780491;3736.9439458408146;1039.4127318065343;1045.0490054050854;1045.4877110216016;1052.1740433759462;1101.1741194104422;12292.910205265127;12296.385180740926;12297.3236471994;12302.81545776814;12342.321172335518;4345.217860785089;4923.212286034217;4983.9252411213865;5115.616328775282;5124.948750512447;10425.530851111718;10438.112760415297;10438.217133715001;10439.739006524898;10492.475376231157;5728.255326807488;5748.850229953942;5753.827385178452;5756;5763.337216220105;4578.084383123389;4737.076270127292;4993.90012199754;5177.974110129425;5202.92195617066;4055.047063939972;4079.3676980068135;4122.732022418533;4142.79286035697;4146.398772178078;1355.5187908292414;1363.0731102850157;1363.3864760857455;1364.1542243808901;1382.3572039001913;1388.5209602687057;1411.6541447345467;1432.8065711129072;1433.254707552067;1433.7132573485344;8390.37072219586;8561.400701950928;8587.613557389932;8588.570571471402;8610.956945215234;1250.6560695808726;1252.83713117296;1259.5213818748816;1268.346801397281;1273.6688461000206;6462;6463.644499552475;6523.18460192476;6534.738610455529;6542.738290468423;10586.258961872749;10935.507902144263;10942.343459392461;11055.06031987274;11093.72265693348;10809.297395669257;10892.455377231265;10896.673099807018;10996.28524145944;11044.834327485094;8567.528785916835;8700.869486957701;8767.298616110696;8815.955920220358;8858.424202426822;6759.459243260626;8130.268275855202;8197.590120493833;8272.917270827214;8279.751607451786;9855.408675479493;10025.899741002788;10110.342827716175;10264.538095785856;10272.486375681037;10276.691699249035;10319.277650564389;10456.63401780934;10600.04599586041;10624.468776561296;6577.704003319766;6761.560498567577;7020.403265722471;7021.1574611047;7342.48602597813;8594.957025214833;8646.567671616269;8671.696490622799;8671.826563468569;8689.217970382695;7530.28462296081;7668.6549105291515;7731.536107833548;7747.883781743166;7754.185810489817;7510.474266801271;7513.924860751323;7574.734884279023;7706.229377062249;7773.611319434119;8015;8080.757577272691;8105.716299929474;8108.797280068045;8114.026316842017;6922.826929326807;7148.535345202546;7162.247963963709;7167.964160179165;7287.599182045038;7225.674844631899;7248.891266630899;7280.9271907280245;7289.599072050876;7291.08861392326 14:26:15 INFO - PROCESS | 1668 | |4;prototype.html;337.0528814032568;341.80346300877;345.50247643393527;347.3521881690648;350.7159201047152;6516.696660667868;6557.90163147552;6617.073609694647;6620.470362371011;6639.468842492601;6480.189976252968;6493.870122597546;6502.447291980187;6517.217933847945;6520.9347906520925;4618.8152473901;4695;4716.292556116576;4807.471178170398;4838.975805120969;3870.825812838429;4797.856064318059;4801.423829140491;4802.471728109905;4803.279508073782;3628.87298944537;4726.66913316068;4739.668223224376;4765.690230135047;4798.7600619969035;4575.565321294472;4643.349931009644;4775.80896764129;4814.903701925977;4818.542238487373;4147.854825081107;4149.232392007458;4165;4172.979135104335;4174.540800511942;6259.436650701458;6269.028300613412;6274.247090349141;6290.528210384194;6295.559310848243;5678.545716342724;5698.202251684767;5702;5702.258706368187;5715.914261286084;6662.56693314933;6879.965600171967;6891.931080689229;6895.862082758316;6903.827404314882;4651.976740116312;4689.413823272091;4690.812367505296;4700.1539722849875;4703.623710103184;7236.819079523054;7278.563286202898;7278.672459739324;7295.16105647852;7297.124345078571;4742.881427964328;4749.121412538691;4754.358161648202;4754.5245475452175;4763.476017638057;4805.8077676892535;4822.927656085161;4833.879153021203;4835.081334546425;4844.491328410537;5664.8867022659315;5676.176954341598;5683.090705487102;5693.117566777156;5699.401562835926;6665.166854143232;6953.930460695429;6961.965190174118;6973.372396484341;6975.128108986377;1654.1315809200025;1676.8910020848605;1681.3946979087518;1691.6447546015474;1692.3146125819062;1032.4373216596973;1066.9519871605796;1091.5851976248975;1096.6381094238882;1125.454154734953;6256.5307601930035;6276.184096067482;6448.355164483608;6463.192100987377;6470.385313395314;7255.746048888263;7259.346658800733;7264.673089710976;7268.127824661075;7269.454790890704;5243.947560524422;5778.5377169826315;6349.746010159634;6420.711068001951;6428.967855160788;3250.3661785951986;3428.9314213715584;3706.777593344408;3817.3319669057805;3826.081740382288;7173.390261827803;7513.962430187814;7532.962335188289;7533.472656914073;7541.132769731441;7399.6670149843385;7451.031365922504;7460.403167746567;7468.439867009887;7483.289087536676;7485.588292643848;7500.662470188855;7512.812179695551;7517.6992920284765;7543.3965282778345;6927.3419025192525;7227.1327440707455;7229.349358557755;7230.385417239593;7253.310935461122;7064.505484616027;7103.857922841617;7119.466040046914;7127.180374257026;7135.750248741269;7487.251274872469;7496.7376141834775;7509.624518774149;7511.586862722603;7524.736634217776;3949.743266687656;4768.928466073081;4773;4776.426828780569;4785.138675038526;7187.460940429384;7212.927870721225;7213.60325182099;7222.783316500303;7225.747098851515;6729.1252137221845;6869.656517174221;6894.13823272091;6986.54587451814;6989.95150727395;5612.961602103624;5629.437056294338;6886.380225779503;6931.341522555349;6944.37500624926;6213.5339849510565;6791.796246112624;6794.694238759169;6796.932030679828;6806.285340039224;6681.33186681328;6919.065926100114;6927.826804329932;6939.930600694131;6942.097527321416;6590.7693230736695;6657.933420665925;6755.290694477009;6772.559783614246;6811.250762416031 14:26:15 INFO - PROCESS | 1668 | |5;yui.html;741.1624863903788;770.1643716567525;785.4501848705904;790.5296348672538;793.3732351442361;11478.540858365666;14973.02675326103;15052.322645480952;15083.245837708111;15083.472078477256;13860.514881979132;13937.954653400984;13946.790798138036;14303.499377521788;14491.202983835885;955.3933252384734;962.9903700963006;976.5117441279325;977.3891317926297;984.478226539935;965.1024547171128;995.3331268050388;997.6907158780765;1000.3297790480395;1000.8398656214971;1013.0426746724348;1044.5769463367349;1045.8483519889612;1045.9633912813053;1048.3395460859647;1014.761531040205;1039.9272050956436;1041.4792603698152;1042.5673345561584;1044.1907521670728;756.4440136499668;775.1163673412258;775.8487095016474;776.9417293702938;778.634042000259;13743.450261989508;13786.862131378659;13792.793108103386;13798.17210967345;13810.516631917935;11525.827112593317;11702.707432314175;11702.882971170264;11824.763504729943;11884.168108232341;8396.706115285997;9469.905300946972;9493.193078588329;9554.665586704501;9681.854772178423;3421.7775844570024;3425.092350527112;3469.5142680024815;3500.264944361687;3633.8001409922445;66611.66694166546;70563.23591910243;77385.22614773836;78451.60774196149;78876.21123788746;9699.175570076623;9737.659181928599;9786.993666298391;9903.80192396148;10221.182305415548;21749.45626359322;21901.342959711234;21907.890460547915;21909.452263693536;21936.780632193793;7590.316871481483;7637.770866874002;7639.312461878347;7677.692892284358;7738.806529836686;4090.734102283342;4114.670826333886;4167.458230430025;4216.265153135591;4285.635720963753;3613.837377317974;3749.643783840531;3787.4697542344093;3800.1069748609057;3800.562935262425;673.5117040145954;681.2404169351237;694.1669996004814;697.3653974882832;698.3365802487657;497.75112443778113;580.4978693430153;584.8859472402926;585.8154681275385;597.0834768630132;7331.706731730777;7663.961680191564;7840;8164.224398682116;8271.503709777433;474.64401698725953;573.8938296415176;583.201014609987;589.4047012517389;608.7260732670228;2965.733084022448;3764.3412402829395;3802.1825307559006;3942.250972315251;3994.2410941920944;91902.54048729714;93372.06627933633;95018.52490737502;95274.52362738142;95388.56917146248;62603.060954085726;66785.33214667888;77314;79691;80943.19056809356;68887.96668049983;71481.6425917867;71663;72233.91649125268;73024;7570.538221062806;7697.46117771773;7779.260970207822;7790.649420776192;7818.960905195438;51211.2318315233;51819.2227116586;51852.74073629608;51986.480135198166;52037;65331.67334163299;66465.66767166133;67742;69867;71517.28482715106;11227;11354.659360219206;11658.825117623412;11677.007454366134;12060.758784824078;12133.514659413877;12366.258024518558;12415.44130514147;12600.180988235736;12615.49538018469;11864.76270474603;12023.699407514881;12090;12216.816747748613;12229.082818788449;11588.88411115878;11799.76400472003;11977.880221197676;12041.217320874115;12215.38923053826;11829.76340473203;11933.701657458632;11965.581204657796;12072.577459788865;12101.394930253628;8694.695685650971;9874.506274686093;9909.157721593458;10025.5488503019;10130.189584833048;9707.805843883225;9891.505424728592;9946.629297236215;10083.193344532274;10187.541560629938 14:26:15 INFO - PROCESS | 1668 | __end_tp_report 14:26:15 INFO - PROCESS | 1668 | __start_cc_report 14:26:15 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1315 14:26:15 INFO - PROCESS | 1668 | __end_cc_report 14:26:15 INFO - PROCESS | 1668 | __startTimestamp1461533175313__endTimestamp 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | ------- Summary: start ------- 14:26:15 INFO - PROCESS | 1668 | Number of tests: 6 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5736.06 Median:5918.44 stddev:627.19 (10.6%) stddev-sans-first:602.32 14:26:15 INFO - PROCESS | 1668 | Values: 3424.1 3484.5 3514.2 3516.3 3559.9 5704.2 5706.1 5709.8 5711.8 5720.6 5701.8 5739.5 5739.7 5747.2 5758.7 5099.8 5137.4 5151.7 5201.1 5229.1 5222.7 5235.5 5244.4 5244.9 5253.7 5184.5 5208.1 5240.5 5243.6 5249.6 5203.6 5241.3 5251.5 5262.5 5295.5 5020.3 5170.6 5192.4 5194.4 5221.2 5624.2 5630.8 5643.5 5655.6 5666.8 6120.9 6268.7 6277.5 6282.8 6292.7 6245.6 6256.2 6265.6 6315.5 6318.7 5790.9 5812.7 5819.1 5834.8 5864.0 6210.6 6275.0 6277.5 6290.7 6298.1 6015.6 6039.2 6071.5 6076.7 6094.6 6214.2 6226.0 6315.7 6329.3 6335.4 5708.8 6187.8 6216.7 6246.3 6251.8 5650.2 5918.4 6054.3 6131.7 6275.6 4852.1 4855.7 4857.8 4869.4 4876.8 4452.5 4459.3 4479.9 4486.8 4491.5 5620.5 5681.5 5702.0 5763.7 5796.0 6240.3 6265.3 6276.6 6297.8 6302.8 5618.7 5637.1 5647.7 5647.7 5692.8 5270.1 5358.9 5359.6 5420.8 5446.8 6294.6 6312.3 6338.8 6350.0 6372.3 6336.9 6337.6 6355.9 6362.1 6370.2 5319.7 6241.4 6290.5 6343.3 6353.8 6212.2 6225.8 6226.5 6244.3 6244.6 6302.4 6320.7 6326.2 6340.6 6342.0 5943.0 6071.4 6119.3 6169.2 6185.6 6086.5 6093.0 6101.3 6106.9 6111.0 5727.0 5869.4 5893.2 5924.4 5929.0 5159.0 5948.2 6022.2 6052.3 6095.0 5924.3 5934.4 5949.3 5951.1 5953.9 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:62599.89 Median:36275.73 stddev:135339.29 (373.1%) stddev-sans-first:135638.51 14:26:15 INFO - PROCESS | 1668 | Values: 449.2 509.1 629.4 666.1 668.3 31410.4 31938.7 31949.2 31975.7 32045.5 33121.2 41532.8 41889.0 41988.4 41990.8 9583.6 9798.6 9804.3 9807.9 9818.0 9622.7 9743.0 9764.4 9770.8 9775.0 8280.4 9718.5 9805.7 9819.7 9820.5 9693.6 9927.5 9936.0 9936.2 9945.7 9763.4 9872.0 9890.1 9890.9 9913.3 42196.0 42654.0 42676.1 42845.6 43003.6 3164.6 3341.3 3403.7 3847.2 3918.9 28309.3 28316.0 28353.3 29115.6 29531.3 2555.5 2565.4 2631.1 2646.8 2648.6 473739.0 474623.0 475342.0 476971.0 476992.0 48413.0 48505.3 48518.8 48683.8 68754.7 47794.3 47838.0 47881.8 47962.8 48021.0 17234.6 17355.6 17367.9 17392.5 17401.7 13730.7 13770.4 13787.7 13793.4 13893.5 12997.5 15558.8 15593.0 15615.2 15772.0 5406.9 6617.2 6618.4 6666.4 6674.3 978.5 1036.3 1064.4 1109.9 1196.2 30356.0 30426.8 30937.9 31068.7 31138.0 1112.9 1158.1 1164.1 1212.9 1252.8 14715.8 14773.4 14901.5 15079.6 15122.3 642799.0 649830.0 653687.0 654710.0 655030.0 63248.7 63830.0 63851.0 63858.7 64035.7 1418.2 1517.4 1529.2 1557.4 1562.9 29143.0 29678.3 29789.9 30112.5 30516.0 48393.0 48498.3 49260.0 49342.3 49588.8 340627.0 343909.0 344040.0 344107.0 344438.0 29654.1 33833.5 34466.0 34628.3 34693.5 41759.0 42304.2 42557.4 43985.0 44142.8 34395.5 35446.8 35579.5 35622.0 36379.6 35384.5 35442.0 35718.8 36171.8 37643.4 36731.4 36946.3 37172.4 37488.4 38055.2 31076.0 31889.3 31982.7 32084.5 32173.7 18111.5 18171.0 24785.5 25104.6 25208.5 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35383.11 Median:21444.62 stddev:103633.22 (483.3%) stddev-sans-first:103890.48 14:26:15 INFO - PROCESS | 1668 | Values: 308.8 387.7 390.7 395.4 400.0 20279.6 20911.2 21045.4 21278.9 21809.3 20171.0 20867.0 20923.2 21145.8 21200.2 5812.8 6000.8 6044.2 6044.5 6066.5 4104.5 4286.5 5681.2 5745.2 5998.3 5959.7 5972.7 5976.8 5983.4 5989.4 5955.7 6190.5 6289.6 6428.4 6490.6 5687.8 5689.6 5689.8 5698.8 5700.8 24621.6 25202.4 26137.7 26482.2 26512.5 1686.8 2083.9 2112.9 2188.4 2718.2 10077.5 10270.0 10524.8 12216.8 15372.9 1758.3 1762.7 1803.0 1830.6 1831.9 111775.9 111842.0 114425.3 114861.9 115357.4 11511.5 11587.9 11589.7 11630.3 11727.9 14433.2 14492.1 14536.0 14541.9 14551.5 8634.3 8907.7 8919.2 8929.6 9000.7 9091.5 9093.0 9146.8 9193.5 9232.8 4509.3 4581.7 4597.3 4637.8 4654.4 1818.3 1839.8 1870.8 1916.3 1924.5 880.6 952.9 992.9 999.7 1061.7 21058.9 21388.6 21500.7 21513.7 21639.6 899.6 993.6 1006.8 1006.9 1056.0 11385.5 11621.9 11727.9 11776.5 11819.9 590280.0 622789.8 633146.0 636266.0 639656.0 105457.5 121397.2 123886.0 125005.3 125788.6 924.8 1014.1 1022.6 1034.2 1048.5 13267.1 18778.9 18953.6 19523.7 19654.5 38842.8 50443.0 51303.7 52138.2 52171.0 69080.7 71930.6 73800.5 73959.0 73987.6 8045.9 8123.1 8343.6 8403.8 8584.7 9253.8 13211.6 13617.6 13754.7 14076.3 11246.6 11689.8 11701.8 11808.1 11856.9 10284.5 11571.4 11676.2 11690.3 12002.2 11903.6 11985.3 12023.4 12149.0 12221.5 8018.0 8378.3 8545.9 8593.7 8757.3 10418.8 11728.3 11891.3 11915.9 12089.6 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:6275.96 Median:4657.58 stddev:3286.75 (70.6%) stddev-sans-first:3276.49 14:26:15 INFO - PROCESS | 1668 | Values: 1515.3 1673.3 1695.9 2042.9 2088.6 8221.1 8818.1 8982.9 8999.0 9007.6 8843.5 9093.9 9094.4 9099.0 9109.0 2269.9 2516.4 2549.0 2575.0 2576.0 2579.4 2586.9 2588.3 2590.5 2591.0 2484.0 2533.3 2536.0 2536.2 2536.5 2491.3 2550.9 2557.0 2557.2 2557.6 2519.7 2527.7 2540.8 2547.6 2573.8 9056.8 9112.1 9125.9 9136.3 9139.4 5559.9 5724.4 5771.6 5775.1 5778.3 3568.4 3624.6 3697.4 3715.7 3736.9 1039.4 1045.0 1045.5 1052.2 1101.2 12292.9 12296.4 12297.3 12302.8 12342.3 4345.2 4923.2 4983.9 5115.6 5124.9 10425.5 10438.1 10438.2 10439.7 10492.5 5728.3 5748.9 5753.8 5756.0 5763.3 4578.1 4737.1 4993.9 5178.0 5202.9 4055.0 4079.4 4122.7 4142.8 4146.4 1355.5 1363.1 1363.4 1364.2 1382.4 1388.5 1411.7 1432.8 1433.3 1433.7 8390.4 8561.4 8587.6 8588.6 8611.0 1250.7 1252.8 1259.5 1268.3 1273.7 6462.0 6463.6 6523.2 6534.7 6542.7 10586.3 10935.5 10942.3 11055.1 11093.7 10809.3 10892.5 10896.7 10996.3 11044.8 8567.5 8700.9 8767.3 8816.0 8858.4 6759.5 8130.3 8197.6 8272.9 8279.8 9855.4 10025.9 10110.3 10264.5 10272.5 10276.7 10319.3 10456.6 10600.0 10624.5 6577.7 6761.6 7020.4 7021.2 7342.5 8595.0 8646.6 8671.7 8671.8 8689.2 7530.3 7668.7 7731.5 7747.9 7754.2 7510.5 7513.9 7574.7 7706.2 7773.6 8015.0 8080.8 8105.7 8108.8 8114.0 6922.8 7148.5 7162.2 7168.0 7287.6 7225.7 7248.9 7280.9 7289.6 7291.1 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5689.91 Median:6455.77 stddev:1808.06 (28.0%) stddev-sans-first:1767.93 14:26:15 INFO - PROCESS | 1668 | Values: 337.1 341.8 345.5 347.4 350.7 6516.7 6557.9 6617.1 6620.5 6639.5 6480.2 6493.9 6502.4 6517.2 6520.9 4618.8 4695.0 4716.3 4807.5 4839.0 3870.8 4797.9 4801.4 4802.5 4803.3 3628.9 4726.7 4739.7 4765.7 4798.8 4575.6 4643.3 4775.8 4814.9 4818.5 4147.9 4149.2 4165.0 4173.0 4174.5 6259.4 6269.0 6274.2 6290.5 6295.6 5678.5 5698.2 5702.0 5702.3 5715.9 6662.6 6880.0 6891.9 6895.9 6903.8 4652.0 4689.4 4690.8 4700.2 4703.6 7236.8 7278.6 7278.7 7295.2 7297.1 4742.9 4749.1 4754.4 4754.5 4763.5 4805.8 4822.9 4833.9 4835.1 4844.5 5664.9 5676.2 5683.1 5693.1 5699.4 6665.2 6953.9 6962.0 6973.4 6975.1 1654.1 1676.9 1681.4 1691.6 1692.3 1032.4 1067.0 1091.6 1096.6 1125.5 6256.5 6276.2 6448.4 6463.2 6470.4 7255.7 7259.3 7264.7 7268.1 7269.5 5243.9 5778.5 6349.7 6420.7 6429.0 3250.4 3428.9 3706.8 3817.3 3826.1 7173.4 7514.0 7533.0 7533.5 7541.1 7399.7 7451.0 7460.4 7468.4 7483.3 7485.6 7500.7 7512.8 7517.7 7543.4 6927.3 7227.1 7229.3 7230.4 7253.3 7064.5 7103.9 7119.5 7127.2 7135.8 7487.3 7496.7 7509.6 7511.6 7524.7 3949.7 4768.9 4773.0 4776.4 4785.1 7187.5 7212.9 7213.6 7222.8 7225.7 6729.1 6869.7 6894.1 6986.5 6990.0 5613.0 5629.4 6886.4 6931.3 6944.4 6213.5 6791.8 6794.7 6796.9 6806.3 6681.3 6919.1 6927.8 6939.9 6942.1 6590.8 6657.9 6755.3 6772.6 6811.3 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18165.35 Median:51835.98 stddev:25348.42 (48.9%) stddev-sans-first:25385.77 14:26:15 INFO - PROCESS | 1668 | Values: 741.2 770.2 785.5 790.5 793.4 11478.5 14973.0 15052.3 15083.2 15083.5 13860.5 13938.0 13946.8 14303.5 14491.2 955.4 963.0 976.5 977.4 984.5 965.1 995.3 997.7 1000.3 1000.8 1013.0 1044.6 1045.8 1046.0 1048.3 1014.8 1039.9 1041.5 1042.6 1044.2 756.4 775.1 775.8 776.9 778.6 13743.5 13786.9 13792.8 13798.2 13810.5 11525.8 11702.7 11702.9 11824.8 11884.2 8396.7 9469.9 9493.2 9554.7 9681.9 3421.8 3425.1 3469.5 3500.3 3633.8 66611.7 70563.2 77385.2 78451.6 78876.2 9699.2 9737.7 9787.0 9903.8 10221.2 21749.5 21901.3 21907.9 21909.5 21936.8 7590.3 7637.8 7639.3 7677.7 7738.8 4090.7 4114.7 4167.5 4216.3 4285.6 3613.8 3749.6 3787.5 3800.1 3800.6 673.5 681.2 694.2 697.4 698.3 497.8 580.5 584.9 585.8 597.1 7331.7 7664.0 7840.0 8164.2 8271.5 474.6 573.9 583.2 589.4 608.7 2965.7 3764.3 3802.2 3942.3 3994.2 91902.5 93372.1 95018.5 95274.5 95388.6 62603.1 66785.3 77314.0 79691.0 80943.2 68888.0 71481.6 71663.0 72233.9 73024.0 7570.5 7697.5 7779.3 7790.6 7819.0 51211.2 51819.2 51852.7 51986.5 52037.0 65331.7 66465.7 67742.0 69867.0 71517.3 11227.0 11354.7 11658.8 11677.0 12060.8 12133.5 12366.3 12415.4 12600.2 12615.5 11864.8 12023.7 12090.0 12216.8 12229.1 11588.9 11799.8 11977.9 12041.2 12215.4 11829.8 11933.7 11965.6 12072.6 12101.4 8694.7 9874.5 9909.2 10025.5 10130.2 9707.8 9891.5 9946.6 10083.2 10187.5 14:26:15 INFO - PROCESS | 1668 | -------- Summary: end -------- 14:26:15 INFO - PROCESS | 1668 | 14:26:15 INFO - TEST-INFO | 1668: exit 0 14:26:15 INFO - TEST-OK | dromaeo_css | took 1113008ms 14:26:15 INFO - TEST-START | kraken 14:26:15 INFO - operating with platform_type : mac_ 14:26:15 INFO - Initialising browser for kraken test... 14:26:15 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpAJrLAR/profile http://localhost:49275/getInfo.html) 14:26:17 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 14:26:17 INFO - PROCESS | 1673 | colorDepth:24 14:26:17 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 14:26:17 INFO - PROCESS | 1673 | __metrics 14:26:17 INFO - PROCESS | 1673 | 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" 14:26:17 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:26:18 INFO - TEST-INFO | 1673: exit 0 14:26:18 INFO - Browser initialized. 14:26:18 INFO - Running cycle 1/1 for kraken test... 14:26:18 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpAJrLAR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:30 INFO - PROCESS | 1675 | RSS: Main: 207769600 14:26:30 INFO - PROCESS | 1675 | 14:26:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 14:26:33 INFO - PROCESS | 1675 | RSS: Main: 263380992 14:26:33 INFO - PROCESS | 1675 | 14:26:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 14:26:36 INFO - PROCESS | 1675 | RSS: Main: 273387520 14:26:36 INFO - PROCESS | 1675 | 14:26:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 14:26:40 INFO - PROCESS | 1675 | RSS: Main: 262885376 14:26:40 INFO - PROCESS | 1675 | 14:26:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 14:26:43 INFO - PROCESS | 1675 | RSS: Main: 264433664 14:26:43 INFO - PROCESS | 1675 | 14:26:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:26:45 INFO - PROCESS | 1675 | RSS: Main: 265269248 14:26:45 INFO - PROCESS | 1675 | 14:26:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 14:26:49 INFO - PROCESS | 1675 | RSS: Main: 265342976 14:26:49 INFO - PROCESS | 1675 | 14:26:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 14:26:52 INFO - PROCESS | 1675 | RSS: Main: 265359360 14:26:52 INFO - PROCESS | 1675 | 14:26:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 14:26:55 INFO - PROCESS | 1675 | RSS: Main: 265723904 14:26:55 INFO - PROCESS | 1675 | 14:26:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:26:57 INFO - PROCESS | 1675 | RSS: Main: 298213376 14:26:57 INFO - PROCESS | 1675 | 14:26:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:27:00 INFO - PROCESS | 1675 | RSS: Main: 299061248 14:27:00 INFO - PROCESS | 1675 | 14:27:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:27:02 INFO - PROCESS | 1675 | RSS: Main: 303800320 14:27:02 INFO - PROCESS | 1675 | 14:27:05 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:27:06 INFO - PROCESS | 1675 | RSS: Main: 306692096 14:27:06 INFO - PROCESS | 1675 | 14:27:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:27:09 INFO - PROCESS | 1675 | RSS: Main: 305815552 14:27:09 INFO - PROCESS | 1675 | 14:27:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 14:27:11 INFO - PROCESS | 1675 | RSS: Main: 369512448 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | __start_tp_report 14:27:11 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 14:27:11 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 14:27:11 INFO - PROCESS | 1675 | |i|pagename|runs| 14:27:11 INFO - PROCESS | 1675 | |0;ai-astar;113;109;111;110;112;110;119;112;110;112 14:27:11 INFO - PROCESS | 1675 | |1;audio-beat-detection;161;132;126;180;106;161;167;146;180;116 14:27:11 INFO - PROCESS | 1675 | |2;audio-dft;183;186;182;184;183;182;183;184;183;184 14:27:11 INFO - PROCESS | 1675 | |3;audio-fft;77;76;73;113;55;58;58;58;121;55 14:27:11 INFO - PROCESS | 1675 | |4;audio-oscillator;92;94;131;80;77;83;117;75;79;115 14:27:11 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;87;123;87;83;87;83;86;84;86;83 14:27:11 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;100;133;93;92;93;93;93;92;92 14:27:11 INFO - PROCESS | 1675 | |7;imaging-desaturate;84;84;85;85;84;85;86;94;135;84 14:27:11 INFO - PROCESS | 1675 | |8;json-parse-financial;55;59;57;57;58;58;57;67;54;53 14:27:11 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;62;62;62;76;71;69;65;65;65;67 14:27:11 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;81;81;80;81;80;79;85;108;101;83 14:27:11 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;132;128;130;113;119;117;117;126;119;122 14:27:11 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;131;124;134;132;134;134;133;133;129;132 14:27:11 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;54;81;94;102;67;66;67;69;64;65 14:27:11 INFO - PROCESS | 1675 | __end_tp_report 14:27:11 INFO - PROCESS | 1675 | __start_cc_report 14:27:11 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1185 14:27:11 INFO - PROCESS | 1675 | __end_cc_report 14:27:11 INFO - PROCESS | 1675 | __startTimestamp1461533231119__endTimestamp 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | ------- Summary: start ------- 14:27:11 INFO - PROCESS | 1675 | Number of tests: 14 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:2.82 (2.5%) stddev-sans-first:2.96 14:27:11 INFO - PROCESS | 1675 | Values: 113.0 109.0 111.0 110.0 112.0 110.0 119.0 112.0 110.0 112.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:161.00 stddev:26.41 (16.4%) stddev-sans-first:27.55 14:27:11 INFO - PROCESS | 1675 | Values: 161.0 132.0 126.0 180.0 106.0 161.0 167.0 146.0 180.0 116.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.17 (0.6%) stddev-sans-first:1.24 14:27:11 INFO - PROCESS | 1675 | Values: 183.0 186.0 182.0 184.0 183.0 182.0 183.0 184.0 183.0 184.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:74.40 Median:58.00 stddev:24.11 (41.6%) stddev-sans-first:25.56 14:27:11 INFO - PROCESS | 1675 | Values: 77.0 76.0 73.0 113.0 55.0 58.0 58.0 58.0 121.0 55.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:79.50 stddev:19.82 (24.9%) stddev-sans-first:21.00 14:27:11 INFO - PROCESS | 1675 | Values: 92.0 94.0 131.0 80.0 77.0 83.0 117.0 75.0 79.0 115.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:86.00 stddev:12.11 (14.1%) stddev-sans-first:12.82 14:27:11 INFO - PROCESS | 1675 | Values: 87.0 123.0 87.0 83.0 87.0 83.0 86.0 84.0 86.0 83.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:92.50 stddev:12.77 (13.8%) stddev-sans-first:13.40 14:27:11 INFO - PROCESS | 1675 | Values: 92.0 100.0 133.0 93.0 92.0 93.0 93.0 93.0 92.0 92.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:90.60 Median:85.00 stddev:15.89 (18.7%) stddev-sans-first:16.67 14:27:11 INFO - PROCESS | 1675 | Values: 84.0 84.0 85.0 85.0 84.0 85.0 86.0 94.0 135.0 84.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:57.50 stddev:3.84 (6.7%) stddev-sans-first:3.96 14:27:11 INFO - PROCESS | 1675 | Values: 55.0 59.0 57.0 57.0 58.0 58.0 57.0 67.0 54.0 53.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:4.53 (6.9%) stddev-sans-first:4.51 14:27:11 INFO - PROCESS | 1675 | Values: 62.0 62.0 62.0 76.0 71.0 69.0 65.0 65.0 65.0 67.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:81.00 stddev:10.08 (12.4%) stddev-sans-first:10.54 14:27:11 INFO - PROCESS | 1675 | Values: 81.0 81.0 80.0 81.0 80.0 79.0 85.0 108.0 101.0 83.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:124.00 stddev:6.36 (5.1%) stddev-sans-first:5.70 14:27:11 INFO - PROCESS | 1675 | Values: 132.0 128.0 130.0 113.0 119.0 117.0 117.0 126.0 119.0 122.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.60 Median:133.00 stddev:3.10 (2.3%) stddev-sans-first:3.28 14:27:11 INFO - PROCESS | 1675 | Values: 131.0 124.0 134.0 132.0 134.0 134.0 133.0 133.0 129.0 132.0 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:67.00 stddev:14.87 (22.2%) stddev-sans-first:14.11 14:27:11 INFO - PROCESS | 1675 | Values: 54.0 81.0 94.0 102.0 67.0 66.0 67.0 69.0 64.0 65.0 14:27:11 INFO - PROCESS | 1675 | -------- Summary: end -------- 14:27:11 INFO - PROCESS | 1675 | 14:27:11 INFO - TEST-INFO | 1675: exit 0 14:27:11 INFO - TEST-OK | kraken | took 55990ms 14:27:11 INFO - SUITE-END | took 1168s 14:27:12 INFO - Completed test suite (00:19:29) 14:27:12 INFO - javascript benchmark 14:27:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3424.143964008998, 3484.547008888844, 3514.174169070268, 3516.2791627716288, 3559.8576056957722, 5704.229928959599, 5706.058500347438, 5709.771609135635, 5711.8000869969555, 5720.628159169656, 5701.800436984707, 5739.483446489815, 5739.713014349287, 5747.224124743158, 5758.683272420015, 5099.796008159669, 5137.409197942228, 5151.74241287936, 5201.063808514466, 5229.084910140729, 5222.738863056851, 5235.450277720842, 5244.423113457517, 5244.868878278055, 5253.684778913258, 5184.507471790174, 5208.140656791625, 5240.528352448279, 5243.580513558925, 5249.580033597322, 5203.609729270301, 5241.344831896013, 5251.527362537352, 5262.500062494057, 5295.4704529547125, 5020.347354843883, 5170.586353091744, 5192.350956130484, 5194.428612852583, 5221.190715439113, 5624.240727501776, 5630.831075067755, 5643.463870932255, 5655.632383895075, 5666.829995100154, 6120.908186377208, 6268.717907694165, 6277.4664153547, 6282.811515654537, 6292.716827742717, 6245.56281060326, 6256.15541901842, 6265.624062556216, 6315.494760419156, 6318.684065796691, 5790.913136302959, 5812.709364531756, 5819.127130930352, 5834.766609335579, 5863.973804584223, 6210.627362358183, 6274.9686251569365, 6277.52918531112, 6290.748370065146, 6298.0552917062805, 6015.639061656315, 6039.234072692226, 6071.544634152457, 6076.726547305382, 6094.603850749687, 6214.1921550198185, 6225.968870155621, 6315.652639104802, 6329.272133704683, 6335.36646335372, 5708.801151758177, 6187.81436556904, 6216.689165541705, 6246.312905580383, 6251.78118765841, 5650.152477128381, 5918.4377484139795, 6054.273487181566, 6131.724072416752, 6275.623462592168, 4852.078105160008, 4855.6601037927, 4857.8299759508245, 4869.391326084239, 4876.804927802919, 4452.510223875371, 4459.331100334911, 4479.888002799891, 4486.75322857246, 4491.505934347219, 5620.494155526022, 5681.488666019995, 5702.030654788696, 5763.6541807491685, 5796.043652797325, 6240.282367527701, 6265.27949285838, 6276.560640755103, 6297.842553936188, 6302.779402720975, 5618.719064046782, 5637.098064309773, 5647.661140331593, 5647.6893770843, 5692.772289108472, 5270.077736396192, 5358.946410535923, 5359.598030147755, 5420.810271640474, 5446.782128714886, 6294.622322660655, 6312.274088479791, 6338.809835704937, 6350.0, 6372.267189273293, 6336.936630633819, 6337.588056776295, 6355.872882542416, 6362.141110949962, 6370.1718776558755, 5319.734013299397, 6241.438270555672, 6290.46531044848, 6343.270523889812, 6353.809385718436, 6212.161358216764, 6225.782097626561, 6226.50187984951, 6244.250689917239, 6244.562880598314, 6302.401271879165, 6320.652364120017, 6326.2092238470195, 6340.556161068681, 6341.968290158519, 5943.0, 6071.392860713893, 6119.265688117454, 6169.2288463942, 6185.567010309235, 6086.462455517837, 6092.969535152296, 6101.267847858286, 6106.855059071508, 6110.991686371699, 5726.9713651431475, 5869.413058694096, 5893.174955506318, 5924.407559244041, 5929.0, 5158.993996170861, 5948.196993405835, 6022.156898034191, 6052.334243233153, 6095.0, 5924.348321684697, 5934.43622855828, 5949.315828679757, 5951.107333899777, 5953.9404605955115], "unit": "score", "name": "dojo.html", "value": 5697.59148894109}, {"lowerIsBetter": false, "replicates": [449.2183600535068, 509.12176495545214, 629.3989240275537, 666.0874601795541, 668.3083009085595, 31410.371792564136, 31938.68061319386, 31949.201269968235, 31975.68024319756, 32045.51931721026, 33121.17197070074, 41532.754017379455, 41889.0, 41988.37017444741, 41990.790046049806, 9583.616655333779, 9798.6080556778, 9804.31369804114, 9807.90192098077, 9817.95091024544, 9622.711318660451, 9742.9512852436, 9764.414135151914, 9770.755731106743, 9775.022497750238, 8280.420370574062, 9718.514074296292, 9805.705828825112, 9819.705408837746, 9820.50897455128, 9693.612255509772, 9927.453990030546, 9935.950320248425, 9936.155426788731, 9945.651902183388, 9763.365381250196, 9872.0, 9890.109890109925, 9890.90109098907, 9913.256505762096, 42196.0, 42654.0, 42676.14647707059, 42845.57154428447, 43003.569964300266, 3164.6202455705234, 3341.331733653279, 3403.710684591813, 3847.172857835551, 3918.8824335269987, 28309.292267693472, 28316.0, 28353.291167720767, 29115.56326655102, 29531.261718456997, 2555.527227462907, 2565.371483986435, 2631.1317265302596, 2646.827956182842, 2648.6159506871395, 473739.0, 474623.0, 475342.0, 476971.0, 476991.9999999965, 48413.0, 48505.272420913716, 48518.75740621345, 48683.75658121687, 68754.65622671954, 47794.28308575374, 47838.0, 47881.8029549264, 47962.760186198844, 48021.03957920892, 17234.569135771457, 17355.56611084733, 17367.913160434116, 17392.47822565325, 17401.65196696059, 13730.65673358174, 13770.449182032808, 13787.724245515034, 13793.448262069405, 13893.513727019506, 12997.545085921947, 15558.766618500731, 15592.986455880331, 15615.219239038002, 15772.0, 5406.864828379322, 6617.239017512951, 6618.437432818168, 6666.366695163952, 6674.2658307586125, 978.5254151736544, 1036.2694300518117, 1064.3826580583243, 1109.9132041912287, 1196.2045239915358, 30356.0, 30426.847865760974, 30937.91717289929, 31068.68931310658, 31138.0, 1112.933224006562, 1158.1429741990867, 1164.057113737875, 1212.9150959432754, 1252.843394575678, 14715.779263311058, 14773.40906363755, 14901.478448254258, 15079.623009424851, 15122.319495622723, 642799.0, 649830.0, 653687.0, 654710.0, 655030.0, 63248.683756580926, 63830.0, 63851.0, 63858.68070659617, 64035.719285614956, 1418.2128918450153, 1517.3627076627843, 1529.2124555853745, 1557.369265447473, 1562.867156291728, 29142.97999570005, 29678.258043549085, 29789.851050744608, 30112.548311774903, 30516.0, 48393.0, 48498.27252591284, 49260.0, 49342.25986610276, 49588.752056239486, 340627.0, 343909.0, 344040.0, 344107.0, 344438.0, 29654.110376688735, 33833.49249761305, 34465.96602101941, 34628.30743385169, 34693.47959780555, 41759.0, 42304.15391692211, 42557.36163957481, 43985.0, 44142.77928610337, 34395.4840677385, 35446.822765886005, 35579.466308004885, 35622.0, 36379.63620363762, 35384.46923296204, 35442.0, 35718.82140589385, 36171.819140904125, 37643.435348470346, 36731.44902826406, 36946.261074777816, 37172.44241336436, 37488.437673434375, 38055.238895222494, 31076.0, 31889.31821014583, 31982.68017319797, 32084.5187322195, 32173.67826321707, 18111.547211319823, 18171.0, 24785.50428991446, 25104.62343064892, 25208.495830082928], "unit": "score", "name": "ext.html", "value": 19286.22494563906}, {"lowerIsBetter": false, "replicates": [308.75454014058823, 387.74796382351485, 390.7206347461565, 395.3930716350401, 399.9860004899829, 20279.59440811183, 20911.16355345784, 21045.368638940818, 21278.89360553197, 21809.345719628396, 20171.0, 20867.0, 20923.16307347708, 21145.788542114573, 21200.19798317141, 5812.796552120677, 6000.819975400723, 6044.24446944132, 6044.455998960093, 6066.454019138277, 4104.466419365478, 4286.5284818669925, 5681.23303354046, 5745.195672605818, 5998.310194327664, 5959.672218028007, 5972.701364931758, 5976.760929562813, 5983.371745966676, 5989.43100405463, 5955.731992060368, 6190.50475961922, 6289.591176573508, 6428.421442070213, 6490.578112422678, 5687.800926967537, 5689.630174038674, 5689.829305120853, 5698.800541981052, 5700.800471983503, 24621.645809480473, 25202.369940751443, 26137.73862261391, 26482.205533834014, 26512.46975060488, 1686.7891513560805, 2083.9270625528034, 2112.926047588327, 2188.39819049761, 2718.211718601603, 10077.496125193784, 10270.0, 10524.842127368096, 12216.816747748791, 15372.923135384362, 1758.2703178181039, 1762.7003409420302, 1802.9819701802944, 1830.560665440298, 1831.9267229310813, 111775.88224117736, 111842.0, 114425.28362074493, 114861.85138148595, 115357.4232128834, 11511.48198331077, 11587.88412115885, 11589.6523104307, 11630.302181868945, 11727.941360293144, 14433.20617366034, 14492.130472171704, 14535.982481226212, 14541.927290363481, 14551.490697825526, 8634.309255259564, 8907.73276801697, 8919.1972722454, 8929.598168082452, 9000.684976025808, 9091.454512729126, 9092.954535227282, 9146.771330716652, 9193.540322983825, 9232.815343693088, 4509.278515437514, 4581.679282450196, 4597.333386658983, 4637.768111594407, 4654.37165982588, 1818.3363072478714, 1839.7554982920815, 1870.803565625617, 1916.2501809902833, 1924.499630096185, 880.55972013993, 952.8665986761858, 992.9453880036523, 999.6950929966284, 1061.6761887624193, 21058.89470552668, 21388.572228555342, 21500.677489837664, 21513.67729484059, 21639.56720865574, 899.6401439424152, 993.5826952679892, 1006.7533454303596, 1006.9395836249848, 1055.9736006599742, 11385.544578216779, 11621.941890290494, 11727.885850844155, 11776.470058847372, 11819.940900295443, 590280.0, 622789.7721022823, 633146.0, 636266.0, 639656.0, 105457.47271263595, 121397.17904231267, 123886.0, 125005.34716799039, 125788.59739909059, 924.7641851327868, 1014.077189757332, 1022.6318525330875, 1034.2191645307744, 1048.5386429970788, 13267.137636053625, 18778.859929812454, 18953.620927581647, 19523.70714439256, 19654.508637284183, 38842.805785970886, 50443.0, 51303.743481282356, 52138.217926731886, 52170.95658086893, 69080.6545967267, 71930.64034679793, 73800.52398951883, 73959.0, 73987.63006184934, 8045.919540804517, 8123.147069557847, 8343.582820859054, 8403.789905252173, 8584.699535516227, 9253.814923701622, 13211.603651890458, 13617.591472255848, 13754.65613359634, 14076.296185190495, 11246.550137994389, 11689.824652630048, 11701.765964680488, 11808.114391420506, 11856.940715296369, 10284.485775711335, 11571.363575003455, 11676.182667213214, 11690.298582085103, 12002.21985570935, 11903.642890713292, 11985.28088314704, 12023.398830058637, 12149.0, 12221.450034748636, 8018.037835459782, 8378.329733621174, 8545.871811922701, 8593.742187734379, 8757.299416046828, 10418.791624167627, 11728.296302221554, 11891.345975971404, 11915.880841191478, 12089.637310880687], "unit": "score", "name": "jquery.html", "value": 9704.862305092567}, {"lowerIsBetter": false, "replicates": [1515.3029606381065, 1673.2721266249182, 1695.9236834342453, 2042.9182832686693, 2088.603165398574, 8221.05457872344, 8818.118188181179, 8982.865257021134, 8999.0, 9007.59465824038, 8843.513606751641, 9093.90906090939, 9094.408863423872, 9099.0, 9108.954455227731, 2269.8638081715067, 2516.421223118684, 2549.031368080127, 2574.987125064372, 2575.961360579593, 2579.3809485723386, 2586.948261034778, 2588.3011586871626, 2590.507803517335, 2590.961135582968, 2483.962740558884, 2533.2526904563197, 2535.961960570584, 2536.1503896194795, 2536.4546622476164, 2491.2526242127287, 2550.8979640814346, 2556.961645575318, 2557.194483737626, 2557.565213913639, 2519.735427780075, 2527.684039495063, 2540.83484573504, 2547.643329933811, 2573.794096472278, 9056.818863622757, 9112.134347237003, 9125.863112053257, 9136.269098472172, 9139.360244782867, 5559.916601250984, 5724.427557244284, 5771.567132465082, 5775.075987841925, 5778.3066032076, 3568.3933731265747, 3624.637536246354, 3697.3899306614376, 3715.702743780491, 3736.9439458408146, 1039.4127318065343, 1045.0490054050854, 1045.4877110216016, 1052.1740433759462, 1101.1741194104422, 12292.910205265127, 12296.385180740926, 12297.3236471994, 12302.81545776814, 12342.321172335518, 4345.217860785089, 4923.212286034217, 4983.9252411213865, 5115.616328775282, 5124.948750512447, 10425.530851111718, 10438.112760415297, 10438.217133715001, 10439.739006524898, 10492.475376231157, 5728.255326807488, 5748.850229953942, 5753.827385178452, 5756.0, 5763.337216220105, 4578.084383123389, 4737.076270127292, 4993.90012199754, 5177.974110129425, 5202.92195617066, 4055.047063939972, 4079.3676980068135, 4122.732022418533, 4142.79286035697, 4146.398772178078, 1355.5187908292414, 1363.0731102850157, 1363.3864760857455, 1364.1542243808901, 1382.3572039001913, 1388.5209602687057, 1411.6541447345467, 1432.8065711129072, 1433.254707552067, 1433.7132573485344, 8390.37072219586, 8561.400701950928, 8587.613557389932, 8588.570571471402, 8610.956945215234, 1250.6560695808726, 1252.83713117296, 1259.5213818748816, 1268.346801397281, 1273.6688461000206, 6462.0, 6463.644499552475, 6523.18460192476, 6534.738610455529, 6542.738290468423, 10586.258961872749, 10935.507902144263, 10942.343459392461, 11055.06031987274, 11093.72265693348, 10809.297395669257, 10892.455377231265, 10896.673099807018, 10996.28524145944, 11044.834327485094, 8567.528785916835, 8700.869486957701, 8767.298616110696, 8815.955920220358, 8858.424202426822, 6759.459243260626, 8130.268275855202, 8197.590120493833, 8272.917270827214, 8279.751607451786, 9855.408675479493, 10025.899741002788, 10110.342827716175, 10264.538095785856, 10272.486375681037, 10276.691699249035, 10319.277650564389, 10456.63401780934, 10600.04599586041, 10624.468776561296, 6577.704003319766, 6761.560498567577, 7020.403265722471, 7021.1574611047, 7342.48602597813, 8594.957025214833, 8646.567671616269, 8671.696490622799, 8671.826563468569, 8689.217970382695, 7530.28462296081, 7668.6549105291515, 7731.536107833548, 7747.883781743166, 7754.185810489817, 7510.474266801271, 7513.924860751323, 7574.734884279023, 7706.229377062249, 7773.611319434119, 8015.0, 8080.757577272691, 8105.716299929474, 8108.797280068045, 8114.026316842017, 6922.826929326807, 7148.535345202546, 7162.247963963709, 7167.964160179165, 7287.599182045038, 7225.674844631899, 7248.891266630899, 7280.9271907280245, 7289.599072050876, 7291.08861392326], "unit": "score", "name": "mootools.html", "value": 5140.849262597834}, {"lowerIsBetter": false, "replicates": [337.0528814032568, 341.80346300877, 345.50247643393527, 347.3521881690648, 350.7159201047152, 6516.696660667868, 6557.90163147552, 6617.073609694647, 6620.470362371011, 6639.468842492601, 6480.189976252968, 6493.870122597546, 6502.447291980187, 6517.217933847945, 6520.9347906520925, 4618.8152473901, 4695.0, 4716.292556116576, 4807.471178170398, 4838.975805120969, 3870.825812838429, 4797.856064318059, 4801.423829140491, 4802.471728109905, 4803.279508073782, 3628.87298944537, 4726.66913316068, 4739.668223224376, 4765.690230135047, 4798.7600619969035, 4575.565321294472, 4643.349931009644, 4775.80896764129, 4814.903701925977, 4818.542238487373, 4147.854825081107, 4149.232392007458, 4165.0, 4172.979135104335, 4174.540800511942, 6259.436650701458, 6269.028300613412, 6274.247090349141, 6290.528210384194, 6295.559310848243, 5678.545716342724, 5698.202251684767, 5702.0, 5702.258706368187, 5715.914261286084, 6662.56693314933, 6879.965600171967, 6891.931080689229, 6895.862082758316, 6903.827404314882, 4651.976740116312, 4689.413823272091, 4690.812367505296, 4700.1539722849875, 4703.623710103184, 7236.819079523054, 7278.563286202898, 7278.672459739324, 7295.16105647852, 7297.124345078571, 4742.881427964328, 4749.121412538691, 4754.358161648202, 4754.5245475452175, 4763.476017638057, 4805.8077676892535, 4822.927656085161, 4833.879153021203, 4835.081334546425, 4844.491328410537, 5664.8867022659315, 5676.176954341598, 5683.090705487102, 5693.117566777156, 5699.401562835926, 6665.166854143232, 6953.930460695429, 6961.965190174118, 6973.372396484341, 6975.128108986377, 1654.1315809200025, 1676.8910020848605, 1681.3946979087518, 1691.6447546015474, 1692.3146125819062, 1032.4373216596973, 1066.9519871605796, 1091.5851976248975, 1096.6381094238882, 1125.454154734953, 6256.5307601930035, 6276.184096067482, 6448.355164483608, 6463.192100987377, 6470.385313395314, 7255.746048888263, 7259.346658800733, 7264.673089710976, 7268.127824661075, 7269.454790890704, 5243.947560524422, 5778.5377169826315, 6349.746010159634, 6420.711068001951, 6428.967855160788, 3250.3661785951986, 3428.9314213715584, 3706.777593344408, 3817.3319669057805, 3826.081740382288, 7173.390261827803, 7513.962430187814, 7532.962335188289, 7533.472656914073, 7541.132769731441, 7399.6670149843385, 7451.031365922504, 7460.403167746567, 7468.439867009887, 7483.289087536676, 7485.588292643848, 7500.662470188855, 7512.812179695551, 7517.6992920284765, 7543.3965282778345, 6927.3419025192525, 7227.1327440707455, 7229.349358557755, 7230.385417239593, 7253.310935461122, 7064.505484616027, 7103.857922841617, 7119.466040046914, 7127.180374257026, 7135.750248741269, 7487.251274872469, 7496.7376141834775, 7509.624518774149, 7511.586862722603, 7524.736634217776, 3949.743266687656, 4768.928466073081, 4773.0, 4776.426828780569, 4785.138675038526, 7187.460940429384, 7212.927870721225, 7213.60325182099, 7222.783316500303, 7225.747098851515, 6729.1252137221845, 6869.656517174221, 6894.13823272091, 6986.54587451814, 6989.95150727395, 5612.961602103624, 5629.437056294338, 6886.380225779503, 6931.341522555349, 6944.37500624926, 6213.5339849510565, 6791.796246112624, 6794.694238759169, 6796.932030679828, 6806.285340039224, 6681.33186681328, 6919.065926100114, 6927.826804329932, 6939.930600694131, 6942.097527321416, 6590.7693230736695, 6657.933420665925, 6755.290694477009, 6772.559783614246, 6811.250762416031], "unit": "score", "name": "prototype.html", "value": 5096.186993901812}, {"lowerIsBetter": false, "replicates": [741.1624863903788, 770.1643716567525, 785.4501848705904, 790.5296348672538, 793.3732351442361, 11478.540858365666, 14973.02675326103, 15052.322645480952, 15083.245837708111, 15083.472078477256, 13860.514881979132, 13937.954653400984, 13946.790798138036, 14303.499377521788, 14491.202983835885, 955.3933252384734, 962.9903700963006, 976.5117441279325, 977.3891317926297, 984.478226539935, 965.1024547171128, 995.3331268050388, 997.6907158780765, 1000.3297790480395, 1000.8398656214971, 1013.0426746724348, 1044.5769463367349, 1045.8483519889612, 1045.9633912813053, 1048.3395460859647, 1014.761531040205, 1039.9272050956436, 1041.4792603698152, 1042.5673345561584, 1044.1907521670728, 756.4440136499668, 775.1163673412258, 775.8487095016474, 776.9417293702938, 778.634042000259, 13743.450261989508, 13786.862131378659, 13792.793108103386, 13798.17210967345, 13810.516631917935, 11525.827112593317, 11702.707432314175, 11702.882971170264, 11824.763504729943, 11884.168108232341, 8396.706115285997, 9469.905300946972, 9493.193078588329, 9554.665586704501, 9681.854772178423, 3421.7775844570024, 3425.092350527112, 3469.5142680024815, 3500.264944361687, 3633.8001409922445, 66611.66694166546, 70563.23591910243, 77385.22614773836, 78451.60774196149, 78876.21123788746, 9699.175570076623, 9737.659181928599, 9786.993666298391, 9903.80192396148, 10221.182305415548, 21749.45626359322, 21901.342959711234, 21907.890460547915, 21909.452263693536, 21936.780632193793, 7590.316871481483, 7637.770866874002, 7639.312461878347, 7677.692892284358, 7738.806529836686, 4090.734102283342, 4114.670826333886, 4167.458230430025, 4216.265153135591, 4285.635720963753, 3613.837377317974, 3749.643783840531, 3787.4697542344093, 3800.1069748609057, 3800.562935262425, 673.5117040145954, 681.2404169351237, 694.1669996004814, 697.3653974882832, 698.3365802487657, 497.75112443778113, 580.4978693430153, 584.8859472402926, 585.8154681275385, 597.0834768630132, 7331.706731730777, 7663.961680191564, 7840.0, 8164.224398682116, 8271.503709777433, 474.64401698725953, 573.8938296415176, 583.201014609987, 589.4047012517389, 608.7260732670228, 2965.733084022448, 3764.3412402829395, 3802.1825307559006, 3942.250972315251, 3994.2410941920944, 91902.54048729714, 93372.06627933633, 95018.52490737502, 95274.52362738142, 95388.56917146248, 62603.060954085726, 66785.33214667888, 77314.0, 79691.0, 80943.19056809356, 68887.96668049983, 71481.6425917867, 71663.0, 72233.91649125268, 73024.0, 7570.538221062806, 7697.46117771773, 7779.260970207822, 7790.649420776192, 7818.960905195438, 51211.2318315233, 51819.2227116586, 51852.74073629608, 51986.480135198166, 52037.0, 65331.67334163299, 66465.66767166133, 67742.0, 69867.0, 71517.28482715106, 11227.0, 11354.659360219206, 11658.825117623412, 11677.007454366134, 12060.758784824078, 12133.514659413877, 12366.258024518558, 12415.44130514147, 12600.180988235736, 12615.49538018469, 11864.76270474603, 12023.699407514881, 12090.0, 12216.816747748613, 12229.082818788449, 11588.88411115878, 11799.76400472003, 11977.880221197676, 12041.217320874115, 12215.38923053826, 11829.76340473203, 11933.701657458632, 11965.581204657796, 12072.577459788865, 12101.394930253628, 8694.695685650971, 9874.506274686093, 9909.157721593458, 10025.5488503019, 10130.189584833048, 9707.805843883225, 9891.505424728592, 9946.629297236215, 10083.193344532274, 10187.541560629938], "unit": "score", "name": "yui.html", "value": 7003.205100366591}], "name": "dromaeo_css", "value": 7619.439621370564}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 111.0, 110.0, 112.0, 110.0, 119.0, 112.0, 110.0, 112.0], "unit": "score", "name": "ai-astar", "value": 111.8}, {"lowerIsBetter": true, "replicates": [161.0, 132.0, 126.0, 180.0, 106.0, 161.0, 167.0, 146.0, 180.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 147.5}, {"lowerIsBetter": true, "replicates": [183.0, 186.0, 182.0, 184.0, 183.0, 182.0, 183.0, 184.0, 183.0, 184.0], "unit": "score", "name": "audio-dft", "value": 183.4}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 73.0, 113.0, 55.0, 58.0, 58.0, 58.0, 121.0, 55.0], "unit": "score", "name": "audio-fft", "value": 74.4}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 131.0, 80.0, 77.0, 83.0, 117.0, 75.0, 79.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 94.3}, {"lowerIsBetter": true, "replicates": [87.0, 123.0, 87.0, 83.0, 87.0, 83.0, 86.0, 84.0, 86.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.9}, {"lowerIsBetter": true, "replicates": [92.0, 100.0, 133.0, 93.0, 92.0, 93.0, 93.0, 93.0, 92.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 97.3}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 85.0, 85.0, 84.0, 85.0, 86.0, 94.0, 135.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 90.6}, {"lowerIsBetter": true, "replicates": [55.0, 59.0, 57.0, 57.0, 58.0, 58.0, 57.0, 67.0, 54.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 62.0, 76.0, 71.0, 69.0, 65.0, 65.0, 65.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.4}, {"lowerIsBetter": true, "replicates": [81.0, 81.0, 80.0, 81.0, 80.0, 79.0, 85.0, 108.0, 101.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.9}, {"lowerIsBetter": true, "replicates": [132.0, 128.0, 130.0, 113.0, 119.0, 117.0, 117.0, 126.0, 119.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.3}, {"lowerIsBetter": true, "replicates": [131.0, 124.0, 134.0, 132.0, 134.0, 134.0, 133.0, 133.0, 129.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.6}, {"lowerIsBetter": true, "replicates": [54.0, 81.0, 94.0, 102.0, 67.0, 66.0, 67.0, 69.0, 64.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 72.9}], "name": "kraken", "value": 1424.8}]} 14:27:12 INFO - Return code: 0 14:27:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:27:12 INFO - # TBPL SUCCESS # 14:27:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:27:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:27:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:27:12 INFO - 2016-04-24 14:27:12.459 screenresolution[1679:10010] starting screenresolution argv=screenresolution get 14:27:12 INFO - 2016-04-24 14:27:12.461 screenresolution[1679:10010] Display 0: 1600x1200x32@60 14:27:12 INFO - 2016-04-24 14:27:12.467 screenresolution[1680:10013] starting screenresolution argv=screenresolution list 14:27:12 INFO - Available Modes on Display 0 14:27:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:27:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:27:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:27:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:27:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:27:12 INFO - Intel Iris: 14:27:12 INFO - Chipset Model: Intel Iris 14:27:12 INFO - Type: GPU 14:27:12 INFO - Bus: Built-In 14:27:12 INFO - VRAM (Dynamic, Max): 1536 MB 14:27:12 INFO - Vendor: Intel (0x8086) 14:27:12 INFO - Device ID: 0x0a2e 14:27:12 INFO - Revision ID: 0x0009 14:27:12 INFO - Displays: 14:27:12 INFO - Display: 14:27:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:27:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:27:12 INFO - Display Serial Number: 1600x1200 60 14:27:12 INFO - Main Display: Yes 14:27:12 INFO - Mirror: Off 14:27:12 INFO - Online: Yes 14:27:12 INFO - Rotation: Supported 14:27:12 INFO - Return code: 0 14:27:12 INFO - Running post-action listener: _resource_record_post_action 14:27:12 INFO - Running post-run listener: _resource_record_post_run 14:27:13 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 32300544; Write bytes: 668508672; Read time: 324; Write time: 2112 14:27:13 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 363207168; Write bytes: 366340096; Read time: 28115; Write time: 861 14:27:13 INFO - run-tests - Wall time: 1172s; CPU: 52.0%; Read bytes: 30518784; Write bytes: 301320704; Read time: 305; Write time: 1248 14:27:13 INFO - Running post-run listener: _upload_blobber_files 14:27:13 INFO - Blob upload gear active. 14:27:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:27:13 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 14:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:27:14 INFO - (blobuploader) - INFO - Open directory for files ... 14:27:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:27:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:27:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:14 INFO - (blobuploader) - INFO - Done attempting. 14:27:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:27:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:27:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:27:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:27:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:27:15 INFO - (blobuploader) - INFO - Done attempting. 14:27:15 INFO - (blobuploader) - INFO - Iteration through files over. 14:27:15 INFO - Return code: 0 14:27:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:27:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:27:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc20fcdff206c713f22f915306a90df622646f0e2e47b5e85cabf718c3c28883a55e2606f48aa3a60acc1a5dc9aae2b96e0387f86fe7500084da7d901bee0b2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b560822803d89367ed1203b591c34124fbafba2aac51e115189f7dcf7ab41132c848205ce0560798d351ca3a5cea28e3988627b91ccc77f1beaaf1a11393dc3"} 14:27:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:27:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:27:15 INFO - Contents: 14:27:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc20fcdff206c713f22f915306a90df622646f0e2e47b5e85cabf718c3c28883a55e2606f48aa3a60acc1a5dc9aae2b96e0387f86fe7500084da7d901bee0b2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b560822803d89367ed1203b591c34124fbafba2aac51e115189f7dcf7ab41132c848205ce0560798d351ca3a5cea28e3988627b91ccc77f1beaaf1a11393dc3"} 14:27:15 INFO - Running post-run listener: copy_logs_to_upload_dir 14:27:15 INFO - Copying logs to upload dir... 14:27:15 INFO - mkdir: /builds/slave/test/build/upload/logs 14:27:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.667514 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-24 14:27:16.184538) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 14:27:16.193381) ========= 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.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc20fcdff206c713f22f915306a90df622646f0e2e47b5e85cabf718c3c28883a55e2606f48aa3a60acc1a5dc9aae2b96e0387f86fe7500084da7d901bee0b2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b560822803d89367ed1203b591c34124fbafba2aac51e115189f7dcf7ab41132c848205ce0560798d351ca3a5cea28e3988627b91ccc77f1beaaf1a11393dc3"} build_url:https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/OhmjSpHVQVGyMjeHEqCLDg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc20fcdff206c713f22f915306a90df622646f0e2e47b5e85cabf718c3c28883a55e2606f48aa3a60acc1a5dc9aae2b96e0387f86fe7500084da7d901bee0b2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b560822803d89367ed1203b591c34124fbafba2aac51e115189f7dcf7ab41132c848205ce0560798d351ca3a5cea28e3988627b91ccc77f1beaaf1a11393dc3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 14:27:16.226712) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:27:16.227041) ========= 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.V6uFOogb87/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.lyS1BJ9VQj/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005195 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 14:27:16.282185) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 14:27:16.282490) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 14:27:16.282856) ========= ========= Total master_lag: 5.30 =========