builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1481124725.06 results: success (0) buildid: 20161207062538 builduid: b56b1ebfee5743d2bd842d23b787158f revision: 93266100e27057f116effff38f70d8563533097b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.068425) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.068977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.069256) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.094463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.094805) ========= 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.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-12-07 07:32:05-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.24M=0.001s 2016-12-07 07:32:05 (8.24 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.620755 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.781062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.781375) ========= 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.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.019364 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:05.856205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-07 07:32:05.856592) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 93266100e27057f116effff38f70d8563533097b --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 93266100e27057f116effff38f70d8563533097b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-12-07 07:32:05,930 truncating revision to first 12 chars 2016-12-07 07:32:05,930 Setting DEBUG logging. 2016-12-07 07:32:05,930 attempt 1/10 2016-12-07 07:32:05,930 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/93266100e270?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-07 07:32:06,660 unpacking tar archive at: mozilla-aurora-93266100e270/testing/mozharness/ program finished with exit code 0 elapsedTime=0.981277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-07 07:32:06.861753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:06.862131) ========= script_repo_revision: 93266100e27057f116effff38f70d8563533097b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:06.862534) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:06.862811) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-07 07:32:06.882963) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-07 07:32:06.883315) ========= /tools/buildbot/bin/python -u 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', '-u', '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:07 INFO - MultiFileLogger online at 20161207 07:32:07 in /builds/slave/test 07:32:07 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 07:32:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:32:07 INFO - {'append_to_log': False, 07:32:07 INFO - 'base_work_dir': '/builds/slave/test', 07:32:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:32:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:32:07 INFO - 'branch': 'Mozilla-Aurora', 07:32:07 INFO - 'buildbot_json_path': 'buildprops.json', 07:32:07 INFO - 'config_files': ('talos/mac_config.py',), 07:32:07 INFO - 'default_actions': ('clobber', 07:32:07 INFO - 'read-buildbot-config', 07:32:07 INFO - 'download-and-extract', 07:32:07 INFO - 'populate-webroot', 07:32:07 INFO - 'create-virtualenv', 07:32:07 INFO - 'install', 07:32:07 INFO - 'run-tests'), 07:32:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:32:07 INFO - 'download_minidump_stackwalk': True, 07:32:07 INFO - 'download_symbols': 'ondemand', 07:32:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:32:07 INFO - 'tooltool.py': '/tools/tooltool.py', 07:32:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:32:07 INFO - '/tools/misc-python/virtualenv.py')}, 07:32:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:32:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:32:07 INFO - 'installer_path': 'installer.exe', 07:32:07 INFO - 'log_level': 'info', 07:32:07 INFO - 'log_name': 'talos', 07:32:07 INFO - 'log_to_console': True, 07:32:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:32:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:32:07 INFO - 'opt_config_files': (), 07:32:07 INFO - 'pip_index': False, 07:32:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:07 INFO - 'cmd': ('bash', 07:32:07 INFO - '-c', 07:32:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:07 INFO - 'enabled': True, 07:32:07 INFO - 'halt_on_failure': False, 07:32:07 INFO - 'name': 'check_screen_resolution'},), 07:32:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:32:07 INFO - 'cmd': ('bash', 07:32:07 INFO - '-c', 07:32:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:32:07 INFO - 'enabled': True, 07:32:07 INFO - 'halt_on_failure': False, 07:32:07 INFO - 'name': 'check_screen_resolution'},), 07:32:07 INFO - 'run_cmd_checks_enabled': True, 07:32:07 INFO - 'sps_profile': False, 07:32:07 INFO - 'sps_profile_interval': 0, 07:32:07 INFO - 'suite': 'dromaeojs', 07:32:07 INFO - 'system_bits': '32', 07:32:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:32:07 INFO - 'title': 't-yosemite-r7-0030', 07:32:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:32:07 INFO - 'use_talos_json': True, 07:32:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:32:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:32:07 INFO - 'work_dir': 'build'} 07:32:07 INFO - [mozharness: 2016-12-07 15:32:07.087734Z] Running clobber step. 07:32:07 INFO - Running pre-action listener: _resource_record_pre_action 07:32:07 INFO - Running main action method: clobber 07:32:07 INFO - rmtree: /builds/slave/test/build 07:32:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:32:07 INFO - Running post-action listener: _resource_record_post_action 07:32:07 INFO - [mozharness: 2016-12-07 15:32:07.736877Z] Finished clobber step (success) 07:32:07 INFO - [mozharness: 2016-12-07 15:32:07.737011Z] Running read-buildbot-config step. 07:32:07 INFO - Running pre-action listener: _resource_record_pre_action 07:32:07 INFO - Running main action method: read_buildbot_config 07:32:07 INFO - Using buildbot properties: 07:32:07 INFO - { 07:32:07 INFO - "project": "", 07:32:07 INFO - "product": "firefox", 07:32:07 INFO - "script_repo_revision": "production", 07:32:07 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:32:07 INFO - "repository": "", 07:32:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:32:07 INFO - "buildid": "20161207062538", 07:32:07 INFO - "pgo_build": "False", 07:32:07 INFO - "basedir": "/builds/slave/test", 07:32:07 INFO - "buildnumber": 6, 07:32:07 INFO - "slavename": "t-yosemite-r7-0030", 07:32:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:32:07 INFO - "platform": "yosemite_r7", 07:32:07 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:32:07 INFO - "branch": "mozilla-aurora", 07:32:07 INFO - "repo_path": "releases/mozilla-aurora", 07:32:07 INFO - "revision": "93266100e27057f116effff38f70d8563533097b", 07:32:07 INFO - "stage_platform": "macosx64", 07:32:07 INFO - "builduid": "b56b1ebfee5743d2bd842d23b787158f", 07:32:07 INFO - "slavebuilddir": "test" 07:32:07 INFO - } 07:32:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:32:07 INFO - Running post-action listener: _resource_record_post_action 07:32:07 INFO - [mozharness: 2016-12-07 15:32:07.740374Z] Finished read-buildbot-config step (success) 07:32:07 INFO - [mozharness: 2016-12-07 15:32:07.740465Z] Running download-and-extract step. 07:32:07 INFO - Running pre-action listener: _resource_record_pre_action 07:32:07 INFO - Running main action method: download_and_extract 07:32:07 INFO - mkdir: /builds/slave/test/build/tests 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:07 INFO - trying https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:32:08 INFO - Downloaded 1199 bytes. 07:32:08 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:32:08 INFO - Using the following test package requirements: 07:32:08 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:32:08 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:32:08 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:32:08 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'jsshell-mac.zip'], 07:32:08 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:32:08 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:32:08 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:32:08 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:32:08 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:32:08 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:32:08 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:32:08 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:32:09 INFO - Http code: 200 07:32:09 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 07:32:09 INFO - x-amz-cf-id: OUzhnrAp7dPeonqmlLNFrLS68pA0Zcb0G9AOtyhVlpoBL0-RC67vDQ== 07:32:09 INFO - x-amz-version-id: Ghz5yaORMF3NCjhtS_XWMRCbQaPNh6qG 07:32:09 INFO - Expected file size: 16281930 07:32:09 INFO - Obtained file size: 16281930 07:32:16 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:32:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:32:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:32:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:32:17 INFO - Http code: 200 07:32:17 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 07:32:17 INFO - x-amz-cf-id: n8yoETVhg2qbPQjoSJ4EkG0JNWmKLEeC4eEi5OpLm8xU2LBpfMdQJw== 07:32:17 INFO - x-amz-version-id: Ghz5yaORMF3NCjhtS_XWMRCbQaPNh6qG 07:32:17 INFO - Expected file size: 16281930 07:32:17 INFO - Obtained file size: 16281930 07:32:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:32:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:32:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:32:25 INFO - Http code: 200 07:32:25 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 07:32:25 INFO - x-amz-cf-id: J4JWldiSJsYy5DEM3jUTRTMxYZcLhgr8lmAFm7tSuexXz4c4jDrgqQ== 07:32:25 INFO - x-amz-version-id: fSeIFnIywqSiYvpkFeAO7qO.zU6rHKRe 07:32:25 INFO - Expected file size: 14409038 07:32:25 INFO - Obtained file size: 14409038 07:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:26 INFO - trying https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:32:29 INFO - Downloaded 109764960 bytes. 07:32:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:29 INFO - mkdir: /builds/slave/test/properties 07:32:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:29 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:29 INFO - Contents: 07:32:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:29 INFO - Running post-action listener: _resource_record_post_action 07:32:29 INFO - Running post-action listener: set_extra_try_arguments 07:32:29 INFO - [mozharness: 2016-12-07 15:32:29.947459Z] Finished download-and-extract step (success) 07:32:29 INFO - [mozharness: 2016-12-07 15:32:29.947607Z] Running populate-webroot step. 07:32:29 INFO - Running pre-action listener: _resource_record_pre_action 07:32:29 INFO - Running main action method: populate_webroot 07:32:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:29 INFO - '--tppagecycles', 07:32:29 INFO - '7'], 07:32:29 INFO - 'tests': ['tsvgm']}}, 07:32:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['damp', 'tps']}, 07:32:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'tests': ['damp', 'tps']}, 07:32:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['dromaeo_dom']}, 07:32:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['basic_compositor_video']}, 07:32:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['a11yr', 07:32:29 INFO - 'ts_paint', 07:32:29 INFO - 'tpaint', 07:32:29 INFO - 'sessionrestore', 07:32:29 INFO - 'sessionrestore_no_auto_restore', 07:32:29 INFO - 'tabpaint']}, 07:32:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['tsvgx', 07:32:29 INFO - 'tsvgr_opacity', 07:32:29 INFO - 'tart', 07:32:29 INFO - 'tscrollx', 07:32:29 INFO - 'cart']}, 07:32:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:29 INFO - 'tsvgr_opacity', 07:32:29 INFO - 'tart', 07:32:29 INFO - 'tscrollx', 07:32:29 INFO - 'cart']}, 07:32:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'talos_options': ['--disable-e10s'], 07:32:29 INFO - 'tests': ['tp5o']}, 07:32:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'tests': ['tp5o']}, 07:32:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'talos_options': ['--disable-e10s', 07:32:29 INFO - '--xperf_path', 07:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:29 INFO - 'tests': ['tp5n']}, 07:32:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:29 INFO - 'talos_options': ['--xperf_path', 07:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:29 INFO - 'tests': ['tp5n']}}, 07:32:29 INFO - 'talos.zip': {'path': '', 07:32:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:29 INFO - Running post-action listener: _resource_record_post_action 07:32:29 INFO - [mozharness: 2016-12-07 15:32:29.965367Z] Finished populate-webroot step (success) 07:32:29 INFO - [mozharness: 2016-12-07 15:32:29.965430Z] Running create-virtualenv step. 07:32:29 INFO - Running pre-action listener: _resource_record_pre_action 07:32:29 INFO - Running main action method: create_virtualenv 07:32:29 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:29 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 07:32:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:30 INFO - Using real prefix '/tools/python27' 07:32:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:30 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:33 INFO - Installing pip.................done. 07:32:33 INFO - Return code: 0 07:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:32:34 INFO - Reading from file tmpfile_stdout 07:32:34 INFO - Output received: 07:32:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:32:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:34 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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:34 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 07:32:34 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 07:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:34 INFO - 'HOME': '/Users/cltbld', 07:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:34 INFO - 'LOGNAME': 'cltbld', 07:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:34 INFO - 'MOZ_NO_REMOTE': '1', 07:32:34 INFO - 'NO_EM_RESTART': '1', 07:32:34 INFO - 'PAGER': '/bin/cat', 07:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:34 INFO - 'PWD': '/builds/slave/test', 07:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:34 INFO - 'SHELL': '/bin/bash', 07:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:34 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:34 INFO - 'USER': 'cltbld', 07:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:34 INFO - 'XPC_FLAGS': '0x0', 07:32:34 INFO - 'XPC_SERVICE_NAME': '0', 07:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:34 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 07:32:34 INFO - Cleaning up... 07:32:34 INFO - Return code: 0 07:32:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:34 INFO - 'HOME': '/Users/cltbld', 07:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:34 INFO - 'LOGNAME': 'cltbld', 07:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:34 INFO - 'MOZ_NO_REMOTE': '1', 07:32:34 INFO - 'NO_EM_RESTART': '1', 07:32:34 INFO - 'PAGER': '/bin/cat', 07:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:34 INFO - 'PWD': '/builds/slave/test', 07:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:34 INFO - 'SHELL': '/bin/bash', 07:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:34 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:34 INFO - 'USER': 'cltbld', 07:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:34 INFO - 'XPC_FLAGS': '0x0', 07:32:34 INFO - 'XPC_SERVICE_NAME': '0', 07:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:34 INFO - Downloading/unpacking psutil>=3.1.1 07:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:38 INFO - Installing collected packages: psutil 07:32:38 INFO - Running setup.py install for psutil 07:32:38 INFO - building 'psutil._psutil_osx' extension 07:32:38 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 07:32:38 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 07:32:38 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 07:32:38 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 07:32:38 INFO - building 'psutil._psutil_posix' extension 07:32:38 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 07:32:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:38 INFO - ^ 07:32:38 INFO - 1 warning generated. 07:32:38 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 07:32:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:39 INFO - Successfully installed psutil 07:32:39 INFO - Cleaning up... 07:32:39 INFO - Return code: 0 07:32:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:39 INFO - 'HOME': '/Users/cltbld', 07:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:39 INFO - 'LOGNAME': 'cltbld', 07:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:39 INFO - 'MOZ_NO_REMOTE': '1', 07:32:39 INFO - 'NO_EM_RESTART': '1', 07:32:39 INFO - 'PAGER': '/bin/cat', 07:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:39 INFO - 'PWD': '/builds/slave/test', 07:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:39 INFO - 'SHELL': '/bin/bash', 07:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:39 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:39 INFO - 'USER': 'cltbld', 07:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:39 INFO - 'XPC_FLAGS': '0x0', 07:32:39 INFO - 'XPC_SERVICE_NAME': '0', 07:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:42 INFO - Installing collected packages: mozsystemmonitor 07:32:42 INFO - Running setup.py install for mozsystemmonitor 07:32:42 INFO - Successfully installed mozsystemmonitor 07:32:42 INFO - Cleaning up... 07:32:42 INFO - Return code: 0 07:32:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:42 INFO - 'HOME': '/Users/cltbld', 07:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:42 INFO - 'LOGNAME': 'cltbld', 07:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:42 INFO - 'MOZ_NO_REMOTE': '1', 07:32:42 INFO - 'NO_EM_RESTART': '1', 07:32:42 INFO - 'PAGER': '/bin/cat', 07:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:42 INFO - 'PWD': '/builds/slave/test', 07:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:42 INFO - 'SHELL': '/bin/bash', 07:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:42 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:42 INFO - 'USER': 'cltbld', 07:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:42 INFO - 'XPC_FLAGS': '0x0', 07:32:42 INFO - 'XPC_SERVICE_NAME': '0', 07:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:42 INFO - Downloading/unpacking jsonschema==2.5.1 07:32:42 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 07:32:42 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 07:32:42 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 07:32:42 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 07:32:42 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 07:32:42 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 07:32:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32:45 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 07:32:45 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 07:32:45 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 07:32:45 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 07:32:45 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 07:32:45 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 07:32:45 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:45 INFO - warning: no files found matching '*.txt' 07:32:45 INFO - no previously-included directories found matching 'build' 07:32:45 INFO - no previously-included directories found matching 'dist' 07:32:45 INFO - no previously-included directories found matching '.git*' 07:32:45 INFO - Installing collected packages: jsonschema, functools32 07:32:45 INFO - Running setup.py install for functools32 07:32:45 INFO - warning: no files found matching '*.txt' 07:32:45 INFO - no previously-included directories found matching 'build' 07:32:45 INFO - no previously-included directories found matching 'dist' 07:32:45 INFO - no previously-included directories found matching '.git*' 07:32:45 INFO - Successfully installed jsonschema functools32 07:32:45 INFO - Cleaning up... 07:32:45 INFO - Return code: 0 07:32:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:45 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32:45 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:32:45 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 functools32==3.2.3-2 07:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:45 INFO - 'HOME': '/Users/cltbld', 07:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:45 INFO - 'LOGNAME': 'cltbld', 07:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:45 INFO - 'MOZ_NO_REMOTE': '1', 07:32:45 INFO - 'NO_EM_RESTART': '1', 07:32:45 INFO - 'PAGER': '/bin/cat', 07:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:45 INFO - 'PWD': '/builds/slave/test', 07:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:45 INFO - 'SHELL': '/bin/bash', 07:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:45 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:45 INFO - 'USER': 'cltbld', 07:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:45 INFO - 'XPC_FLAGS': '0x0', 07:32:45 INFO - 'XPC_SERVICE_NAME': '0', 07:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:46 INFO - Cleaning up... 07:32:46 INFO - Return code: 0 07:32:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32: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 07:32: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 07:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:46 INFO - 'HOME': '/Users/cltbld', 07:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:46 INFO - 'LOGNAME': 'cltbld', 07:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:46 INFO - 'MOZ_NO_REMOTE': '1', 07:32:46 INFO - 'NO_EM_RESTART': '1', 07:32:46 INFO - 'PAGER': '/bin/cat', 07:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:46 INFO - 'PWD': '/builds/slave/test', 07:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:46 INFO - 'SHELL': '/bin/bash', 07:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:46 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:46 INFO - 'USER': 'cltbld', 07:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:46 INFO - 'XPC_FLAGS': '0x0', 07:32:46 INFO - 'XPC_SERVICE_NAME': '0', 07:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:46 INFO - Downloading/unpacking blobuploader==1.2.4 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:50 INFO - Downloading docopt-0.6.1.tar.gz 07:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:50 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:50 INFO - Running setup.py install for blobuploader 07:32:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:50 INFO - Running setup.py install for requests 07:32:50 INFO - Running setup.py install for docopt 07:32:51 INFO - Successfully installed blobuploader requests docopt 07:32:51 INFO - Cleaning up... 07:32:51 INFO - Return code: 0 07:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32: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 07:32: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 07:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:51 INFO - 'HOME': '/Users/cltbld', 07:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:51 INFO - 'LOGNAME': 'cltbld', 07:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:51 INFO - 'MOZ_NO_REMOTE': '1', 07:32:51 INFO - 'NO_EM_RESTART': '1', 07:32:51 INFO - 'PAGER': '/bin/cat', 07:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:51 INFO - 'PWD': '/builds/slave/test', 07:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:51 INFO - 'SHELL': '/bin/bash', 07:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:51 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:51 INFO - 'USER': 'cltbld', 07:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:51 INFO - 'XPC_FLAGS': '0x0', 07:32:51 INFO - 'XPC_SERVICE_NAME': '0', 07:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-k6nhWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YdfNWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zIgjEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-OcTkjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1lv0Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-egz4ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-cZFzL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Qm4Lvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-koFIhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-uNKOHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-2_KxaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-I2lS54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zx7RBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CdIXY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-XUwlCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:52 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tusex6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:53 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-o30fDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:53 INFO - Running setup.py install for manifestparser 07:32:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:53 INFO - Running setup.py install for mozcrash 07:32:53 INFO - Running setup.py install for mozdebug 07:32:53 INFO - Running setup.py install for mozdevice 07:32:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:53 INFO - Running setup.py install for mozfile 07:32:53 INFO - Running setup.py install for mozhttpd 07:32:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Running setup.py install for mozinfo 07:32:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Running setup.py install for mozInstall 07:32:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Running setup.py install for mozleak 07:32:54 INFO - Running setup.py install for mozlog 07:32:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Running setup.py install for moznetwork 07:32:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:54 INFO - Running setup.py install for mozprocess 07:32:55 INFO - Running setup.py install for mozprofile 07:32:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:55 INFO - Running setup.py install for mozrunner 07:32:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:55 INFO - Running setup.py install for mozscreenshot 07:32:55 INFO - Running setup.py install for moztest 07:32:55 INFO - Running setup.py install for mozversion 07:32:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:55 INFO - Cleaning up... 07:32:55 INFO - Return code: 0 07:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:32: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 07:32: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 07:32:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:32:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:55 INFO - 'HOME': '/Users/cltbld', 07:32:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:55 INFO - 'LOGNAME': 'cltbld', 07:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:55 INFO - 'MOZ_NO_REMOTE': '1', 07:32:55 INFO - 'NO_EM_RESTART': '1', 07:32:55 INFO - 'PAGER': '/bin/cat', 07:32:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:55 INFO - 'PWD': '/builds/slave/test', 07:32:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:55 INFO - 'SHELL': '/bin/bash', 07:32:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:32:55 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:32:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:55 INFO - 'USER': 'cltbld', 07:32:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:55 INFO - 'XPC_FLAGS': '0x0', 07:32:55 INFO - 'XPC_SERVICE_NAME': '0', 07:32:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-agXC3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-oEYzrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-xUkUhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-0ZxAfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-w5RIai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-66HFk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PyWDW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32: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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-uAoup2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:56 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)) 07:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:56 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PvEGsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32: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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-5XYwFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-fZhDhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32: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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-SHLNRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BWkATI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32: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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EiUjV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8r6tJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32: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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CRpYfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32: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)) 07:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:57 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-cgghHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32: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)) 07:32: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32: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==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32: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)) 07:32: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)) 07:32: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)) 07:32:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:33:00 INFO - Downloading blessings-1.6.tar.gz 07:33:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:33:00 INFO - Installing collected packages: blessings 07:33:00 INFO - Running setup.py install for blessings 07:33:00 INFO - Successfully installed blessings 07:33:00 INFO - Cleaning up... 07:33:00 INFO - Return code: 0 07:33:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:01 INFO - Reading from file tmpfile_stdout 07:33:01 INFO - Current package versions: 07:33:01 INFO - blessings == 1.6 07:33:01 INFO - blobuploader == 1.2.4 07:33:01 INFO - docopt == 0.6.1 07:33:01 INFO - functools32 == 3.2.3-2 07:33:01 INFO - jsonschema == 2.5.1 07:33:01 INFO - manifestparser == 1.1 07:33:01 INFO - mozInstall == 1.12 07:33:01 INFO - mozcrash == 1.0 07:33:01 INFO - mozdebug == 0.1 07:33:01 INFO - mozdevice == 0.48 07:33:01 INFO - mozfile == 1.2 07:33:01 INFO - mozhttpd == 0.7 07:33:01 INFO - mozinfo == 0.9 07:33:01 INFO - mozleak == 0.1 07:33:01 INFO - mozlog == 3.4 07:33:01 INFO - moznetwork == 0.27 07:33:01 INFO - mozprocess == 0.23 07:33:01 INFO - mozprofile == 0.28 07:33:01 INFO - mozrunner == 6.13 07:33:01 INFO - mozscreenshot == 0.1 07:33:01 INFO - mozsystemmonitor == 0.3 07:33:01 INFO - moztest == 0.7 07:33:01 INFO - mozversion == 1.4 07:33:01 INFO - psutil == 3.1.1 07:33:01 INFO - requests == 1.2.3 07:33:01 INFO - wsgiref == 0.1.2 07:33:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:33: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 07:33: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 07:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:01 INFO - 'HOME': '/Users/cltbld', 07:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:01 INFO - 'LOGNAME': 'cltbld', 07:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:01 INFO - 'MOZ_NO_REMOTE': '1', 07:33:01 INFO - 'NO_EM_RESTART': '1', 07:33:01 INFO - 'PAGER': '/bin/cat', 07:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:01 INFO - 'PWD': '/builds/slave/test', 07:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:01 INFO - 'SHELL': '/bin/bash', 07:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:33:01 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:01 INFO - 'USER': 'cltbld', 07:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:01 INFO - 'XPC_FLAGS': '0x0', 07:33:01 INFO - 'XPC_SERVICE_NAME': '0', 07:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33: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)) 07:33:01 INFO - Cleaning up... 07:33:01 INFO - Return code: 0 07:33:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:33: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 0x10fc0c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb82570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96115558c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, '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.vFuMqAWIIX/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.WME8XqBOjO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 07:33: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 07:33: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 07:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:01 INFO - 'HOME': '/Users/cltbld', 07:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:01 INFO - 'LOGNAME': 'cltbld', 07:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:01 INFO - 'MOZ_NO_REMOTE': '1', 07:33:01 INFO - 'NO_EM_RESTART': '1', 07:33:01 INFO - 'PAGER': '/bin/cat', 07:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:01 INFO - 'PWD': '/builds/slave/test', 07:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:01 INFO - 'SHELL': '/bin/bash', 07:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:33:01 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:01 INFO - 'USER': 'cltbld', 07:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:01 INFO - 'XPC_FLAGS': '0x0', 07:33:01 INFO - 'XPC_SERVICE_NAME': '0', 07:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:33:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:33:01 INFO - Cleaning up... 07:33:01 INFO - Return code: 0 07:33:01 INFO - Running post-action listener: _resource_record_post_action 07:33:01 INFO - Running post-action listener: _start_resource_monitoring 07:33:01 INFO - Starting resource monitoring. 07:33:01 INFO - [mozharness: 2016-12-07 15:33:01.650608Z] Finished create-virtualenv step (success) 07:33:01 INFO - [mozharness: 2016-12-07 15:33:01.651681Z] Running install step. 07:33:01 INFO - Running pre-action listener: _resource_record_pre_action 07:33:01 INFO - Running main action method: install 07:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:33:01 INFO - Reading from file tmpfile_stdout 07:33:01 INFO - Detecting whether we're running mozinstall >=1.0... 07:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:33:02 INFO - Reading from file tmpfile_stdout 07:33:02 INFO - Output received: 07:33:02 INFO - Usage: mozinstall [options] installer 07:33:02 INFO - Options: 07:33:02 INFO - -h, --help show this help message and exit 07:33:02 INFO - -d DEST, --destination=DEST 07:33:02 INFO - Directory to install application into. [default: 07:33:02 INFO - "/builds/slave/test"] 07:33:02 INFO - --app=APP Application being installed. [default: firefox] 07:33:02 INFO - mkdir: /builds/slave/test/build/application 07:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:33:32 INFO - Reading from file tmpfile_stdout 07:33:32 INFO - Output received: 07:33:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:33:32 INFO - Running post-action listener: _resource_record_post_action 07:33:32 INFO - [mozharness: 2016-12-07 15:33:32.890169Z] Finished install step (success) 07:33:32 INFO - [mozharness: 2016-12-07 15:33:32.890316Z] Running run-tests step. 07:33:32 INFO - Running pre-action listener: _resource_record_pre_action 07:33:32 INFO - Running main action method: run_tests 07:33:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:32 INFO - 2016-12-07 07:33:32.904 screenresolution[29700:275803] starting screenresolution argv=screenresolution get 07:33:32 INFO - 2016-12-07 07:33:32.907 screenresolution[29700:275803] Display 0: 1600x1200x32@60 07:33:32 INFO - 2016-12-07 07:33:32.913 screenresolution[29701:275805] starting screenresolution argv=screenresolution list 07:33:32 INFO - Available Modes on Display 0 07:33:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:33 INFO - Intel Iris: 07:33:33 INFO - Chipset Model: Intel Iris 07:33:33 INFO - Type: GPU 07:33:33 INFO - Bus: Built-In 07:33:33 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:33 INFO - Vendor: Intel (0x8086) 07:33:33 INFO - Device ID: 0x0a2e 07:33:33 INFO - Revision ID: 0x0009 07:33:33 INFO - Displays: 07:33:33 INFO - Display: 07:33:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:33 INFO - Display Serial Number: 1600x1200 60 07:33:33 INFO - Main Display: Yes 07:33:33 INFO - Mirror: Off 07:33:33 INFO - Online: Yes 07:33:33 INFO - Rotation: Supported 07:33:33 INFO - Return code: 0 07:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:33:33 INFO - Python 2.7.3 07:33:33 INFO - Return code: 0 07:33:33 INFO - grabbing minidump binary from tooltool 07:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:33 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa20d30>, '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 0x10f886db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa18b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:33:33 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 07:33:33 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 07:33:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:33:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:33:33 INFO - Return code: 0 07:33:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:33:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:33:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:33:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:33:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:33:33 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/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 07:33:33 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/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 07:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vFuMqAWIIX/Render', 07:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:33 INFO - 'HOME': '/Users/cltbld', 07:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:33 INFO - 'LOGNAME': 'cltbld', 07:33:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:33 INFO - 'MOZ_NO_REMOTE': '1', 07:33:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:33 INFO - 'NO_EM_RESTART': '1', 07:33:33 INFO - 'PAGER': '/bin/cat', 07:33:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:33 INFO - 'PWD': '/builds/slave/test', 07:33:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:33 INFO - 'SHELL': '/bin/bash', 07:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WME8XqBOjO/Listeners', 07:33:33 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 07:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:33 INFO - 'USER': 'cltbld', 07:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:33 INFO - 'XPC_FLAGS': '0x0', 07:33:33 INFO - 'XPC_SERVICE_NAME': '0', 07:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:33 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/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 07:33:33 INFO - mozversion application_buildid: 20161207062538 07:33:33 INFO - mozversion application_changeset: 93266100e27057f116effff38f70d8563533097b 07:33:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:33:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:33:33 INFO - mozversion application_name: Firefox 07:33:33 INFO - mozversion application_remotingname: firefox-dev 07:33:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:33 INFO - mozversion application_vendor: Mozilla 07:33:33 INFO - mozversion application_version: 52.0a2 07:33:33 INFO - mozversion platform_buildid: 20161207062538 07:33:33 INFO - mozversion platform_changeset: 93266100e27057f116effff38f70d8563533097b 07:33:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:33 INFO - mozversion platform_version: 52.0a2 07:33:33 INFO - using testdate: 1481124813 07:33:33 INFO - actual date: 1481124813 07:33:33 INFO - starting webserver on 'localhost:49412' 07:33:33 INFO - SUITE-START | Running 2 tests 07:33:33 INFO - TEST-START | dromaeo_css 07:33:33 INFO - operating with platform_type : mac_ 07:33:33 INFO - Initialising browser for dromaeo_css test... 07:33:33 INFO - TEST-INFO | started process 29711 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpc_ck7p/profile http://localhost:49412/getInfo.html) 07:33:36 INFO - PROCESS | 29711 | __metrics Screen width/height:1600/1200 07:33:36 INFO - PROCESS | 29711 | colorDepth:24 07:33:36 INFO - PROCESS | 29711 | Browser inner width/height: 1024/689 07:33:36 INFO - PROCESS | 29711 | __metrics 07:33:36 INFO - PROCESS | 29711 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:33:36 INFO - PROCESS | 29711 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:33:37 INFO - TEST-INFO | 29711: exit 0 07:33:37 INFO - Browser initialized. 07:33:37 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:37 INFO - TEST-INFO | started process 29714 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpc_ck7p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:49 INFO - PROCESS | 29714 | RSS: Main: 222695424 07:33:49 INFO - PROCESS | 29714 | 07:34:40 INFO - PROCESS | 29714 | 1481124880978 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] 07:36:55 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49412/tests/dromaeo/cssquery-ext.html) 07:36:56 INFO - PROCESS | 29714 | RSS: Main: 347549696 07:36:56 INFO - PROCESS | 29714 | 07:39:59 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-ext.html (next: http://localhost:49412/tests/dromaeo/cssquery-jquery.html) 07:40:00 INFO - PROCESS | 29714 | RSS: Main: 349339648 07:40:00 INFO - PROCESS | 29714 | 07:43:02 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49412/tests/dromaeo/cssquery-mootools.html) 07:43:03 INFO - PROCESS | 29714 | RSS: Main: 331915264 07:43:03 INFO - PROCESS | 29714 | 07:46:05 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49412/tests/dromaeo/cssquery-prototype.html) 07:46:06 INFO - PROCESS | 29714 | RSS: Main: 334213120 07:46:06 INFO - PROCESS | 29714 | 07:49:09 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49412/tests/dromaeo/cssquery-yui.html) 07:49:10 INFO - PROCESS | 29714 | RSS: Main: 311980032 07:49:10 INFO - PROCESS | 29714 | 07:52:12 INFO - PROCESS | 29714 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-yui.html (next: http://localhost:49412/tests/dromaeo/cssquery-dojo.html) 07:52:13 INFO - PROCESS | 29714 | RSS: Main: 534523904 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | __start_tp_report 07:52:13 INFO - PROCESS | 29714 | _x_x_mozilla_page_load 07:52:13 INFO - PROCESS | 29714 | _x_x_mozilla_page_load_details 07:52:13 INFO - PROCESS | 29714 | |i|pagename|runs| 07:52:13 INFO - PROCESS | 29714 | |0;dojo.html;2710.796690248231;3263.053714422817;3266.526353678716;3297.093299342682;3332.8333583320828;5599.384067752544;5658.405867383922;5658.43415658434;5672.886542269152;5688.687122208277;5585.944140558603;5685.516731077863;5690.459406356389;5705.942940570593;5729.28383952006;5126.436092029874;5159.509846564571;5166.793328266865;5189.662671926313;5234.842954711346;5028.421731500887;5049;5079.060373830835;5138.306328645623;5302.6023048271345;5127.538521533062;5141.203113517411;5145.485451454843;5152.433232344439;5155.7422128893595;5120.052790233819;5133.460986596391;5134.05020071288;5136.203888397305;5140.614453915971;4987.177115775893;5079.161938280179;5086.18621020635;5094.439611642716;5099.898002039976;5602.607817452739;5608.4391560844;5609.859753506195;5610.747516361774;5615.326160860682;6045.697715114226;6050.969745151246;6064.696765161724;6082.239720034996;6118.541109416811;6154.84612884677;6163.7842675506145;6167.722452489649;6195.504359651217;6229.96885015572;5690.118031705092;5712.828615141552;5720.68536230503;5722.341930677942;5724.084146536534;4492.820287188476;6151.815445536641;6218;6227.439530442282;6234.064890266496;5941.287045554561;5944.316403613553;5956.195913551615;5974.103884417372;5976.85057873548;6189.84525386869;6204;6206.038064099981;6211.9068213976825;6211.9689401553605;5892.616979896293;5940.138679891392;5941.197938278364;6048.243969503812;6150.7539698411565;6123.510119190454;6145.661988590581;6188.907166392508;6191.81424557264;6198.6280823150755;4857.854264372075;4863.927041094386;4878.829240976549;4885.9755701221975;4896.926546101811;4285.978570107191;4324.394584758136;4384.05742765307;4393.912121757547;4403.735775853395;5540.113581826889;5550.084236101079;5608.046632072557;5625.718714064362;5696.686682232434;6188.721507532172;6194.721237544321;6202.503799696014;6222.657753823583;6238.1890354254565;5487.506124448819;5630.155476678449;5636.4927156556105;5640.407757185519;5641.774329026875;5216.087184742655;5222.086134926372;5235.083860324504;5279.577633789288;5286.920696189561;6238.532110091852;6279.623222606659;6288.27684816252;6290.811275661737;6297.244330680348;6230.59501132425;6251.34360892118;6251.937480625135;6254.8123556293385;6276.215473065867;6166.938330616636;6284.685765711604;6285.214348206474;6297.811065668037;6298.653574053288;5726.226959360433;5942.494887934401;5975.880482390415;5992.610480318765;6117.785877494266;6170.290416602148;6201.875962480815;6205.844853878509;6223.875522489616;6227.688615569294;5983.252093488314;5993.160957466045;6002.069679199731;6008.369121242206;6009.278886533644;5794.913076303943;5848.298204215522;5902.380250073852;5981.970090149522;5995.970020149872;4853.272009198648;5825.592208545361;5837.824865254049;5838.299404071538;5902.1441890926435;4838.153323168502;5680.857978550404;6007.759689612367;6047.727852246748;6053.939460605514;5673.3759286477625;5676.77292908279;5821.970890145692;5829.94170058294;5864.589478736618 07:52:13 INFO - PROCESS | 29714 | |1;ext.html;465.3485120830836;497.1870990929829;502.1939786642439;503.4713550771689;506.4656787089619;30310.545341819863;31002.844985775097;38414.42378364323;39923.40114898279;40026.79986600063;33001;41586.58413415865;42054.94862628436;42082.579174208324;42129;9245.982941876371;9309.115634014757;9451.432914025181;9458.905410945872;9470.857937130913;9359.95320023399;9408.81182376352;9422;9430.25540275048;9455.432674039545;9410.106039926197;9467.95266023669;9474.715758527254;9474.905250947506;9482.288828337867;9392.671256506024;9440.433573985549;9444.763880902963;9452.95273523635;9515.333926625139;9336.486493242868;9412.152906238403;9414.717558473258;9422.62309507619;9450.385724927857;38650.80674596609;38969.22061558753;39008.414873776914;39019.60980390188;39053.60946390528;3315.370079684852;3324.434846076173;3401.6598340166033;3468.1849765305133;3897.2400381925536;28817.711822881924;29035;29435;29623.851880740458;30204.84897575498;2588.7799537039186;2995.3709720958623;3127.499600063979;3156.4949608062598;3168.762342824274;377906;490111;494768;495525;497302;59855.40144598585;60470;60544;60566.0915086274;60664.090038649454;34104.97685069452;34937.47593786095;35117.122071948404;36057.91826245217;36153;15389.307481163374;15596.532104036896;15612.609684757972;15619.765703514457;15644.217789110498;11838.88161118395;11929.522819087138;11930;11930.284182949052;11942.880571194177;15952.840471595367;16609.41867034672;16698.4155554555;17552.210150543255;18386.724199137025;7027.262137475594;7408.370158565107;7706.267904549059;7729.652165652559;7779.260970207822;835.4193835284541;917.1607978699549;1014.4724743133529;1048.4652827058255;1051.805415998043;29988;30645.693543064284;31157.065288041395;31472.37055258882;32058.519122213187;980.3284749946167;1035.0736091198335;1051.6548097742077;1060.7695073714453;1139;14319.713605727828;14719.896007799458;14821;15066.773998390034;15126.470573529874;503133;644864;648198;651164;652264;54059.18911216335;54346;54433;54463.18305225425;54545.45454545483;1097.2429023973432;1226.4971361741643;1267.3853181207107;1343.1269674711516;1358.7622166121087;29195.41609167847;29627.555586666647;29692.554611681273;29801.403971920874;30484;40455.59544404636;47623.52376476191;47841;48177.5182248173;48427.5157248423;515002;519106;522133;522209;522380;22652.207172748876;22877.08491660315;23225.419364516023;23258.0696772134;23301.18445854387;41702.79148604238;42533.36199957065;42919.7854010728;43409.34885976776;43501;30668.693313067477;34972.12569685676;37345.43981840329;37492;38392.61607384002;37310;37574;37951.24097517979;38402.61597383991;38635.8409247723;36421.27157456889;36980;37302;37535.249295013404;37706;31960.36079278448;33702.49446258357;33857.49213761845;33905.83047084847;33986.66013339835;24649;24726.629100563146;25107.87446062758;25302.620460693473;25362 07:52:13 INFO - PROCESS | 29714 | |2;jquery.html;352.6138877928667;372.3985762992766;375.22703231343417;383.4574077680082;403.3848381218641;14821.333040013198;15021.92489037556;15813;19891.40325790225;20507.74414814369;19325.903370483127;19765.308214212506;20329;20407.183712651477;20556.280530181448;5658.34928983166;5682.602217844753;5889.823305300848;5920.259967504062;5923.911141332884;5664.376918538957;5783.826485205451;5819.185314056035;5822.970885145568;5841.299044114691;5586.55307575393;5769.1346298055205;5934.139549765281;5963.314218864821;6049.425304596057;5761.337446193657;5918.408159184069;5982.790602328898;5989.431004054608;5989.5507836911975;5737.799177028825;5819.418058194189;5822.621529600562;5826.679532625704;5836.883262334772;12862;13112.409941552653;23028.539429211323;24034.87982560076;24121.396965075837;2263.7849404306567;2323.6745464213104;2479.119912431097;2523.470071285032;2684.610731443949;9668;9732.90267097334;10216.13162881156;10995.780084398268;15322.080675159526;1757.8242175782448;1758;1774.9201285942033;1811.3388613156235;1830.670479313723;119847.80152198454;122257;126861;127411.25887411185;131256.34371828174;11100.278481898651;11352.921472460102;11481.827772583418;11532.94233528827;11682.299062056303;14459.566213013626;14463.204523751294;14477.203753793432;14504.129752214902;14531.927340363442;8020.679172833138;8262.380321475914;8268;8437.240648341678;8456.11210822867;8131.471454355448;8146.2261085196815;8178.263956243966;8299.004119505698;8303.750887473385;4321.67587430944;4407.537208593051;4439.023414848728;4498.50516443191;4498.910021799546;1545.4668139491857;1685.8398452147026;1812.3203798575535;1926.7398901148447;1927.1616846671743;779.906411230656;894.6242578117207;938.202527851321;940.2337095267335;969.9563519641492;18593.628127437376;20628.96855157236;21377.465563361042;21449.67825482619;21509.247176349065;923.70903165504;937.4703292639637;968.4431451915036;969.8060387922444;1024.462157367388;10758.354498729945;11148.852544055251;11163.929919272265;11239.269447485887;11326.773464530828;593265;600529;602087;602957;606588;68773.31226687669;71298.64350678318;116049.41975290069;126649;131476;767.7926959720844;938.4275591888942;969.9136966597456;986.9062439068277;991.4893829677721;14440.927795360956;15435.15106669144;15562.61093472627;18405.355812546495;19836.10737516844;53139;55206.72396637991;56310.7184464075;56962;57836;76897.84653230094;78447;79212.20787792279;80351.19648803672;80667;7298.379637730853;7968.601569921597;8112.797180070545;8203.30271926893;8291.95854020726;6856.8546293410955;9654.324197306121;12159.878401216229;12909.677258068623;13099.279539625411;10765.89234107649;11070.446477676245;11203.103751699682;11269.267497612629;11306.208565400342;10525.315854469742;10828.837567436653;11004.339739615649;11058.447077645924;11149;10272.383656980604;10555.683329500127;10951.397673128055;11132.38771867555;11491.712707182387;6794.626295553792;7956.880646790419;7977.680892764457;8007.879881801661;8392.748217553482;10437.739056523344;10792.028717415471;10947.83578246343;11228.4385780709;11299.265547739371 07:52:13 INFO - PROCESS | 29714 | |3;mootools.html;1476.497990683168;1505.4655597262972;1670.2984746406507;1804.8736588438799;1861.0880668472453;7926.682932682693;8346.74959751207;8357.498550086999;8363.790905227364;8376.53929033903;7350.816229594249;7591;7599.316061554459;7675.194104619019;8168.550729709864;1844.3360390259495;2089.613421517017;2122.257209976511;2189.7956124131742;2200.482886521666;2333.754955729641;2338.754430784769;2342.6954495915506;2343.238447504568;2348;1895.8293753562177;2253.4141123307895;2312.0405031911737;2312.982287793374;2313.9305820825402;2047.242520267503;2117.9788202117934;2121.2681624839406;2234.2626933112033;2311.7688231176917;2305.8385912986096;2320.953580928372;2324.3143272734414;2324.7907688307964;2326.336993956714;8205.384596155312;8269.338452923752;8270;8271.048829384637;8297.83404331916;4163.5628259032665;4331.740095594275;5088.516590923857;5109.233614957749;5111.718855462948;3229;3370.359631669975;3421.726261899042;3524.753267271292;3538.168530395355;927.5594092805931;1011.1758916483043;1038;1056.27645063132;1074.790415868906;10673.679789606247;10722.356658600509;10812.891871081345;10813.67558973232;10834.079103276152;4410.07388448423;4520.186366454004;4624.028953919647;4626.629869610423;4669.696469729457;9273.397229180082;9279.118483744034;9283.860742088875;9286.953565232267;9295.209907157967;5113.795448182031;5114.105031619526;5115.283860259567;5118.436971933084;5134.460881607452;4586.908261834745;4627.398438203012;4653.302004699254;4690.226112691368;4709.693869898446;3055.801372910754;3722.10669439338;3727.906802329909;3766.7928263945196;3805.8858234253016;1239.324568110382;1243.055277988717;1250.4498020870788;1261.7413430246777;1262.3561983388538;754.3851760814919;873.3406278259873;876.1326286975848;899.9280057595377;918.7703074231442;5537.280153580089;5542.251796007568;5637.774489020393;5705.686187259657;5925.259342582177;969.762408209993;1005.1857995024054;1016.8068067067234;1042.7393151712072;1057.0592172966067;3445.29550263763;3739.682127019234;3976.980115099406;4032.1734044520804;4116.958830411718;9796.069373409515;10214.693559193236;10232.693019209435;10233.385996840065;10237.641682541223;9646.951765241129;9803.656872009446;10072.194224462026;10164.0344167304;10166.390016599027;7573.659185336673;7818.179091195342;7820.921790782133;7827.452078354574;7849.725259615886;5556.694381809039;5568.053430916673;5606.271184745957;5772.307323121252;5793.826185214451;9211.124943130391;9268.490233036979;9337.71986840396;9346.20557252614;9366.063393660852;8952.01527831951;9317.16145546904;9327.906720932704;9335.53322333867;9444.102810233016;7034.613096279753;7183.928160718535;7194.316539928698;7208.56748595086;7208.56748595086;7570.470067095251;7682.0397450318715;7766.417518686008;7772.300492955661;7773.572453514885;6900.447964162755;7019.789406317818;7053.435725141873;7177.605231712306;7238.348548630648;6792.286809884891;6937.93062069393;6949.235584085847;6951.721931122901;6959.582425054513;6479.222493300834;6643.069970204272;6646.634435106115;6656.467482601283;6657.800265992028;4725.755266248504;4768.213244814567;4853.223484242504;4919.163742163769;4968.652194346361;4844.370231869834;4857.344258525054;4975.5522003019905;5009.298698182331;5011.498850115106 07:52:13 INFO - PROCESS | 29714 | |4;prototype.html;299.8246026074746;302.1253471200872;307.847615430362;311.17538522914276;313.06081754735794;4768.0702263058765;5046.82336118236;5076.289319495274;5257.605679574038;5337.439568845269;4922.55696987271;5024.447310795818;5129.487051294868;5129.871753206163;5182.663126896749;3714.294284086022;3986.382110772835;4021.658159056484;4034.8413598724687;4049.574794646564;3917.941230881525;4037.37420699792;4060.451839001741;4062.3033039933666;4092.283850326181;3914.3149948758996;3980.4029395590605;3998.2003599280115;4002.1595464952393;4003.4195041718935;3738.803582853488;3981.1838573092446;3985.302572049894;4006.7395619284653;4014.759114453113;3208.2139875730363;3500.264944361687;3502.8615699897637;3514.4376899695926;3534.3284775892757;4159;4891;5105.7024339047575;5165.210786175461;5250.264962905197;4559.977200113978;4563.315502674593;4565.497795242521;4605.585497305258;4622.214223581999;4280.892977675552;5469.562435005229;5472.945270547323;5528.281323427928;5529.723513824292;3535.5957948594946;3789.677877380426;3822.2164456286396;3871.5868707921404;3891.805409729502;5096.337476128079;5761.222234998263;5830.271216097945;5845.1232315153065;5912.280351038527;3234.9838250808593;3288.391647545212;3693.8891833245048;3845.4616353710503;3878.0498777799057;3025.8335791531235;3464.688178063936;3927.5286965563746;3935.685145188378;3937.251922134785;4120.011197312623;4209.621134097946;4266.146770645883;4510.368548403226;4531.84138555149;4855.975720121377;4997.800087996439;5617.38208797032;5630.831075067755;5700.258966334433;1207.8550573931184;1218.7501562179732;1222.5879109628393;1244.6639407359962;1254.328934020313;785.9371250299962;858.7938894665235;862.7023676831483;862.7886167888907;870.7953630896734;4974.7512624369365;4991.128326877421;4998.475160108209;5060.381826993717;5076.162433198481;4762.261849413347;5080.618953578496;5327.493888080626;5339.3058902342445;5511.283533140746;3893.824777885002;3951.7431366961055;4238;4274.893127671833;4940.555350018516;2451.7793398593853;2653.270350653547;2770.847603381793;2804.971950280512;2824.347756562434;4911.698399924023;4925.38432695913;5569.66582005081;5587.720613969285;5626.099824028136;5162.948370516322;5363.383210930715;5518.813455107172;5531.585131115183;5622.662640241599;5502.58730595199;5503.862403439866;5507.476789704971;5596.4403559644525;5601.551875849922;4394.780260987001;4896.755162241945;5193.091209038479;5350.421625620529;5868.618539794899;4356.455443069616;4981.37732783402;5417.3477089486505;5418.322709661293;5786.797462088806;5982.4017598239825;6033.185519954925;6034.668093254738;6043.184170136975;6058.212432383762;3197.7368939268395;3968.4047392890143;3970.960290397059;4011.8967283997135;4013.9598604013586;5631.63394379364;5681.9431805681415;5693.572982026448;5759.769609215752;5759.769609215752;5252.65857719247;5488.259085023471;5501.752421141138;5529.253550770594;5635.633683810539;5539.972300138474;5571.359293681278;5605.6456288359805;5617.865211219711;5621.606487545996;5281.313429254173;5374.548871804669;5405.567554595702;5448.7548060336585;5502.367227768857;4769.856904292877;5549.278593782944;5562.582806289625;5666.376698563237;5691.089425692035;4936.4569897311985;5192.636515444034;5258.579313654975;5479.205515200194;5519.558435325244 07:52:13 INFO - PROCESS | 29714 | |5;yui.html;607.8389226854881;684.7466437418154;740.9666565004576;744.5941961630914;787.2088551006245;13904.513342033031;13934.303284835756;14348.928255358722;14366;14564;12679.746405071915;12814.231146131238;12816.359182040907;12816.935915320433;12821.935890320536;661.5369241530924;684.5550392245055;825.4180802534214;838.5136620759976;842.9536375499345;681.5944513014748;805.7985503624094;821.5070957425556;833.1418638802013;836.2640876029117;872.7818045488627;873.1399571422144;873.7029410000598;874.6938571499987;876.5967654878764;697.8883378659441;867.2065060469663;867.280157469299;867.4708427859064;868.6742471573192;486.03764546198374;533.016584401782;533.6717918480145;533.970631615261;581.7207740284645;13724.862751372559;14172.645683857883;14192.077514961598;14201.644958876008;14204.644883877882;13074.738505229938;13238.66903327415;13273.402696878664;13314.134581252187;13363.532276370377;9522.952385238099;11747.952299151011;11847.822282665767;12164.51341946321;12503.74992500154;4378.715383500063;4416.823327066913;4531.161735079021;4531.52418996004;4536.115457485792;80503.37734801948;83846;87856;90570;92201;10205.183585313158;10305.84541231882;10346.275760696679;10619.097376723064;10658.946705266424;19993.600127997357;20124;20249;20251;20265.29071482491;6943.9652801735665;7429.2570742926355;7451.478396512301;7487.438442116863;7679.2320767923875;4164.250434921743;4257.489101307801;4269.018125831076;4299.9570004300185;4437.511873693956;3182;3276.7870088444174;3281.032095531776;3285.5235990781202;3285.572875526214;612.9203203583505;618.0790621973227;623.9532035097295;631.7157279224367;635.1933045032782;512.7359409903903;530.3317819547398;577.3677822784044;599.9790007349721;610.2097783370457;8497.74506764798;8677.39258251929;8699.913000870038;8767.868481972775;8812.823743525221;545.6371512943928;555.5999680230228;571.3829064610211;625.1622825413967;643.5848877474097;4221.261279276115;4230.830766769294;4233.745975241495;4292.18448494785;4367.606915377632;86285.13714862897;102587;105094;107868.92131078588;110744.44627776809;56862.86274274491;59708;79080;82366.58816705998;84428.57785711031;74017.62991185009;76003.8599420998;78027;79744.60127699324;80959.59520202361;9048.638054477748;9329.813403731752;9450.43297402158;9514.858217014003;9518.286128540525;46752.29871551861;47074.35107787087;50743.238851418;51407.74296128495;52518.687032824484;67088.66455667885;68126.65936670285;70791.64604176946;71750;72925;11809.11431642613;12130;12182.39088045612;12317.384130793604;12343.876561234632;11444;13050.282234477196;13262.673732627047;13404.927605791709;13431.529896453578;12346.320952347707;13173.40719667598;13496.122752021087;13760.449582016607;13934.721305574034;12352.567660131852;13108.934455327662;13345.266010369523;13435.19388836673;13633.250171240677;11591.478383472933;12613.243205407705;13127.343632818513;13375.331233438095;13387.263700496565;9791.559379828068;10303;11289.37908415045;11344.716382090513;11385.316880987168;9113.726588202364;9567.596170165889;9602.471864047544;10934.726631834268;11002.559897604326 07:52:13 INFO - PROCESS | 29714 | __end_tp_report 07:52:13 INFO - PROCESS | 29714 | __start_cc_report 07:52:13 INFO - PROCESS | 29714 | _x_x_mozilla_cycle_collect,1425 07:52:13 INFO - PROCESS | 29714 | __end_cc_report 07:52:13 INFO - PROCESS | 29714 | __startTimestamp1481125933125__endTimestamp 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | ------- Summary: start ------- 07:52:13 INFO - PROCESS | 29714 | Number of tests: 6 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#0] dojo.html Cycles:165 Average:5631.36 Median:5794.91 stddev:659.26 (11.4%) stddev-sans-first:620.20 07:52:13 INFO - PROCESS | 29714 | Values: 2710.8 3263.1 3266.5 3297.1 3332.8 5599.4 5658.4 5658.4 5672.9 5688.7 5585.9 5685.5 5690.5 5705.9 5729.3 5126.4 5159.5 5166.8 5189.7 5234.8 5028.4 5049.0 5079.1 5138.3 5302.6 5127.5 5141.2 5145.5 5152.4 5155.7 5120.1 5133.5 5134.1 5136.2 5140.6 4987.2 5079.2 5086.2 5094.4 5099.9 5602.6 5608.4 5609.9 5610.7 5615.3 6045.7 6051.0 6064.7 6082.2 6118.5 6154.8 6163.8 6167.7 6195.5 6230.0 5690.1 5712.8 5720.7 5722.3 5724.1 4492.8 6151.8 6218.0 6227.4 6234.1 5941.3 5944.3 5956.2 5974.1 5976.9 6189.8 6204.0 6206.0 6211.9 6212.0 5892.6 5940.1 5941.2 6048.2 6150.8 6123.5 6145.7 6188.9 6191.8 6198.6 4857.9 4863.9 4878.8 4886.0 4896.9 4286.0 4324.4 4384.1 4393.9 4403.7 5540.1 5550.1 5608.0 5625.7 5696.7 6188.7 6194.7 6202.5 6222.7 6238.2 5487.5 5630.2 5636.5 5640.4 5641.8 5216.1 5222.1 5235.1 5279.6 5286.9 6238.5 6279.6 6288.3 6290.8 6297.2 6230.6 6251.3 6251.9 6254.8 6276.2 6166.9 6284.7 6285.2 6297.8 6298.7 5726.2 5942.5 5975.9 5992.6 6117.8 6170.3 6201.9 6205.8 6223.9 6227.7 5983.3 5993.2 6002.1 6008.4 6009.3 5794.9 5848.3 5902.4 5982.0 5996.0 4853.3 5825.6 5837.8 5838.3 5902.1 4838.2 5680.9 6007.8 6047.7 6053.9 5673.4 5676.8 5822.0 5829.9 5864.6 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#1] ext.html Cycles:180 Average:65907.22 Median:207928.62 stddev:145020.20 (69.7%) stddev-sans-first:145343.78 07:52:13 INFO - PROCESS | 29714 | Values: 465.3 497.2 502.2 503.5 506.5 30310.5 31002.8 38414.4 39923.4 40026.8 33001.0 41586.6 42054.9 42082.6 42129.0 9246.0 9309.1 9451.4 9458.9 9470.9 9360.0 9408.8 9422.0 9430.3 9455.4 9410.1 9468.0 9474.7 9474.9 9482.3 9392.7 9440.4 9444.8 9453.0 9515.3 9336.5 9412.2 9414.7 9422.6 9450.4 38650.8 38969.2 39008.4 39019.6 39053.6 3315.4 3324.4 3401.7 3468.2 3897.2 28817.7 29035.0 29435.0 29623.9 30204.8 2588.8 2995.4 3127.5 3156.5 3168.8 377906.0 490111.0 494768.0 495525.0 497302.0 59855.4 60470.0 60544.0 60566.1 60664.1 34105.0 34937.5 35117.1 36057.9 36153.0 15389.3 15596.5 15612.6 15619.8 15644.2 11838.9 11929.5 11930.0 11930.3 11942.9 15952.8 16609.4 16698.4 17552.2 18386.7 7027.3 7408.4 7706.3 7729.7 7779.3 835.4 917.2 1014.5 1048.5 1051.8 29988.0 30645.7 31157.1 31472.4 32058.5 980.3 1035.1 1051.7 1060.8 1139.0 14319.7 14719.9 14821.0 15066.8 15126.5 503133.0 644864.0 648198.0 651164.0 652264.0 54059.2 54346.0 54433.0 54463.2 54545.5 1097.2 1226.5 1267.4 1343.1 1358.8 29195.4 29627.6 29692.6 29801.4 30484.0 40455.6 47623.5 47841.0 48177.5 48427.5 515002.0 519106.0 522133.0 522209.0 522380.0 22652.2 22877.1 23225.4 23258.1 23301.2 41702.8 42533.4 42919.8 43409.3 43501.0 30668.7 34972.1 37345.4 37492.0 38392.6 37310.0 37574.0 37951.2 38402.6 38635.8 36421.3 36980.0 37302.0 37535.2 37706.0 31960.4 33702.5 33857.5 33905.8 33986.7 24649.0 24726.6 25107.9 25302.6 25362.0 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#2] jquery.html Cycles:180 Average:34355.35 Median:362.51 stddev:100112.67 (27616.8%) stddev-sans-first:100360.96 07:52:13 INFO - PROCESS | 29714 | Values: 352.6 372.4 375.2 383.5 403.4 14821.3 15021.9 15813.0 19891.4 20507.7 19325.9 19765.3 20329.0 20407.2 20556.3 5658.3 5682.6 5889.8 5920.3 5923.9 5664.4 5783.8 5819.2 5823.0 5841.3 5586.6 5769.1 5934.1 5963.3 6049.4 5761.3 5918.4 5982.8 5989.4 5989.6 5737.8 5819.4 5822.6 5826.7 5836.9 12862.0 13112.4 23028.5 24034.9 24121.4 2263.8 2323.7 2479.1 2523.5 2684.6 9668.0 9732.9 10216.1 10995.8 15322.1 1757.8 1758.0 1774.9 1811.3 1830.7 119847.8 122257.0 126861.0 127411.3 131256.3 11100.3 11352.9 11481.8 11532.9 11682.3 14459.6 14463.2 14477.2 14504.1 14531.9 8020.7 8262.4 8268.0 8437.2 8456.1 8131.5 8146.2 8178.3 8299.0 8303.8 4321.7 4407.5 4439.0 4498.5 4498.9 1545.5 1685.8 1812.3 1926.7 1927.2 779.9 894.6 938.2 940.2 970.0 18593.6 20629.0 21377.5 21449.7 21509.2 923.7 937.5 968.4 969.8 1024.5 10758.4 11148.9 11163.9 11239.3 11326.8 593265.0 600529.0 602087.0 602957.0 606588.0 68773.3 71298.6 116049.4 126649.0 131476.0 767.8 938.4 969.9 986.9 991.5 14440.9 15435.2 15562.6 18405.4 19836.1 53139.0 55206.7 56310.7 56962.0 57836.0 76897.8 78447.0 79212.2 80351.2 80667.0 7298.4 7968.6 8112.8 8203.3 8292.0 6856.9 9654.3 12159.9 12909.7 13099.3 10765.9 11070.4 11203.1 11269.3 11306.2 10525.3 10828.8 11004.3 11058.4 11149.0 10272.4 10555.7 10951.4 11132.4 11491.7 6794.6 7956.9 7977.7 8007.9 8392.7 10437.7 10792.0 10947.8 11228.4 11299.3 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#3] mootools.html Cycles:180 Average:5397.29 Median:5010.40 stddev:2957.10 (59.0%) stddev-sans-first:2950.72 07:52:13 INFO - PROCESS | 29714 | Values: 1476.5 1505.5 1670.3 1804.9 1861.1 7926.7 8346.7 8357.5 8363.8 8376.5 7350.8 7591.0 7599.3 7675.2 8168.6 1844.3 2089.6 2122.3 2189.8 2200.5 2333.8 2338.8 2342.7 2343.2 2348.0 1895.8 2253.4 2312.0 2313.0 2313.9 2047.2 2118.0 2121.3 2234.3 2311.8 2305.8 2321.0 2324.3 2324.8 2326.3 8205.4 8269.3 8270.0 8271.0 8297.8 4163.6 4331.7 5088.5 5109.2 5111.7 3229.0 3370.4 3421.7 3524.8 3538.2 927.6 1011.2 1038.0 1056.3 1074.8 10673.7 10722.4 10812.9 10813.7 10834.1 4410.1 4520.2 4624.0 4626.6 4669.7 9273.4 9279.1 9283.9 9287.0 9295.2 5113.8 5114.1 5115.3 5118.4 5134.5 4586.9 4627.4 4653.3 4690.2 4709.7 3055.8 3722.1 3727.9 3766.8 3805.9 1239.3 1243.1 1250.4 1261.7 1262.4 754.4 873.3 876.1 899.9 918.8 5537.3 5542.3 5637.8 5705.7 5925.3 969.8 1005.2 1016.8 1042.7 1057.1 3445.3 3739.7 3977.0 4032.2 4117.0 9796.1 10214.7 10232.7 10233.4 10237.6 9647.0 9803.7 10072.2 10164.0 10166.4 7573.7 7818.2 7820.9 7827.5 7849.7 5556.7 5568.1 5606.3 5772.3 5793.8 9211.1 9268.5 9337.7 9346.2 9366.1 8952.0 9317.2 9327.9 9335.5 9444.1 7034.6 7183.9 7194.3 7208.6 7208.6 7570.5 7682.0 7766.4 7772.3 7773.6 6900.4 7019.8 7053.4 7177.6 7238.3 6792.3 6937.9 6949.2 6951.7 6959.6 6479.2 6643.1 6646.6 6656.5 6657.8 4725.8 4768.2 4853.2 4919.2 4968.7 4844.4 4857.3 4975.6 5009.3 5011.5 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#4] prototype.html Cycles:180 Average:4435.69 Median:4986.25 stddev:1375.50 (27.6%) stddev-sans-first:1343.87 07:52:13 INFO - PROCESS | 29714 | Values: 299.8 302.1 307.8 311.2 313.1 4768.1 5046.8 5076.3 5257.6 5337.4 4922.6 5024.4 5129.5 5129.9 5182.7 3714.3 3986.4 4021.7 4034.8 4049.6 3917.9 4037.4 4060.5 4062.3 4092.3 3914.3 3980.4 3998.2 4002.2 4003.4 3738.8 3981.2 3985.3 4006.7 4014.8 3208.2 3500.3 3502.9 3514.4 3534.3 4159.0 4891.0 5105.7 5165.2 5250.3 4560.0 4563.3 4565.5 4605.6 4622.2 4280.9 5469.6 5472.9 5528.3 5529.7 3535.6 3789.7 3822.2 3871.6 3891.8 5096.3 5761.2 5830.3 5845.1 5912.3 3235.0 3288.4 3693.9 3845.5 3878.0 3025.8 3464.7 3927.5 3935.7 3937.3 4120.0 4209.6 4266.1 4510.4 4531.8 4856.0 4997.8 5617.4 5630.8 5700.3 1207.9 1218.8 1222.6 1244.7 1254.3 785.9 858.8 862.7 862.8 870.8 4974.8 4991.1 4998.5 5060.4 5076.2 4762.3 5080.6 5327.5 5339.3 5511.3 3893.8 3951.7 4238.0 4274.9 4940.6 2451.8 2653.3 2770.8 2805.0 2824.3 4911.7 4925.4 5569.7 5587.7 5626.1 5162.9 5363.4 5518.8 5531.6 5622.7 5502.6 5503.9 5507.5 5596.4 5601.6 4394.8 4896.8 5193.1 5350.4 5868.6 4356.5 4981.4 5417.3 5418.3 5786.8 5982.4 6033.2 6034.7 6043.2 6058.2 3197.7 3968.4 3971.0 4011.9 4014.0 5631.6 5681.9 5693.6 5759.8 5759.8 5252.7 5488.3 5501.8 5529.3 5635.6 5540.0 5571.4 5605.6 5617.9 5621.6 5281.3 5374.5 5405.6 5448.8 5502.4 4769.9 5549.3 5562.6 5666.4 5691.1 4936.5 5192.6 5258.6 5479.2 5519.6 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - PROCESS | 29714 | [#5] yui.html Cycles:180 Average:19151.36 Median:23780.19 stddev:27228.43 (114.5%) stddev-sans-first:27269.22 07:52:13 INFO - PROCESS | 29714 | Values: 607.8 684.7 741.0 744.6 787.2 13904.5 13934.3 14348.9 14366.0 14564.0 12679.7 12814.2 12816.4 12816.9 12821.9 661.5 684.6 825.4 838.5 843.0 681.6 805.8 821.5 833.1 836.3 872.8 873.1 873.7 874.7 876.6 697.9 867.2 867.3 867.5 868.7 486.0 533.0 533.7 534.0 581.7 13724.9 14172.6 14192.1 14201.6 14204.6 13074.7 13238.7 13273.4 13314.1 13363.5 9523.0 11748.0 11847.8 12164.5 12503.7 4378.7 4416.8 4531.2 4531.5 4536.1 80503.4 83846.0 87856.0 90570.0 92201.0 10205.2 10305.8 10346.3 10619.1 10658.9 19993.6 20124.0 20249.0 20251.0 20265.3 6944.0 7429.3 7451.5 7487.4 7679.2 4164.3 4257.5 4269.0 4300.0 4437.5 3182.0 3276.8 3281.0 3285.5 3285.6 612.9 618.1 624.0 631.7 635.2 512.7 530.3 577.4 600.0 610.2 8497.7 8677.4 8699.9 8767.9 8812.8 545.6 555.6 571.4 625.2 643.6 4221.3 4230.8 4233.7 4292.2 4367.6 86285.1 102587.0 105094.0 107868.9 110744.4 56862.9 59708.0 79080.0 82366.6 84428.6 74017.6 76003.9 78027.0 79744.6 80959.6 9048.6 9329.8 9450.4 9514.9 9518.3 46752.3 47074.4 50743.2 51407.7 52518.7 67088.7 68126.7 70791.6 71750.0 72925.0 11809.1 12130.0 12182.4 12317.4 12343.9 11444.0 13050.3 13262.7 13404.9 13431.5 12346.3 13173.4 13496.1 13760.4 13934.7 12352.6 13108.9 13345.3 13435.2 13633.3 11591.5 12613.2 13127.3 13375.3 13387.3 9791.6 10303.0 11289.4 11344.7 11385.3 9113.7 9567.6 9602.5 10934.7 11002.6 07:52:13 INFO - PROCESS | 29714 | -------- Summary: end -------- 07:52:13 INFO - PROCESS | 29714 | 07:52:13 INFO - TEST-INFO | 29714: exit 0 07:52:13 INFO - TEST-OK | dromaeo_css | took 1120273ms 07:52:13 INFO - TEST-START | kraken 07:52:13 INFO - operating with platform_type : mac_ 07:52:13 INFO - Initialising browser for kraken test... 07:52:13 INFO - TEST-INFO | started process 29719 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpsy99jC/profile http://localhost:49412/getInfo.html) 07:52:16 INFO - PROCESS | 29719 | __metrics Screen width/height:1600/1200 07:52:16 INFO - PROCESS | 29719 | colorDepth:24 07:52:16 INFO - PROCESS | 29719 | Browser inner width/height: 1024/689 07:52:16 INFO - PROCESS | 29719 | __metrics 07:52:16 INFO - PROCESS | 29719 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:52:16 INFO - PROCESS | 29719 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:52:17 INFO - TEST-INFO | 29719: exit 0 07:52:17 INFO - Browser initialized. 07:52:17 INFO - Running cycle 1/1 for kraken test... 07:52:17 INFO - TEST-INFO | started process 29720 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpsy99jC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:29 INFO - PROCESS | 29720 | RSS: Main: 222384128 07:52:29 INFO - PROCESS | 29720 | 07:52:32 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:33 INFO - PROCESS | 29720 | RSS: Main: 271974400 07:52:33 INFO - PROCESS | 29720 | 07:52:36 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-dft) 07:52:37 INFO - PROCESS | 29720 | RSS: Main: 282669056 07:52:37 INFO - PROCESS | 29720 | 07:52:40 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-fft) 07:52:40 INFO - PROCESS | 29720 | RSS: Main: 380190720 07:52:40 INFO - PROCESS | 29720 | 07:52:42 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-oscillator) 07:52:43 INFO - PROCESS | 29720 | RSS: Main: 281989120 07:52:43 INFO - PROCESS | 29720 | 07:52:45 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:46 INFO - PROCESS | 29720 | RSS: Main: 283078656 07:52:46 INFO - PROCESS | 29720 | 07:52:49 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:49 INFO - PROCESS | 29720 | RSS: Main: 323829760 07:52:49 INFO - PROCESS | 29720 | 07:52:52 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:53 INFO - PROCESS | 29720 | RSS: Main: 324976640 07:52:53 INFO - PROCESS | 29720 | 07:52:55 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49412/tests/kraken/driver.html?testName=json-parse-financial) 07:52:56 INFO - PROCESS | 29720 | RSS: Main: 321355776 07:52:56 INFO - PROCESS | 29720 | 07:52:58 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49412/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:58 INFO - PROCESS | 29720 | RSS: Main: 297615360 07:52:58 INFO - PROCESS | 29720 | 07:53:00 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:53:01 INFO - PROCESS | 29720 | RSS: Main: 298553344 07:53:01 INFO - PROCESS | 29720 | 07:53:03 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:04 INFO - PROCESS | 29720 | RSS: Main: 301105152 07:53:04 INFO - PROCESS | 29720 | 07:53:07 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:08 INFO - PROCESS | 29720 | RSS: Main: 319623168 07:53:08 INFO - PROCESS | 29720 | 07:53:11 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:11 INFO - PROCESS | 29720 | RSS: Main: 312381440 07:53:11 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49412/tests/kraken/driver.html?testName=ai-astar) 07:53:13 INFO - PROCESS | 29720 | RSS: Main: 356876288 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | __start_tp_report 07:53:13 INFO - PROCESS | 29720 | _x_x_mozilla_page_load 07:53:13 INFO - PROCESS | 29720 | _x_x_mozilla_page_load_details 07:53:13 INFO - PROCESS | 29720 | |i|pagename|runs| 07:53:13 INFO - PROCESS | 29720 | |0;ai-astar;117;116;117;115;116;113;118;164;170;115 07:53:13 INFO - PROCESS | 29720 | |1;audio-beat-detection;154;138;151;152;115;114;116;113;145;113 07:53:13 INFO - PROCESS | 29720 | |2;audio-dft;185;182;184;187;185;184;185;184;184;182 07:53:13 INFO - PROCESS | 29720 | |3;audio-fft;81;58;59;93;61;58;59;58;84;61 07:53:13 INFO - PROCESS | 29720 | |4;audio-oscillator;87;71;96;71;68;69;91;68;69;93 07:53:13 INFO - PROCESS | 29720 | |5;imaging-gaussian-blur;87;86;90;89;133;113;94;91;133;71 07:53:13 INFO - PROCESS | 29720 | |6;imaging-darkroom;97;97;97;97;96;96;96;99;96;97 07:53:13 INFO - PROCESS | 29720 | |7;imaging-desaturate;83;83;79;81;81;81;90;84;66;107 07:53:13 INFO - PROCESS | 29720 | |8;json-parse-financial;55;54;53;54;63;59;58;57;57;57 07:53:13 INFO - PROCESS | 29720 | |9;json-stringify-tinderbox;65;78;75;74;73;72;72;72;73;75 07:53:13 INFO - PROCESS | 29720 | |10;stanford-crypto-aes;86;85;86;85;85;84;85;88;84;84 07:53:13 INFO - PROCESS | 29720 | |11;stanford-crypto-ccm;151;161;156;149;164;169;149;152;231;178 07:53:13 INFO - PROCESS | 29720 | |12;stanford-crypto-pbkdf2;205;170;170;171;177;228;191;167;226;173 07:53:13 INFO - PROCESS | 29720 | |13;stanford-crypto-sha256-iterative;69;69;70;67;67;69;67;68;70;69 07:53:13 INFO - PROCESS | 29720 | __end_tp_report 07:53:13 INFO - PROCESS | 29720 | __start_cc_report 07:53:13 INFO - PROCESS | 29720 | _x_x_mozilla_cycle_collect,2077 07:53:13 INFO - PROCESS | 29720 | __end_cc_report 07:53:13 INFO - PROCESS | 29720 | __startTimestamp1481125993797__endTimestamp 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | ------- Summary: start ------- 07:53:13 INFO - PROCESS | 29720 | Number of tests: 14 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#0] ai-astar Cycles:10 Average:126.10 Median:117.00 stddev:21.65 (18.5%) stddev-sans-first:22.71 07:53:13 INFO - PROCESS | 29720 | Values: 117.0 116.0 117.0 115.0 116.0 113.0 118.0 164.0 170.0 115.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:141.50 stddev:18.36 (13.0%) stddev-sans-first:17.50 07:53:13 INFO - PROCESS | 29720 | Values: 154.0 138.0 151.0 152.0 115.0 114.0 116.0 113.0 145.0 113.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.48 (0.8%) stddev-sans-first:1.54 07:53:13 INFO - PROCESS | 29720 | Values: 185.0 182.0 184.0 187.0 185.0 184.0 185.0 184.0 184.0 182.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#3] audio-fft Cycles:10 Average:67.20 Median:61.00 stddev:13.35 (21.9%) stddev-sans-first:13.19 07:53:13 INFO - PROCESS | 29720 | Values: 81.0 58.0 59.0 93.0 61.0 58.0 59.0 58.0 84.0 61.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:79.00 stddev:11.82 (15.0%) stddev-sans-first:12.11 07:53:13 INFO - PROCESS | 29720 | Values: 87.0 71.0 96.0 71.0 68.0 69.0 91.0 68.0 69.0 93.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:88.00 stddev:20.75 (23.6%) stddev-sans-first:21.57 07:53:13 INFO - PROCESS | 29720 | Values: 87.0 86.0 90.0 89.0 133.0 113.0 94.0 91.0 133.0 71.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:0.92 (0.9%) stddev-sans-first:0.97 07:53:13 INFO - PROCESS | 29720 | Values: 97.0 97.0 97.0 97.0 96.0 96.0 96.0 99.0 96.0 97.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#7] imaging-desaturate Cycles:10 Average:83.50 Median:82.00 stddev:10.22 (12.5%) stddev-sans-first:10.84 07:53:13 INFO - PROCESS | 29720 | Values: 83.0 83.0 79.0 81.0 81.0 81.0 90.0 84.0 66.0 107.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:2.95 (5.2%) stddev-sans-first:3.06 07:53:13 INFO - PROCESS | 29720 | Values: 55.0 54.0 53.0 54.0 63.0 59.0 58.0 57.0 57.0 57.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:73.50 stddev:3.35 (4.6%) stddev-sans-first:1.99 07:53:13 INFO - PROCESS | 29720 | Values: 65.0 78.0 75.0 74.0 73.0 72.0 72.0 72.0 73.0 75.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.27 07:53:13 INFO - PROCESS | 29720 | Values: 86.0 85.0 86.0 85.0 85.0 84.0 85.0 88.0 84.0 84.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#11] stanford-crypto-ccm Cycles:10 Average:166.00 Median:162.50 stddev:24.73 (15.2%) stddev-sans-first:25.63 07:53:13 INFO - PROCESS | 29720 | Values: 151.0 161.0 156.0 149.0 164.0 169.0 149.0 152.0 231.0 178.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:184.00 stddev:23.72 (12.9%) stddev-sans-first:24.33 07:53:13 INFO - PROCESS | 29720 | Values: 205.0 170.0 170.0 171.0 177.0 228.0 191.0 167.0 226.0 173.0 07:53:13 INFO - PROCESS | 29720 | 07:53:13 INFO - PROCESS | 29720 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.18 (1.7%) stddev-sans-first:1.24 07:53:13 INFO - PROCESS | 29720 | Values: 69.0 69.0 70.0 67.0 67.0 69.0 67.0 68.0 70.0 69.0 07:53:13 INFO - PROCESS | 29720 | -------- Summary: end -------- 07:53:13 INFO - PROCESS | 29720 | 07:53:14 INFO - TEST-INFO | 29720: exit 0 07:53:14 INFO - TEST-OK | kraken | took 61197ms 07:53:14 INFO - SUITE-END | took 1181s 07:53:15 INFO - Completed test suite (00:19:41) 07:53:15 INFO - javascript benchmark 07:53:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2710.796690248231, 3263.053714422817, 3266.526353678716, 3297.093299342682, 3332.8333583320828, 5599.384067752544, 5658.405867383922, 5658.43415658434, 5672.886542269152, 5688.687122208277, 5585.944140558603, 5685.516731077863, 5690.459406356389, 5705.942940570593, 5729.28383952006, 5126.436092029874, 5159.509846564571, 5166.793328266865, 5189.662671926313, 5234.842954711346, 5028.421731500887, 5049.0, 5079.060373830835, 5138.306328645623, 5302.6023048271345, 5127.538521533062, 5141.203113517411, 5145.485451454843, 5152.433232344439, 5155.7422128893595, 5120.052790233819, 5133.460986596391, 5134.05020071288, 5136.203888397305, 5140.614453915971, 4987.177115775893, 5079.161938280179, 5086.18621020635, 5094.439611642716, 5099.898002039976, 5602.607817452739, 5608.4391560844, 5609.859753506195, 5610.747516361774, 5615.326160860682, 6045.697715114226, 6050.969745151246, 6064.696765161724, 6082.239720034996, 6118.541109416811, 6154.84612884677, 6163.7842675506145, 6167.722452489649, 6195.504359651217, 6229.96885015572, 5690.118031705092, 5712.828615141552, 5720.68536230503, 5722.341930677942, 5724.084146536534, 4492.820287188476, 6151.815445536641, 6218.0, 6227.439530442282, 6234.064890266496, 5941.287045554561, 5944.316403613553, 5956.195913551615, 5974.103884417372, 5976.85057873548, 6189.84525386869, 6204.0, 6206.038064099981, 6211.9068213976825, 6211.9689401553605, 5892.616979896293, 5940.138679891392, 5941.197938278364, 6048.243969503812, 6150.7539698411565, 6123.510119190454, 6145.661988590581, 6188.907166392508, 6191.81424557264, 6198.6280823150755, 4857.854264372075, 4863.927041094386, 4878.829240976549, 4885.9755701221975, 4896.926546101811, 4285.978570107191, 4324.394584758136, 4384.05742765307, 4393.912121757547, 4403.735775853395, 5540.113581826889, 5550.084236101079, 5608.046632072557, 5625.718714064362, 5696.686682232434, 6188.721507532172, 6194.721237544321, 6202.503799696014, 6222.657753823583, 6238.1890354254565, 5487.506124448819, 5630.155476678449, 5636.4927156556105, 5640.407757185519, 5641.774329026875, 5216.087184742655, 5222.086134926372, 5235.083860324504, 5279.577633789288, 5286.920696189561, 6238.532110091852, 6279.623222606659, 6288.27684816252, 6290.811275661737, 6297.244330680348, 6230.59501132425, 6251.34360892118, 6251.937480625135, 6254.8123556293385, 6276.215473065867, 6166.938330616636, 6284.685765711604, 6285.214348206474, 6297.811065668037, 6298.653574053288, 5726.226959360433, 5942.494887934401, 5975.880482390415, 5992.610480318765, 6117.785877494266, 6170.290416602148, 6201.875962480815, 6205.844853878509, 6223.875522489616, 6227.688615569294, 5983.252093488314, 5993.160957466045, 6002.069679199731, 6008.369121242206, 6009.278886533644, 5794.913076303943, 5848.298204215522, 5902.380250073852, 5981.970090149522, 5995.970020149872, 4853.272009198648, 5825.592208545361, 5837.824865254049, 5838.299404071538, 5902.1441890926435, 4838.153323168502, 5680.857978550404, 6007.759689612367, 6047.727852246748, 6053.939460605514, 5673.3759286477625, 5676.77292908279, 5821.970890145692, 5829.94170058294, 5864.589478736618], "value": 5588.065141273486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.3485120830836, 497.1870990929829, 502.1939786642439, 503.4713550771689, 506.4656787089619, 30310.545341819863, 31002.844985775097, 38414.42378364323, 39923.40114898279, 40026.79986600063, 33001.0, 41586.58413415865, 42054.94862628436, 42082.579174208324, 42129.0, 9245.982941876371, 9309.115634014757, 9451.432914025181, 9458.905410945872, 9470.857937130913, 9359.95320023399, 9408.81182376352, 9422.0, 9430.25540275048, 9455.432674039545, 9410.106039926197, 9467.95266023669, 9474.715758527254, 9474.905250947506, 9482.288828337867, 9392.671256506024, 9440.433573985549, 9444.763880902963, 9452.95273523635, 9515.333926625139, 9336.486493242868, 9412.152906238403, 9414.717558473258, 9422.62309507619, 9450.385724927857, 38650.80674596609, 38969.22061558753, 39008.414873776914, 39019.60980390188, 39053.60946390528, 3315.370079684852, 3324.434846076173, 3401.6598340166033, 3468.1849765305133, 3897.2400381925536, 28817.711822881924, 29035.0, 29435.0, 29623.851880740458, 30204.84897575498, 2588.7799537039186, 2995.3709720958623, 3127.499600063979, 3156.4949608062598, 3168.762342824274, 377906.0, 490111.0, 494768.0, 495525.0, 497302.0, 59855.40144598585, 60470.0, 60544.0, 60566.0915086274, 60664.090038649454, 34104.97685069452, 34937.47593786095, 35117.122071948404, 36057.91826245217, 36153.0, 15389.307481163374, 15596.532104036896, 15612.609684757972, 15619.765703514457, 15644.217789110498, 11838.88161118395, 11929.522819087138, 11930.0, 11930.284182949052, 11942.880571194177, 15952.840471595367, 16609.41867034672, 16698.4155554555, 17552.210150543255, 18386.724199137025, 7027.262137475594, 7408.370158565107, 7706.267904549059, 7729.652165652559, 7779.260970207822, 835.4193835284541, 917.1607978699549, 1014.4724743133529, 1048.4652827058255, 1051.805415998043, 29988.0, 30645.693543064284, 31157.065288041395, 31472.37055258882, 32058.519122213187, 980.3284749946167, 1035.0736091198335, 1051.6548097742077, 1060.7695073714453, 1139.0, 14319.713605727828, 14719.896007799458, 14821.0, 15066.773998390034, 15126.470573529874, 503133.0, 644864.0, 648198.0, 651164.0, 652264.0, 54059.18911216335, 54346.0, 54433.0, 54463.18305225425, 54545.45454545483, 1097.2429023973432, 1226.4971361741643, 1267.3853181207107, 1343.1269674711516, 1358.7622166121087, 29195.41609167847, 29627.555586666647, 29692.554611681273, 29801.403971920874, 30484.0, 40455.59544404636, 47623.52376476191, 47841.0, 48177.5182248173, 48427.5157248423, 515002.0, 519106.0, 522133.0, 522209.0, 522380.0, 22652.207172748876, 22877.08491660315, 23225.419364516023, 23258.0696772134, 23301.18445854387, 41702.79148604238, 42533.36199957065, 42919.7854010728, 43409.34885976776, 43501.0, 30668.693313067477, 34972.12569685676, 37345.43981840329, 37492.0, 38392.61607384002, 37310.0, 37574.0, 37951.24097517979, 38402.61597383991, 38635.8409247723, 36421.27157456889, 36980.0, 37302.0, 37535.249295013404, 37706.0, 31960.36079278448, 33702.49446258357, 33857.49213761845, 33905.83047084847, 33986.66013339835, 24649.0, 24726.629100563146, 25107.87446062758, 25302.620460693473, 25362.0], "value": 18914.574624590896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6138877928667, 372.3985762992766, 375.22703231343417, 383.4574077680082, 403.3848381218641, 14821.333040013198, 15021.92489037556, 15813.0, 19891.40325790225, 20507.74414814369, 19325.903370483127, 19765.308214212506, 20329.0, 20407.183712651477, 20556.280530181448, 5658.34928983166, 5682.602217844753, 5889.823305300848, 5920.259967504062, 5923.911141332884, 5664.376918538957, 5783.826485205451, 5819.185314056035, 5822.970885145568, 5841.299044114691, 5586.55307575393, 5769.1346298055205, 5934.139549765281, 5963.314218864821, 6049.425304596057, 5761.337446193657, 5918.408159184069, 5982.790602328898, 5989.431004054608, 5989.5507836911975, 5737.799177028825, 5819.418058194189, 5822.621529600562, 5826.679532625704, 5836.883262334772, 12862.0, 13112.409941552653, 23028.539429211323, 24034.87982560076, 24121.396965075837, 2263.7849404306567, 2323.6745464213104, 2479.119912431097, 2523.470071285032, 2684.610731443949, 9668.0, 9732.90267097334, 10216.13162881156, 10995.780084398268, 15322.080675159526, 1757.8242175782448, 1758.0, 1774.9201285942033, 1811.3388613156235, 1830.670479313723, 119847.80152198454, 122257.0, 126861.0, 127411.25887411185, 131256.34371828174, 11100.278481898651, 11352.921472460102, 11481.827772583418, 11532.94233528827, 11682.299062056303, 14459.566213013626, 14463.204523751294, 14477.203753793432, 14504.129752214902, 14531.927340363442, 8020.679172833138, 8262.380321475914, 8268.0, 8437.240648341678, 8456.11210822867, 8131.471454355448, 8146.2261085196815, 8178.263956243966, 8299.004119505698, 8303.750887473385, 4321.67587430944, 4407.537208593051, 4439.023414848728, 4498.50516443191, 4498.910021799546, 1545.4668139491857, 1685.8398452147026, 1812.3203798575535, 1926.7398901148447, 1927.1616846671743, 779.906411230656, 894.6242578117207, 938.202527851321, 940.2337095267335, 969.9563519641492, 18593.628127437376, 20628.96855157236, 21377.465563361042, 21449.67825482619, 21509.247176349065, 923.70903165504, 937.4703292639637, 968.4431451915036, 969.8060387922444, 1024.462157367388, 10758.354498729945, 11148.852544055251, 11163.929919272265, 11239.269447485887, 11326.773464530828, 593265.0, 600529.0, 602087.0, 602957.0, 606588.0, 68773.31226687669, 71298.64350678318, 116049.41975290069, 126649.0, 131476.0, 767.7926959720844, 938.4275591888942, 969.9136966597456, 986.9062439068277, 991.4893829677721, 14440.927795360956, 15435.15106669144, 15562.61093472627, 18405.355812546495, 19836.10737516844, 53139.0, 55206.72396637991, 56310.7184464075, 56962.0, 57836.0, 76897.84653230094, 78447.0, 79212.20787792279, 80351.19648803672, 80667.0, 7298.379637730853, 7968.601569921597, 8112.797180070545, 8203.30271926893, 8291.95854020726, 6856.8546293410955, 9654.324197306121, 12159.878401216229, 12909.677258068623, 13099.279539625411, 10765.89234107649, 11070.446477676245, 11203.103751699682, 11269.267497612629, 11306.208565400342, 10525.315854469742, 10828.837567436653, 11004.339739615649, 11058.447077645924, 11149.0, 10272.383656980604, 10555.683329500127, 10951.397673128055, 11132.38771867555, 11491.712707182387, 6794.626295553792, 7956.880646790419, 7977.680892764457, 8007.879881801661, 8392.748217553482, 10437.739056523344, 10792.028717415471, 10947.83578246343, 11228.4385780709, 11299.265547739371], "value": 9322.824963744484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.497990683168, 1505.4655597262972, 1670.2984746406507, 1804.8736588438799, 1861.0880668472453, 7926.682932682693, 8346.74959751207, 8357.498550086999, 8363.790905227364, 8376.53929033903, 7350.816229594249, 7591.0, 7599.316061554459, 7675.194104619019, 8168.550729709864, 1844.3360390259495, 2089.613421517017, 2122.257209976511, 2189.7956124131742, 2200.482886521666, 2333.754955729641, 2338.754430784769, 2342.6954495915506, 2343.238447504568, 2348.0, 1895.8293753562177, 2253.4141123307895, 2312.0405031911737, 2312.982287793374, 2313.9305820825402, 2047.242520267503, 2117.9788202117934, 2121.2681624839406, 2234.2626933112033, 2311.7688231176917, 2305.8385912986096, 2320.953580928372, 2324.3143272734414, 2324.7907688307964, 2326.336993956714, 8205.384596155312, 8269.338452923752, 8270.0, 8271.048829384637, 8297.83404331916, 4163.5628259032665, 4331.740095594275, 5088.516590923857, 5109.233614957749, 5111.718855462948, 3229.0, 3370.359631669975, 3421.726261899042, 3524.753267271292, 3538.168530395355, 927.5594092805931, 1011.1758916483043, 1038.0, 1056.27645063132, 1074.790415868906, 10673.679789606247, 10722.356658600509, 10812.891871081345, 10813.67558973232, 10834.079103276152, 4410.07388448423, 4520.186366454004, 4624.028953919647, 4626.629869610423, 4669.696469729457, 9273.397229180082, 9279.118483744034, 9283.860742088875, 9286.953565232267, 9295.209907157967, 5113.795448182031, 5114.105031619526, 5115.283860259567, 5118.436971933084, 5134.460881607452, 4586.908261834745, 4627.398438203012, 4653.302004699254, 4690.226112691368, 4709.693869898446, 3055.801372910754, 3722.10669439338, 3727.906802329909, 3766.7928263945196, 3805.8858234253016, 1239.324568110382, 1243.055277988717, 1250.4498020870788, 1261.7413430246777, 1262.3561983388538, 754.3851760814919, 873.3406278259873, 876.1326286975848, 899.9280057595377, 918.7703074231442, 5537.280153580089, 5542.251796007568, 5637.774489020393, 5705.686187259657, 5925.259342582177, 969.762408209993, 1005.1857995024054, 1016.8068067067234, 1042.7393151712072, 1057.0592172966067, 3445.29550263763, 3739.682127019234, 3976.980115099406, 4032.1734044520804, 4116.958830411718, 9796.069373409515, 10214.693559193236, 10232.693019209435, 10233.385996840065, 10237.641682541223, 9646.951765241129, 9803.656872009446, 10072.194224462026, 10164.0344167304, 10166.390016599027, 7573.659185336673, 7818.179091195342, 7820.921790782133, 7827.452078354574, 7849.725259615886, 5556.694381809039, 5568.053430916673, 5606.271184745957, 5772.307323121252, 5793.826185214451, 9211.124943130391, 9268.490233036979, 9337.71986840396, 9346.20557252614, 9366.063393660852, 8952.01527831951, 9317.16145546904, 9327.906720932704, 9335.53322333867, 9444.102810233016, 7034.613096279753, 7183.928160718535, 7194.316539928698, 7208.56748595086, 7208.56748595086, 7570.470067095251, 7682.0397450318715, 7766.417518686008, 7772.300492955661, 7773.572453514885, 6900.447964162755, 7019.789406317818, 7053.435725141873, 7177.605231712306, 7238.348548630648, 6792.286809884891, 6937.93062069393, 6949.235584085847, 6951.721931122901, 6959.582425054513, 6479.222493300834, 6643.069970204272, 6646.634435106115, 6656.467482601283, 6657.800265992028, 4725.755266248504, 4768.213244814567, 4853.223484242504, 4919.163742163769, 4968.652194346361, 4844.370231869834, 4857.344258525054, 4975.5522003019905, 5009.298698182331, 5011.498850115106], "value": 4380.237140547349, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8246026074746, 302.1253471200872, 307.847615430362, 311.17538522914276, 313.06081754735794, 4768.0702263058765, 5046.82336118236, 5076.289319495274, 5257.605679574038, 5337.439568845269, 4922.55696987271, 5024.447310795818, 5129.487051294868, 5129.871753206163, 5182.663126896749, 3714.294284086022, 3986.382110772835, 4021.658159056484, 4034.8413598724687, 4049.574794646564, 3917.941230881525, 4037.37420699792, 4060.451839001741, 4062.3033039933666, 4092.283850326181, 3914.3149948758996, 3980.4029395590605, 3998.2003599280115, 4002.1595464952393, 4003.4195041718935, 3738.803582853488, 3981.1838573092446, 3985.302572049894, 4006.7395619284653, 4014.759114453113, 3208.2139875730363, 3500.264944361687, 3502.8615699897637, 3514.4376899695926, 3534.3284775892757, 4159.0, 4891.0, 5105.7024339047575, 5165.210786175461, 5250.264962905197, 4559.977200113978, 4563.315502674593, 4565.497795242521, 4605.585497305258, 4622.214223581999, 4280.892977675552, 5469.562435005229, 5472.945270547323, 5528.281323427928, 5529.723513824292, 3535.5957948594946, 3789.677877380426, 3822.2164456286396, 3871.5868707921404, 3891.805409729502, 5096.337476128079, 5761.222234998263, 5830.271216097945, 5845.1232315153065, 5912.280351038527, 3234.9838250808593, 3288.391647545212, 3693.8891833245048, 3845.4616353710503, 3878.0498777799057, 3025.8335791531235, 3464.688178063936, 3927.5286965563746, 3935.685145188378, 3937.251922134785, 4120.011197312623, 4209.621134097946, 4266.146770645883, 4510.368548403226, 4531.84138555149, 4855.975720121377, 4997.800087996439, 5617.38208797032, 5630.831075067755, 5700.258966334433, 1207.8550573931184, 1218.7501562179732, 1222.5879109628393, 1244.6639407359962, 1254.328934020313, 785.9371250299962, 858.7938894665235, 862.7023676831483, 862.7886167888907, 870.7953630896734, 4974.7512624369365, 4991.128326877421, 4998.475160108209, 5060.381826993717, 5076.162433198481, 4762.261849413347, 5080.618953578496, 5327.493888080626, 5339.3058902342445, 5511.283533140746, 3893.824777885002, 3951.7431366961055, 4238.0, 4274.893127671833, 4940.555350018516, 2451.7793398593853, 2653.270350653547, 2770.847603381793, 2804.971950280512, 2824.347756562434, 4911.698399924023, 4925.38432695913, 5569.66582005081, 5587.720613969285, 5626.099824028136, 5162.948370516322, 5363.383210930715, 5518.813455107172, 5531.585131115183, 5622.662640241599, 5502.58730595199, 5503.862403439866, 5507.476789704971, 5596.4403559644525, 5601.551875849922, 4394.780260987001, 4896.755162241945, 5193.091209038479, 5350.421625620529, 5868.618539794899, 4356.455443069616, 4981.37732783402, 5417.3477089486505, 5418.322709661293, 5786.797462088806, 5982.4017598239825, 6033.185519954925, 6034.668093254738, 6043.184170136975, 6058.212432383762, 3197.7368939268395, 3968.4047392890143, 3970.960290397059, 4011.8967283997135, 4013.9598604013586, 5631.63394379364, 5681.9431805681415, 5693.572982026448, 5759.769609215752, 5759.769609215752, 5252.65857719247, 5488.259085023471, 5501.752421141138, 5529.253550770594, 5635.633683810539, 5539.972300138474, 5571.359293681278, 5605.6456288359805, 5617.865211219711, 5621.606487545996, 5281.313429254173, 5374.548871804669, 5405.567554595702, 5448.7548060336585, 5502.367227768857, 4769.856904292877, 5549.278593782944, 5562.582806289625, 5666.376698563237, 5691.089425692035, 4936.4569897311985, 5192.636515444034, 5258.579313654975, 5479.205515200194, 5519.558435325244], "value": 3994.9885179095495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.8389226854881, 684.7466437418154, 740.9666565004576, 744.5941961630914, 787.2088551006245, 13904.513342033031, 13934.303284835756, 14348.928255358722, 14366.0, 14564.0, 12679.746405071915, 12814.231146131238, 12816.359182040907, 12816.935915320433, 12821.935890320536, 661.5369241530924, 684.5550392245055, 825.4180802534214, 838.5136620759976, 842.9536375499345, 681.5944513014748, 805.7985503624094, 821.5070957425556, 833.1418638802013, 836.2640876029117, 872.7818045488627, 873.1399571422144, 873.7029410000598, 874.6938571499987, 876.5967654878764, 697.8883378659441, 867.2065060469663, 867.280157469299, 867.4708427859064, 868.6742471573192, 486.03764546198374, 533.016584401782, 533.6717918480145, 533.970631615261, 581.7207740284645, 13724.862751372559, 14172.645683857883, 14192.077514961598, 14201.644958876008, 14204.644883877882, 13074.738505229938, 13238.66903327415, 13273.402696878664, 13314.134581252187, 13363.532276370377, 9522.952385238099, 11747.952299151011, 11847.822282665767, 12164.51341946321, 12503.74992500154, 4378.715383500063, 4416.823327066913, 4531.161735079021, 4531.52418996004, 4536.115457485792, 80503.37734801948, 83846.0, 87856.0, 90570.0, 92201.0, 10205.183585313158, 10305.84541231882, 10346.275760696679, 10619.097376723064, 10658.946705266424, 19993.600127997357, 20124.0, 20249.0, 20251.0, 20265.29071482491, 6943.9652801735665, 7429.2570742926355, 7451.478396512301, 7487.438442116863, 7679.2320767923875, 4164.250434921743, 4257.489101307801, 4269.018125831076, 4299.9570004300185, 4437.511873693956, 3182.0, 3276.7870088444174, 3281.032095531776, 3285.5235990781202, 3285.572875526214, 612.9203203583505, 618.0790621973227, 623.9532035097295, 631.7157279224367, 635.1933045032782, 512.7359409903903, 530.3317819547398, 577.3677822784044, 599.9790007349721, 610.2097783370457, 8497.74506764798, 8677.39258251929, 8699.913000870038, 8767.868481972775, 8812.823743525221, 545.6371512943928, 555.5999680230228, 571.3829064610211, 625.1622825413967, 643.5848877474097, 4221.261279276115, 4230.830766769294, 4233.745975241495, 4292.18448494785, 4367.606915377632, 86285.13714862897, 102587.0, 105094.0, 107868.92131078588, 110744.44627776809, 56862.86274274491, 59708.0, 79080.0, 82366.58816705998, 84428.57785711031, 74017.62991185009, 76003.8599420998, 78027.0, 79744.60127699324, 80959.59520202361, 9048.638054477748, 9329.813403731752, 9450.43297402158, 9514.858217014003, 9518.286128540525, 46752.29871551861, 47074.35107787087, 50743.238851418, 51407.74296128495, 52518.687032824484, 67088.66455667885, 68126.65936670285, 70791.64604176946, 71750.0, 72925.0, 11809.11431642613, 12130.0, 12182.39088045612, 12317.384130793604, 12343.876561234632, 11444.0, 13050.282234477196, 13262.673732627047, 13404.927605791709, 13431.529896453578, 12346.320952347707, 13173.40719667598, 13496.122752021087, 13760.449582016607, 13934.721305574034, 12352.567660131852, 13108.934455327662, 13345.266010369523, 13435.19388836673, 13633.250171240677, 11591.478383472933, 12613.243205407705, 13127.343632818513, 13375.331233438095, 13387.263700496565, 9791.559379828068, 10303.0, 11289.37908415045, 11344.716382090513, 11385.316880987168, 9113.726588202364, 9567.596170165889, 9602.471864047544, 10934.726631834268, 11002.559897604326], "value": 7037.094095669659, "unit": "score"}], "name": "dromaeo_css", "value": 7036.279720903595, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 115.0, 116.0, 113.0, 118.0, 164.0, 170.0, 115.0], "value": 126.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 138.0, 151.0, 152.0, 115.0, 114.0, 116.0, 113.0, 145.0, 113.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 187.0, 185.0, 184.0, 185.0, 184.0, 184.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 93.0, 61.0, 58.0, 59.0, 58.0, 84.0, 61.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 96.0, 71.0, 68.0, 69.0, 91.0, 68.0, 69.0, 93.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 89.0, 133.0, 113.0, 94.0, 91.0, 133.0, 71.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 96.0, 96.0, 96.0, 99.0, 96.0, 97.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 79.0, 81.0, 81.0, 81.0, 90.0, 84.0, 66.0, 107.0], "value": 83.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 63.0, 59.0, 58.0, 57.0, 57.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 75.0, 74.0, 73.0, 72.0, 72.0, 72.0, 73.0, 75.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 85.0, 84.0, 85.0, 88.0, 84.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 161.0, 156.0, 149.0, 164.0, 169.0, 149.0, 152.0, 231.0, 178.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 170.0, 170.0, 171.0, 177.0, 228.0, 191.0, 167.0, 226.0, 173.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 67.0, 67.0, 69.0, 67.0, 68.0, 70.0, 69.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1503.0, "alertThreshold": 2.0}]} 07:53:15 INFO - Return code: 0 07:53:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:15 INFO - # TBPL SUCCESS # 07:53:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:15 INFO - 2016-12-07 07:53:15.583 screenresolution[29724:277925] starting screenresolution argv=screenresolution get 07:53:15 INFO - 2016-12-07 07:53:15.586 screenresolution[29724:277925] Display 0: 1600x1200x32@60 07:53:15 INFO - 2016-12-07 07:53:15.592 screenresolution[29725:277928] starting screenresolution argv=screenresolution list 07:53:15 INFO - Available Modes on Display 0 07:53:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:15 INFO - Intel Iris: 07:53:15 INFO - Chipset Model: Intel Iris 07:53:15 INFO - Type: GPU 07:53:15 INFO - Bus: Built-In 07:53:15 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:15 INFO - Vendor: Intel (0x8086) 07:53:15 INFO - Device ID: 0x0a2e 07:53:15 INFO - Revision ID: 0x0009 07:53:15 INFO - Displays: 07:53:15 INFO - Display: 07:53:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:15 INFO - Display Serial Number: 1600x1200 60 07:53:15 INFO - Main Display: Yes 07:53:15 INFO - Mirror: Off 07:53:15 INFO - Online: Yes 07:53:15 INFO - Rotation: Supported 07:53:15 INFO - Return code: 0 07:53:15 INFO - Running post-action listener: _resource_record_post_action 07:53:15 INFO - [mozharness: 2016-12-07 15:53:15.826022Z] Finished run-tests step (success) 07:53:15 INFO - Running post-run listener: _resource_record_post_run 07:53:15 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 1495040; Write bytes: 660025344; Read time: 17; Write time: 2064 07:53:15 INFO - TinderboxPrint: CPU usage
53.4% 07:53:15 INFO - TinderboxPrint: I/O read bytes / time
1,495,040 / 17 07:53:15 INFO - TinderboxPrint: I/O write bytes / time
660,025,344 / 2,064 07:53:15 INFO - TinderboxPrint: CPU idle
2,263.0 (46.6%) 07:53:15 INFO - TinderboxPrint: CPU system
973.9 (20.1%) 07:53:15 INFO - TinderboxPrint: CPU user
1,616.8 (33.3%) 07:53:15 INFO - TinderboxPrint: Swap in / out
350,445,568 / 0 07:53:15 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347699712; Write bytes: 344950784; Read time: 26728; Write time: 654 07:53:15 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 1298432; Write bytes: 307816448; Read time: 14; Write time: 1380 07:53:16 INFO - Running post-run listener: _upload_blobber_files 07:53:16 INFO - Blob upload gear active. 07:53:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:16 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 07:53:16 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'] 07:53:16 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 07:53:16 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:53:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:17 INFO - (blobuploader) - INFO - Done attempting. 07:53:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:53:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:18 INFO - (blobuploader) - INFO - Done attempting. 07:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:21 INFO - (blobuploader) - INFO - Done attempting. 07:53:21 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:21 INFO - Return code: 0 07:53:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e4be897c3470a5174a3a9efc00122605452803069a0ec0815a72922475550d60c879b1b8fb223ed85c31e025f5ccef521bdbaec2d8b4fcff72c6262fa89d8b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c66734f68c3187da6583311db07c746b51dcae0322d8385dd39e8d457a106abc00d1ed7518e5c5fd4fa046a348091f6774fe0957b86a7f757b8063c2d14c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/757698a5fa50243dc62b12649aef7b086dc8baff35f9b562dcccf1141ed93cfde1defc4efa4411d7fe37ee596b66264c97c85790d0745a3607688110236de2a7"} 07:53:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:21 INFO - Contents: 07:53:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e4be897c3470a5174a3a9efc00122605452803069a0ec0815a72922475550d60c879b1b8fb223ed85c31e025f5ccef521bdbaec2d8b4fcff72c6262fa89d8b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c66734f68c3187da6583311db07c746b51dcae0322d8385dd39e8d457a106abc00d1ed7518e5c5fd4fa046a348091f6774fe0957b86a7f757b8063c2d14c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/757698a5fa50243dc62b12649aef7b086dc8baff35f9b562dcccf1141ed93cfde1defc4efa4411d7fe37ee596b66264c97c85790d0745a3607688110236de2a7"} 07:53:21 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:21 INFO - Copying logs to upload dir... 07:53:21 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.568747 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-07 07:53:21.495080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-07 07:53:21.498551) ========= 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.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/3e4be897c3470a5174a3a9efc00122605452803069a0ec0815a72922475550d60c879b1b8fb223ed85c31e025f5ccef521bdbaec2d8b4fcff72c6262fa89d8b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c66734f68c3187da6583311db07c746b51dcae0322d8385dd39e8d457a106abc00d1ed7518e5c5fd4fa046a348091f6774fe0957b86a7f757b8063c2d14c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/757698a5fa50243dc62b12649aef7b086dc8baff35f9b562dcccf1141ed93cfde1defc4efa4411d7fe37ee596b66264c97c85790d0745a3607688110236de2a7"} build_url:https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/Kh_tlUElQJam_hBde7nVNQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e4be897c3470a5174a3a9efc00122605452803069a0ec0815a72922475550d60c879b1b8fb223ed85c31e025f5ccef521bdbaec2d8b4fcff72c6262fa89d8b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/697c66734f68c3187da6583311db07c746b51dcae0322d8385dd39e8d457a106abc00d1ed7518e5c5fd4fa046a348091f6774fe0957b86a7f757b8063c2d14c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/757698a5fa50243dc62b12649aef7b086dc8baff35f9b562dcccf1141ed93cfde1defc4efa4411d7fe37ee596b66264c97c85790d0745a3607688110236de2a7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-07 07:53:21.534907) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:53:21.535319) ========= 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.vFuMqAWIIX/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.WME8XqBOjO/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005150 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 07:53:21.585365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-07 07:53:21.585750) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-07 07:53:21.586169) ========= ========= Total master_lag: 0.26 =========