builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0172 starttime: 1467288146.89 results: success (0) buildid: 20160630025528 builduid: 7c2474b8f06e4e13acbdaf7b1377d375 revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:26.889464) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:26.889931) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:26.890201) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:26.934597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:26.934878) ========= 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.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-30 05:02:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.53M=0.002s 2016-06-30 05:02:27 (7.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.605144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:27.561478) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:27.561894) ========= 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.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.020381 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:27.610489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:27.610849) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d700dc054751333e0735f975fce3d3adf153c62a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d700dc054751333e0735f975fce3d3adf153c62a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-30 05:02:27,683 truncating revision to first 12 chars 2016-06-30 05:02:27,683 Setting DEBUG logging. 2016-06-30 05:02:27,683 attempt 1/10 2016-06-30 05:02:27,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d700dc054751?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 05:02:28,022 unpacking tar archive at: mozilla-central-d700dc054751/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612775 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:28.246509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:28.247048) ========= script_repo_revision: d700dc054751333e0735f975fce3d3adf153c62a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:28.247621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:28.247997) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 05:02:28.267049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2016-06-30 05:02:28.267671) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:02:28 INFO - MultiFileLogger online at 20160630 05:02:28 in /builds/slave/test 05:02:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:02:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:02:28 INFO - {'append_to_log': False, 05:02:28 INFO - 'base_work_dir': '/builds/slave/test', 05:02:28 INFO - 'blob_upload_branch': 'Firefox', 05:02:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:02:28 INFO - 'branch': 'Firefox', 05:02:28 INFO - 'buildbot_json_path': 'buildprops.json', 05:02:28 INFO - 'config_files': ('talos/mac_config.py',), 05:02:28 INFO - 'default_actions': ('clobber', 05:02:28 INFO - 'read-buildbot-config', 05:02:28 INFO - 'download-and-extract', 05:02:28 INFO - 'populate-webroot', 05:02:28 INFO - 'create-virtualenv', 05:02:28 INFO - 'install', 05:02:28 INFO - 'run-tests'), 05:02:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:02:28 INFO - 'download_minidump_stackwalk': True, 05:02:28 INFO - 'download_symbols': 'ondemand', 05:02:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:02:28 INFO - 'tooltool.py': '/tools/tooltool.py', 05:02:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:02:28 INFO - '/tools/misc-python/virtualenv.py')}, 05:02:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:02:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:02:28 INFO - 'installer_path': 'installer.exe', 05:02:28 INFO - 'log_level': 'info', 05:02:28 INFO - 'log_name': 'talos', 05:02:28 INFO - 'log_to_console': True, 05:02:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:02:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:02:28 INFO - 'opt_config_files': (), 05:02:28 INFO - 'pip_index': False, 05:02:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:28 INFO - 'cmd': ('bash', 05:02:28 INFO - '-c', 05:02:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:28 INFO - 'enabled': True, 05:02:28 INFO - 'halt_on_failure': False, 05:02:28 INFO - 'name': 'check_screen_resolution'},), 05:02:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:28 INFO - 'cmd': ('bash', 05:02:28 INFO - '-c', 05:02:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:28 INFO - 'enabled': True, 05:02:28 INFO - 'halt_on_failure': False, 05:02:28 INFO - 'name': 'check_screen_resolution'},), 05:02:28 INFO - 'run_cmd_checks_enabled': True, 05:02:28 INFO - 'sps_profile': False, 05:02:28 INFO - 'sps_profile_interval': 0, 05:02:28 INFO - 'suite': 'dromaeojs', 05:02:28 INFO - 'system_bits': '32', 05:02:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:02:28 INFO - 'title': 't-yosemite-r7-0172', 05:02:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:02:28 INFO - 'use_talos_json': True, 05:02:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:02:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:02:28 INFO - 'work_dir': 'build'} 05:02:28 INFO - ##### 05:02:28 INFO - ##### Running clobber step. 05:02:28 INFO - ##### 05:02:28 INFO - Running pre-action listener: _resource_record_pre_action 05:02:28 INFO - Running main action method: clobber 05:02:28 INFO - rmtree: /builds/slave/test/build 05:02:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:02:30 INFO - Running post-action listener: _resource_record_post_action 05:02:30 INFO - ##### 05:02:30 INFO - ##### Finished clobber step (success) 05:02:30 INFO - ##### 05:02:30 INFO - ##### 05:02:30 INFO - ##### Running read-buildbot-config step. 05:02:30 INFO - ##### 05:02:30 INFO - Running pre-action listener: _resource_record_pre_action 05:02:30 INFO - Running main action method: read_buildbot_config 05:02:30 INFO - Using buildbot properties: 05:02:30 INFO - { 05:02:30 INFO - "project": "", 05:02:30 INFO - "product": "firefox", 05:02:30 INFO - "script_repo_revision": "production", 05:02:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:02:30 INFO - "repository": "", 05:02:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:02:30 INFO - "buildid": "20160630025528", 05:02:30 INFO - "pgo_build": "False", 05:02:30 INFO - "basedir": "/builds/slave/test", 05:02:30 INFO - "buildnumber": 0, 05:02:30 INFO - "slavename": "t-yosemite-r7-0172", 05:02:30 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:02:30 INFO - "platform": "yosemite_r7", 05:02:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:02:30 INFO - "branch": "mozilla-central", 05:02:30 INFO - "repo_path": "mozilla-central", 05:02:30 INFO - "revision": "d700dc054751333e0735f975fce3d3adf153c62a", 05:02:30 INFO - "stage_platform": "macosx64", 05:02:30 INFO - "builduid": "7c2474b8f06e4e13acbdaf7b1377d375", 05:02:30 INFO - "slavebuilddir": "test" 05:02:30 INFO - } 05:02:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:02:30 INFO - Running post-action listener: _resource_record_post_action 05:02:30 INFO - ##### 05:02:30 INFO - ##### Finished read-buildbot-config step (success) 05:02:30 INFO - ##### 05:02:30 INFO - ##### 05:02:30 INFO - ##### Running download-and-extract step. 05:02:30 INFO - ##### 05:02:30 INFO - Running pre-action listener: _resource_record_pre_action 05:02:30 INFO - Running main action method: download_and_extract 05:02:30 INFO - mkdir: /builds/slave/test/build/tests 05:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:30 INFO - https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:02:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:02:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:02:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:02:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:03:00 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 05:03:00 INFO - retry: attempt #1 caught exception: timed out 05:03:00 INFO - retry: Failed, sleeping 30 seconds before retrying 05:03:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #2 05:03:48 INFO - Downloaded 1199 bytes. 05:03:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:03:48 INFO - Using the following test package requirements: 05:03:48 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:03:48 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:03:48 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:03:48 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'jsshell-mac.zip'], 05:03:48 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:03:48 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:03:48 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:03:48 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:03:48 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:03:48 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:03:48 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:03:48 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:03:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:03:48 INFO - https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:03:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:03:56 INFO - Downloaded 18788522 bytes. 05:03:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:03:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:03:59 INFO - Return code: 0 05:03:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:03:59 INFO - https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:03:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:03:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:00 INFO - Downloaded 18788522 bytes. 05:04:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:04:04 INFO - Return code: 0 05:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:04 INFO - https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:04:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:04:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:04:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:04:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:04:05 INFO - Downloaded 14405245 bytes. 05:04:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:04:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:04:06 INFO - Return code: 0 05:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:06 INFO - https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:04:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:04:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:04:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:04:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:04:13 INFO - Downloaded 112478531 bytes. 05:04:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:04:13 INFO - mkdir: /builds/slave/test/properties 05:04:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:13 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:13 INFO - Contents: 05:04:13 INFO - build_url:https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:04:14 INFO - Running post-action listener: _resource_record_post_action 05:04:14 INFO - Running post-action listener: set_extra_try_arguments 05:04:14 INFO - ##### 05:04:14 INFO - ##### Finished download-and-extract step (success) 05:04:14 INFO - ##### 05:04:14 INFO - ##### 05:04:14 INFO - ##### Running populate-webroot step. 05:04:14 INFO - ##### 05:04:14 INFO - Running pre-action listener: _resource_record_pre_action 05:04:14 INFO - Running main action method: populate_webroot 05:04:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:14 INFO - '--tppagecycles', 05:04:14 INFO - '7'], 05:04:14 INFO - 'tests': ['tsvgm']}}, 05:04:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['damp', 'tps']}, 05:04:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'tests': ['damp', 'tps']}, 05:04:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['dromaeo_dom']}, 05:04:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['basic_compositor_video']}, 05:04:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:04:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['a11yr', 05:04:14 INFO - 'ts_paint', 05:04:14 INFO - 'tpaint', 05:04:14 INFO - 'sessionrestore', 05:04:14 INFO - 'sessionrestore_no_auto_restore', 05:04:14 INFO - 'tabpaint']}, 05:04:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['tsvgx', 05:04:14 INFO - 'tsvgr_opacity', 05:04:14 INFO - 'tart', 05:04:14 INFO - 'tscrollx', 05:04:14 INFO - 'cart']}, 05:04:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:14 INFO - 'tsvgr_opacity', 05:04:14 INFO - 'tart', 05:04:14 INFO - 'tscrollx', 05:04:14 INFO - 'cart']}, 05:04:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'talos_options': ['--disable-e10s'], 05:04:14 INFO - 'tests': ['tp5o']}, 05:04:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'tests': ['tp5o']}, 05:04:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'talos_options': ['--disable-e10s', 05:04:14 INFO - '--xperf_path', 05:04:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:14 INFO - 'tests': ['tp5n']}, 05:04:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:14 INFO - 'talos_options': ['--xperf_path', 05:04:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:14 INFO - 'tests': ['tp5n']}}, 05:04:14 INFO - 'talos.zip': {'path': '', 05:04:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:14 INFO - Running post-action listener: _resource_record_post_action 05:04:14 INFO - ##### 05:04:14 INFO - ##### Finished populate-webroot step (success) 05:04:14 INFO - ##### 05:04:14 INFO - ##### 05:04:14 INFO - ##### Running create-virtualenv step. 05:04:14 INFO - ##### 05:04:14 INFO - Running pre-action listener: _resource_record_pre_action 05:04:14 INFO - Running main action method: create_virtualenv 05:04:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:14 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 05:04:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:14 INFO - Using real prefix '/tools/python27' 05:04:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:15 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:18 INFO - Installing pip.................done. 05:04:18 INFO - Return code: 0 05:04:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:18 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:18 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 05:04:18 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 05:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:18 INFO - 'HOME': '/Users/cltbld', 05:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:18 INFO - 'LOGNAME': 'cltbld', 05:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:18 INFO - 'MOZ_NO_REMOTE': '1', 05:04:18 INFO - 'NO_EM_RESTART': '1', 05:04:18 INFO - 'PAGER': '/bin/cat', 05:04:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:18 INFO - 'PWD': '/builds/slave/test', 05:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:18 INFO - 'SHELL': '/bin/bash', 05:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:18 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:18 INFO - 'USER': 'cltbld', 05:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:18 INFO - 'XPC_FLAGS': '0x0', 05:04:18 INFO - 'XPC_SERVICE_NAME': '0', 05:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:18 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 05:04:18 INFO - Cleaning up... 05:04:18 INFO - Return code: 0 05:04:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:18 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:18 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 05:04:18 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 05:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:18 INFO - 'HOME': '/Users/cltbld', 05:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:18 INFO - 'LOGNAME': 'cltbld', 05:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:18 INFO - 'MOZ_NO_REMOTE': '1', 05:04:18 INFO - 'NO_EM_RESTART': '1', 05:04:18 INFO - 'PAGER': '/bin/cat', 05:04:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:18 INFO - 'PWD': '/builds/slave/test', 05:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:18 INFO - 'SHELL': '/bin/bash', 05:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:18 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:18 INFO - 'USER': 'cltbld', 05:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:18 INFO - 'XPC_FLAGS': '0x0', 05:04:18 INFO - 'XPC_SERVICE_NAME': '0', 05:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:18 INFO - Downloading/unpacking psutil>=3.1.1 05:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:22 INFO - Installing collected packages: psutil 05:04:22 INFO - Running setup.py install for psutil 05:04:22 INFO - building 'psutil._psutil_osx' extension 05:04:22 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 05:04:22 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 05:04:22 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 05:04:22 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 05:04:22 INFO - building 'psutil._psutil_posix' extension 05:04:22 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 05:04:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:22 INFO - ^ 05:04:22 INFO - 1 warning generated. 05:04:22 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 05:04:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:22 INFO - Successfully installed psutil 05:04:22 INFO - Cleaning up... 05:04:22 INFO - Return code: 0 05:04:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:22 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:22 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 05:04:22 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 05:04:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:22 INFO - 'HOME': '/Users/cltbld', 05:04:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:22 INFO - 'LOGNAME': 'cltbld', 05:04:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:22 INFO - 'MOZ_NO_REMOTE': '1', 05:04:22 INFO - 'NO_EM_RESTART': '1', 05:04:22 INFO - 'PAGER': '/bin/cat', 05:04:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:22 INFO - 'PWD': '/builds/slave/test', 05:04:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:22 INFO - 'SHELL': '/bin/bash', 05:04:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:22 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:22 INFO - 'USER': 'cltbld', 05:04:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:22 INFO - 'XPC_FLAGS': '0x0', 05:04:22 INFO - 'XPC_SERVICE_NAME': '0', 05:04:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:23 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 05:04:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:25 INFO - Installing collected packages: mozsystemmonitor 05:04:25 INFO - Running setup.py install for mozsystemmonitor 05:04:25 INFO - Successfully installed mozsystemmonitor 05:04:25 INFO - Cleaning up... 05:04:25 INFO - Return code: 0 05:04:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:25 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:25 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 05:04:25 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 05:04:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:25 INFO - 'HOME': '/Users/cltbld', 05:04:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:25 INFO - 'LOGNAME': 'cltbld', 05:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:25 INFO - 'MOZ_NO_REMOTE': '1', 05:04:25 INFO - 'NO_EM_RESTART': '1', 05:04:25 INFO - 'PAGER': '/bin/cat', 05:04:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:25 INFO - 'PWD': '/builds/slave/test', 05:04:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:25 INFO - 'SHELL': '/bin/bash', 05:04:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:25 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:25 INFO - 'USER': 'cltbld', 05:04:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:25 INFO - 'XPC_FLAGS': '0x0', 05:04:25 INFO - 'XPC_SERVICE_NAME': '0', 05:04:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:26 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 05:04:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:29 INFO - warning: no files found matching '*.txt' 05:04:29 INFO - no previously-included directories found matching 'build' 05:04:29 INFO - no previously-included directories found matching 'dist' 05:04:29 INFO - no previously-included directories found matching '.git*' 05:04:29 INFO - Installing collected packages: jsonschema, functools32 05:04:29 INFO - Running setup.py install for functools32 05:04:29 INFO - warning: no files found matching '*.txt' 05:04:29 INFO - no previously-included directories found matching 'build' 05:04:29 INFO - no previously-included directories found matching 'dist' 05:04:29 INFO - no previously-included directories found matching '.git*' 05:04:29 INFO - Successfully installed jsonschema functools32 05:04:29 INFO - Cleaning up... 05:04:29 INFO - Return code: 0 05:04:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:04:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:29 INFO - 'HOME': '/Users/cltbld', 05:04:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:29 INFO - 'LOGNAME': 'cltbld', 05:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:29 INFO - 'MOZ_NO_REMOTE': '1', 05:04:29 INFO - 'NO_EM_RESTART': '1', 05:04:29 INFO - 'PAGER': '/bin/cat', 05:04:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:29 INFO - 'PWD': '/builds/slave/test', 05:04:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:29 INFO - 'SHELL': '/bin/bash', 05:04:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:29 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:29 INFO - 'USER': 'cltbld', 05:04:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:29 INFO - 'XPC_FLAGS': '0x0', 05:04:29 INFO - 'XPC_SERVICE_NAME': '0', 05:04:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:29 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 05:04:29 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 05:04:29 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 05:04:29 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 05:04:29 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 05:04:29 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 05:04:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:32 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 05:04:32 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 05:04:32 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 05:04:32 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 05:04:32 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 05:04:32 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 05:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:33 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 05:04:33 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 05:04:33 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 05:04:33 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 05:04:33 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 05:04:33 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 05:04:33 INFO - Downloading docopt-0.6.1.tar.gz 05:04:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:33 INFO - Running setup.py install for blobuploader 05:04:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:33 INFO - Running setup.py install for requests 05:04:34 INFO - Running setup.py install for docopt 05:04:34 INFO - Successfully installed blobuploader requests docopt 05:04:34 INFO - Cleaning up... 05:04:34 INFO - Return code: 0 05:04:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:34 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:34 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 05:04:34 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 05:04:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:34 INFO - 'HOME': '/Users/cltbld', 05:04:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:34 INFO - 'LOGNAME': 'cltbld', 05:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:34 INFO - 'MOZ_NO_REMOTE': '1', 05:04:34 INFO - 'NO_EM_RESTART': '1', 05:04:34 INFO - 'PAGER': '/bin/cat', 05:04:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:34 INFO - 'PWD': '/builds/slave/test', 05:04:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:34 INFO - 'SHELL': '/bin/bash', 05:04:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:34 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:34 INFO - 'USER': 'cltbld', 05:04:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:34 INFO - 'XPC_FLAGS': '0x0', 05:04:34 INFO - 'XPC_SERVICE_NAME': '0', 05:04:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:34 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-s9ik1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-w5qkas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-5Pa1XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-GyzYF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-J_VH04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-P6U_rX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-wQltjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-HiQe0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-aKsv7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-IUAAOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:35 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Y86nGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-tMeoQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-HNImTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-gZZn3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-XC3HJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-jqJj78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:36 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-37X7zU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:36 INFO - Running setup.py install for manifestparser 05:04:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:36 INFO - Running setup.py install for mozcrash 05:04:36 INFO - Running setup.py install for mozdebug 05:04:37 INFO - Running setup.py install for mozdevice 05:04:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozfile 05:04:37 INFO - Running setup.py install for mozhttpd 05:04:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozinfo 05:04:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozInstall 05:04:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:37 INFO - Running setup.py install for mozleak 05:04:37 INFO - Running setup.py install for mozlog 05:04:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for moznetwork 05:04:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozprocess 05:04:38 INFO - Running setup.py install for mozprofile 05:04:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozrunner 05:04:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:38 INFO - Running setup.py install for mozscreenshot 05:04:38 INFO - Running setup.py install for moztest 05:04:39 INFO - Running setup.py install for mozversion 05:04:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:39 INFO - Cleaning up... 05:04:39 INFO - Return code: 0 05:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:39 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:39 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 05:04:39 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 05:04:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:39 INFO - 'HOME': '/Users/cltbld', 05:04:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:39 INFO - 'LOGNAME': 'cltbld', 05:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:39 INFO - 'MOZ_NO_REMOTE': '1', 05:04:39 INFO - 'NO_EM_RESTART': '1', 05:04:39 INFO - 'PAGER': '/bin/cat', 05:04:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:39 INFO - 'PWD': '/builds/slave/test', 05:04:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:39 INFO - 'SHELL': '/bin/bash', 05:04:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:39 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:39 INFO - 'USER': 'cltbld', 05:04:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:39 INFO - 'XPC_FLAGS': '0x0', 05:04:39 INFO - 'XPC_SERVICE_NAME': '0', 05:04:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:39 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-hp4uOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:39 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-rgnS2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:39 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-16wq3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:39 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Jn4QQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:39 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)) 05:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:39 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-hMTMnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-T1I9gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-5B_f7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-vPKqUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-HiP2Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-TDB9UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-nXiGNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-8SWCyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-da_gwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:40 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)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-W0JPC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:04:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:40 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-b9dMm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:41 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)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:41 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-fXaMoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:41 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)) 05:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:41 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-fmtB7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:41 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)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:04:41 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)) 05:04:41 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)) 05:04:41 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)) 05:04:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:41 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 05:04:41 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 05:04:41 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 05:04:41 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 05:04:41 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 05:04:41 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 05:04:43 INFO - Downloading blessings-1.6.tar.gz 05:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:43 INFO - Installing collected packages: blessings 05:04:43 INFO - Running setup.py install for blessings 05:04:44 INFO - Successfully installed blessings 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:44 INFO - Reading from file tmpfile_stdout 05:04:44 INFO - Current package versions: 05:04:44 INFO - blessings == 1.6 05:04:44 INFO - blobuploader == 1.2.4 05:04:44 INFO - docopt == 0.6.1 05:04:44 INFO - functools32 == 3.2.3-2 05:04:44 INFO - jsonschema == 2.5.1 05:04:44 INFO - manifestparser == 1.1 05:04:44 INFO - mozInstall == 1.12 05:04:44 INFO - mozcrash == 0.17 05:04:44 INFO - mozdebug == 0.1 05:04:44 INFO - mozdevice == 0.48 05:04:44 INFO - mozfile == 1.2 05:04:44 INFO - mozhttpd == 0.7 05:04:44 INFO - mozinfo == 0.9 05:04:44 INFO - mozleak == 0.1 05:04:44 INFO - mozlog == 3.2 05:04:44 INFO - moznetwork == 0.27 05:04:44 INFO - mozprocess == 0.22 05:04:44 INFO - mozprofile == 0.28 05:04:44 INFO - mozrunner == 6.11 05:04:44 INFO - mozscreenshot == 0.1 05:04:44 INFO - mozsystemmonitor == 0.3 05:04:44 INFO - moztest == 0.7 05:04:44 INFO - mozversion == 1.4 05:04:44 INFO - psutil == 3.1.1 05:04:44 INFO - requests == 1.2.3 05:04:44 INFO - wsgiref == 0.1.2 05:04:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:44 INFO - 'HOME': '/Users/cltbld', 05:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PAGER': '/bin/cat', 05:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:44 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - 'XPC_FLAGS': '0x0', 05:04:44 INFO - 'XPC_SERVICE_NAME': '0', 05:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 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)) 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:44 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 0x10b50fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5a4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa81a4cec10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, '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.hdch3lxH1K/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.iBnrIvOP50/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:04:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:44 INFO - 'HOME': '/Users/cltbld', 05:04:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PAGER': '/bin/cat', 05:04:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:04:44 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:04:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - 'XPC_FLAGS': '0x0', 05:04:44 INFO - 'XPC_SERVICE_NAME': '0', 05:04:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Running post-action listener: _resource_record_post_action 05:04:44 INFO - Running post-action listener: _start_resource_monitoring 05:04:44 INFO - Starting resource monitoring. 05:04:44 INFO - ##### 05:04:44 INFO - ##### Finished create-virtualenv step (success) 05:04:44 INFO - ##### 05:04:44 INFO - ##### 05:04:44 INFO - ##### Running install step. 05:04:44 INFO - ##### 05:04:44 INFO - Running pre-action listener: _resource_record_pre_action 05:04:44 INFO - Running main action method: install 05:04:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:45 INFO - Reading from file tmpfile_stdout 05:04:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:45 INFO - Reading from file tmpfile_stdout 05:04:45 INFO - Output received: 05:04:45 INFO - Usage: mozinstall [options] installer 05:04:45 INFO - Options: 05:04:45 INFO - -h, --help show this help message and exit 05:04:45 INFO - -d DEST, --destination=DEST 05:04:45 INFO - Directory to install application into. [default: 05:04:45 INFO - "/builds/slave/test"] 05:04:45 INFO - --app=APP Application being installed. [default: firefox] 05:04:45 INFO - mkdir: /builds/slave/test/build/application 05:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:05:19 INFO - Reading from file tmpfile_stdout 05:05:19 INFO - Output received: 05:05:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:05:19 INFO - Running post-action listener: _resource_record_post_action 05:05:19 INFO - ##### 05:05:19 INFO - ##### Finished install step (success) 05:05:19 INFO - ##### 05:05:19 INFO - ##### 05:05:19 INFO - ##### Running run-tests step. 05:05:19 INFO - ##### 05:05:19 INFO - Running pre-action listener: _resource_record_pre_action 05:05:19 INFO - Running main action method: run_tests 05:05:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:19 INFO - 2016-06-30 05:05:19.886 screenresolution[1924:14320] starting screenresolution argv=screenresolution get 05:05:19 INFO - 2016-06-30 05:05:19.889 screenresolution[1924:14320] Display 0: 1600x1200x32@60 05:05:19 INFO - 2016-06-30 05:05:19.894 screenresolution[1925:14322] starting screenresolution argv=screenresolution list 05:05:19 INFO - Available Modes on Display 0 05:05:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:20 INFO - Intel Iris: 05:05:20 INFO - Chipset Model: Intel Iris 05:05:20 INFO - Type: GPU 05:05:20 INFO - Bus: Built-In 05:05:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:20 INFO - Vendor: Intel (0x8086) 05:05:20 INFO - Device ID: 0x0a2e 05:05:20 INFO - Revision ID: 0x0009 05:05:20 INFO - Displays: 05:05:20 INFO - Display: 05:05:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:20 INFO - Display Serial Number: 1600x1200 60 05:05:20 INFO - Main Display: Yes 05:05:20 INFO - Mirror: Off 05:05:20 INFO - Online: Yes 05:05:20 INFO - Rotation: Supported 05:05:20 INFO - Return code: 0 05:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:20 INFO - Python 2.7.3 05:05:20 INFO - Return code: 0 05:05:20 INFO - grabbing minidump binary from tooltool 05:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:20 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 0x10b473d30>, '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 0x10b1d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b46bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:20 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 05:05:20 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 05:05:20 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 05:05:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:20 INFO - Return code: 0 05:05:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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 05:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0172 --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 05:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdch3lxH1K/Render', 05:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:20 INFO - 'HOME': '/Users/cltbld', 05:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:20 INFO - 'LOGNAME': 'cltbld', 05:05:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:20 INFO - 'MOZ_NO_REMOTE': '1', 05:05:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:20 INFO - 'NO_EM_RESTART': '1', 05:05:20 INFO - 'PAGER': '/bin/cat', 05:05:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:20 INFO - 'PWD': '/builds/slave/test', 05:05:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:20 INFO - 'SHELL': '/bin/bash', 05:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iBnrIvOP50/Listeners', 05:05:20 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:20 INFO - 'USER': 'cltbld', 05:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:20 INFO - 'XPC_FLAGS': '0x0', 05:05:20 INFO - 'XPC_SERVICE_NAME': '0', 05:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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 05:05:20 INFO - mozversion application_buildid: 20160630025528 05:05:20 INFO - mozversion application_changeset: d700dc054751333e0735f975fce3d3adf153c62a 05:05:20 INFO - mozversion application_display_name: Nightly 05:05:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:20 INFO - mozversion application_name: Firefox 05:05:20 INFO - mozversion application_remotingname: firefox 05:05:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:20 INFO - mozversion application_vendor: Mozilla 05:05:20 INFO - mozversion application_version: 50.0a1 05:05:20 INFO - mozversion platform_buildid: 20160630025528 05:05:20 INFO - mozversion platform_changeset: d700dc054751333e0735f975fce3d3adf153c62a 05:05:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:20 INFO - mozversion platform_version: 50.0a1 05:05:20 INFO - using testdate: 1467288320 05:05:20 INFO - actual date: 1467288320 05:05:20 INFO - starting webserver on 'localhost:50905' 05:05:20 INFO - SUITE-START | Running 2 tests 05:05:20 INFO - TEST-START | dromaeo_css 05:05:20 INFO - operating with platform_type : mac_ 05:05:20 INFO - Initialising browser for dromaeo_css test... 05:05:20 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpZa6aJN/profile http://localhost:50905/getInfo.html) 05:05:22 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 05:05:22 INFO - PROCESS | 1935 | colorDepth:24 05:05:22 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 05:05:22 INFO - PROCESS | 1935 | __metrics 05:05:22 INFO - PROCESS | 1935 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:05:22 INFO - PROCESS | 1935 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:05:23 INFO - TEST-INFO | 1935: exit 0 05:05:23 INFO - Browser initialized. 05:05:23 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:23 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpZa6aJN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:35 INFO - PROCESS | 1938 | RSS: Main: 244633600 05:05:35 INFO - PROCESS | 1938 | 05:06:27 INFO - PROCESS | 1938 | 1467288387038 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] 05:08:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50905/tests/dromaeo/cssquery-ext.html) 05:08:38 INFO - PROCESS | 1938 | RSS: Main: 395227136 05:08:38 INFO - PROCESS | 1938 | 05:11:41 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-ext.html (next: http://localhost:50905/tests/dromaeo/cssquery-jquery.html) 05:11:42 INFO - PROCESS | 1938 | RSS: Main: 376651776 05:11:42 INFO - PROCESS | 1938 | 05:14:44 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50905/tests/dromaeo/cssquery-mootools.html) 05:14:45 INFO - PROCESS | 1938 | RSS: Main: 370802688 05:14:45 INFO - PROCESS | 1938 | 05:17:47 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50905/tests/dromaeo/cssquery-prototype.html) 05:17:48 INFO - PROCESS | 1938 | RSS: Main: 366809088 05:17:48 INFO - PROCESS | 1938 | 05:20:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50905/tests/dromaeo/cssquery-yui.html) 05:20:52 INFO - PROCESS | 1938 | RSS: Main: 332587008 05:20:52 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50905/tests/dromaeo/cssquery-yui.html (next: http://localhost:50905/tests/dromaeo/cssquery-dojo.html) 05:23:55 INFO - PROCESS | 1938 | RSS: Main: 415338496 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | __start_tp_report 05:23:55 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 05:23:55 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 05:23:55 INFO - PROCESS | 1938 | |i|pagename|runs| 05:23:55 INFO - PROCESS | 1938 | |0;dojo.html;3331.400347937371;3336.965540682387;3347.096284003319;3362.983185084074;3407.846646900889;5570.832875013756;5655.604107712456;5662.348829884564;5799.2750906136735;5799.449052340032;5720;5747.827565173052;5806.477417032466;5948.226730525036;5976.581639285252;5187.818426355059;5226.895462090756;5229.241759944805;5275.657082289648;5295.4969277918535;5203.5056669616515;5314.707691076989;5393.460653934614;5399.676019438846;5428.375736790261;5316.601254905898;5320.973395133038;5332.653377530468;5333.786648534093;5346.1713434417725;5147.613928955343;5167.457416971239;5212.374515058217;5216.113260745682;5273.947260527384;5160.767765450526;5178.378594568676;5199.012187684366;5256.658317209369;5275.630705850592;5816.592838501307;5839.182514447981;5849.678267695318;5895.764169433218;5920.289565252154;6151.200343955302;6177.876442471171;6227.688615569204;6243.2508099027955;6279.215098112736;6196.287426945869;6212.627242365473;6268.341824108494;6282.654454005028;6310.936890631081;5727.971360143172;5968.641881487125;5987.670678112702;6000.159977603139;6009.879802403927;6213.161223234805;6255.624662520263;6272.3100458949475;6279.623222606659;6284.402981716729;5483.972580137153;6132.478739307119;6214.223222097238;6215.254169499599;6242.875142497124;6187.938120618826;6190.659513726789;6256.812295631138;6285.088662143963;6315.905261421082;6080.659382027436;6213.378662133841;6270.811875643738;6285.120083188357;6285.277193122758;6142.6522545212965;6158.445739883342;6217.036359364306;6222.066689996536;6224.502039836802;5010.89978200434;5033.471485494044;5039.4708555601865;5041.243813427942;5066.873328166826;4454.933176002362;4470.150671372428;4492.573205545468;4526.479454862667;4566.292224705109;5638.548916086703;5675.914861277084;5714.657120572779;5736.6558006519745;5807.5063619591965;6255.6872156392;6266.091416744546;6266.248050234001;6279.686015699197;6283.497320214372;5740.196372507852;5773.6824474654295;5788.565857560699;5795.159701843293;5839.649621022752;5350.839474815762;5376.623636345418;5410.404855465896;5482.068048431776;5490.972545137249;6256.749730010749;6303.810885673437;6318.936810632019;6324.177856878577;6325.146105275729;6203.96898015507;6227.657478838707;6258.968705156627;6259.624422534663;6265.4361107500545;6162.753489860355;6232.875342493216;6234.532410069353;6273.9372606273355;6303.401176888188;6229.53278504123;6231.626102433868;6238.625682459067;6245.718942647501;6251.062340648939;6241.063840423973;6252.343503932022;6257.09272155544;6263.592866463665;6266.968665156645;6021.789237376671;6091.695415229132;6093.878122437615;6098.664573448503;6127.050307202391;6105.358937311696;6137.631742095488;6179.474744646575;6185;6218.098375735583;6046.30467496226;6059.424354686298;6077.999333509166;6084.335848962579;6128.111423843428;5942.286925568959;6099.582179613229;6131.233595800525;6165.72254248551;6191.19514463117;6085.878282434238;6100.603460775035;6102.145699601971;6117.877642447215;6142.140100386039 05:23:55 INFO - PROCESS | 1938 | |1;ext.html;475.6408911271989;501.5435953282513;514.1285521041833;519.9818006369778;536.019085074314;27456.901723931045;29848.850755746218;39086.413703794395;40013;40062;41776.79111604446;42079.15841683165;42094.7895260524;42168.789156054256;42169.57830421695;9505.429674219535;9699.806003879918;9707.126358627722;9711.271654625896;9712.70861874145;8499.74500764978;8723.30213582915;11433.942830285838;11673.066154707603;11695.298282103058;11592.59425920093;11681.59114430995;11682.357470339128;11701.765964680744;11705.3562054087;8683.696070637561;11490.253133546294;11557.19099663024;11617.025105732046;11628.709282267926;11452;11764.117691173196;11798.46906889192;11802.232854864493;11805.40972951349;39274.60725392738;39763.21065552057;39763.60236397628;39772.60227397747;39855.4021689672;3362.260302733395;3376.5441665375106;3498.3702933471964;3588.7129029677826;3673.7061035117395;30038;30297.545536816964;30970.845145774354;31357.52963705546;31957.041288761142;3240.1575590346442;3244.7566432517656;3261.184703824044;3272.885449009297;3400.506926495669;509049;510885;515303;516852;517566;30029.099127026224;56392;56724;56849;56910.430895690515;30759.8462007693;31411.37177256488;31574.737010519322;31625.525617115763;31789.52315715266;14135.15189088658;14340.498082567061;14374.712505749827;14400.135991840523;14411.495597654031;10997.230193886351;11270.605528806616;11275.210735248618;12430.81353779676;12536.310502922426;6557.737690492327;6619.437347825488;6743.460523158135;6843.7604683835825;7072.717091316393;2924.2835505300914;2930.9853450733035;2957.1276473440385;2963.73326400625;2976.940461190764;951.9381240219363;955.47926380123;1032.339302846179;1085.978280434387;1087.5168530414771;29932.251693707833;30107.397852042835;30469.56139650074;30499.847500762797;30682.84658576693;1033.214756784849;1041.3595638682157;1065.6909496246;1134.744682446443;1135.7898788723946;15641;15643.687126257639;15749.448769293249;15884.364625415084;16062.598435039217;194124;196402;197785;198554;200476;57330;57761.422385776445;57900;58013.70993145092;58085.12872306919;1254.2913254011455;1280.3790161771533;1369.301656155348;1401.9439222430988;1418.7942748301646;29423.85288073546;30445;30861;31202.531962020134;31227.53158702576;42403.7879810599;42695.292188312116;42898;42986.78506607572;43063.784681076395;560571;561226;565008.3499164956;566842;569020;21226.7877321231;21280.042398091813;23816.523669526858;24327.148549800673;24440.022399103837;46342.53657463382;46612.067758643956;47146.292805607256;48353;48384.516154838;38779.6122038776;39122.41316380313;39397.80301098476;40114.78794726512;40480.59519404768;36371.81814090913;38816.6118338813;38957.6104238954;39197.804010979766;39736.40395394129;37989.81005095068;38442.231155376176;38707.41938870863;38740.80629596834;38828.2234355317;32574.51138232881;33484.665153348156;33854.890473000945;34128.829355853064;34224.82887585546;19818.50453738668;21079.367618971457;25200.36197647147;25292.747072529775;25826.35434114162 05:23:55 INFO - PROCESS | 1938 | |2;jquery.html;346.63083815736223;364.78295414228535;365.15102386950343;379.8917308567059;391.17852509729573;15606.219689015561;16903.408380706664;20374.592508149828;21048.89475552622;21134.15463381467;20658.967051647396;20677.862717550568;21034.894825525887;21099.050542725614;21635.567288654216;5347.839564813062;5932.347441781411;6146.38536146384;6208.906866397007;6210.4100110489435;6065.909011364833;6130.877382452349;6178.227721534809;6181.196444462214;6183.072539119124;5850.6782126983;6071.271447426293;6093.542984276175;6119.143319935212;6140.723667434954;5822.505087067604;5983.850403739898;6060.212172417558;6074.17998570194;6094.146819445281;5194.948050519484;5216.9739151304;5234.110201265794;5234.691327168208;5240.685558866442;23259.7674023261;23938.64092038621;26933.46133077347;27270.5909411359;27289.727102729117;2188.7482939462006;2412.3848418653306;2505.4112283613517;2954.5715871198554;3210.2616398228306;15619.453319133776;16134.515964521082;16280.51158465236;16716.74924876128;16880.662386752196;1813.3562585282298;1822.0889555222388;1833.7799464064399;1845.8707890447674;1915.7796853361901;124394.37802811018;125138;125731.3713431427;126512.99999999908;127444.45111097819;10415.010573995458;10597.9470102649;10870.619528316629;10917.727056823484;10989.725256868482;13355.198688078746;13373.799393009112;13411.597652070453;13419.194848309133;13491.190528568317;8429.747107586782;8583.313334933191;8659.830922825464;8695.913040869636;8729.519876406732;8726.389152759246;8742.475451472932;8750.69372571957;8873.955630221808;8876.245519130945;5539.861503462365;5591.552675785927;6537.640429776408;6717.529772915849;6738.494612904052;1571.5128310223868;1776.7512548243255;1784.732290156461;1817.9272829086688;1828.8536917046604;922.8754118193958;934.7102398256562;1030.7474668706216;1063.9148868090533;1112.1325366214367;22830.200942967247;23369.41576460602;23385;23389.298321050395;23445.88277058604;952.3238500664605;985.4776968206862;1008.1984822066474;1026.3021145621049;1088.0697004061572;11781.764364712657;12109.757804843854;12262.938685306695;12285.508579656893;12313.753724925631;568561;577060;578700.1064994648;594385;602547;132307.01539476722;141767;143855;144656;145029;954.6754103604808;984.8473486609587;1000.429755039635;1019.23557332001;1021.1881554164457;14734.062093547785;19522.828630282227;19982.70025949583;20073.610563165825;20139.892305923317;54110.45889541211;55553;58671.119933200185;60064;61165.47086322877;81266;84145;84751.57624212086;85233;85924.57037714771;5826.650400975955;6240.469560087444;7883.921160788319;8040.356771458152;8235.752927412188;10203.18374530021;13221.735565288833;13231.735365292832;13417.932910335387;13426.127301725357;10934.289271197347;11194.440277985905;11479.827802583135;11501.884981150082;11535.711607209887;11499;11507.769844602893;11661.76676466449;11741.119416044;11799.99700025473;11693.239939403911;11818.172727909308;12074.154809163274;12078.610959739392;12128.393580321124;6821.727130914708;7168.390686791681;8155.184481551797;8162.10216876155;8457.534835584102;9053;11118.49966751482;11404.315741055563;11739.530418783495;11779.469923853618 05:23:55 INFO - PROCESS | 1938 | |3;mootools.html;1368.609946165343;1506.1264466609368;1552.0222259976217;1741.33829144925;1899.8575106866979;7725.575093369863;8264.62809173587;8509;8511.319094472443;8520.786980325494;6647.800565983016;8454.154584541542;8479.872801907977;8566.143385661431;8600.22597966183;1703.5826222575486;2142.4750936020696;2143.2070134050346;2291.255342013844;2352.200251914349;2157.2341818654386;2166.5450255446376;2306.5041016181517;2349.800266977309;2353.670486131943;2339.2982105368324;2342.227065068523;2351.870647114408;2356.234223877238;2358.78770910618;2302.7697230277004;2354.6232602783643;2359.0210062823908;2359.9410014749683;2361.161787565407;2100.2124203423714;2321.1991862807304;2369.301056188411;2376.916807911715;2385.0221409222136;8562.357823163225;8635.481871087755;8638.697645582375;8641.740747777576;8654;5180.326557547494;5344.7862085516535;5345.4387289334445;5353;5357;3512.7189824813954;3588.0132963435003;3650.233450975298;3671.81640917953;3689.24370504046;887.2414085956478;1063.8936106389376;1089.8964598363143;1091.557919042781;1092.3336764573603;11381.943090284578;11531.250468719507;11584.47869845859;11613.709657258552;11647.417629118594;4529.275315949432;4714.575688188011;4786.856394308176;4789.161896668069;4835.58897493717;9991.250656200815;10024.248181386425;10024.849627255597;10045.296829221885;10053.597856085675;5344.118220493653;5352.4915133062295;5354.973225133927;5356.517913387814;5363.517283444508;4435.35687325336;4744.739039352799;4825.227963525889;4882.7314497702255;4890.608751299887;3611.6749492545796;3671.0822294426393;3672.6878215351994;3732.42147467143;3754.5682246541996;1101.432762127505;1116.6929094499078;1117.07841031148;1121.3328069798456;1165.6036947437742;817.7832874288199;907.2923119966437;909.9454032758056;932.5849996751381;958.1855422890627;5822.155787410887;5844;6045.093236014633;6076.635401875901;6118.1128736332985;958.8034452937131;963.9759006024906;965.3966271080575;982.597135174575;1002.9297949143636;3912.628300311466;3935.7835319057726;4045.3931910213114;4062.025113972625;4137.338025915899;10317.9484102579;10473.895261047444;10519.631812886666;10627.415492147851;10632.94683526593;10554.155667546578;10567.15462762977;10581.947090264499;10596.364218146782;10616.522256498476;7607.201243869425;8047.396445266629;8049.39629527776;8064.8387032259025;8189.222023907839;5920.911186332122;6008.609440386361;6060.787872424444;6061.605995610271;6066.908996364969;9782.706518804447;10010.899891001289;10030.598776048877;10035.799284014132;10053.547590358598;9770.36492627977;9865.408075515492;10271.202539555698;10278.794424111325;10295;7505.924940750522;7516.361109305771;7610.972518709903;7616.0099187105325;7619.1999840016;7487.588182650006;7722.575258360844;8155.632996515053;8173.67305307781;8212.97337832771;6814.591124532544;7252.782416527512;7310.342069213796;7477.813054673459;7508.361789247975;7126.857462850818;7187.748428804966;7255.2019277878335;7409.740659076906;7428.888566671396;6589.374009469093;6726.428253598303;6730.4952128589575;6731.394174524316;6742.157230346207;4821.228603423436;4995.350604421402;5022.096022715946;5088.720120393414;5123.487651234846;5002.274670172732;5024.221245706921;5034.471380504994;5040.243963405518;5085.92371114441 05:23:55 INFO - PROCESS | 1938 | |4;prototype.html;285.26970954356864;288.3641570337406;298.7430809503827;304.20753935996726;304.5173400160745;5317.521423071932;5329.413764485904;5399.514043736063;5400.675959442437;5485.341758988927;4988.551030407262;5145.3311069560905;5145.948540514593;5328.6802791832515;5360.65155764875;3445.931081378371;4052.8176232069627;4066.085130845566;4069.2065047315787;4101.323281658522;3201.695838895301;3403.7447191460615;4070.4504891839665;4082.8775136745944;4112.157007813391;3809.4285857121513;3859;3980;4052.0882801369753;4094.324436467994;3316.966830331702;3529.7645823961407;3790.507234059582;3998.7800670963084;4080.3471444568877;3095.876164953399;3478.3825521132794;3499.825008749552;3517.103138699615;3536.3104194682046;4933.654644174909;4975.4527002029745;5191.9480805192225;5203.531682148625;5264.631475796696;4018.357062869956;4579.3131030345385;4662.645364091146;4670.532946705336;4674.182018146845;5663.660180389149;5691;5731.398203188689;5769.221155144086;5779.508741756914;3732.234891847165;3775.565809931838;3878.0111071676542;3902.453656488094;3917.9608203917883;5337.969832776229;5603.7478313475995;5807.419258074159;5835.9708201458725;5844.912326315151;3300.5214243934497;3621.5654121505586;3884.5726970033274;3908.003459117907;3925.666318362914;3329.716974057232;3747.2880152770886;3798.5061941947924;3799.316123097869;3965.504311961005;4449.3326001100095;4581.518940511265;4613.607843333354;4622.121796858653;4637.559431853969;5595.972020139955;5618.719064046782;5631.380548139701;5652.378238393773;5658.7170641467765;1143.9771204575861;1284.1652925598248;1332.4137379552965;1338.1903948111253;1347.7843545032945;724.3697982755037;864.2092485375937;914.1909410172045;919.9898222384112;924.6209054287709;4096.672266218696;4676.532346765296;5219.817306394258;5222.738863056832;5286.7092309922555;5518.365387980433;5698.230738850202;5735.512481439125;5789.8552536186935;5793.2179155814265;4837.516248375205;5211;5249.580033597303;5256.448072952285;5279.788808447619;2931.311141881656;2997.145813443158;3011.6687164411896;3026.6821983691834;3037.719263195743;4805.471398146201;5042.8753515084545;5592.748326325325;5854.8536286592325;5894.970525147347;5480.862978425571;5604.570834437192;5747.252857128547;5771.538276937835;5830.854228644318;4619.930701039487;5828.621139625996;5857.4435428634215;5889;5951.285845698544;4844.660873738804;5436.972815136057;5471.261379713687;5862.472377485876;5971.373005834324;5018.5232402921665;5685.60200785941;5776.880484538302;5808.5062769665055;5860.882782344414;5437.564994800327;5807.954568177768;6118.235220597426;6129.567442232365;6219.533534984804;3179.729722973573;3826.2730081284467;3851.6340947609933;3872.6708229800784;3904.7852368120025;5364.356277246599;5885.705714714161;5909.734061967308;5950.935268336723;5957.285125784933;4825.565699087099;5196.870078247923;5381.784728610812;5747.19539264495;5799.391063938226;5644.971775141098;5688.061469857427;5703.344115426779;5711.914321285267;5718.914216286676;5219.269302297757;5432.239486471818;5440.863978400572;5476.835694929158;5543.584231182758;4769.163871909271;5086.720230387365;5446.074167391474;5662.462066103713;5754.827355179351;5164.767585458589;5205.479452054764;5373.623846330719;5386.784528618811;5588.944110558842 05:23:55 INFO - PROCESS | 1938 | |5;yui.html;704.387183150659;757.6855604923957;779.3453499060785;806.6931570855215;833.9332853371731;14911.32899019544;15725.37098516058;15759.684806303881;15773.684526309497;15778.447754328587;14697.559073227794;14869.628259293522;14893.180875051868;14893.627659308495;14913.32890019949;676.7394553097068;699.6781480518941;828.6064119543199;833.9457935234205;854.8504011797942;847.0470720439506;850.2093053460279;852.5992783391796;853.4239388412827;853.4409961475244;874.9825003499926;891.2468963725642;891.6611687558718;891.8795962545038;892.4645212872257;709.971601135954;881.5592203898051;886.2732559301376;887.0863011098579;909.077286554148;664.5912763650321;666.4335314982275;666.9233038200621;666.9833254168636;670.2995369838482;15225.923870380688;15273.312700928485;15284.847151528453;15285.388584456608;15292.923535382362;14038.36827342772;14174.50389236382;14197.290135493184;14229.928850355682;14446.205458699873;12684.112112152154;12732.299723515203;12836.358182090858;12877.806832897513;13015.284159371231;4503.774811259456;4511.1654343946475;4529.433820772403;4680.789364478606;4747.074320507502;112306.99999999919;113359;113404.86595134108;113445;113458.43270783676;10966.451677416097;11206.49570769301;11269.71825704364;11587.88412115868;11759.941200293944;18188.63622727538;18411;21810.127594896025;22060.88969555142;22061.889690551445;6180.351063138396;7832;7853.921460785319;8024.438289319808;8171.305439037629;4472.888177795581;4477.485089214782;4479.350494178326;4506.188886000551;4616.122936642095;3001.2196828824226;3405.829708514614;3544.7518673692593;3549.4143466328283;3551.4495253235273;613.08344025681;640.7533099756628;644.1400730025468;652.960822350651;658.1312667279241;539.4309004000817;661.408039804382;679.8844196486609;693.2201273567237;702.3398005874461;9131.360804743605;9622.711318660311;9628.951855240679;9677.563349055941;9701.320907536547;546.7266366816592;625.2746813696182;626.1797045869833;689.8447849233883;700.3206889317354;4062.2687916175373;4499.257622492318;4702.365180700562;4733.83431579893;4758;136710;152481;152939;153672;158864;87015;123665;125045;125480;126848;119096.21355679672;119347;119648.41054768371;119773.40113299378;120402.99999999825;9144.771380715536;9183.31125165602;9191.310651701282;9212.263018958332;9480.288978326515;57509.712451437474;63141;64766.38084047937;65229;67186.32034199184;90901.54549227476;92304;92341.5382923081;92725.60911586197;94235.52882235819;10478.214133939833;11942.522299107939;11985.460654270753;12408.131430800118;12455.314957677414;11057.612983545538;13455.59633210966;13701.794473083111;13822.377992990541;14088.872890168557;10942.562297508011;12476.688082798002;12605.432755526206;12753.48986040548;13179.67050823737;12047.096467765128;12361.381930903599;12766.106372554204;12796.808047879103;12916.677083072998;11683.948444639684;12201.464891823713;12353.691157721129;12590.307533085768;12751.234925904475;9509.857352139861;10471.476426178813;10498.370097794157;10924.726881828017;10939.343639381663;10042.548085336031;10265.84601230997;10385.065344119066;10669.039786418949;10727.839082413613 05:23:55 INFO - PROCESS | 1938 | __end_tp_report 05:23:55 INFO - PROCESS | 1938 | __start_cc_report 05:23:55 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1501 05:23:55 INFO - PROCESS | 1938 | __end_cc_report 05:23:55 INFO - PROCESS | 1938 | __startTimestamp1467289435732__endTimestamp 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | ------- Summary: start ------- 05:23:55 INFO - PROCESS | 1938 | Number of tests: 6 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5789.70 Median:6084.34 stddev:628.66 (10.3%) stddev-sans-first:600.28 05:23:55 INFO - PROCESS | 1938 | Values: 3331.4 3337.0 3347.1 3363.0 3407.8 5570.8 5655.6 5662.3 5799.3 5799.4 5720.0 5747.8 5806.5 5948.2 5976.6 5187.8 5226.9 5229.2 5275.7 5295.5 5203.5 5314.7 5393.5 5399.7 5428.4 5316.6 5321.0 5332.7 5333.8 5346.2 5147.6 5167.5 5212.4 5216.1 5273.9 5160.8 5178.4 5199.0 5256.7 5275.6 5816.6 5839.2 5849.7 5895.8 5920.3 6151.2 6177.9 6227.7 6243.3 6279.2 6196.3 6212.6 6268.3 6282.7 6310.9 5728.0 5968.6 5987.7 6000.2 6009.9 6213.2 6255.6 6272.3 6279.6 6284.4 5484.0 6132.5 6214.2 6215.3 6242.9 6187.9 6190.7 6256.8 6285.1 6315.9 6080.7 6213.4 6270.8 6285.1 6285.3 6142.7 6158.4 6217.0 6222.1 6224.5 5010.9 5033.5 5039.5 5041.2 5066.9 4454.9 4470.2 4492.6 4526.5 4566.3 5638.5 5675.9 5714.7 5736.7 5807.5 6255.7 6266.1 6266.2 6279.7 6283.5 5740.2 5773.7 5788.6 5795.2 5839.6 5350.8 5376.6 5410.4 5482.1 5491.0 6256.7 6303.8 6318.9 6324.2 6325.1 6204.0 6227.7 6259.0 6259.6 6265.4 6162.8 6232.9 6234.5 6273.9 6303.4 6229.5 6231.6 6238.6 6245.7 6251.1 6241.1 6252.3 6257.1 6263.6 6267.0 6021.8 6091.7 6093.9 6098.7 6127.1 6105.4 6137.6 6179.5 6185.0 6218.1 6046.3 6059.4 6078.0 6084.3 6128.1 5942.3 6099.6 6131.2 6165.7 6191.2 6085.9 6100.6 6102.1 6117.9 6142.1 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:56368.92 Median:17917.85 stddev:122142.47 (681.7%) stddev-sans-first:122413.02 05:23:55 INFO - PROCESS | 1938 | Values: 475.6 501.5 514.1 520.0 536.0 27456.9 29848.9 39086.4 40013.0 40062.0 41776.8 42079.2 42094.8 42168.8 42169.6 9505.4 9699.8 9707.1 9711.3 9712.7 8499.7 8723.3 11433.9 11673.1 11695.3 11592.6 11681.6 11682.4 11701.8 11705.4 8683.7 11490.3 11557.2 11617.0 11628.7 11452.0 11764.1 11798.5 11802.2 11805.4 39274.6 39763.2 39763.6 39772.6 39855.4 3362.3 3376.5 3498.4 3588.7 3673.7 30038.0 30297.5 30970.8 31357.5 31957.0 3240.2 3244.8 3261.2 3272.9 3400.5 509049.0 510885.0 515303.0 516852.0 517566.0 30029.1 56392.0 56724.0 56849.0 56910.4 30759.8 31411.4 31574.7 31625.5 31789.5 14135.2 14340.5 14374.7 14400.1 14411.5 10997.2 11270.6 11275.2 12430.8 12536.3 6557.7 6619.4 6743.5 6843.8 7072.7 2924.3 2931.0 2957.1 2963.7 2976.9 951.9 955.5 1032.3 1086.0 1087.5 29932.3 30107.4 30469.6 30499.8 30682.8 1033.2 1041.4 1065.7 1134.7 1135.8 15641.0 15643.7 15749.4 15884.4 16062.6 194124.0 196402.0 197785.0 198554.0 200476.0 57330.0 57761.4 57900.0 58013.7 58085.1 1254.3 1280.4 1369.3 1401.9 1418.8 29423.9 30445.0 30861.0 31202.5 31227.5 42403.8 42695.3 42898.0 42986.8 43063.8 560571.0 561226.0 565008.3 566842.0 569020.0 21226.8 21280.0 23816.5 24327.1 24440.0 46342.5 46612.1 47146.3 48353.0 48384.5 38779.6 39122.4 39397.8 40114.8 40480.6 36371.8 38816.6 38957.6 39197.8 39736.4 37989.8 38442.2 38707.4 38740.8 38828.2 32574.5 33484.7 33854.9 34128.8 34224.8 19818.5 21079.4 25200.4 25292.7 25826.4 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:35853.44 Median:23314.59 stddev:98288.95 (421.6%) stddev-sans-first:98528.52 05:23:55 INFO - PROCESS | 1938 | Values: 346.6 364.8 365.2 379.9 391.2 15606.2 16903.4 20374.6 21048.9 21134.2 20659.0 20677.9 21034.9 21099.1 21635.6 5347.8 5932.3 6146.4 6208.9 6210.4 6065.9 6130.9 6178.2 6181.2 6183.1 5850.7 6071.3 6093.5 6119.1 6140.7 5822.5 5983.9 6060.2 6074.2 6094.1 5194.9 5217.0 5234.1 5234.7 5240.7 23259.8 23938.6 26933.5 27270.6 27289.7 2188.7 2412.4 2505.4 2954.6 3210.3 15619.5 16134.5 16280.5 16716.7 16880.7 1813.4 1822.1 1833.8 1845.9 1915.8 124394.4 125138.0 125731.4 126513.0 127444.5 10415.0 10597.9 10870.6 10917.7 10989.7 13355.2 13373.8 13411.6 13419.2 13491.2 8429.7 8583.3 8659.8 8695.9 8729.5 8726.4 8742.5 8750.7 8874.0 8876.2 5539.9 5591.6 6537.6 6717.5 6738.5 1571.5 1776.8 1784.7 1817.9 1828.9 922.9 934.7 1030.7 1063.9 1112.1 22830.2 23369.4 23385.0 23389.3 23445.9 952.3 985.5 1008.2 1026.3 1088.1 11781.8 12109.8 12262.9 12285.5 12313.8 568561.0 577060.0 578700.1 594385.0 602547.0 132307.0 141767.0 143855.0 144656.0 145029.0 954.7 984.8 1000.4 1019.2 1021.2 14734.1 19522.8 19982.7 20073.6 20139.9 54110.5 55553.0 58671.1 60064.0 61165.5 81266.0 84145.0 84751.6 85233.0 85924.6 5826.7 6240.5 7883.9 8040.4 8235.8 10203.2 13221.7 13231.7 13417.9 13426.1 10934.3 11194.4 11479.8 11501.9 11535.7 11499.0 11507.8 11661.8 11741.1 11800.0 11693.2 11818.2 12074.2 12078.6 12128.4 6821.7 7168.4 8155.2 8162.1 8457.5 9053.0 11118.5 11404.3 11739.5 11779.5 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5644.89 Median:4886.67 stddev:3159.62 (64.7%) stddev-sans-first:3152.14 05:23:55 INFO - PROCESS | 1938 | Values: 1368.6 1506.1 1552.0 1741.3 1899.9 7725.6 8264.6 8509.0 8511.3 8520.8 6647.8 8454.2 8479.9 8566.1 8600.2 1703.6 2142.5 2143.2 2291.3 2352.2 2157.2 2166.5 2306.5 2349.8 2353.7 2339.3 2342.2 2351.9 2356.2 2358.8 2302.8 2354.6 2359.0 2359.9 2361.2 2100.2 2321.2 2369.3 2376.9 2385.0 8562.4 8635.5 8638.7 8641.7 8654.0 5180.3 5344.8 5345.4 5353.0 5357.0 3512.7 3588.0 3650.2 3671.8 3689.2 887.2 1063.9 1089.9 1091.6 1092.3 11381.9 11531.3 11584.5 11613.7 11647.4 4529.3 4714.6 4786.9 4789.2 4835.6 9991.3 10024.2 10024.8 10045.3 10053.6 5344.1 5352.5 5355.0 5356.5 5363.5 4435.4 4744.7 4825.2 4882.7 4890.6 3611.7 3671.1 3672.7 3732.4 3754.6 1101.4 1116.7 1117.1 1121.3 1165.6 817.8 907.3 909.9 932.6 958.2 5822.2 5844.0 6045.1 6076.6 6118.1 958.8 964.0 965.4 982.6 1002.9 3912.6 3935.8 4045.4 4062.0 4137.3 10317.9 10473.9 10519.6 10627.4 10632.9 10554.2 10567.2 10581.9 10596.4 10616.5 7607.2 8047.4 8049.4 8064.8 8189.2 5920.9 6008.6 6060.8 6061.6 6066.9 9782.7 10010.9 10030.6 10035.8 10053.5 9770.4 9865.4 10271.2 10278.8 10295.0 7505.9 7516.4 7611.0 7616.0 7619.2 7487.6 7722.6 8155.6 8173.7 8213.0 6814.6 7252.8 7310.3 7477.8 7508.4 7126.9 7187.7 7255.2 7409.7 7428.9 6589.4 6726.4 6730.5 6731.4 6742.2 4821.2 4995.4 5022.1 5088.7 5123.5 5002.3 5024.2 5034.5 5040.2 5085.9 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4549.89 Median:5208.24 stddev:1423.67 (27.3%) stddev-sans-first:1391.21 05:23:55 INFO - PROCESS | 1938 | Values: 285.3 288.4 298.7 304.2 304.5 5317.5 5329.4 5399.5 5400.7 5485.3 4988.6 5145.3 5145.9 5328.7 5360.7 3445.9 4052.8 4066.1 4069.2 4101.3 3201.7 3403.7 4070.5 4082.9 4112.2 3809.4 3859.0 3980.0 4052.1 4094.3 3317.0 3529.8 3790.5 3998.8 4080.3 3095.9 3478.4 3499.8 3517.1 3536.3 4933.7 4975.5 5191.9 5203.5 5264.6 4018.4 4579.3 4662.6 4670.5 4674.2 5663.7 5691.0 5731.4 5769.2 5779.5 3732.2 3775.6 3878.0 3902.5 3918.0 5338.0 5603.7 5807.4 5836.0 5844.9 3300.5 3621.6 3884.6 3908.0 3925.7 3329.7 3747.3 3798.5 3799.3 3965.5 4449.3 4581.5 4613.6 4622.1 4637.6 5596.0 5618.7 5631.4 5652.4 5658.7 1144.0 1284.2 1332.4 1338.2 1347.8 724.4 864.2 914.2 920.0 924.6 4096.7 4676.5 5219.8 5222.7 5286.7 5518.4 5698.2 5735.5 5789.9 5793.2 4837.5 5211.0 5249.6 5256.4 5279.8 2931.3 2997.1 3011.7 3026.7 3037.7 4805.5 5042.9 5592.7 5854.9 5895.0 5480.9 5604.6 5747.3 5771.5 5830.9 4619.9 5828.6 5857.4 5889.0 5951.3 4844.7 5437.0 5471.3 5862.5 5971.4 5018.5 5685.6 5776.9 5808.5 5860.9 5437.6 5808.0 6118.2 6129.6 6219.5 3179.7 3826.3 3851.6 3872.7 3904.8 5364.4 5885.7 5909.7 5950.9 5957.3 4825.6 5196.9 5381.8 5747.2 5799.4 5645.0 5688.1 5703.3 5711.9 5718.9 5219.3 5432.2 5440.9 5476.8 5543.6 4769.2 5086.7 5446.1 5662.5 5754.8 5164.8 5205.5 5373.6 5386.8 5588.9 05:23:55 INFO - PROCESS | 1938 | 05:23:55 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:24876.77 Median:3547.08 stddev:40005.49 (1127.8%) stddev-sans-first:40076.55 05:23:55 INFO - PROCESS | 1938 | Values: 704.4 757.7 779.3 806.7 833.9 14911.3 15725.4 15759.7 15773.7 15778.4 14697.6 14869.6 14893.2 14893.6 14913.3 676.7 699.7 828.6 833.9 854.9 847.0 850.2 852.6 853.4 853.4 875.0 891.2 891.7 891.9 892.5 710.0 881.6 886.3 887.1 909.1 664.6 666.4 666.9 667.0 670.3 15225.9 15273.3 15284.8 15285.4 15292.9 14038.4 14174.5 14197.3 14229.9 14446.2 12684.1 12732.3 12836.4 12877.8 13015.3 4503.8 4511.2 4529.4 4680.8 4747.1 112307.0 113359.0 113404.9 113445.0 113458.4 10966.5 11206.5 11269.7 11587.9 11759.9 18188.6 18411.0 21810.1 22060.9 22061.9 6180.4 7832.0 7853.9 8024.4 8171.3 4472.9 4477.5 4479.4 4506.2 4616.1 3001.2 3405.8 3544.8 3549.4 3551.4 613.1 640.8 644.1 653.0 658.1 539.4 661.4 679.9 693.2 702.3 9131.4 9622.7 9629.0 9677.6 9701.3 546.7 625.3 626.2 689.8 700.3 4062.3 4499.3 4702.4 4733.8 4758.0 136710.0 152481.0 152939.0 153672.0 158864.0 87015.0 123665.0 125045.0 125480.0 126848.0 119096.2 119347.0 119648.4 119773.4 120403.0 9144.8 9183.3 9191.3 9212.3 9480.3 57509.7 63141.0 64766.4 65229.0 67186.3 90901.5 92304.0 92341.5 92725.6 94235.5 10478.2 11942.5 11985.5 12408.1 12455.3 11057.6 13455.6 13701.8 13822.4 14088.9 10942.6 12476.7 12605.4 12753.5 13179.7 12047.1 12361.4 12766.1 12796.8 12916.7 11683.9 12201.5 12353.7 12590.3 12751.2 9509.9 10471.5 10498.4 10924.7 10939.3 10042.5 10265.8 10385.1 10669.0 10727.8 05:23:55 INFO - PROCESS | 1938 | -------- Summary: end -------- 05:23:55 INFO - PROCESS | 1938 | 05:23:56 INFO - TEST-INFO | 1938: exit 0 05:23:56 INFO - TEST-OK | dromaeo_css | took 1115855ms 05:23:56 INFO - TEST-START | kraken 05:23:56 INFO - operating with platform_type : mac_ 05:23:56 INFO - Initialising browser for kraken test... 05:23:56 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpcs0IRG/profile http://localhost:50905/getInfo.html) 05:23:58 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 05:23:58 INFO - PROCESS | 1940 | colorDepth:24 05:23:58 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 05:23:58 INFO - PROCESS | 1940 | __metrics 05:23:58 INFO - PROCESS | 1940 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:23:58 INFO - PROCESS | 1940 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:23:59 INFO - TEST-INFO | 1940: exit 0 05:23:59 INFO - Browser initialized. 05:23:59 INFO - Running cycle 1/1 for kraken test... 05:23:59 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpcs0IRG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:11 INFO - PROCESS | 1941 | RSS: Main: 244662272 05:24:11 INFO - PROCESS | 1941 | 05:24:13 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50905/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:14 INFO - PROCESS | 1941 | RSS: Main: 294498304 05:24:14 INFO - PROCESS | 1941 | 05:24:17 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50905/tests/kraken/driver.html?testName=audio-dft) 05:24:17 INFO - PROCESS | 1941 | RSS: Main: 304644096 05:24:17 INFO - PROCESS | 1941 | 05:24:21 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50905/tests/kraken/driver.html?testName=audio-fft) 05:24:22 INFO - PROCESS | 1941 | RSS: Main: 388743168 05:24:22 INFO - PROCESS | 1941 | 05:24:24 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50905/tests/kraken/driver.html?testName=audio-oscillator) 05:24:24 INFO - PROCESS | 1941 | RSS: Main: 288456704 05:24:24 INFO - PROCESS | 1941 | 05:24:26 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50905/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:27 INFO - PROCESS | 1941 | RSS: Main: 292544512 05:24:27 INFO - PROCESS | 1941 | 05:24:30 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50905/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:30 INFO - PROCESS | 1941 | RSS: Main: 331059200 05:24:30 INFO - PROCESS | 1941 | 05:24:33 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50905/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:34 INFO - PROCESS | 1941 | RSS: Main: 331210752 05:24:34 INFO - PROCESS | 1941 | 05:24:37 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50905/tests/kraken/driver.html?testName=json-parse-financial) 05:24:37 INFO - PROCESS | 1941 | RSS: Main: 331845632 05:24:37 INFO - PROCESS | 1941 | 05:24:39 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50905/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:40 INFO - PROCESS | 1941 | RSS: Main: 311894016 05:24:40 INFO - PROCESS | 1941 | 05:24:41 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:42 INFO - PROCESS | 1941 | RSS: Main: 318468096 05:24:42 INFO - PROCESS | 1941 | 05:24:44 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:45 INFO - PROCESS | 1941 | RSS: Main: 316641280 05:24:45 INFO - PROCESS | 1941 | 05:24:48 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:48 INFO - PROCESS | 1941 | RSS: Main: 325541888 05:24:48 INFO - PROCESS | 1941 | 05:24:51 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:52 INFO - PROCESS | 1941 | RSS: Main: 327577600 05:24:52 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:50905/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50905/tests/kraken/driver.html?testName=ai-astar) 05:24:54 INFO - PROCESS | 1941 | RSS: Main: 371183616 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | __start_tp_report 05:24:54 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 05:24:54 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 05:24:54 INFO - PROCESS | 1941 | |i|pagename|runs| 05:24:54 INFO - PROCESS | 1941 | |0;ai-astar;102;112;115;112;113;112;112;113;113;112 05:24:54 INFO - PROCESS | 1941 | |1;audio-beat-detection;143;110;104;157;109;107;128;105;140;112 05:24:54 INFO - PROCESS | 1941 | |2;audio-dft;188;185;185;186;187;183;237;183;187;182 05:24:54 INFO - PROCESS | 1941 | |3;audio-fft;79;74;62;117;80;81;64;59;83;56 05:24:54 INFO - PROCESS | 1941 | |4;audio-oscillator;83;94;91;69;69;69;94;71;68;101 05:24:54 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;117;90;86;83;87;83;88;83;89;84 05:24:54 INFO - PROCESS | 1941 | |6;imaging-darkroom;92;91;95;93;93;93;94;94;92;92 05:24:54 INFO - PROCESS | 1941 | |7;imaging-desaturate;84;85;86;87;86;83;83;83;83;83 05:24:54 INFO - PROCESS | 1941 | |8;json-parse-financial;56;56;56;53;53;54;55;53;53;55 05:24:54 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;67;73;70;77;72;71;70;71;68;62 05:24:54 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;81;86;83;92;100;89;79;85;83;85 05:24:54 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;137;135;136;134;132;132;133;135;233;138 05:24:54 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;167;166;168;170;167;166;165;166;166;165 05:24:54 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;68;69;68;69;67;68;67;64;71;83 05:24:54 INFO - PROCESS | 1941 | __end_tp_report 05:24:54 INFO - PROCESS | 1941 | __start_cc_report 05:24:54 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1996 05:24:54 INFO - PROCESS | 1941 | __end_cc_report 05:24:54 INFO - PROCESS | 1941 | __startTimestamp1467289494315__endTimestamp 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | ------- Summary: start ------- 05:24:54 INFO - PROCESS | 1941 | Number of tests: 14 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.50 (3.1%) stddev-sans-first:1.00 05:24:54 INFO - PROCESS | 1941 | Values: 102.0 112.0 115.0 112.0 113.0 112.0 112.0 113.0 113.0 112.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:120.00 stddev:19.07 (15.9%) stddev-sans-first:18.58 05:24:54 INFO - PROCESS | 1941 | Values: 143.0 110.0 104.0 157.0 109.0 107.0 128.0 105.0 140.0 112.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:190.30 Median:186.50 stddev:16.53 (8.9%) stddev-sans-first:17.51 05:24:54 INFO - PROCESS | 1941 | Values: 188.0 185.0 185.0 186.0 187.0 183.0 237.0 183.0 187.0 182.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:75.50 Median:76.50 stddev:17.67 (23.1%) stddev-sans-first:18.70 05:24:54 INFO - PROCESS | 1941 | Values: 79.0 74.0 62.0 117.0 80.0 81.0 64.0 59.0 83.0 56.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:77.00 stddev:13.09 (17.0%) stddev-sans-first:13.87 05:24:54 INFO - PROCESS | 1941 | Values: 83.0 94.0 91.0 69.0 69.0 69.0 94.0 71.0 68.0 101.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:86.50 stddev:10.18 (11.8%) stddev-sans-first:2.76 05:24:54 INFO - PROCESS | 1941 | Values: 117.0 90.0 86.0 83.0 87.0 83.0 88.0 83.0 89.0 84.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:1.20 (1.3%) stddev-sans-first:1.22 05:24:54 INFO - PROCESS | 1941 | Values: 92.0 91.0 95.0 93.0 93.0 93.0 94.0 94.0 92.0 92.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:1.57 (1.9%) stddev-sans-first:1.66 05:24:54 INFO - PROCESS | 1941 | Values: 84.0 85.0 86.0 87.0 86.0 83.0 83.0 83.0 83.0 83.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.30 05:24:54 INFO - PROCESS | 1941 | Values: 56.0 56.0 56.0 53.0 53.0 54.0 55.0 53.0 53.0 55.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.96 (5.6%) stddev-sans-first:4.03 05:24:54 INFO - PROCESS | 1941 | Values: 67.0 73.0 70.0 77.0 72.0 71.0 70.0 71.0 68.0 62.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.00 stddev:6.09 (7.2%) stddev-sans-first:6.15 05:24:54 INFO - PROCESS | 1941 | Values: 81.0 86.0 83.0 92.0 100.0 89.0 79.0 85.0 83.0 85.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:135.50 stddev:31.16 (23.0%) stddev-sans-first:32.93 05:24:54 INFO - PROCESS | 1941 | Values: 137.0 135.0 136.0 134.0 132.0 132.0 133.0 135.0 233.0 138.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:166.50 stddev:1.51 (0.9%) stddev-sans-first:1.59 05:24:54 INFO - PROCESS | 1941 | Values: 167.0 166.0 168.0 170.0 167.0 166.0 165.0 166.0 166.0 165.0 05:24:54 INFO - PROCESS | 1941 | 05:24:54 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:68.50 stddev:5.10 (7.5%) stddev-sans-first:5.39 05:24:54 INFO - PROCESS | 1941 | Values: 68.0 69.0 68.0 69.0 67.0 68.0 67.0 64.0 71.0 83.0 05:24:54 INFO - PROCESS | 1941 | -------- Summary: end -------- 05:24:54 INFO - PROCESS | 1941 | 05:24:55 INFO - TEST-INFO | 1941: exit 0 05:24:55 INFO - TEST-OK | kraken | took 58903ms 05:24:55 INFO - SUITE-END | took 1174s 05:24:55 INFO - Completed test suite (00:19:34) 05:24:55 INFO - javascript benchmark 05:24:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.400347937371, 3336.965540682387, 3347.096284003319, 3362.983185084074, 3407.846646900889, 5570.832875013756, 5655.604107712456, 5662.348829884564, 5799.2750906136735, 5799.449052340032, 5720.0, 5747.827565173052, 5806.477417032466, 5948.226730525036, 5976.581639285252, 5187.818426355059, 5226.895462090756, 5229.241759944805, 5275.657082289648, 5295.4969277918535, 5203.5056669616515, 5314.707691076989, 5393.460653934614, 5399.676019438846, 5428.375736790261, 5316.601254905898, 5320.973395133038, 5332.653377530468, 5333.786648534093, 5346.1713434417725, 5147.613928955343, 5167.457416971239, 5212.374515058217, 5216.113260745682, 5273.947260527384, 5160.767765450526, 5178.378594568676, 5199.012187684366, 5256.658317209369, 5275.630705850592, 5816.592838501307, 5839.182514447981, 5849.678267695318, 5895.764169433218, 5920.289565252154, 6151.200343955302, 6177.876442471171, 6227.688615569204, 6243.2508099027955, 6279.215098112736, 6196.287426945869, 6212.627242365473, 6268.341824108494, 6282.654454005028, 6310.936890631081, 5727.971360143172, 5968.641881487125, 5987.670678112702, 6000.159977603139, 6009.879802403927, 6213.161223234805, 6255.624662520263, 6272.3100458949475, 6279.623222606659, 6284.402981716729, 5483.972580137153, 6132.478739307119, 6214.223222097238, 6215.254169499599, 6242.875142497124, 6187.938120618826, 6190.659513726789, 6256.812295631138, 6285.088662143963, 6315.905261421082, 6080.659382027436, 6213.378662133841, 6270.811875643738, 6285.120083188357, 6285.277193122758, 6142.6522545212965, 6158.445739883342, 6217.036359364306, 6222.066689996536, 6224.502039836802, 5010.89978200434, 5033.471485494044, 5039.4708555601865, 5041.243813427942, 5066.873328166826, 4454.933176002362, 4470.150671372428, 4492.573205545468, 4526.479454862667, 4566.292224705109, 5638.548916086703, 5675.914861277084, 5714.657120572779, 5736.6558006519745, 5807.5063619591965, 6255.6872156392, 6266.091416744546, 6266.248050234001, 6279.686015699197, 6283.497320214372, 5740.196372507852, 5773.6824474654295, 5788.565857560699, 5795.159701843293, 5839.649621022752, 5350.839474815762, 5376.623636345418, 5410.404855465896, 5482.068048431776, 5490.972545137249, 6256.749730010749, 6303.810885673437, 6318.936810632019, 6324.177856878577, 6325.146105275729, 6203.96898015507, 6227.657478838707, 6258.968705156627, 6259.624422534663, 6265.4361107500545, 6162.753489860355, 6232.875342493216, 6234.532410069353, 6273.9372606273355, 6303.401176888188, 6229.53278504123, 6231.626102433868, 6238.625682459067, 6245.718942647501, 6251.062340648939, 6241.063840423973, 6252.343503932022, 6257.09272155544, 6263.592866463665, 6266.968665156645, 6021.789237376671, 6091.695415229132, 6093.878122437615, 6098.664573448503, 6127.050307202391, 6105.358937311696, 6137.631742095488, 6179.474744646575, 6185.0, 6218.098375735583, 6046.30467496226, 6059.424354686298, 6077.999333509166, 6084.335848962579, 6128.111423843428, 5942.286925568959, 6099.582179613229, 6131.233595800525, 6165.72254248551, 6191.19514463117, 6085.878282434238, 6100.603460775035, 6102.145699601971, 6117.877642447215, 6142.140100386039], "value": 5749.159991173049, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.6408911271989, 501.5435953282513, 514.1285521041833, 519.9818006369778, 536.019085074314, 27456.901723931045, 29848.850755746218, 39086.413703794395, 40013.0, 40062.0, 41776.79111604446, 42079.15841683165, 42094.7895260524, 42168.789156054256, 42169.57830421695, 9505.429674219535, 9699.806003879918, 9707.126358627722, 9711.271654625896, 9712.70861874145, 8499.74500764978, 8723.30213582915, 11433.942830285838, 11673.066154707603, 11695.298282103058, 11592.59425920093, 11681.59114430995, 11682.357470339128, 11701.765964680744, 11705.3562054087, 8683.696070637561, 11490.253133546294, 11557.19099663024, 11617.025105732046, 11628.709282267926, 11452.0, 11764.117691173196, 11798.46906889192, 11802.232854864493, 11805.40972951349, 39274.60725392738, 39763.21065552057, 39763.60236397628, 39772.60227397747, 39855.4021689672, 3362.260302733395, 3376.5441665375106, 3498.3702933471964, 3588.7129029677826, 3673.7061035117395, 30038.0, 30297.545536816964, 30970.845145774354, 31357.52963705546, 31957.041288761142, 3240.1575590346442, 3244.7566432517656, 3261.184703824044, 3272.885449009297, 3400.506926495669, 509049.0, 510885.0, 515303.0, 516852.0, 517566.0, 30029.099127026224, 56392.0, 56724.0, 56849.0, 56910.430895690515, 30759.8462007693, 31411.37177256488, 31574.737010519322, 31625.525617115763, 31789.52315715266, 14135.15189088658, 14340.498082567061, 14374.712505749827, 14400.135991840523, 14411.495597654031, 10997.230193886351, 11270.605528806616, 11275.210735248618, 12430.81353779676, 12536.310502922426, 6557.737690492327, 6619.437347825488, 6743.460523158135, 6843.7604683835825, 7072.717091316393, 2924.2835505300914, 2930.9853450733035, 2957.1276473440385, 2963.73326400625, 2976.940461190764, 951.9381240219363, 955.47926380123, 1032.339302846179, 1085.978280434387, 1087.5168530414771, 29932.251693707833, 30107.397852042835, 30469.56139650074, 30499.847500762797, 30682.84658576693, 1033.214756784849, 1041.3595638682157, 1065.6909496246, 1134.744682446443, 1135.7898788723946, 15641.0, 15643.687126257639, 15749.448769293249, 15884.364625415084, 16062.598435039217, 194124.0, 196402.0, 197785.0, 198554.0, 200476.0, 57330.0, 57761.422385776445, 57900.0, 58013.70993145092, 58085.12872306919, 1254.2913254011455, 1280.3790161771533, 1369.301656155348, 1401.9439222430988, 1418.7942748301646, 29423.85288073546, 30445.0, 30861.0, 31202.531962020134, 31227.53158702576, 42403.7879810599, 42695.292188312116, 42898.0, 42986.78506607572, 43063.784681076395, 560571.0, 561226.0, 565008.3499164956, 566842.0, 569020.0, 21226.7877321231, 21280.042398091813, 23816.523669526858, 24327.148549800673, 24440.022399103837, 46342.53657463382, 46612.067758643956, 47146.292805607256, 48353.0, 48384.516154838, 38779.6122038776, 39122.41316380313, 39397.80301098476, 40114.78794726512, 40480.59519404768, 36371.81814090913, 38816.6118338813, 38957.6104238954, 39197.804010979766, 39736.40395394129, 37989.81005095068, 38442.231155376176, 38707.41938870863, 38740.80629596834, 38828.2234355317, 32574.51138232881, 33484.665153348156, 33854.890473000945, 34128.829355853064, 34224.82887585546, 19818.50453738668, 21079.367618971457, 25200.36197647147, 25292.747072529775, 25826.35434114162], "value": 17941.784234236653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.63083815736223, 364.78295414228535, 365.15102386950343, 379.8917308567059, 391.17852509729573, 15606.219689015561, 16903.408380706664, 20374.592508149828, 21048.89475552622, 21134.15463381467, 20658.967051647396, 20677.862717550568, 21034.894825525887, 21099.050542725614, 21635.567288654216, 5347.839564813062, 5932.347441781411, 6146.38536146384, 6208.906866397007, 6210.4100110489435, 6065.909011364833, 6130.877382452349, 6178.227721534809, 6181.196444462214, 6183.072539119124, 5850.6782126983, 6071.271447426293, 6093.542984276175, 6119.143319935212, 6140.723667434954, 5822.505087067604, 5983.850403739898, 6060.212172417558, 6074.17998570194, 6094.146819445281, 5194.948050519484, 5216.9739151304, 5234.110201265794, 5234.691327168208, 5240.685558866442, 23259.7674023261, 23938.64092038621, 26933.46133077347, 27270.5909411359, 27289.727102729117, 2188.7482939462006, 2412.3848418653306, 2505.4112283613517, 2954.5715871198554, 3210.2616398228306, 15619.453319133776, 16134.515964521082, 16280.51158465236, 16716.74924876128, 16880.662386752196, 1813.3562585282298, 1822.0889555222388, 1833.7799464064399, 1845.8707890447674, 1915.7796853361901, 124394.37802811018, 125138.0, 125731.3713431427, 126512.99999999908, 127444.45111097819, 10415.010573995458, 10597.9470102649, 10870.619528316629, 10917.727056823484, 10989.725256868482, 13355.198688078746, 13373.799393009112, 13411.597652070453, 13419.194848309133, 13491.190528568317, 8429.747107586782, 8583.313334933191, 8659.830922825464, 8695.913040869636, 8729.519876406732, 8726.389152759246, 8742.475451472932, 8750.69372571957, 8873.955630221808, 8876.245519130945, 5539.861503462365, 5591.552675785927, 6537.640429776408, 6717.529772915849, 6738.494612904052, 1571.5128310223868, 1776.7512548243255, 1784.732290156461, 1817.9272829086688, 1828.8536917046604, 922.8754118193958, 934.7102398256562, 1030.7474668706216, 1063.9148868090533, 1112.1325366214367, 22830.200942967247, 23369.41576460602, 23385.0, 23389.298321050395, 23445.88277058604, 952.3238500664605, 985.4776968206862, 1008.1984822066474, 1026.3021145621049, 1088.0697004061572, 11781.764364712657, 12109.757804843854, 12262.938685306695, 12285.508579656893, 12313.753724925631, 568561.0, 577060.0, 578700.1064994648, 594385.0, 602547.0, 132307.01539476722, 141767.0, 143855.0, 144656.0, 145029.0, 954.6754103604808, 984.8473486609587, 1000.429755039635, 1019.23557332001, 1021.1881554164457, 14734.062093547785, 19522.828630282227, 19982.70025949583, 20073.610563165825, 20139.892305923317, 54110.45889541211, 55553.0, 58671.119933200185, 60064.0, 61165.47086322877, 81266.0, 84145.0, 84751.57624212086, 85233.0, 85924.57037714771, 5826.650400975955, 6240.469560087444, 7883.921160788319, 8040.356771458152, 8235.752927412188, 10203.18374530021, 13221.735565288833, 13231.735365292832, 13417.932910335387, 13426.127301725357, 10934.289271197347, 11194.440277985905, 11479.827802583135, 11501.884981150082, 11535.711607209887, 11499.0, 11507.769844602893, 11661.76676466449, 11741.119416044, 11799.99700025473, 11693.239939403911, 11818.172727909308, 12074.154809163274, 12078.610959739392, 12128.393580321124, 6821.727130914708, 7168.390686791681, 8155.184481551797, 8162.10216876155, 8457.534835584102, 9053.0, 11118.49966751482, 11404.315741055563, 11739.530418783495, 11779.469923853618], "value": 9963.399140469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.609946165343, 1506.1264466609368, 1552.0222259976217, 1741.33829144925, 1899.8575106866979, 7725.575093369863, 8264.62809173587, 8509.0, 8511.319094472443, 8520.786980325494, 6647.800565983016, 8454.154584541542, 8479.872801907977, 8566.143385661431, 8600.22597966183, 1703.5826222575486, 2142.4750936020696, 2143.2070134050346, 2291.255342013844, 2352.200251914349, 2157.2341818654386, 2166.5450255446376, 2306.5041016181517, 2349.800266977309, 2353.670486131943, 2339.2982105368324, 2342.227065068523, 2351.870647114408, 2356.234223877238, 2358.78770910618, 2302.7697230277004, 2354.6232602783643, 2359.0210062823908, 2359.9410014749683, 2361.161787565407, 2100.2124203423714, 2321.1991862807304, 2369.301056188411, 2376.916807911715, 2385.0221409222136, 8562.357823163225, 8635.481871087755, 8638.697645582375, 8641.740747777576, 8654.0, 5180.326557547494, 5344.7862085516535, 5345.4387289334445, 5353.0, 5357.0, 3512.7189824813954, 3588.0132963435003, 3650.233450975298, 3671.81640917953, 3689.24370504046, 887.2414085956478, 1063.8936106389376, 1089.8964598363143, 1091.557919042781, 1092.3336764573603, 11381.943090284578, 11531.250468719507, 11584.47869845859, 11613.709657258552, 11647.417629118594, 4529.275315949432, 4714.575688188011, 4786.856394308176, 4789.161896668069, 4835.58897493717, 9991.250656200815, 10024.248181386425, 10024.849627255597, 10045.296829221885, 10053.597856085675, 5344.118220493653, 5352.4915133062295, 5354.973225133927, 5356.517913387814, 5363.517283444508, 4435.35687325336, 4744.739039352799, 4825.227963525889, 4882.7314497702255, 4890.608751299887, 3611.6749492545796, 3671.0822294426393, 3672.6878215351994, 3732.42147467143, 3754.5682246541996, 1101.432762127505, 1116.6929094499078, 1117.07841031148, 1121.3328069798456, 1165.6036947437742, 817.7832874288199, 907.2923119966437, 909.9454032758056, 932.5849996751381, 958.1855422890627, 5822.155787410887, 5844.0, 6045.093236014633, 6076.635401875901, 6118.1128736332985, 958.8034452937131, 963.9759006024906, 965.3966271080575, 982.597135174575, 1002.9297949143636, 3912.628300311466, 3935.7835319057726, 4045.3931910213114, 4062.025113972625, 4137.338025915899, 10317.9484102579, 10473.895261047444, 10519.631812886666, 10627.415492147851, 10632.94683526593, 10554.155667546578, 10567.15462762977, 10581.947090264499, 10596.364218146782, 10616.522256498476, 7607.201243869425, 8047.396445266629, 8049.39629527776, 8064.8387032259025, 8189.222023907839, 5920.911186332122, 6008.609440386361, 6060.787872424444, 6061.605995610271, 6066.908996364969, 9782.706518804447, 10010.899891001289, 10030.598776048877, 10035.799284014132, 10053.547590358598, 9770.36492627977, 9865.408075515492, 10271.202539555698, 10278.794424111325, 10295.0, 7505.924940750522, 7516.361109305771, 7610.972518709903, 7616.0099187105325, 7619.1999840016, 7487.588182650006, 7722.575258360844, 8155.632996515053, 8173.67305307781, 8212.97337832771, 6814.591124532544, 7252.782416527512, 7310.342069213796, 7477.813054673459, 7508.361789247975, 7126.857462850818, 7187.748428804966, 7255.2019277878335, 7409.740659076906, 7428.888566671396, 6589.374009469093, 6726.428253598303, 6730.4952128589575, 6731.394174524316, 6742.157230346207, 4821.228603423436, 4995.350604421402, 5022.096022715946, 5088.720120393414, 5123.487651234846, 5002.274670172732, 5024.221245706921, 5034.471380504994, 5040.243963405518, 5085.92371114441], "value": 4530.388185646679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.26970954356864, 288.3641570337406, 298.7430809503827, 304.20753935996726, 304.5173400160745, 5317.521423071932, 5329.413764485904, 5399.514043736063, 5400.675959442437, 5485.341758988927, 4988.551030407262, 5145.3311069560905, 5145.948540514593, 5328.6802791832515, 5360.65155764875, 3445.931081378371, 4052.8176232069627, 4066.085130845566, 4069.2065047315787, 4101.323281658522, 3201.695838895301, 3403.7447191460615, 4070.4504891839665, 4082.8775136745944, 4112.157007813391, 3809.4285857121513, 3859.0, 3980.0, 4052.0882801369753, 4094.324436467994, 3316.966830331702, 3529.7645823961407, 3790.507234059582, 3998.7800670963084, 4080.3471444568877, 3095.876164953399, 3478.3825521132794, 3499.825008749552, 3517.103138699615, 3536.3104194682046, 4933.654644174909, 4975.4527002029745, 5191.9480805192225, 5203.531682148625, 5264.631475796696, 4018.357062869956, 4579.3131030345385, 4662.645364091146, 4670.532946705336, 4674.182018146845, 5663.660180389149, 5691.0, 5731.398203188689, 5769.221155144086, 5779.508741756914, 3732.234891847165, 3775.565809931838, 3878.0111071676542, 3902.453656488094, 3917.9608203917883, 5337.969832776229, 5603.7478313475995, 5807.419258074159, 5835.9708201458725, 5844.912326315151, 3300.5214243934497, 3621.5654121505586, 3884.5726970033274, 3908.003459117907, 3925.666318362914, 3329.716974057232, 3747.2880152770886, 3798.5061941947924, 3799.316123097869, 3965.504311961005, 4449.3326001100095, 4581.518940511265, 4613.607843333354, 4622.121796858653, 4637.559431853969, 5595.972020139955, 5618.719064046782, 5631.380548139701, 5652.378238393773, 5658.7170641467765, 1143.9771204575861, 1284.1652925598248, 1332.4137379552965, 1338.1903948111253, 1347.7843545032945, 724.3697982755037, 864.2092485375937, 914.1909410172045, 919.9898222384112, 924.6209054287709, 4096.672266218696, 4676.532346765296, 5219.817306394258, 5222.738863056832, 5286.7092309922555, 5518.365387980433, 5698.230738850202, 5735.512481439125, 5789.8552536186935, 5793.2179155814265, 4837.516248375205, 5211.0, 5249.580033597303, 5256.448072952285, 5279.788808447619, 2931.311141881656, 2997.145813443158, 3011.6687164411896, 3026.6821983691834, 3037.719263195743, 4805.471398146201, 5042.8753515084545, 5592.748326325325, 5854.8536286592325, 5894.970525147347, 5480.862978425571, 5604.570834437192, 5747.252857128547, 5771.538276937835, 5830.854228644318, 4619.930701039487, 5828.621139625996, 5857.4435428634215, 5889.0, 5951.285845698544, 4844.660873738804, 5436.972815136057, 5471.261379713687, 5862.472377485876, 5971.373005834324, 5018.5232402921665, 5685.60200785941, 5776.880484538302, 5808.5062769665055, 5860.882782344414, 5437.564994800327, 5807.954568177768, 6118.235220597426, 6129.567442232365, 6219.533534984804, 3179.729722973573, 3826.2730081284467, 3851.6340947609933, 3872.6708229800784, 3904.7852368120025, 5364.356277246599, 5885.705714714161, 5909.734061967308, 5950.935268336723, 5957.285125784933, 4825.565699087099, 5196.870078247923, 5381.784728610812, 5747.19539264495, 5799.391063938226, 5644.971775141098, 5688.061469857427, 5703.344115426779, 5711.914321285267, 5718.914216286676, 5219.269302297757, 5432.239486471818, 5440.863978400572, 5476.835694929158, 5543.584231182758, 4769.163871909271, 5086.720230387365, 5446.074167391474, 5662.462066103713, 5754.827355179351, 5164.767585458589, 5205.479452054764, 5373.623846330719, 5386.784528618811, 5588.944110558842], "value": 4089.6631209041366, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.387183150659, 757.6855604923957, 779.3453499060785, 806.6931570855215, 833.9332853371731, 14911.32899019544, 15725.37098516058, 15759.684806303881, 15773.684526309497, 15778.447754328587, 14697.559073227794, 14869.628259293522, 14893.180875051868, 14893.627659308495, 14913.32890019949, 676.7394553097068, 699.6781480518941, 828.6064119543199, 833.9457935234205, 854.8504011797942, 847.0470720439506, 850.2093053460279, 852.5992783391796, 853.4239388412827, 853.4409961475244, 874.9825003499926, 891.2468963725642, 891.6611687558718, 891.8795962545038, 892.4645212872257, 709.971601135954, 881.5592203898051, 886.2732559301376, 887.0863011098579, 909.077286554148, 664.5912763650321, 666.4335314982275, 666.9233038200621, 666.9833254168636, 670.2995369838482, 15225.923870380688, 15273.312700928485, 15284.847151528453, 15285.388584456608, 15292.923535382362, 14038.36827342772, 14174.50389236382, 14197.290135493184, 14229.928850355682, 14446.205458699873, 12684.112112152154, 12732.299723515203, 12836.358182090858, 12877.806832897513, 13015.284159371231, 4503.774811259456, 4511.1654343946475, 4529.433820772403, 4680.789364478606, 4747.074320507502, 112306.99999999919, 113359.0, 113404.86595134108, 113445.0, 113458.43270783676, 10966.451677416097, 11206.49570769301, 11269.71825704364, 11587.88412115868, 11759.941200293944, 18188.63622727538, 18411.0, 21810.127594896025, 22060.88969555142, 22061.889690551445, 6180.351063138396, 7832.0, 7853.921460785319, 8024.438289319808, 8171.305439037629, 4472.888177795581, 4477.485089214782, 4479.350494178326, 4506.188886000551, 4616.122936642095, 3001.2196828824226, 3405.829708514614, 3544.7518673692593, 3549.4143466328283, 3551.4495253235273, 613.08344025681, 640.7533099756628, 644.1400730025468, 652.960822350651, 658.1312667279241, 539.4309004000817, 661.408039804382, 679.8844196486609, 693.2201273567237, 702.3398005874461, 9131.360804743605, 9622.711318660311, 9628.951855240679, 9677.563349055941, 9701.320907536547, 546.7266366816592, 625.2746813696182, 626.1797045869833, 689.8447849233883, 700.3206889317354, 4062.2687916175373, 4499.257622492318, 4702.365180700562, 4733.83431579893, 4758.0, 136710.0, 152481.0, 152939.0, 153672.0, 158864.0, 87015.0, 123665.0, 125045.0, 125480.0, 126848.0, 119096.21355679672, 119347.0, 119648.41054768371, 119773.40113299378, 120402.99999999825, 9144.771380715536, 9183.31125165602, 9191.310651701282, 9212.263018958332, 9480.288978326515, 57509.712451437474, 63141.0, 64766.38084047937, 65229.0, 67186.32034199184, 90901.54549227476, 92304.0, 92341.5382923081, 92725.60911586197, 94235.52882235819, 10478.214133939833, 11942.522299107939, 11985.460654270753, 12408.131430800118, 12455.314957677414, 11057.612983545538, 13455.59633210966, 13701.794473083111, 13822.377992990541, 14088.872890168557, 10942.562297508011, 12476.688082798002, 12605.432755526206, 12753.48986040548, 13179.67050823737, 12047.096467765128, 12361.381930903599, 12766.106372554204, 12796.808047879103, 12916.677083072998, 11683.948444639684, 12201.464891823713, 12353.691157721129, 12590.307533085768, 12751.234925904475, 9509.857352139861, 10471.476426178813, 10498.370097794157, 10924.726881828017, 10939.343639381663, 10042.548085336031, 10265.84601230997, 10385.065344119066, 10669.039786418949, 10727.839082413613], "value": 7756.070651981588, "unit": "score"}], "name": "dromaeo_css", "value": 7270.498768321196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 115.0, 112.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 104.0, 157.0, 109.0, 107.0, 128.0, 105.0, 140.0, 112.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 185.0, 185.0, 186.0, 187.0, 183.0, 237.0, 183.0, 187.0, 182.0], "value": 190.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 62.0, 117.0, 80.0, 81.0, 64.0, 59.0, 83.0, 56.0], "value": 75.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 94.0, 91.0, 69.0, 69.0, 69.0, 94.0, 71.0, 68.0, 101.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 90.0, 86.0, 83.0, 87.0, 83.0, 88.0, 83.0, 89.0, 84.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 95.0, 93.0, 93.0, 93.0, 94.0, 94.0, 92.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 87.0, 86.0, 83.0, 83.0, 83.0, 83.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 53.0, 53.0, 54.0, 55.0, 53.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 70.0, 77.0, 72.0, 71.0, 70.0, 71.0, 68.0, 62.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 83.0, 92.0, 100.0, 89.0, 79.0, 85.0, 83.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 136.0, 134.0, 132.0, 132.0, 133.0, 135.0, 233.0, 138.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 168.0, 170.0, 167.0, 166.0, 165.0, 166.0, 166.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 69.0, 67.0, 68.0, 67.0, 64.0, 71.0, 83.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1437.3, "alertThreshold": 2.0}]} 05:24:55 INFO - Return code: 0 05:24:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:55 INFO - # TBPL SUCCESS # 05:24:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:55 INFO - 2016-06-30 05:24:55.615 screenresolution[1945:16297] starting screenresolution argv=screenresolution get 05:24:55 INFO - 2016-06-30 05:24:55.618 screenresolution[1945:16297] Display 0: 1600x1200x32@60 05:24:55 INFO - 2016-06-30 05:24:55.624 screenresolution[1946:16300] starting screenresolution argv=screenresolution list 05:24:55 INFO - Available Modes on Display 0 05:24:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:55 INFO - Intel Iris: 05:24:55 INFO - Chipset Model: Intel Iris 05:24:55 INFO - Type: GPU 05:24:55 INFO - Bus: Built-In 05:24:55 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:55 INFO - Vendor: Intel (0x8086) 05:24:55 INFO - Device ID: 0x0a2e 05:24:55 INFO - Revision ID: 0x0009 05:24:55 INFO - Displays: 05:24:55 INFO - Display: 05:24:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:55 INFO - Display Serial Number: 1600x1200 60 05:24:55 INFO - Main Display: Yes 05:24:55 INFO - Mirror: Off 05:24:55 INFO - Online: Yes 05:24:55 INFO - Rotation: Supported 05:24:55 INFO - Return code: 0 05:24:55 INFO - Running post-action listener: _resource_record_post_action 05:24:55 INFO - ##### 05:24:55 INFO - ##### Finished run-tests step (success) 05:24:55 INFO - ##### 05:24:55 INFO - Running post-run listener: _resource_record_post_run 05:24:55 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 462848; Write bytes: 705789440; Read time: 5; Write time: 2120 05:24:55 INFO - TinderboxPrint: CPU usage
53.2% 05:24:55 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 5 05:24:55 INFO - TinderboxPrint: I/O write bytes / time
705,789,440 / 2,120 05:24:55 INFO - TinderboxPrint: CPU idle
2,265.1 (46.8%) 05:24:55 INFO - TinderboxPrint: CPU system
960.4 (19.8%) 05:24:55 INFO - TinderboxPrint: CPU user
1,616.1 (33.4%) 05:24:55 INFO - TinderboxPrint: Swap in / out
406,855,680 / 0 05:24:55 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389868032; Write bytes: 386521600; Read time: 29327; Write time: 743 05:24:55 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 462848; Write bytes: 295031808; Read time: 5; Write time: 1262 05:24:56 INFO - Running post-run listener: _upload_blobber_files 05:24:56 INFO - Blob upload gear active. 05:24:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:56 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 05:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:24:56 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:24:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:57 INFO - (blobuploader) - INFO - Done attempting. 05:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:24:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:24:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:58 INFO - (blobuploader) - INFO - Done attempting. 05:24:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:01 INFO - (blobuploader) - INFO - Done attempting. 05:25:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:01 INFO - Return code: 0 05:25:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b89dc79ca02f7e525cfeee96fe93e1959b91e1293159a12a3f2ab4b71cf85618eb13d8c96a0b139b209cdf99786c6485bdda6f15e118f85066245e75919a87ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29780a7bd9fc4e3e248dbcee99d94b9bebee355a96167700b5d6030fbfbeb08232a72c6599a2a1aa83fde12586dcf376fbae37f4a9ee2aef1d1846df47e99b1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0cd781c3893228d7d8556fc9692b97f17ecaedcecce847391566dbd831efccd933b3b909fd884d609af39201c33b4d90fd346db242f387b19f490d49ad2b47"} 05:25:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:01 INFO - Contents: 05:25:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b89dc79ca02f7e525cfeee96fe93e1959b91e1293159a12a3f2ab4b71cf85618eb13d8c96a0b139b209cdf99786c6485bdda6f15e118f85066245e75919a87ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29780a7bd9fc4e3e248dbcee99d94b9bebee355a96167700b5d6030fbfbeb08232a72c6599a2a1aa83fde12586dcf376fbae37f4a9ee2aef1d1846df47e99b1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0cd781c3893228d7d8556fc9692b97f17ecaedcecce847391566dbd831efccd933b3b909fd884d609af39201c33b4d90fd346db242f387b19f490d49ad2b47"} 05:25:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:01 INFO - Copying logs to upload dir... 05:25:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1352.935314 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2016-06-30 05:25:01.242978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 05:25:01.246257) ========= 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.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b89dc79ca02f7e525cfeee96fe93e1959b91e1293159a12a3f2ab4b71cf85618eb13d8c96a0b139b209cdf99786c6485bdda6f15e118f85066245e75919a87ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29780a7bd9fc4e3e248dbcee99d94b9bebee355a96167700b5d6030fbfbeb08232a72c6599a2a1aa83fde12586dcf376fbae37f4a9ee2aef1d1846df47e99b1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0cd781c3893228d7d8556fc9692b97f17ecaedcecce847391566dbd831efccd933b3b909fd884d609af39201c33b4d90fd346db242f387b19f490d49ad2b47"} build_url:https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011278 build_url: 'https://queue.taskcluster.net/v1/task/YEaQ9hGzQLSY1Bz8cVI9LA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b89dc79ca02f7e525cfeee96fe93e1959b91e1293159a12a3f2ab4b71cf85618eb13d8c96a0b139b209cdf99786c6485bdda6f15e118f85066245e75919a87ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29780a7bd9fc4e3e248dbcee99d94b9bebee355a96167700b5d6030fbfbeb08232a72c6599a2a1aa83fde12586dcf376fbae37f4a9ee2aef1d1846df47e99b1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a0cd781c3893228d7d8556fc9692b97f17ecaedcecce847391566dbd831efccd933b3b909fd884d609af39201c33b4d90fd346db242f387b19f490d49ad2b47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 05:25:01.279866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:25:01.280367) ========= 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.hdch3lxH1K/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.iBnrIvOP50/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005040 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:25:01.331725) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 05:25:01.332144) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 05:25:01.332607) ========= ========= Total master_lag: 0.18 =========