builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0113 starttime: 1480097858.36 results: success (0) buildid: 20161125075519 builduid: 5c71f37d3e2c4028afbef4c8aff83ecd revision: a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.356183) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.356650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.356947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.383436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.383799) ========= 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.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-11-25 10:17:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.44M=0.001s 2016-11-25 10:17:38 (9.44 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079088 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.486922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.487247) ========= 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.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.026043 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:38.535435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 10:17:38.535801) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-11-25 10:17:38,630 truncating revision to first 12 chars 2016-11-25 10:17:38,630 Setting DEBUG logging. 2016-11-25 10:17:38,631 attempt 1/10 2016-11-25 10:17:38,631 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a7c5e5d204bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 10:17:40,572 unpacking tar archive at: mozilla-aurora-a7c5e5d204bd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.207821 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 10:17:40.767328) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:40.767655) ========= script_repo_revision: a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:40.768052) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:40.768325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 10:17:40.788023) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 10:17:40.788386) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:17:40 INFO - MultiFileLogger online at 20161125 10:17:40 in /builds/slave/test 10:17:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:17:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:17:40 INFO - {'append_to_log': False, 10:17:40 INFO - 'base_work_dir': '/builds/slave/test', 10:17:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:17:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:17:40 INFO - 'branch': 'Mozilla-Aurora', 10:17:40 INFO - 'buildbot_json_path': 'buildprops.json', 10:17:40 INFO - 'config_files': ('talos/mac_config.py',), 10:17:40 INFO - 'default_actions': ('clobber', 10:17:40 INFO - 'read-buildbot-config', 10:17:40 INFO - 'download-and-extract', 10:17:40 INFO - 'populate-webroot', 10:17:40 INFO - 'create-virtualenv', 10:17:40 INFO - 'install', 10:17:40 INFO - 'run-tests'), 10:17:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:17:40 INFO - 'download_minidump_stackwalk': True, 10:17:40 INFO - 'download_symbols': 'ondemand', 10:17:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:17:40 INFO - 'tooltool.py': '/tools/tooltool.py', 10:17:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:17:40 INFO - '/tools/misc-python/virtualenv.py')}, 10:17:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:17:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:17:40 INFO - 'installer_path': 'installer.exe', 10:17:40 INFO - 'log_level': 'info', 10:17:40 INFO - 'log_name': 'talos', 10:17:40 INFO - 'log_to_console': True, 10:17:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:17:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:17:40 INFO - 'opt_config_files': (), 10:17:40 INFO - 'pip_index': False, 10:17:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:40 INFO - 'cmd': ('bash', 10:17:40 INFO - '-c', 10:17:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:40 INFO - 'enabled': True, 10:17:40 INFO - 'halt_on_failure': False, 10:17:40 INFO - 'name': 'check_screen_resolution'},), 10:17:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:40 INFO - 'cmd': ('bash', 10:17:40 INFO - '-c', 10:17:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:40 INFO - 'enabled': True, 10:17:40 INFO - 'halt_on_failure': False, 10:17:40 INFO - 'name': 'check_screen_resolution'},), 10:17:40 INFO - 'run_cmd_checks_enabled': True, 10:17:40 INFO - 'sps_profile': False, 10:17:40 INFO - 'sps_profile_interval': 0, 10:17:40 INFO - 'suite': 'dromaeojs', 10:17:40 INFO - 'system_bits': '32', 10:17:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:17:40 INFO - 'title': 't-yosemite-r7-0113', 10:17:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:17:40 INFO - 'use_talos_json': True, 10:17:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:17:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:17:40 INFO - 'work_dir': 'build'} 10:17:40 INFO - [mozharness: 2016-11-25 18:17:40.992406Z] Running clobber step. 10:17:40 INFO - Running pre-action listener: _resource_record_pre_action 10:17:40 INFO - Running main action method: clobber 10:17:40 INFO - rmtree: /builds/slave/test/build 10:17:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:17:43 INFO - Running post-action listener: _resource_record_post_action 10:17:43 INFO - [mozharness: 2016-11-25 18:17:43.409179Z] Finished clobber step (success) 10:17:43 INFO - [mozharness: 2016-11-25 18:17:43.409302Z] Running read-buildbot-config step. 10:17:43 INFO - Running pre-action listener: _resource_record_pre_action 10:17:43 INFO - Running main action method: read_buildbot_config 10:17:43 INFO - Using buildbot properties: 10:17:43 INFO - { 10:17:43 INFO - "project": "", 10:17:43 INFO - "product": "firefox", 10:17:43 INFO - "script_repo_revision": "production", 10:17:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:17:43 INFO - "repository": "", 10:17:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:17:43 INFO - "buildid": "20161125075519", 10:17:43 INFO - "pgo_build": "False", 10:17:43 INFO - "basedir": "/builds/slave/test", 10:17:43 INFO - "buildnumber": 35, 10:17:43 INFO - "slavename": "t-yosemite-r7-0113", 10:17:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:17:43 INFO - "platform": "yosemite_r7", 10:17:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:17:43 INFO - "branch": "mozilla-aurora", 10:17:43 INFO - "repo_path": "releases/mozilla-aurora", 10:17:43 INFO - "revision": "a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f", 10:17:43 INFO - "stage_platform": "macosx64", 10:17:43 INFO - "builduid": "5c71f37d3e2c4028afbef4c8aff83ecd", 10:17:43 INFO - "slavebuilddir": "test" 10:17:43 INFO - } 10:17:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:17:43 INFO - Running post-action listener: _resource_record_post_action 10:17:43 INFO - [mozharness: 2016-11-25 18:17:43.412564Z] Finished read-buildbot-config step (success) 10:17:43 INFO - [mozharness: 2016-11-25 18:17:43.412679Z] Running download-and-extract step. 10:17:43 INFO - Running pre-action listener: _resource_record_pre_action 10:17:43 INFO - Running main action method: download_and_extract 10:17:43 INFO - mkdir: /builds/slave/test/build/tests 10:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:43 INFO - trying https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:17:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:17:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 10:17:44 INFO - Downloaded 1199 bytes. 10:17:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:17:44 INFO - Using the following test package requirements: 10:17:44 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:17:44 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:17:44 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:17:44 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'jsshell-mac.zip'], 10:17:44 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:17:44 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:17:44 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:17:44 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:17:44 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:17:44 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:17:44 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:17:44 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:17:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:17:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:17:44 INFO - Fetch https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:17:45 INFO - Http code: 200 10:17:45 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 10:17:45 INFO - x-amz-cf-id: 5rVwbo276KcEp5k9yQNyb4qcBhTSTVMtLm3whFldD8uKAtIeinfppA== 10:17:45 INFO - x-amz-version-id: qqsasCX.80AZm4zfnRobSpdZtZWpoS4n 10:17:45 INFO - Expected file size: 16278997 10:17:45 INFO - Obtained file size: 16278997 10:17:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:17:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:17:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:17:52 INFO - Fetch https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:17:52 INFO - Http code: 200 10:17:52 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:17:52 INFO - x-amz-cf-id: 02OShqyf8UUIegCBOJU3ORVJeWMMpNfJvVtBvveBx6zH6rWQaLY5Cg== 10:17:52 INFO - x-amz-version-id: qqsasCX.80AZm4zfnRobSpdZtZWpoS4n 10:17:53 INFO - Expected file size: 16278997 10:17:53 INFO - Obtained file size: 16278997 10:17:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:17:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:17:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:18:00 INFO - Http code: 200 10:18:00 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 10:18:00 INFO - x-amz-cf-id: msqUOMeDfhvQb2r979z__gghx1u-arlGHZkIGn5FrAuvV9-4s4Z9Fg== 10:18:00 INFO - x-amz-version-id: TQi4ymJFQgfE6Pc.H2lOV11XqSNNTzyq 10:18:00 INFO - Expected file size: 14409038 10:18:00 INFO - Obtained file size: 14409038 10:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:01 INFO - trying https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:18:01 INFO - Downloading https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:18:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 10:18:03 INFO - Downloaded 109742497 bytes. 10:18:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:18:03 INFO - mkdir: /builds/slave/test/properties 10:18:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:18:03 INFO - Writing to file /builds/slave/test/properties/build_url 10:18:03 INFO - Contents: 10:18:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:18:04 INFO - Running post-action listener: _resource_record_post_action 10:18:04 INFO - Running post-action listener: set_extra_try_arguments 10:18:04 INFO - [mozharness: 2016-11-25 18:18:04.576792Z] Finished download-and-extract step (success) 10:18:04 INFO - [mozharness: 2016-11-25 18:18:04.576896Z] Running populate-webroot step. 10:18:04 INFO - Running pre-action listener: _resource_record_pre_action 10:18:04 INFO - Running main action method: populate_webroot 10:18:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:18:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:18:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:18:04 INFO - '--tppagecycles', 10:18:04 INFO - '7'], 10:18:04 INFO - 'tests': ['tsvgm']}}, 10:18:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:18:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:18:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:18:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:18:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['damp', 'tps']}, 10:18:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'tests': ['damp', 'tps']}, 10:18:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['dromaeo_dom']}, 10:18:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:18:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['basic_compositor_video']}, 10:18:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:18:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'other-e10s': {'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['a11yr', 10:18:04 INFO - 'ts_paint', 10:18:04 INFO - 'tpaint', 10:18:04 INFO - 'sessionrestore', 10:18:04 INFO - 'sessionrestore_no_auto_restore', 10:18:04 INFO - 'tabpaint']}, 10:18:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['tsvgx', 10:18:04 INFO - 'tsvgr_opacity', 10:18:04 INFO - 'tart', 10:18:04 INFO - 'tscrollx', 10:18:04 INFO - 'cart']}, 10:18:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:18:04 INFO - 'tsvgr_opacity', 10:18:04 INFO - 'tart', 10:18:04 INFO - 'tscrollx', 10:18:04 INFO - 'cart']}, 10:18:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'talos_options': ['--disable-e10s'], 10:18:04 INFO - 'tests': ['tp5o']}, 10:18:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'tests': ['tp5o']}, 10:18:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'talos_options': ['--disable-e10s', 10:18:04 INFO - '--xperf_path', 10:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:04 INFO - 'tests': ['tp5n']}, 10:18:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:18:04 INFO - 'talos_options': ['--xperf_path', 10:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:04 INFO - 'tests': ['tp5n']}}, 10:18:04 INFO - 'talos.zip': {'path': '', 10:18:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:04 INFO - Running post-action listener: _resource_record_post_action 10:18:04 INFO - [mozharness: 2016-11-25 18:18:04.595985Z] Finished populate-webroot step (success) 10:18:04 INFO - [mozharness: 2016-11-25 18:18:04.596121Z] Running create-virtualenv step. 10:18:04 INFO - Running pre-action listener: _resource_record_pre_action 10:18:04 INFO - Running main action method: create_virtualenv 10:18:04 INFO - Creating virtualenv /builds/slave/test/build/venv 10:18:04 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 10:18:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:18:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:18:04 INFO - Using real prefix '/tools/python27' 10:18:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:18:05 INFO - Installing distribute.............................................................................................................................................................................................done. 10:18:08 INFO - Installing pip.................done. 10:18:08 INFO - Return code: 0 10:18:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:18:08 INFO - Reading from file tmpfile_stdout 10:18:08 INFO - Output received: 10:18:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:18:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:08 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 10:18:08 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 10:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:08 INFO - 'HOME': '/Users/cltbld', 10:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:08 INFO - 'LOGNAME': 'cltbld', 10:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:08 INFO - 'MOZ_NO_REMOTE': '1', 10:18:08 INFO - 'NO_EM_RESTART': '1', 10:18:08 INFO - 'PAGER': '/bin/cat', 10:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:08 INFO - 'PWD': '/builds/slave/test', 10:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:08 INFO - 'SHELL': '/bin/bash', 10:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:08 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:08 INFO - 'USER': 'cltbld', 10:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:08 INFO - 'XPC_FLAGS': '0x0', 10:18:08 INFO - 'XPC_SERVICE_NAME': '0', 10:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:08 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 10:18:08 INFO - Cleaning up... 10:18:08 INFO - Return code: 0 10:18:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:08 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:08 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 10:18:08 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 10:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:08 INFO - 'HOME': '/Users/cltbld', 10:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:08 INFO - 'LOGNAME': 'cltbld', 10:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:08 INFO - 'MOZ_NO_REMOTE': '1', 10:18:08 INFO - 'NO_EM_RESTART': '1', 10:18:08 INFO - 'PAGER': '/bin/cat', 10:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:08 INFO - 'PWD': '/builds/slave/test', 10:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:08 INFO - 'SHELL': '/bin/bash', 10:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:08 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:08 INFO - 'USER': 'cltbld', 10:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:08 INFO - 'XPC_FLAGS': '0x0', 10:18:08 INFO - 'XPC_SERVICE_NAME': '0', 10:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:09 INFO - Downloading/unpacking psutil>=3.1.1 10:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:18:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:12 INFO - Installing collected packages: psutil 10:18:12 INFO - Running setup.py install for psutil 10:18:12 INFO - building 'psutil._psutil_osx' extension 10:18:12 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 10:18:13 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 10:18:13 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 10:18:13 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 10:18:13 INFO - building 'psutil._psutil_posix' extension 10:18:13 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 10:18:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:18:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:18:13 INFO - ^ 10:18:13 INFO - 1 warning generated. 10:18:13 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 10:18:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:13 INFO - Successfully installed psutil 10:18:13 INFO - Cleaning up... 10:18:13 INFO - Return code: 0 10:18:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:13 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:13 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 10:18:13 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 10:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:13 INFO - 'HOME': '/Users/cltbld', 10:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:13 INFO - 'LOGNAME': 'cltbld', 10:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:13 INFO - 'MOZ_NO_REMOTE': '1', 10:18:13 INFO - 'NO_EM_RESTART': '1', 10:18:13 INFO - 'PAGER': '/bin/cat', 10:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:13 INFO - 'PWD': '/builds/slave/test', 10:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:13 INFO - 'SHELL': '/bin/bash', 10:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:13 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:13 INFO - 'USER': 'cltbld', 10:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:13 INFO - 'XPC_FLAGS': '0x0', 10:18:13 INFO - 'XPC_SERVICE_NAME': '0', 10:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:14 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 10:18:14 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 10:18:14 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 10:18:14 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 10:18:14 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 10:18:14 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 10:18:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:18:16 INFO - Installing collected packages: mozsystemmonitor 10:18:16 INFO - Running setup.py install for mozsystemmonitor 10:18:16 INFO - Successfully installed mozsystemmonitor 10:18:16 INFO - Cleaning up... 10:18:16 INFO - Return code: 0 10:18:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:16 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:16 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 10:18:16 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 10:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:16 INFO - 'HOME': '/Users/cltbld', 10:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:16 INFO - 'LOGNAME': 'cltbld', 10:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:16 INFO - 'MOZ_NO_REMOTE': '1', 10:18:16 INFO - 'NO_EM_RESTART': '1', 10:18:16 INFO - 'PAGER': '/bin/cat', 10:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:16 INFO - 'PWD': '/builds/slave/test', 10:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:16 INFO - 'SHELL': '/bin/bash', 10:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:16 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:16 INFO - 'USER': 'cltbld', 10:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:16 INFO - 'XPC_FLAGS': '0x0', 10:18:16 INFO - 'XPC_SERVICE_NAME': '0', 10:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:17 INFO - Downloading/unpacking jsonschema==2.5.1 10:18:17 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 10:18:17 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 10:18:17 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 10:18:17 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 10:18:17 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 10:18:17 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 10:18:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:18:19 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 10:18:19 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 10:18:19 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 10:18:19 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 10:18:19 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 10:18:19 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 10:18:19 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:18:20 INFO - warning: no files found matching '*.txt' 10:18:20 INFO - no previously-included directories found matching 'build' 10:18:20 INFO - no previously-included directories found matching 'dist' 10:18:20 INFO - no previously-included directories found matching '.git*' 10:18:20 INFO - Installing collected packages: jsonschema, functools32 10:18:20 INFO - Running setup.py install for functools32 10:18:20 INFO - warning: no files found matching '*.txt' 10:18:20 INFO - no previously-included directories found matching 'build' 10:18:20 INFO - no previously-included directories found matching 'dist' 10:18:20 INFO - no previously-included directories found matching '.git*' 10:18:20 INFO - Successfully installed jsonschema functools32 10:18:20 INFO - Cleaning up... 10:18:20 INFO - Return code: 0 10:18:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:20 INFO - 'HOME': '/Users/cltbld', 10:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:20 INFO - 'LOGNAME': 'cltbld', 10:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:20 INFO - 'MOZ_NO_REMOTE': '1', 10:18:20 INFO - 'NO_EM_RESTART': '1', 10:18:20 INFO - 'PAGER': '/bin/cat', 10:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:20 INFO - 'PWD': '/builds/slave/test', 10:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:20 INFO - 'SHELL': '/bin/bash', 10:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:20 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:20 INFO - 'USER': 'cltbld', 10:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:20 INFO - 'XPC_FLAGS': '0x0', 10:18:20 INFO - 'XPC_SERVICE_NAME': '0', 10:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:18:20 INFO - Cleaning up... 10:18:20 INFO - Return code: 0 10:18:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:20 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:20 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 10:18:20 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 10:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:20 INFO - 'HOME': '/Users/cltbld', 10:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:20 INFO - 'LOGNAME': 'cltbld', 10:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:20 INFO - 'MOZ_NO_REMOTE': '1', 10:18:20 INFO - 'NO_EM_RESTART': '1', 10:18:20 INFO - 'PAGER': '/bin/cat', 10:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:20 INFO - 'PWD': '/builds/slave/test', 10:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:20 INFO - 'SHELL': '/bin/bash', 10:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:20 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:20 INFO - 'USER': 'cltbld', 10:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:20 INFO - 'XPC_FLAGS': '0x0', 10:18:20 INFO - 'XPC_SERVICE_NAME': '0', 10:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:20 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:20 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 10:18:20 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 10:18:20 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 10:18:20 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 10:18:20 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 10:18:20 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 10:18:23 INFO - Downloading blobuploader-1.2.4.tar.gz 10:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:18:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:18:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:24 INFO - Downloading docopt-0.6.1.tar.gz 10:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:18:24 INFO - Installing collected packages: blobuploader, requests, docopt 10:18:24 INFO - Running setup.py install for blobuploader 10:18:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:18:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:18:24 INFO - Running setup.py install for requests 10:18:25 INFO - Running setup.py install for docopt 10:18:25 INFO - Successfully installed blobuploader requests docopt 10:18:25 INFO - Cleaning up... 10:18:25 INFO - Return code: 0 10:18:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:25 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:25 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 10:18:25 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 10:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:25 INFO - 'HOME': '/Users/cltbld', 10:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:25 INFO - 'LOGNAME': 'cltbld', 10:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:25 INFO - 'MOZ_NO_REMOTE': '1', 10:18:25 INFO - 'NO_EM_RESTART': '1', 10:18:25 INFO - 'PAGER': '/bin/cat', 10:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:25 INFO - 'PWD': '/builds/slave/test', 10:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:25 INFO - 'SHELL': '/bin/bash', 10:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:25 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:25 INFO - 'USER': 'cltbld', 10:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:25 INFO - 'XPC_FLAGS': '0x0', 10:18:25 INFO - 'XPC_SERVICE_NAME': '0', 10:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:25 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-FRivm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:25 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-uyCyzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-h8rkSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-OHv7q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-bGo439-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-VLDdNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ILRK3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-DNnfS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Ie9rZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ehGRmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:26 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-rEOgeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-qcjhKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-gq0I43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ioG0m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-el24W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-I9yyx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:27 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-MdyI0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:18:27 INFO - Running setup.py install for manifestparser 10:18:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:18:27 INFO - Running setup.py install for mozcrash 10:18:27 INFO - Running setup.py install for mozdebug 10:18:28 INFO - Running setup.py install for mozdevice 10:18:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Running setup.py install for mozfile 10:18:28 INFO - Running setup.py install for mozhttpd 10:18:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Running setup.py install for mozinfo 10:18:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Running setup.py install for mozInstall 10:18:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:18:28 INFO - Running setup.py install for mozleak 10:18:28 INFO - Running setup.py install for mozlog 10:18:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Running setup.py install for moznetwork 10:18:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Running setup.py install for mozprocess 10:18:29 INFO - Running setup.py install for mozprofile 10:18:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Running setup.py install for mozrunner 10:18:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:18:29 INFO - Running setup.py install for mozscreenshot 10:18:29 INFO - Running setup.py install for moztest 10:18:30 INFO - Running setup.py install for mozversion 10:18:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:18:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:18:30 INFO - Cleaning up... 10:18:30 INFO - Return code: 0 10:18:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:30 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:30 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 10:18:30 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 10:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:30 INFO - 'HOME': '/Users/cltbld', 10:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:30 INFO - 'LOGNAME': 'cltbld', 10:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:30 INFO - 'MOZ_NO_REMOTE': '1', 10:18:30 INFO - 'NO_EM_RESTART': '1', 10:18:30 INFO - 'PAGER': '/bin/cat', 10:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:30 INFO - 'PWD': '/builds/slave/test', 10:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:30 INFO - 'SHELL': '/bin/bash', 10:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:30 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:30 INFO - 'USER': 'cltbld', 10:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:30 INFO - 'XPC_FLAGS': '0x0', 10:18:30 INFO - 'XPC_SERVICE_NAME': '0', 10:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:18:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wyHTYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:18:30 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)) 10:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:18:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-81pRe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:18:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-a54ZOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:18:30 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)) 10:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:18:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Rlw9OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:18:30 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)) 10:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:18:30 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-TaCsWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-DVmPdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-9NQ4rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-kkGWWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-IY1Xgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-p_oXex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-oIsThw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-9RjU1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-iLf2vY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:18:31 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)) 10:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:18:31 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-JP_ui_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:32 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-vKykXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:18:32 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)) 10:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:18:32 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-RbKMDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:18:32 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)) 10:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:18:32 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-c9SJSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:18:32 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)) 10:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:18:32 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)) 10:18:32 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)) 10:18:32 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)) 10:18:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18: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 10:18:34 INFO - Downloading blessings-1.6.tar.gz 10:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:18:35 INFO - Installing collected packages: blessings 10:18:35 INFO - Running setup.py install for blessings 10:18:35 INFO - Successfully installed blessings 10:18:35 INFO - Cleaning up... 10:18:35 INFO - Return code: 0 10:18:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:35 INFO - Reading from file tmpfile_stdout 10:18:35 INFO - Current package versions: 10:18:35 INFO - blessings == 1.6 10:18:35 INFO - blobuploader == 1.2.4 10:18:35 INFO - docopt == 0.6.1 10:18:35 INFO - functools32 == 3.2.3-2 10:18:35 INFO - jsonschema == 2.5.1 10:18:35 INFO - manifestparser == 1.1 10:18:35 INFO - mozInstall == 1.12 10:18:35 INFO - mozcrash == 1.0 10:18:35 INFO - mozdebug == 0.1 10:18:35 INFO - mozdevice == 0.48 10:18:35 INFO - mozfile == 1.2 10:18:35 INFO - mozhttpd == 0.7 10:18:35 INFO - mozinfo == 0.9 10:18:35 INFO - mozleak == 0.1 10:18:35 INFO - mozlog == 3.4 10:18:35 INFO - moznetwork == 0.27 10:18:35 INFO - mozprocess == 0.23 10:18:35 INFO - mozprofile == 0.28 10:18:35 INFO - mozrunner == 6.13 10:18:35 INFO - mozscreenshot == 0.1 10:18:35 INFO - mozsystemmonitor == 0.3 10:18:35 INFO - moztest == 0.7 10:18:35 INFO - mozversion == 1.4 10:18:35 INFO - psutil == 3.1.1 10:18:35 INFO - requests == 1.2.3 10:18:35 INFO - wsgiref == 0.1.2 10:18:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:36 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:36 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 10:18:36 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 10:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:36 INFO - 'HOME': '/Users/cltbld', 10:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:36 INFO - 'LOGNAME': 'cltbld', 10:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:36 INFO - 'MOZ_NO_REMOTE': '1', 10:18:36 INFO - 'NO_EM_RESTART': '1', 10:18:36 INFO - 'PAGER': '/bin/cat', 10:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:36 INFO - 'PWD': '/builds/slave/test', 10:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:36 INFO - 'SHELL': '/bin/bash', 10:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:36 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:36 INFO - 'USER': 'cltbld', 10:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:36 INFO - 'XPC_FLAGS': '0x0', 10:18:36 INFO - 'XPC_SERVICE_NAME': '0', 10:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 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)) 10:18:36 INFO - Cleaning up... 10:18:36 INFO - Return code: 0 10:18:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:18:37 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 0x10d7e7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d75d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f256cd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, '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.1kIUtgEapi/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.nc5nvvWFyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 10:18:37 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 10:18:37 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 10:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:37 INFO - 'HOME': '/Users/cltbld', 10:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:37 INFO - 'LOGNAME': 'cltbld', 10:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:37 INFO - 'MOZ_NO_REMOTE': '1', 10:18:37 INFO - 'NO_EM_RESTART': '1', 10:18:37 INFO - 'PAGER': '/bin/cat', 10:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:37 INFO - 'PWD': '/builds/slave/test', 10:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:37 INFO - 'SHELL': '/bin/bash', 10:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:18:37 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:37 INFO - 'USER': 'cltbld', 10:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:37 INFO - 'XPC_FLAGS': '0x0', 10:18:37 INFO - 'XPC_SERVICE_NAME': '0', 10:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:18:37 INFO - Cleaning up... 10:18:37 INFO - Return code: 0 10:18:37 INFO - Running post-action listener: _resource_record_post_action 10:18:37 INFO - Running post-action listener: _start_resource_monitoring 10:18:37 INFO - Starting resource monitoring. 10:18:37 INFO - [mozharness: 2016-11-25 18:18:37.331614Z] Finished create-virtualenv step (success) 10:18:37 INFO - [mozharness: 2016-11-25 18:18:37.332583Z] Running install step. 10:18:37 INFO - Running pre-action listener: _resource_record_pre_action 10:18:37 INFO - Running main action method: install 10:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:18:37 INFO - Reading from file tmpfile_stdout 10:18:37 INFO - Detecting whether we're running mozinstall >=1.0... 10:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:18:37 INFO - Reading from file tmpfile_stdout 10:18:37 INFO - Output received: 10:18:37 INFO - Usage: mozinstall [options] installer 10:18:37 INFO - Options: 10:18:37 INFO - -h, --help show this help message and exit 10:18:37 INFO - -d DEST, --destination=DEST 10:18:37 INFO - Directory to install application into. [default: 10:18:37 INFO - "/builds/slave/test"] 10:18:37 INFO - --app=APP Application being installed. [default: firefox] 10:18:37 INFO - mkdir: /builds/slave/test/build/application 10:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:19:08 INFO - Reading from file tmpfile_stdout 10:19:08 INFO - Output received: 10:19:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:19:08 INFO - Running post-action listener: _resource_record_post_action 10:19:08 INFO - [mozharness: 2016-11-25 18:19:08.339286Z] Finished install step (success) 10:19:08 INFO - [mozharness: 2016-11-25 18:19:08.339453Z] Running run-tests step. 10:19:08 INFO - Running pre-action listener: _resource_record_pre_action 10:19:08 INFO - Running main action method: run_tests 10:19:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:08 INFO - 2016-11-25 10:19:08.353 screenresolution[1627:7412] starting screenresolution argv=screenresolution get 10:19:08 INFO - 2016-11-25 10:19:08.356 screenresolution[1627:7412] Display 0: 1600x1200x32@60 10:19:08 INFO - 2016-11-25 10:19:08.362 screenresolution[1628:7414] starting screenresolution argv=screenresolution list 10:19:08 INFO - Available Modes on Display 0 10:19:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:08 INFO - Intel Iris: 10:19:08 INFO - Chipset Model: Intel Iris 10:19:08 INFO - Type: GPU 10:19:08 INFO - Bus: Built-In 10:19:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:08 INFO - Vendor: Intel (0x8086) 10:19:08 INFO - Device ID: 0x0a2e 10:19:08 INFO - Revision ID: 0x0009 10:19:08 INFO - Displays: 10:19:08 INFO - Display: 10:19:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:08 INFO - Display Serial Number: 1600x1200 60 10:19:08 INFO - Main Display: Yes 10:19:08 INFO - Mirror: Off 10:19:08 INFO - Online: Yes 10:19:08 INFO - Rotation: Supported 10:19:08 INFO - Return code: 0 10:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:08 INFO - Python 2.7.3 10:19:08 INFO - Return code: 0 10:19:08 INFO - grabbing minidump binary from tooltool 10:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:08 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 0x10d5fbd30>, '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 0x10d461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:08 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 10:19:08 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 10:19:08 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 10:19:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:08 INFO - Return code: 0 10:19:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:19:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:19:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 10:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 10:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1kIUtgEapi/Render', 10:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:08 INFO - 'HOME': '/Users/cltbld', 10:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:08 INFO - 'LOGNAME': 'cltbld', 10:19:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:08 INFO - 'MOZ_NO_REMOTE': '1', 10:19:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:08 INFO - 'NO_EM_RESTART': '1', 10:19:08 INFO - 'PAGER': '/bin/cat', 10:19:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:08 INFO - 'PWD': '/builds/slave/test', 10:19:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:08 INFO - 'SHELL': '/bin/bash', 10:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nc5nvvWFyV/Listeners', 10:19:08 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 10:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:08 INFO - 'USER': 'cltbld', 10:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:08 INFO - 'XPC_FLAGS': '0x0', 10:19:08 INFO - 'XPC_SERVICE_NAME': '0', 10:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 10:19:08 INFO - mozversion application_buildid: 20161125075519 10:19:08 INFO - mozversion application_changeset: a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f 10:19:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:19:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:08 INFO - mozversion application_name: Firefox 10:19:08 INFO - mozversion application_remotingname: firefox-dev 10:19:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:19:08 INFO - mozversion application_vendor: Mozilla 10:19:08 INFO - mozversion application_version: 52.0a2 10:19:08 INFO - mozversion platform_buildid: 20161125075519 10:19:08 INFO - mozversion platform_changeset: a7c5e5d204bdf11a3aa6d44cc0b08fd7a770344f 10:19:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:19:08 INFO - mozversion platform_version: 52.0a2 10:19:08 INFO - using testdate: 1480097948 10:19:08 INFO - actual date: 1480097948 10:19:08 INFO - starting webserver on 'localhost:49241' 10:19:08 INFO - SUITE-START | Running 2 tests 10:19:08 INFO - TEST-START | dromaeo_css 10:19:08 INFO - operating with platform_type : mac_ 10:19:08 INFO - Initialising browser for dromaeo_css test... 10:19:08 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpLGZOhu/profile http://localhost:49241/getInfo.html) 10:19:11 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 10:19:11 INFO - PROCESS | 1638 | colorDepth:24 10:19:11 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 10:19:11 INFO - PROCESS | 1638 | __metrics 10:19:11 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:19:11 INFO - PROCESS | 1638 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:12 INFO - TEST-INFO | 1638: exit 0 10:19:12 INFO - Browser initialized. 10:19:12 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:12 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpLGZOhu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:25 INFO - PROCESS | 1640 | RSS: Main: 222801920 10:19:25 INFO - PROCESS | 1640 | 10:20:16 INFO - PROCESS | 1640 | 1480098016683 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] 10:22:29 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:22:31 INFO - PROCESS | 1640 | RSS: Main: 342925312 10:22:31 INFO - PROCESS | 1640 | 10:25:34 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:25:34 INFO - PROCESS | 1640 | RSS: Main: 348319744 10:25:34 INFO - PROCESS | 1640 | 10:28:37 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:28:37 INFO - PROCESS | 1640 | RSS: Main: 308940800 10:28:37 INFO - PROCESS | 1640 | 10:31:40 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:31:40 INFO - PROCESS | 1640 | RSS: Main: 333545472 10:31:40 INFO - PROCESS | 1640 | 10:34:43 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:34:44 INFO - PROCESS | 1640 | RSS: Main: 315293696 10:34:44 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:37:47 INFO - PROCESS | 1640 | RSS: Main: 406601728 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | __start_tp_report 10:37:47 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 10:37:47 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 10:37:47 INFO - PROCESS | 1640 | |i|pagename|runs| 10:37:47 INFO - PROCESS | 1640 | |0;dojo.html;3333.4833100869364;3352.413327667659;3354.144693103259;3373.7469689773275;3417.7265818734486;5374.489423504766;5682.488576028162;5687.971560142204;5707.743151558179;5746.712664366781;5655.208270842086;5725.570582206341;5737.942620573793;5738.42615738426;5805.593608447411;5178.6892786432745;5200.92198617021;5209.505097015777;5237.869053273661;5282.07563676357;4317.913641727164;5156.329677141966;5189.143791274454;5190.299309593213;5309.681419114846;5241.973790131044;5281.9207711884355;5288.867778305554;5290.074237008508;5300.761465734032;5270.710110943896;5283.313169287987;5296.549793267577;5299.390570084451;5316.9202461963105;5016.247562865563;5099.184130539096;5202.687838729688;5211.140161873286;5239.633225674204;5580.218769372291;5582.358028826697;5586.804461843816;5612.635178713371;5613.887722245573;6075.17985072014;6103.314362901373;6115.788007244244;6128.601640893372;6132.877342453126;6174.289956654998;6198.163247961513;6208.689565521751;6210.689465526705;6231.376862313778;5700.087985922232;5752.453516915886;5765.019946609086;5768.451997060272;5821.94178058214;6372.107904893226;6378.234611846516;6389.169407976933;6394.552381333262;6398.9040164397575;5961.314448838439;5970.76116955317;5985.401459854067;6140.754369825157;6151.96924015377;6128.392057524159;6172.475339596095;6233.781817636361;6273.592216505913;6278.372162783685;6137.294211165684;6178.845528861724;6187.628742275478;6188.721507532172;6193.783217587363;6070.908936365958;6108.258823178584;6189.566730328834;6228.906566401508;6235.469985051322;4232.32282834745;4846.224604063404;4874.8537543873745;4924.507549245119;4945.851624451272;3725.273571653556;4330.155619654137;4401.823927042882;4413.977930110394;4444.511103778582;5655.4910058094965;5737.598368114275;5754.56840736938;5772.5670574707065;5805.651660900359;5039.571636410946;6145.40219542922;6154.046122850965;6154.107654390088;6248.31268560458;5742.598018138774;5762.567807414461;5762.625429347079;5782.826515204551;5785.942140578541;4499.572540608702;5345.7594408251725;5359.490848369399;5363.222332761728;5398.946010539844;6253.687315634291;6256.061590761422;6299.401556852092;6325.082862984934;6341.239051313687;6162;6235.625862448268;6239.563230573816;6245.28179259391;6301.779437719658;4906;6338.271098823694;6346.365363463617;6384.201974753156;6396.808095757135;4659.860204193879;6004.8799024020145;6095.2685677718955;6165.260168779775;6177.413145751147;6119.07606531266;6120.540959427972;6128.28924408451;6172.567920245539;6257.155284036597;5839;6067.271927368567;6071.058985857199;6074.453299203093;6106.175666284995;5823.155642431739;6040.728167232573;6041.24484439445;6043.2748070231855;6044;5783.392743761844;5823.679697616672;5887.852803679943;5910.290765108214;5968.4926781224085;6018.21763170785;6058.606190597597;6066.787662431793;6070.817875463743;6071.939280607137;5745.224394706661;5767.5674324425;5792.304923409218;5917.408259174048;5920.111983202381 10:37:47 INFO - PROCESS | 1640 | |1;ext.html;434.5155152005514;478.56928764112297;521.4759167037129;525.4955242966754;553.1923391847902;30549.38901221974;31052.37895242094;33384.66615333846;38899.805500972456;39545.41818327263;40493.987650308685;40816.795916020455;40842;40906.386404203884;40945;9356.672516461942;9555;9567.282453815957;9569.521523923811;9579;8578.18507241811;8790.956045219766;8801;8886.111388861127;8917.152870477326;11386.60146894859;11666.883331166664;11674.708132296719;11678.532858685643;11685.7662846743;11390.202685811997;11440.370779607118;11449.828252576219;11465.942670286679;11479.426028698574;8578.056413794478;8791.60437780295;11008.504617292241;11239.550417983271;11316.660500185008;37613.43579846305;39591;39646.60353396458;39659.20681586352;39727;3237.951430728541;3300.5214243934734;3411.5394421752994;3534.8409321580334;3829.483019792348;28685.139445816658;28691.71308286911;28702;28927.710722892713;29999.850000750073;3090.922726931822;3149.7322727568185;3306.272620023591;3339.2653616204398;3339.916502087443;446674;505313;506826;509776;523684.9999999962;59985;62099.75800484055;62189;62209.37790622036;62211.688941555;64861.67569162124;65054.674726626065;65065;65102.67448662821;65211.34788652148;15911.204439777965;16496.587585310463;16502.83497165037;16527.256273467723;16553.6689266214;12626.179298345578;12699.619011429671;12705.047121465926;12711.936440317739;12726.109172357847;16110.919445402698;16318.836811631969;16706.164691765607;16778.74831877523;17831.92752264922;6447.903281450782;7461.440391970624;7565.6217189140325;7583.9620801895635;7647.923520764833;880.7049638371218;944.6032666280179;1024.6772266735954;1026.3790406803926;1039.6828967165088;29753.553696694566;29754.256143596584;29974.700252997627;30185.396292074034;30275.697243027727;1085.478970094359;1093.6172339681048;1094.141099237095;1117.2905205194597;1140.5551834784442;14932.178730169944;15205.695886082216;15265.847341526665;15304.693906121815;15532.534023979299;672572;672981.6350918312;675527;678201;680642;41924.95187620273;49087.75456122697;49551.75224123929;55198.724006379714;56779;1225.1791299828958;1354.5462270139417;1354.837419509665;1365.5698454986648;1373.3613869550707;29330.97341593034;29851.402971940006;29993.700062999964;30341.69658303477;30655.08776666213;49918.001639967726;50796.238056429924;50888;50916.74541627416;50925.49074509207;577640;695008;873434;876483;877192;40300.77593792548;40314.9921251971;40678;41082.383764242964;41097.58902411057;44692.55307447014;44706.55293447154;44829.77585112053;44937.550624494645;45170.77414612906;36703.632963670025;36748;37771.43342849914;38788.41817372798;38972.80513597414;35976.92069237927;36872.26255474929;37074.6292537082;37394;38518.42222366723;35198.82400588083;36209.45685814768;37037.814810925775;37196.6280337204;37532.24935501329;32432;32468.675313246564;32790;32850.50724239186;33759.324813504085;20530.870802106027;23494.177703780286;24729.629055563822;24838.875805620857;25663.486730264918 10:37:47 INFO - PROCESS | 1640 | |2;jquery.html;309.90083173384517;333.11580157207277;351.46401737350556;379.12611430652333;399.7521536647279;14774;15547.61130971725;17127.486175414728;20230.988450577486;20277.493062673442;19038.42884713457;19272.132754026068;19369.806301936977;19444.22223111074;20136.49658758532;4093.713440059197;5465.699386533749;5750.913736293938;5758.049921762904;5767.6827774472595;4228.302330115543;6108.908366374508;6324.525660575452;6406.679666016704;6442;6240.126382306481;6260.092286618437;6389.137466442041;6399.392057754506;6402.135711678933;6380.1705778249;6412.93587064128;6432.935670643327;6435.292117867031;6436.517261205381;5319.9202011969855;5464.808731694372;5537.335519737658;5537.972310138463;5545.861353466155;23536.176233831906;24562.754372456224;24690.753092469025;24855.881485333204;24888.751112489004;2010.7687615924062;2219.4007617943225;2350.4828937633692;2458.950820983588;2692.8249663771794;9639.518024098837;10075.748106297327;10135.493225338703;10514.31656942304;14182.43270269188;1844.7417361569392;1883.5102873252915;1911.3119277060248;1913.87559808612;1931.893745843978;86857;133961;150120;157086;157734;15780.60548486297;20016.49958751043;20577.69133462999;20949.162033518794;21159;34580.48129278063;34659.6136154551;35348.469772953424;35627.57489700435;35685.82157089198;10350.844737328945;10560.366378017343;10674.946625266979;10733.51699173539;10927.617533386452;8802.383833131747;8835.823283534293;8841.867371989425;8948.552572371485;8964.417312874642;9405.57674904631;9539.666111686058;9559.713208603614;9585.856212156823;10036.193485172593;3165.5568220449154;3739.2334571412794;3745.4943582616543;3748.212875296164;3758.2483503298904;838.5304229631425;896.6368620708492;922.935394522377;998.3909815012836;1040.458961340099;18543.25826966933;18831.529211769815;19491.610167796847;19524.316648917218;19770.703439448418;908.9045650206765;930.3627015494272;934.6541779541614;944.4663764972769;970.3983530211173;9785.36395134314;9844.80310393788;10464.319819211727;10499.475026248658;10513.52689128991;608512;612203;612857;617888;623304;88244;108175;124887;128565.6184902284;130069;930.851063829784;931.2456909903;952.7332346942867;963.4749061761357;964.3876138652007;17981.100944952963;18177.636447271245;18614.627707446045;18638.254469821055;18641.813581864008;47620.76189619168;55303.17045244405;57190.71404643117;57301.713491432274;57427.1385929203;61938;68892;73327;74489.88265176135;83604.58197708972;7561.09266887977;7722.53664780115;7827.882581761392;7907.881381779162;7912.406569507195;9346.626134954526;11120.332780033224;12176.45205965716;12186.146969712036;12327.952124069196;10251.84622230682;10402.739931501774;10450.738731531772;10680.679579612624;10684.679459616224;10421.895781042092;10495.317804342998;10570.630027948984;10575.04824565762;10649.562309088484;10309.793804124025;10393.792124157324;10409.375437473776;10449.582016719247;10783.784324313627;7042.0845290114;7243.710251589878;7258.165310989304;7326.120865496175;7752.0697516298405;8448.746537603882;8704.173103555153;10344.224183186441;10746.8387974182;10825.512851921525 10:37:47 INFO - PROCESS | 1640 | |3;mootools.html;1487.9807331977593;1495.7905893174955;1684.8146703862574;1823.5441139715072;1841.7237414387848;8253.215944485271;8701.08638592948;8709.2597129244;8721.389502734803;8723.345749068814;7363.5213711108745;7656.732014379498;7668.118166410864;8166.060902996157;8485.151484851513;2285.622872226085;2348.9060437582475;2352.776486233805;2353.329301149173;2354.6232602783557;2346.4251258441705;2359;2399.5320912422085;2399.9880000599974;2400.84394514356;2230.631945728953;2241.047554789208;2256.4697296135396;2263.241813992306;2264.071730590458;2060.443680206351;2313.5372925414986;2315.2128276386024;2341.8946147423408;2344.191254017361;2283.7259528856644;2367.135995361698;2368.9170879019325;2369.7393286738443;2370.3837002379505;8407.495550267004;8451.492910425333;8528.488290702579;8529.616167272425;8533.914660853436;4458.353538736865;5106.4893510648635;5283.9471605284225;5292.417834038252;5293.788248470095;3294.2423242654086;3430.3310854383403;3541.8052007139595;3589.4436362363876;3591.856325746967;1038.6416686243233;1044.7074819050679;1057;1066.06680033253;1100.2793170473353;10957.50691218897;11125.777484450266;11216.831747523795;11231.719207019809;11261.774764504746;4485.215087359699;4643.094596553639;4730.101280756645;4735.857924262277;4740.6681532292405;9727.319087663796;9740.46427446483;9743.756406089904;9745.12293893553;9768.07203315684;5307.761150748225;5310.230016647564;5317.8936421271355;5320.361556613231;5325.3875804282225;4109.97945010279;4285.635720963753;4289.935650965238;4738.668293219511;4820.927686084711;3812.4281357796553;3818.121831978605;3900.2199560088097;3906.4921560197563;3935.370340745467;1244.6639407360144;1258.0564576567574;1271.8028705550655;1273.1151849464534;1289.9742005159844;761.1551178192202;861.2980420957025;898.8876390451194;905.7373361725026;914.9359544831931;4997.525235102659;5149.459306772735;5411.377691565442;5693.202951586781;5806.361300256968;855.1149560205158;886.6586364249811;919.5264438814016;955.3360414511986;965.7247684409909;3671.210689701727;3803.5625903021505;3901.258760835432;3940.9211815763524;3949.743266687656;8110.270075693216;9744.707658770249;10310.381377117397;10455.790884182274;10478.109360704426;9711.077447642463;10394.324368915995;10394.792104157874;10403.84394234087;10422.166226701846;7720.729774457867;8078.071021832447;8094.069182044141;8102.351811855037;8103.1491693371345;5569.303837020372;5631.408702086386;5690.174924635822;5820.126980952891;5868.559858010752;9500.287478439282;9591.184749296388;9674.854877176707;9678.467684277432;9721.222302215665;9477.52612369365;9689.176420004378;9705.223582113555;9866.901330986886;9891.703248902544;7062;7231.4214862809795;7323.523970941872;7357.632118394166;7360.07999000125;6438.710258038306;7854.685812567662;7873.110453491006;7955.4828936118965;7962.323202527618;7181.928180718126;7201.3158749918675;7202.171750248579;7298.963505182653;7361.852762944818;7043.225245223124;7101.964490177723;7116.146062472537;7145.46409019336;7158.534695245001;6600.405963463311;6744.291849355944;6748.93251067483;6757.729690812313;6759.695813688298;4751.857444276678;4801.807927682854;4835.29888166221;4868.391451068616;4884.389451318585;4761.40482439695;4848.3454733610515;4856.320115183807;4956.6530342877095;4995.825146119869 10:37:47 INFO - PROCESS | 1640 | |4;prototype.html;292.7818775012616;298.37043837502864;307.2641024745734;312.21166554450014;313.14355238422894;4564.763265999662;5115.104856650085;5127.974360128199;5161.79352825887;5212.2963399941;4421.823127074921;4617.753206634206;4836.951630483694;5065.645404821655;5119;3437.3297207044575;3879.767213967171;3921.3726803041886;3932.2725295820364;3936.921261574767;3472.6700963408434;3790.374588192945;3923.7444017914136;3946;3967.642912137907;2921.547160190163;3773.5660399054186;3876.856327383429;3891.9610803892024;3901.726879118463;3869.729118961674;3892.863749768773;3899.6685281750943;3909.6481316681634;3927.9983604180893;2836.9432611347866;3084.3831233753117;3346.6111563701033;3373.1567108222944;3376.7973921564785;4328.372386004044;4331.631811296043;4984.227444746034;4988.925166122511;4991.675541089818;3860.3244432224246;4213.51544572375;4335.6965012449145;4419.580139886705;4446.911061778778;5339.5461385782255;5362.613636931567;5387.568994480433;5388.838334849961;5389.73051347435;3133.5613014178034;3282.3435312937268;3714.0900479382726;3724.2737666155112;3732.42147467143;4173;4477.529859364785;4756.429228492603;5504.779808807642;5702.54379649627;3223.9677603223668;3573.6605022522817;3756.328433846912;3771.5097037385513;3792.563855156637;3820.9426858597144;3826.9617303826603;3827.3684842001317;3834.367329390675;3843.5387753469763;4432.889177770581;4453;4469.687121901501;4470.731756094645;4500.189965806187;5097.413797413345;5433.320834895638;5496.972515137399;5498.257735205776;5499.7250137492965;1014.2747935226201;1124.775044991005;1133.665568657248;1214.0773012510379;1236.4744983382207;690.5960013392122;759.4038679636459;763.9121501027453;788.6608758234014;851.3231980575457;4704.411948506437;4756.9762151189025;4766.356541866873;4774.904501909942;4784.856454306377;4117.464729585135;5152.562032227228;5154.355705536808;5154.768035438415;5163;4130.942327753204;4721.409823772028;4740.7392593407;4769.664493941702;4779.30700048498;2612.660354153948;2629.7107318194985;2734.53512902807;2739.7808175345926;2743.3676962207455;4870.439899411613;5169.25045868347;5208.906129712727;5218.9739051304505;5388;4600.401947746772;4954.281629163751;5323.041852466593;5356.5714742821265;5514.889702205934;4500.4149460569915;5335.413104558418;5789.334226563999;5789.668376273481;5816.563757718188;5080.891430056485;5596.580256480862;5601.831945041656;5622.353429355677;5644.85887852807;4698.271767875985;5235.973465473188;5523.530499907552;5579.298314014264;5583.776648934159;5039.949600504095;5744.741486633028;5769.3942136076785;5793.623414478046;5810;3149.73227275675;3445.6898879101;3731.533558305212;3749.3813520768767;3806.904827379227;5407.026735187704;5498.285222920995;5593.552515798645;5621.381648018796;5676.120201368794;4566.70316429431;4760.524527245743;4839.854804355875;5424.880693601274;5425.403205647297;4644.953550464587;5403.567714582745;5444.210589464585;5444.891102178013;5452.890942181213;4591.4260717410325;4688.812447502061;4838;5209.74853717992;5380.461953804588;4161.001359673717;4802.927956080592;5239.423663396947;5389.197617239092;5416.945830541802;4808.2547205183255;4926.945293917794;5036.597072234139;5328.254044433705;5340.439253878287 10:37:47 INFO - PROCESS | 1640 | |5;yui.html;621.9378062193779;695.8677851208273;754.1252147508891;760.4638729695564;779.2713812585233;14246.074005189656;14789;14807.407703691839;14811.629709257273;14831.332590033448;13148.6712832179;13197.934010329958;13256.072074954756;13270.933645331785;13329.400176992034;661.0843981086194;683.770936736194;685.2496516314627;685.5715178013742;686.1457485430645;798.6046906781122;822.6298165825373;828.1263267253046;834.8747687846811;836.7322456813822;841.9831603367929;884.8451520983803;885.0972008551274;890.9866352004725;891.4963045879058;838.5220424358118;871.2463718883184;883.8674198870157;884.0010787809798;884.8628462588309;518.6836030021684;533.6131304803986;533.7331334332833;535.8526405238551;537.9031774280628;10823.567057317698;13389.799153012713;13870.514531991434;14116.223607701573;14426.495072672406;12946.611601651966;13121.147125436815;13443.395047222899;13549.796753048613;13590.660233494144;10104.89895101047;11866.940665296705;11893.522944550628;11995;11998.58004969822;4324.740515569076;4372;4414.801333939949;4432.445944256968;4595.03504264105;96397;98196.01803982012;99919.0008099917;100152;100242.99999999927;10130.138938190335;10185.694429167137;10655.200859935536;10655.520501577448;10752.19358548112;19877.105530251174;20444.897775511028;20456.386308410772;20462.693059604117;20496.59006819855;7303.160136584254;7369.152547457111;7454.627268636546;7641.312281894545;7645.770626881203;4074.2055299216963;4109.671226301889;4171.624553790173;4228.746275223496;4275.722078064916;3289.490129030004;3370.983145084259;3396.9150771230916;3668.229671768905;3817.179306449071;627.231641239478;663.8572706868047;687.848673291875;693.8924466707668;694.0803435448051;543.5814422894349;596.1772753600004;634.3624657219465;639.7748311982594;654.5418207254941;7994;8711.25954293892;8774.868376974351;8824.823503529893;8829.33779966505;538.9947747450975;577.4485366475006;632.9525285603598;651.4527796650837;657.7993711917816;4081.4898137732785;4251.744895306291;4257.74453532789;4279.315309550457;4368.541303163186;79165;81112.59443702862;90706.02233754282;104095;130951;71432.2856771436;104164;104476;106881.46559267309;109339.45330273297;62185.37814621886;64606.38484037843;66965.66517167383;80124.59937700274;98145;8106.83786324282;8107.1892810718455;8179.304759095544;8199.631016604386;8244.57515300842;47481.05037899292;51057.74471127621;54591;56919.430805691416;57237.999999998334;56482;75934.62032689802;77381;77540;80766;12060;12209.450574723982;12215.511379545074;12242.326672033123;12755.02297143944;13029.348532573524;13325.267110309027;13341.866581334063;13435.380066098296;13801.89584833231;12330.136890417944;12354.25874447536;12393.504259829506;12439.813402799145;12668.113232073667;10849.728756781144;12199.817002744789;12234.938825305817;12595.370231488572;13095.869041309465;12421.19262247951;12443.813342799684;12772.87227127754;12795.488180472677;13006.674833129247;10446.791064178826;10546.894531054591;10833.349999000086;10911.618093366695;11066.004059634672;11102.111831053659;11128.109751220045;11364.715882103013;11437;11446.771064578828 10:37:47 INFO - PROCESS | 1640 | __end_tp_report 10:37:47 INFO - PROCESS | 1640 | __start_cc_report 10:37:47 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1401 10:37:47 INFO - PROCESS | 1640 | __end_cc_report 10:37:47 INFO - PROCESS | 1640 | __startTimestamp1480099067884__endTimestamp 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | ------- Summary: start ------- 10:37:47 INFO - PROCESS | 1640 | Number of tests: 6 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5680.08 Median:5823.16 stddev:665.09 (11.4%) stddev-sans-first:641.15 10:37:47 INFO - PROCESS | 1640 | Values: 3333.5 3352.4 3354.1 3373.7 3417.7 5374.5 5682.5 5688.0 5707.7 5746.7 5655.2 5725.6 5737.9 5738.4 5805.6 5178.7 5200.9 5209.5 5237.9 5282.1 4317.9 5156.3 5189.1 5190.3 5309.7 5242.0 5281.9 5288.9 5290.1 5300.8 5270.7 5283.3 5296.5 5299.4 5316.9 5016.2 5099.2 5202.7 5211.1 5239.6 5580.2 5582.4 5586.8 5612.6 5613.9 6075.2 6103.3 6115.8 6128.6 6132.9 6174.3 6198.2 6208.7 6210.7 6231.4 5700.1 5752.5 5765.0 5768.5 5821.9 6372.1 6378.2 6389.2 6394.6 6398.9 5961.3 5970.8 5985.4 6140.8 6152.0 6128.4 6172.5 6233.8 6273.6 6278.4 6137.3 6178.8 6187.6 6188.7 6193.8 6070.9 6108.3 6189.6 6228.9 6235.5 4232.3 4846.2 4874.9 4924.5 4945.9 3725.3 4330.2 4401.8 4414.0 4444.5 5655.5 5737.6 5754.6 5772.6 5805.7 5039.6 6145.4 6154.0 6154.1 6248.3 5742.6 5762.6 5762.6 5782.8 5785.9 4499.6 5345.8 5359.5 5363.2 5398.9 6253.7 6256.1 6299.4 6325.1 6341.2 6162.0 6235.6 6239.6 6245.3 6301.8 4906.0 6338.3 6346.4 6384.2 6396.8 4659.9 6004.9 6095.3 6165.3 6177.4 6119.1 6120.5 6128.3 6172.6 6257.2 5839.0 6067.3 6071.1 6074.5 6106.2 5823.2 6040.7 6041.2 6043.3 6044.0 5783.4 5823.7 5887.9 5910.3 5968.5 6018.2 6058.6 6066.8 6070.8 6071.9 5745.2 5767.6 5792.3 5917.4 5920.1 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:76962.63 Median:37135.63 stddev:179045.68 (482.1%) stddev-sans-first:179455.75 10:37:47 INFO - PROCESS | 1640 | Values: 434.5 478.6 521.5 525.5 553.2 30549.4 31052.4 33384.7 38899.8 39545.4 40494.0 40816.8 40842.0 40906.4 40945.0 9356.7 9555.0 9567.3 9569.5 9579.0 8578.2 8791.0 8801.0 8886.1 8917.2 11386.6 11666.9 11674.7 11678.5 11685.8 11390.2 11440.4 11449.8 11465.9 11479.4 8578.1 8791.6 11008.5 11239.6 11316.7 37613.4 39591.0 39646.6 39659.2 39727.0 3238.0 3300.5 3411.5 3534.8 3829.5 28685.1 28691.7 28702.0 28927.7 29999.9 3090.9 3149.7 3306.3 3339.3 3339.9 446674.0 505313.0 506826.0 509776.0 523685.0 59985.0 62099.8 62189.0 62209.4 62211.7 64861.7 65054.7 65065.0 65102.7 65211.3 15911.2 16496.6 16502.8 16527.3 16553.7 12626.2 12699.6 12705.0 12711.9 12726.1 16110.9 16318.8 16706.2 16778.7 17831.9 6447.9 7461.4 7565.6 7584.0 7647.9 880.7 944.6 1024.7 1026.4 1039.7 29753.6 29754.3 29974.7 30185.4 30275.7 1085.5 1093.6 1094.1 1117.3 1140.6 14932.2 15205.7 15265.8 15304.7 15532.5 672572.0 672981.6 675527.0 678201.0 680642.0 41925.0 49087.8 49551.8 55198.7 56779.0 1225.2 1354.5 1354.8 1365.6 1373.4 29331.0 29851.4 29993.7 30341.7 30655.1 49918.0 50796.2 50888.0 50916.7 50925.5 577640.0 695008.0 873434.0 876483.0 877192.0 40300.8 40315.0 40678.0 41082.4 41097.6 44692.6 44706.6 44829.8 44937.6 45170.8 36703.6 36748.0 37771.4 38788.4 38972.8 35976.9 36872.3 37074.6 37394.0 38518.4 35198.8 36209.5 37037.8 37196.6 37532.2 32432.0 32468.7 32790.0 32850.5 33759.3 20530.9 23494.2 24729.6 24838.9 25663.5 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:36341.45 Median:35488.02 stddev:102650.16 (289.3%) stddev-sans-first:102902.47 10:37:47 INFO - PROCESS | 1640 | Values: 309.9 333.1 351.5 379.1 399.8 14774.0 15547.6 17127.5 20231.0 20277.5 19038.4 19272.1 19369.8 19444.2 20136.5 4093.7 5465.7 5750.9 5758.0 5767.7 4228.3 6108.9 6324.5 6406.7 6442.0 6240.1 6260.1 6389.1 6399.4 6402.1 6380.2 6412.9 6432.9 6435.3 6436.5 5319.9 5464.8 5537.3 5538.0 5545.9 23536.2 24562.8 24690.8 24855.9 24888.8 2010.8 2219.4 2350.5 2459.0 2692.8 9639.5 10075.7 10135.5 10514.3 14182.4 1844.7 1883.5 1911.3 1913.9 1931.9 86857.0 133961.0 150120.0 157086.0 157734.0 15780.6 20016.5 20577.7 20949.2 21159.0 34580.5 34659.6 35348.5 35627.6 35685.8 10350.8 10560.4 10674.9 10733.5 10927.6 8802.4 8835.8 8841.9 8948.6 8964.4 9405.6 9539.7 9559.7 9585.9 10036.2 3165.6 3739.2 3745.5 3748.2 3758.2 838.5 896.6 922.9 998.4 1040.5 18543.3 18831.5 19491.6 19524.3 19770.7 908.9 930.4 934.7 944.5 970.4 9785.4 9844.8 10464.3 10499.5 10513.5 608512.0 612203.0 612857.0 617888.0 623304.0 88244.0 108175.0 124887.0 128565.6 130069.0 930.9 931.2 952.7 963.5 964.4 17981.1 18177.6 18614.6 18638.3 18641.8 47620.8 55303.2 57190.7 57301.7 57427.1 61938.0 68892.0 73327.0 74489.9 83604.6 7561.1 7722.5 7827.9 7907.9 7912.4 9346.6 11120.3 12176.5 12186.1 12328.0 10251.8 10402.7 10450.7 10680.7 10684.7 10421.9 10495.3 10570.6 10575.0 10649.6 10309.8 10393.8 10409.4 10449.6 10783.8 7042.1 7243.7 7258.2 7326.1 7752.1 8448.7 8704.2 10344.2 10746.8 10825.5 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5507.53 Median:5216.70 stddev:3041.97 (58.3%) stddev-sans-first:3035.51 10:37:47 INFO - PROCESS | 1640 | Values: 1488.0 1495.8 1684.8 1823.5 1841.7 8253.2 8701.1 8709.3 8721.4 8723.3 7363.5 7656.7 7668.1 8166.1 8485.2 2285.6 2348.9 2352.8 2353.3 2354.6 2346.4 2359.0 2399.5 2400.0 2400.8 2230.6 2241.0 2256.5 2263.2 2264.1 2060.4 2313.5 2315.2 2341.9 2344.2 2283.7 2367.1 2368.9 2369.7 2370.4 8407.5 8451.5 8528.5 8529.6 8533.9 4458.4 5106.5 5283.9 5292.4 5293.8 3294.2 3430.3 3541.8 3589.4 3591.9 1038.6 1044.7 1057.0 1066.1 1100.3 10957.5 11125.8 11216.8 11231.7 11261.8 4485.2 4643.1 4730.1 4735.9 4740.7 9727.3 9740.5 9743.8 9745.1 9768.1 5307.8 5310.2 5317.9 5320.4 5325.4 4110.0 4285.6 4289.9 4738.7 4820.9 3812.4 3818.1 3900.2 3906.5 3935.4 1244.7 1258.1 1271.8 1273.1 1290.0 761.2 861.3 898.9 905.7 914.9 4997.5 5149.5 5411.4 5693.2 5806.4 855.1 886.7 919.5 955.3 965.7 3671.2 3803.6 3901.3 3940.9 3949.7 8110.3 9744.7 10310.4 10455.8 10478.1 9711.1 10394.3 10394.8 10403.8 10422.2 7720.7 8078.1 8094.1 8102.4 8103.1 5569.3 5631.4 5690.2 5820.1 5868.6 9500.3 9591.2 9674.9 9678.5 9721.2 9477.5 9689.2 9705.2 9866.9 9891.7 7062.0 7231.4 7323.5 7357.6 7360.1 6438.7 7854.7 7873.1 7955.5 7962.3 7181.9 7201.3 7202.2 7299.0 7361.9 7043.2 7102.0 7116.1 7145.5 7158.5 6600.4 6744.3 6748.9 6757.7 6759.7 4751.9 4801.8 4835.3 4868.4 4884.4 4761.4 4848.3 4856.3 4956.7 4995.8 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4286.49 Median:4756.70 stddev:1334.00 (28.0%) stddev-sans-first:1303.63 10:37:47 INFO - PROCESS | 1640 | Values: 292.8 298.4 307.3 312.2 313.1 4564.8 5115.1 5128.0 5161.8 5212.3 4421.8 4617.8 4837.0 5065.6 5119.0 3437.3 3879.8 3921.4 3932.3 3936.9 3472.7 3790.4 3923.7 3946.0 3967.6 2921.5 3773.6 3876.9 3892.0 3901.7 3869.7 3892.9 3899.7 3909.6 3928.0 2836.9 3084.4 3346.6 3373.2 3376.8 4328.4 4331.6 4984.2 4988.9 4991.7 3860.3 4213.5 4335.7 4419.6 4446.9 5339.5 5362.6 5387.6 5388.8 5389.7 3133.6 3282.3 3714.1 3724.3 3732.4 4173.0 4477.5 4756.4 5504.8 5702.5 3224.0 3573.7 3756.3 3771.5 3792.6 3820.9 3827.0 3827.4 3834.4 3843.5 4432.9 4453.0 4469.7 4470.7 4500.2 5097.4 5433.3 5497.0 5498.3 5499.7 1014.3 1124.8 1133.7 1214.1 1236.5 690.6 759.4 763.9 788.7 851.3 4704.4 4757.0 4766.4 4774.9 4784.9 4117.5 5152.6 5154.4 5154.8 5163.0 4130.9 4721.4 4740.7 4769.7 4779.3 2612.7 2629.7 2734.5 2739.8 2743.4 4870.4 5169.3 5208.9 5219.0 5388.0 4600.4 4954.3 5323.0 5356.6 5514.9 4500.4 5335.4 5789.3 5789.7 5816.6 5080.9 5596.6 5601.8 5622.4 5644.9 4698.3 5236.0 5523.5 5579.3 5583.8 5039.9 5744.7 5769.4 5793.6 5810.0 3149.7 3445.7 3731.5 3749.4 3806.9 5407.0 5498.3 5593.6 5621.4 5676.1 4566.7 4760.5 4839.9 5424.9 5425.4 4645.0 5403.6 5444.2 5444.9 5452.9 4591.4 4688.8 4838.0 5209.7 5380.5 4161.0 4802.9 5239.4 5389.2 5416.9 4808.3 4926.9 5036.6 5328.3 5340.4 10:37:47 INFO - PROCESS | 1640 | 10:37:47 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20167.24 Median:4277.52 stddev:29811.67 (696.9%) stddev-sans-first:29859.17 10:37:47 INFO - PROCESS | 1640 | Values: 621.9 695.9 754.1 760.5 779.3 14246.1 14789.0 14807.4 14811.6 14831.3 13148.7 13197.9 13256.1 13270.9 13329.4 661.1 683.8 685.2 685.6 686.1 798.6 822.6 828.1 834.9 836.7 842.0 884.8 885.1 891.0 891.5 838.5 871.2 883.9 884.0 884.9 518.7 533.6 533.7 535.9 537.9 10823.6 13389.8 13870.5 14116.2 14426.5 12946.6 13121.1 13443.4 13549.8 13590.7 10104.9 11866.9 11893.5 11995.0 11998.6 4324.7 4372.0 4414.8 4432.4 4595.0 96397.0 98196.0 99919.0 100152.0 100243.0 10130.1 10185.7 10655.2 10655.5 10752.2 19877.1 20444.9 20456.4 20462.7 20496.6 7303.2 7369.2 7454.6 7641.3 7645.8 4074.2 4109.7 4171.6 4228.7 4275.7 3289.5 3371.0 3396.9 3668.2 3817.2 627.2 663.9 687.8 693.9 694.1 543.6 596.2 634.4 639.8 654.5 7994.0 8711.3 8774.9 8824.8 8829.3 539.0 577.4 633.0 651.5 657.8 4081.5 4251.7 4257.7 4279.3 4368.5 79165.0 81112.6 90706.0 104095.0 130951.0 71432.3 104164.0 104476.0 106881.5 109339.5 62185.4 64606.4 66965.7 80124.6 98145.0 8106.8 8107.2 8179.3 8199.6 8244.6 47481.1 51057.7 54591.0 56919.4 57238.0 56482.0 75934.6 77381.0 77540.0 80766.0 12060.0 12209.5 12215.5 12242.3 12755.0 13029.3 13325.3 13341.9 13435.4 13801.9 12330.1 12354.3 12393.5 12439.8 12668.1 10849.7 12199.8 12234.9 12595.4 13095.9 12421.2 12443.8 12772.9 12795.5 13006.7 10446.8 10546.9 10833.3 10911.6 11066.0 11102.1 11128.1 11364.7 11437.0 11446.8 10:37:47 INFO - PROCESS | 1640 | -------- Summary: end -------- 10:37:47 INFO - PROCESS | 1640 | 10:37:48 INFO - TEST-INFO | 1640: exit 0 10:37:48 INFO - TEST-OK | dromaeo_css | took 1119609ms 10:37:48 INFO - TEST-START | kraken 10:37:48 INFO - operating with platform_type : mac_ 10:37:48 INFO - Initialising browser for kraken test... 10:37:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp3Z3esK/profile http://localhost:49241/getInfo.html) 10:37:51 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 10:37:51 INFO - PROCESS | 1650 | colorDepth:24 10:37:51 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 10:37:51 INFO - PROCESS | 1650 | __metrics 10:37:51 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:37:51 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:37:52 INFO - TEST-INFO | 1650: exit 0 10:37:52 INFO - Browser initialized. 10:37:52 INFO - Running cycle 1/1 for kraken test... 10:37:52 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp3Z3esK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:04 INFO - PROCESS | 1651 | RSS: Main: 223129600 10:38:04 INFO - PROCESS | 1651 | 10:38:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:38:08 INFO - PROCESS | 1651 | RSS: Main: 270417920 10:38:08 INFO - PROCESS | 1651 | 10:38:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:38:11 INFO - PROCESS | 1651 | RSS: Main: 282849280 10:38:11 INFO - PROCESS | 1651 | 10:38:14 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:38:15 INFO - PROCESS | 1651 | RSS: Main: 379170816 10:38:15 INFO - PROCESS | 1651 | 10:38:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:38:17 INFO - PROCESS | 1651 | RSS: Main: 284094464 10:38:17 INFO - PROCESS | 1651 | 10:38:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:20 INFO - PROCESS | 1651 | RSS: Main: 285679616 10:38:20 INFO - PROCESS | 1651 | 10:38:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:23 INFO - PROCESS | 1651 | RSS: Main: 327069696 10:38:23 INFO - PROCESS | 1651 | 10:38:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:27 INFO - PROCESS | 1651 | RSS: Main: 324390912 10:38:27 INFO - PROCESS | 1651 | 10:38:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:38:30 INFO - PROCESS | 1651 | RSS: Main: 324038656 10:38:30 INFO - PROCESS | 1651 | 10:38:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:32 INFO - PROCESS | 1651 | RSS: Main: 301240320 10:38:32 INFO - PROCESS | 1651 | 10:38:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:35 INFO - PROCESS | 1651 | RSS: Main: 299835392 10:38:35 INFO - PROCESS | 1651 | 10:38:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:38 INFO - PROCESS | 1651 | RSS: Main: 302260224 10:38:38 INFO - PROCESS | 1651 | 10:38:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:42 INFO - PROCESS | 1651 | RSS: Main: 319213568 10:38:42 INFO - PROCESS | 1651 | 10:38:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:45 INFO - PROCESS | 1651 | RSS: Main: 312737792 10:38:45 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:38:47 INFO - PROCESS | 1651 | RSS: Main: 356573184 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | __start_tp_report 10:38:47 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 10:38:47 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 10:38:47 INFO - PROCESS | 1651 | |i|pagename|runs| 10:38:47 INFO - PROCESS | 1651 | |0;ai-astar;104;113;115;112;118;114;114;113;119;114 10:38:47 INFO - PROCESS | 1651 | |1;audio-beat-detection;148;112;114;146;118;140;135;115;143;112 10:38:47 INFO - PROCESS | 1651 | |2;audio-dft;183;184;185;183;182;182;182;183;184;183 10:38:47 INFO - PROCESS | 1651 | |3;audio-fft;84;72;83;114;78;59;59;59;82;59 10:38:47 INFO - PROCESS | 1651 | |4;audio-oscillator;89;70;94;70;70;70;90;68;69;94 10:38:47 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;84;87;85;87;84;88;85;87;84 10:38:47 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;93;93;94;93;94;93;98;92;93 10:38:47 INFO - PROCESS | 1651 | |7;imaging-desaturate;118;122;90;80;99;122;129;86;81;82 10:38:47 INFO - PROCESS | 1651 | |8;json-parse-financial;54;52;52;53;57;56;56;56;56;56 10:38:47 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;64;72;69;76;76;73;68;70;93;103 10:38:47 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;86;84;84;85;85;80;82;86;86 10:38:47 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;149;149;150;146;149;150;149;151;220;184 10:38:47 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;161;171;168;166;168;169;172;165;166;167 10:38:47 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;67;68;77;84;87;73;66;66;65 10:38:47 INFO - PROCESS | 1651 | __end_tp_report 10:38:47 INFO - PROCESS | 1651 | __start_cc_report 10:38:47 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2033 10:38:47 INFO - PROCESS | 1651 | __end_cc_report 10:38:47 INFO - PROCESS | 1651 | __startTimestamp1480099127712__endTimestamp 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | ------- Summary: start ------- 10:38:47 INFO - PROCESS | 1651 | Number of tests: 14 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:4.03 (3.5%) stddev-sans-first:2.35 10:38:47 INFO - PROCESS | 1651 | Values: 104.0 113.0 115.0 112.0 118.0 114.0 114.0 113.0 119.0 114.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:137.50 stddev:15.34 (11.2%) stddev-sans-first:14.52 10:38:47 INFO - PROCESS | 1651 | Values: 148.0 112.0 114.0 146.0 118.0 140.0 135.0 115.0 143.0 112.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:0.99 (0.5%) stddev-sans-first:1.05 10:38:47 INFO - PROCESS | 1651 | Values: 183.0 184.0 185.0 183.0 182.0 182.0 182.0 183.0 184.0 183.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:74.90 Median:75.00 stddev:17.50 (23.3%) stddev-sans-first:18.25 10:38:47 INFO - PROCESS | 1651 | Values: 84.0 72.0 83.0 114.0 78.0 59.0 59.0 59.0 82.0 59.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:79.50 stddev:11.61 (14.6%) stddev-sans-first:11.66 10:38:47 INFO - PROCESS | 1651 | Values: 89.0 70.0 94.0 70.0 70.0 70.0 90.0 68.0 69.0 94.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.83 (2.1%) stddev-sans-first:1.58 10:38:47 INFO - PROCESS | 1651 | Values: 89.0 84.0 87.0 85.0 87.0 84.0 88.0 85.0 87.0 84.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.00 stddev:1.65 (1.8%) stddev-sans-first:1.73 10:38:47 INFO - PROCESS | 1651 | Values: 93.0 93.0 93.0 94.0 93.0 94.0 93.0 98.0 92.0 93.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:81.50 stddev:19.74 (24.2%) stddev-sans-first:19.94 10:38:47 INFO - PROCESS | 1651 | Values: 118.0 122.0 90.0 80.0 99.0 122.0 129.0 86.0 81.0 82.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:56.00 stddev:1.87 (3.3%) stddev-sans-first:1.96 10:38:47 INFO - PROCESS | 1651 | Values: 54.0 52.0 52.0 53.0 57.0 56.0 56.0 56.0 56.0 56.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:76.40 Median:72.50 stddev:12.18 (16.8%) stddev-sans-first:12.06 10:38:47 INFO - PROCESS | 1651 | Values: 64.0 72.0 69.0 76.0 76.0 73.0 68.0 70.0 93.0 103.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.50 stddev:2.01 (2.4%) stddev-sans-first:2.05 10:38:47 INFO - PROCESS | 1651 | Values: 86.0 86.0 84.0 84.0 85.0 85.0 80.0 82.0 86.0 86.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:159.70 Median:150.00 stddev:23.89 (15.9%) stddev-sans-first:25.02 10:38:47 INFO - PROCESS | 1651 | Values: 149.0 149.0 150.0 146.0 149.0 150.0 149.0 151.0 220.0 184.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:3.13 (1.9%) stddev-sans-first:2.35 10:38:47 INFO - PROCESS | 1651 | Values: 161.0 171.0 168.0 166.0 168.0 169.0 172.0 165.0 166.0 167.0 10:38:47 INFO - PROCESS | 1651 | 10:38:47 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:70.50 stddev:8.04 (11.4%) stddev-sans-first:8.32 10:38:47 INFO - PROCESS | 1651 | Values: 67.0 67.0 68.0 77.0 84.0 87.0 73.0 66.0 66.0 65.0 10:38:47 INFO - PROCESS | 1651 | -------- Summary: end -------- 10:38:47 INFO - PROCESS | 1651 | 10:38:48 INFO - TEST-INFO | 1651: exit 0 10:38:48 INFO - TEST-OK | kraken | took 60264ms 10:38:48 INFO - SUITE-END | took 1179s 10:38:48 INFO - Completed test suite (00:19:39) 10:38:48 INFO - javascript benchmark 10:38:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.4833100869364, 3352.413327667659, 3354.144693103259, 3373.7469689773275, 3417.7265818734486, 5374.489423504766, 5682.488576028162, 5687.971560142204, 5707.743151558179, 5746.712664366781, 5655.208270842086, 5725.570582206341, 5737.942620573793, 5738.42615738426, 5805.593608447411, 5178.6892786432745, 5200.92198617021, 5209.505097015777, 5237.869053273661, 5282.07563676357, 4317.913641727164, 5156.329677141966, 5189.143791274454, 5190.299309593213, 5309.681419114846, 5241.973790131044, 5281.9207711884355, 5288.867778305554, 5290.074237008508, 5300.761465734032, 5270.710110943896, 5283.313169287987, 5296.549793267577, 5299.390570084451, 5316.9202461963105, 5016.247562865563, 5099.184130539096, 5202.687838729688, 5211.140161873286, 5239.633225674204, 5580.218769372291, 5582.358028826697, 5586.804461843816, 5612.635178713371, 5613.887722245573, 6075.17985072014, 6103.314362901373, 6115.788007244244, 6128.601640893372, 6132.877342453126, 6174.289956654998, 6198.163247961513, 6208.689565521751, 6210.689465526705, 6231.376862313778, 5700.087985922232, 5752.453516915886, 5765.019946609086, 5768.451997060272, 5821.94178058214, 6372.107904893226, 6378.234611846516, 6389.169407976933, 6394.552381333262, 6398.9040164397575, 5961.314448838439, 5970.76116955317, 5985.401459854067, 6140.754369825157, 6151.96924015377, 6128.392057524159, 6172.475339596095, 6233.781817636361, 6273.592216505913, 6278.372162783685, 6137.294211165684, 6178.845528861724, 6187.628742275478, 6188.721507532172, 6193.783217587363, 6070.908936365958, 6108.258823178584, 6189.566730328834, 6228.906566401508, 6235.469985051322, 4232.32282834745, 4846.224604063404, 4874.8537543873745, 4924.507549245119, 4945.851624451272, 3725.273571653556, 4330.155619654137, 4401.823927042882, 4413.977930110394, 4444.511103778582, 5655.4910058094965, 5737.598368114275, 5754.56840736938, 5772.5670574707065, 5805.651660900359, 5039.571636410946, 6145.40219542922, 6154.046122850965, 6154.107654390088, 6248.31268560458, 5742.598018138774, 5762.567807414461, 5762.625429347079, 5782.826515204551, 5785.942140578541, 4499.572540608702, 5345.7594408251725, 5359.490848369399, 5363.222332761728, 5398.946010539844, 6253.687315634291, 6256.061590761422, 6299.401556852092, 6325.082862984934, 6341.239051313687, 6162.0, 6235.625862448268, 6239.563230573816, 6245.28179259391, 6301.779437719658, 4906.0, 6338.271098823694, 6346.365363463617, 6384.201974753156, 6396.808095757135, 4659.860204193879, 6004.8799024020145, 6095.2685677718955, 6165.260168779775, 6177.413145751147, 6119.07606531266, 6120.540959427972, 6128.28924408451, 6172.567920245539, 6257.155284036597, 5839.0, 6067.271927368567, 6071.058985857199, 6074.453299203093, 6106.175666284995, 5823.155642431739, 6040.728167232573, 6041.24484439445, 6043.2748070231855, 6044.0, 5783.392743761844, 5823.679697616672, 5887.852803679943, 5910.290765108214, 5968.4926781224085, 6018.21763170785, 6058.606190597597, 6066.787662431793, 6070.817875463743, 6071.939280607137, 5745.224394706661, 5767.5674324425, 5792.304923409218, 5917.408259174048, 5920.111983202381], "value": 5638.432197054994, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.5155152005514, 478.56928764112297, 521.4759167037129, 525.4955242966754, 553.1923391847902, 30549.38901221974, 31052.37895242094, 33384.66615333846, 38899.805500972456, 39545.41818327263, 40493.987650308685, 40816.795916020455, 40842.0, 40906.386404203884, 40945.0, 9356.672516461942, 9555.0, 9567.282453815957, 9569.521523923811, 9579.0, 8578.18507241811, 8790.956045219766, 8801.0, 8886.111388861127, 8917.152870477326, 11386.60146894859, 11666.883331166664, 11674.708132296719, 11678.532858685643, 11685.7662846743, 11390.202685811997, 11440.370779607118, 11449.828252576219, 11465.942670286679, 11479.426028698574, 8578.056413794478, 8791.60437780295, 11008.504617292241, 11239.550417983271, 11316.660500185008, 37613.43579846305, 39591.0, 39646.60353396458, 39659.20681586352, 39727.0, 3237.951430728541, 3300.5214243934734, 3411.5394421752994, 3534.8409321580334, 3829.483019792348, 28685.139445816658, 28691.71308286911, 28702.0, 28927.710722892713, 29999.850000750073, 3090.922726931822, 3149.7322727568185, 3306.272620023591, 3339.2653616204398, 3339.916502087443, 446674.0, 505313.0, 506826.0, 509776.0, 523684.9999999962, 59985.0, 62099.75800484055, 62189.0, 62209.37790622036, 62211.688941555, 64861.67569162124, 65054.674726626065, 65065.0, 65102.67448662821, 65211.34788652148, 15911.204439777965, 16496.587585310463, 16502.83497165037, 16527.256273467723, 16553.6689266214, 12626.179298345578, 12699.619011429671, 12705.047121465926, 12711.936440317739, 12726.109172357847, 16110.919445402698, 16318.836811631969, 16706.164691765607, 16778.74831877523, 17831.92752264922, 6447.903281450782, 7461.440391970624, 7565.6217189140325, 7583.9620801895635, 7647.923520764833, 880.7049638371218, 944.6032666280179, 1024.6772266735954, 1026.3790406803926, 1039.6828967165088, 29753.553696694566, 29754.256143596584, 29974.700252997627, 30185.396292074034, 30275.697243027727, 1085.478970094359, 1093.6172339681048, 1094.141099237095, 1117.2905205194597, 1140.5551834784442, 14932.178730169944, 15205.695886082216, 15265.847341526665, 15304.693906121815, 15532.534023979299, 672572.0, 672981.6350918312, 675527.0, 678201.0, 680642.0, 41924.95187620273, 49087.75456122697, 49551.75224123929, 55198.724006379714, 56779.0, 1225.1791299828958, 1354.5462270139417, 1354.837419509665, 1365.5698454986648, 1373.3613869550707, 29330.97341593034, 29851.402971940006, 29993.700062999964, 30341.69658303477, 30655.08776666213, 49918.001639967726, 50796.238056429924, 50888.0, 50916.74541627416, 50925.49074509207, 577640.0, 695008.0, 873434.0, 876483.0, 877192.0, 40300.77593792548, 40314.9921251971, 40678.0, 41082.383764242964, 41097.58902411057, 44692.55307447014, 44706.55293447154, 44829.77585112053, 44937.550624494645, 45170.77414612906, 36703.632963670025, 36748.0, 37771.43342849914, 38788.41817372798, 38972.80513597414, 35976.92069237927, 36872.26255474929, 37074.6292537082, 37394.0, 38518.42222366723, 35198.82400588083, 36209.45685814768, 37037.814810925775, 37196.6280337204, 37532.24935501329, 32432.0, 32468.675313246564, 32790.0, 32850.50724239186, 33759.324813504085, 20530.870802106027, 23494.177703780286, 24729.629055563822, 24838.875805620857, 25663.486730264918], "value": 20238.975169203622, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.90083173384517, 333.11580157207277, 351.46401737350556, 379.12611430652333, 399.7521536647279, 14774.0, 15547.61130971725, 17127.486175414728, 20230.988450577486, 20277.493062673442, 19038.42884713457, 19272.132754026068, 19369.806301936977, 19444.22223111074, 20136.49658758532, 4093.713440059197, 5465.699386533749, 5750.913736293938, 5758.049921762904, 5767.6827774472595, 4228.302330115543, 6108.908366374508, 6324.525660575452, 6406.679666016704, 6442.0, 6240.126382306481, 6260.092286618437, 6389.137466442041, 6399.392057754506, 6402.135711678933, 6380.1705778249, 6412.93587064128, 6432.935670643327, 6435.292117867031, 6436.517261205381, 5319.9202011969855, 5464.808731694372, 5537.335519737658, 5537.972310138463, 5545.861353466155, 23536.176233831906, 24562.754372456224, 24690.753092469025, 24855.881485333204, 24888.751112489004, 2010.7687615924062, 2219.4007617943225, 2350.4828937633692, 2458.950820983588, 2692.8249663771794, 9639.518024098837, 10075.748106297327, 10135.493225338703, 10514.31656942304, 14182.43270269188, 1844.7417361569392, 1883.5102873252915, 1911.3119277060248, 1913.87559808612, 1931.893745843978, 86857.0, 133961.0, 150120.0, 157086.0, 157734.0, 15780.60548486297, 20016.49958751043, 20577.69133462999, 20949.162033518794, 21159.0, 34580.48129278063, 34659.6136154551, 35348.469772953424, 35627.57489700435, 35685.82157089198, 10350.844737328945, 10560.366378017343, 10674.946625266979, 10733.51699173539, 10927.617533386452, 8802.383833131747, 8835.823283534293, 8841.867371989425, 8948.552572371485, 8964.417312874642, 9405.57674904631, 9539.666111686058, 9559.713208603614, 9585.856212156823, 10036.193485172593, 3165.5568220449154, 3739.2334571412794, 3745.4943582616543, 3748.212875296164, 3758.2483503298904, 838.5304229631425, 896.6368620708492, 922.935394522377, 998.3909815012836, 1040.458961340099, 18543.25826966933, 18831.529211769815, 19491.610167796847, 19524.316648917218, 19770.703439448418, 908.9045650206765, 930.3627015494272, 934.6541779541614, 944.4663764972769, 970.3983530211173, 9785.36395134314, 9844.80310393788, 10464.319819211727, 10499.475026248658, 10513.52689128991, 608512.0, 612203.0, 612857.0, 617888.0, 623304.0, 88244.0, 108175.0, 124887.0, 128565.6184902284, 130069.0, 930.851063829784, 931.2456909903, 952.7332346942867, 963.4749061761357, 964.3876138652007, 17981.100944952963, 18177.636447271245, 18614.627707446045, 18638.254469821055, 18641.813581864008, 47620.76189619168, 55303.17045244405, 57190.71404643117, 57301.713491432274, 57427.1385929203, 61938.0, 68892.0, 73327.0, 74489.88265176135, 83604.58197708972, 7561.09266887977, 7722.53664780115, 7827.882581761392, 7907.881381779162, 7912.406569507195, 9346.626134954526, 11120.332780033224, 12176.45205965716, 12186.146969712036, 12327.952124069196, 10251.84622230682, 10402.739931501774, 10450.738731531772, 10680.679579612624, 10684.679459616224, 10421.895781042092, 10495.317804342998, 10570.630027948984, 10575.04824565762, 10649.562309088484, 10309.793804124025, 10393.792124157324, 10409.375437473776, 10449.582016719247, 10783.784324313627, 7042.0845290114, 7243.710251589878, 7258.165310989304, 7326.120865496175, 7752.0697516298405, 8448.746537603882, 8704.173103555153, 10344.224183186441, 10746.8387974182, 10825.512851921525], "value": 10201.82365011069, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.9807331977593, 1495.7905893174955, 1684.8146703862574, 1823.5441139715072, 1841.7237414387848, 8253.215944485271, 8701.08638592948, 8709.2597129244, 8721.389502734803, 8723.345749068814, 7363.5213711108745, 7656.732014379498, 7668.118166410864, 8166.060902996157, 8485.151484851513, 2285.622872226085, 2348.9060437582475, 2352.776486233805, 2353.329301149173, 2354.6232602783557, 2346.4251258441705, 2359.0, 2399.5320912422085, 2399.9880000599974, 2400.84394514356, 2230.631945728953, 2241.047554789208, 2256.4697296135396, 2263.241813992306, 2264.071730590458, 2060.443680206351, 2313.5372925414986, 2315.2128276386024, 2341.8946147423408, 2344.191254017361, 2283.7259528856644, 2367.135995361698, 2368.9170879019325, 2369.7393286738443, 2370.3837002379505, 8407.495550267004, 8451.492910425333, 8528.488290702579, 8529.616167272425, 8533.914660853436, 4458.353538736865, 5106.4893510648635, 5283.9471605284225, 5292.417834038252, 5293.788248470095, 3294.2423242654086, 3430.3310854383403, 3541.8052007139595, 3589.4436362363876, 3591.856325746967, 1038.6416686243233, 1044.7074819050679, 1057.0, 1066.06680033253, 1100.2793170473353, 10957.50691218897, 11125.777484450266, 11216.831747523795, 11231.719207019809, 11261.774764504746, 4485.215087359699, 4643.094596553639, 4730.101280756645, 4735.857924262277, 4740.6681532292405, 9727.319087663796, 9740.46427446483, 9743.756406089904, 9745.12293893553, 9768.07203315684, 5307.761150748225, 5310.230016647564, 5317.8936421271355, 5320.361556613231, 5325.3875804282225, 4109.97945010279, 4285.635720963753, 4289.935650965238, 4738.668293219511, 4820.927686084711, 3812.4281357796553, 3818.121831978605, 3900.2199560088097, 3906.4921560197563, 3935.370340745467, 1244.6639407360144, 1258.0564576567574, 1271.8028705550655, 1273.1151849464534, 1289.9742005159844, 761.1551178192202, 861.2980420957025, 898.8876390451194, 905.7373361725026, 914.9359544831931, 4997.525235102659, 5149.459306772735, 5411.377691565442, 5693.202951586781, 5806.361300256968, 855.1149560205158, 886.6586364249811, 919.5264438814016, 955.3360414511986, 965.7247684409909, 3671.210689701727, 3803.5625903021505, 3901.258760835432, 3940.9211815763524, 3949.743266687656, 8110.270075693216, 9744.707658770249, 10310.381377117397, 10455.790884182274, 10478.109360704426, 9711.077447642463, 10394.324368915995, 10394.792104157874, 10403.84394234087, 10422.166226701846, 7720.729774457867, 8078.071021832447, 8094.069182044141, 8102.351811855037, 8103.1491693371345, 5569.303837020372, 5631.408702086386, 5690.174924635822, 5820.126980952891, 5868.559858010752, 9500.287478439282, 9591.184749296388, 9674.854877176707, 9678.467684277432, 9721.222302215665, 9477.52612369365, 9689.176420004378, 9705.223582113555, 9866.901330986886, 9891.703248902544, 7062.0, 7231.4214862809795, 7323.523970941872, 7357.632118394166, 7360.07999000125, 6438.710258038306, 7854.685812567662, 7873.110453491006, 7955.4828936118965, 7962.323202527618, 7181.928180718126, 7201.3158749918675, 7202.171750248579, 7298.963505182653, 7361.852762944818, 7043.225245223124, 7101.964490177723, 7116.146062472537, 7145.46409019336, 7158.534695245001, 6600.405963463311, 6744.291849355944, 6748.93251067483, 6757.729690812313, 6759.695813688298, 4751.857444276678, 4801.807927682854, 4835.29888166221, 4868.391451068616, 4884.389451318585, 4761.40482439695, 4848.3454733610515, 4856.320115183807, 4956.6530342877095, 4995.825146119869], "value": 4457.60379100424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.7818775012616, 298.37043837502864, 307.2641024745734, 312.21166554450014, 313.14355238422894, 4564.763265999662, 5115.104856650085, 5127.974360128199, 5161.79352825887, 5212.2963399941, 4421.823127074921, 4617.753206634206, 4836.951630483694, 5065.645404821655, 5119.0, 3437.3297207044575, 3879.767213967171, 3921.3726803041886, 3932.2725295820364, 3936.921261574767, 3472.6700963408434, 3790.374588192945, 3923.7444017914136, 3946.0, 3967.642912137907, 2921.547160190163, 3773.5660399054186, 3876.856327383429, 3891.9610803892024, 3901.726879118463, 3869.729118961674, 3892.863749768773, 3899.6685281750943, 3909.6481316681634, 3927.9983604180893, 2836.9432611347866, 3084.3831233753117, 3346.6111563701033, 3373.1567108222944, 3376.7973921564785, 4328.372386004044, 4331.631811296043, 4984.227444746034, 4988.925166122511, 4991.675541089818, 3860.3244432224246, 4213.51544572375, 4335.6965012449145, 4419.580139886705, 4446.911061778778, 5339.5461385782255, 5362.613636931567, 5387.568994480433, 5388.838334849961, 5389.73051347435, 3133.5613014178034, 3282.3435312937268, 3714.0900479382726, 3724.2737666155112, 3732.42147467143, 4173.0, 4477.529859364785, 4756.429228492603, 5504.779808807642, 5702.54379649627, 3223.9677603223668, 3573.6605022522817, 3756.328433846912, 3771.5097037385513, 3792.563855156637, 3820.9426858597144, 3826.9617303826603, 3827.3684842001317, 3834.367329390675, 3843.5387753469763, 4432.889177770581, 4453.0, 4469.687121901501, 4470.731756094645, 4500.189965806187, 5097.413797413345, 5433.320834895638, 5496.972515137399, 5498.257735205776, 5499.7250137492965, 1014.2747935226201, 1124.775044991005, 1133.665568657248, 1214.0773012510379, 1236.4744983382207, 690.5960013392122, 759.4038679636459, 763.9121501027453, 788.6608758234014, 851.3231980575457, 4704.411948506437, 4756.9762151189025, 4766.356541866873, 4774.904501909942, 4784.856454306377, 4117.464729585135, 5152.562032227228, 5154.355705536808, 5154.768035438415, 5163.0, 4130.942327753204, 4721.409823772028, 4740.7392593407, 4769.664493941702, 4779.30700048498, 2612.660354153948, 2629.7107318194985, 2734.53512902807, 2739.7808175345926, 2743.3676962207455, 4870.439899411613, 5169.25045868347, 5208.906129712727, 5218.9739051304505, 5388.0, 4600.401947746772, 4954.281629163751, 5323.041852466593, 5356.5714742821265, 5514.889702205934, 4500.4149460569915, 5335.413104558418, 5789.334226563999, 5789.668376273481, 5816.563757718188, 5080.891430056485, 5596.580256480862, 5601.831945041656, 5622.353429355677, 5644.85887852807, 4698.271767875985, 5235.973465473188, 5523.530499907552, 5579.298314014264, 5583.776648934159, 5039.949600504095, 5744.741486633028, 5769.3942136076785, 5793.623414478046, 5810.0, 3149.73227275675, 3445.6898879101, 3731.533558305212, 3749.3813520768767, 3806.904827379227, 5407.026735187704, 5498.285222920995, 5593.552515798645, 5621.381648018796, 5676.120201368794, 4566.70316429431, 4760.524527245743, 4839.854804355875, 5424.880693601274, 5425.403205647297, 4644.953550464587, 5403.567714582745, 5444.210589464585, 5444.891102178013, 5452.890942181213, 4591.4260717410325, 4688.812447502061, 4838.0, 5209.74853717992, 5380.461953804588, 4161.001359673717, 4802.927956080592, 5239.423663396947, 5389.197617239092, 5416.945830541802, 4808.2547205183255, 4926.945293917794, 5036.597072234139, 5328.254044433705, 5340.439253878287], "value": 3857.0771047321246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.9378062193779, 695.8677851208273, 754.1252147508891, 760.4638729695564, 779.2713812585233, 14246.074005189656, 14789.0, 14807.407703691839, 14811.629709257273, 14831.332590033448, 13148.6712832179, 13197.934010329958, 13256.072074954756, 13270.933645331785, 13329.400176992034, 661.0843981086194, 683.770936736194, 685.2496516314627, 685.5715178013742, 686.1457485430645, 798.6046906781122, 822.6298165825373, 828.1263267253046, 834.8747687846811, 836.7322456813822, 841.9831603367929, 884.8451520983803, 885.0972008551274, 890.9866352004725, 891.4963045879058, 838.5220424358118, 871.2463718883184, 883.8674198870157, 884.0010787809798, 884.8628462588309, 518.6836030021684, 533.6131304803986, 533.7331334332833, 535.8526405238551, 537.9031774280628, 10823.567057317698, 13389.799153012713, 13870.514531991434, 14116.223607701573, 14426.495072672406, 12946.611601651966, 13121.147125436815, 13443.395047222899, 13549.796753048613, 13590.660233494144, 10104.89895101047, 11866.940665296705, 11893.522944550628, 11995.0, 11998.58004969822, 4324.740515569076, 4372.0, 4414.801333939949, 4432.445944256968, 4595.03504264105, 96397.0, 98196.01803982012, 99919.0008099917, 100152.0, 100242.99999999927, 10130.138938190335, 10185.694429167137, 10655.200859935536, 10655.520501577448, 10752.19358548112, 19877.105530251174, 20444.897775511028, 20456.386308410772, 20462.693059604117, 20496.59006819855, 7303.160136584254, 7369.152547457111, 7454.627268636546, 7641.312281894545, 7645.770626881203, 4074.2055299216963, 4109.671226301889, 4171.624553790173, 4228.746275223496, 4275.722078064916, 3289.490129030004, 3370.983145084259, 3396.9150771230916, 3668.229671768905, 3817.179306449071, 627.231641239478, 663.8572706868047, 687.848673291875, 693.8924466707668, 694.0803435448051, 543.5814422894349, 596.1772753600004, 634.3624657219465, 639.7748311982594, 654.5418207254941, 7994.0, 8711.25954293892, 8774.868376974351, 8824.823503529893, 8829.33779966505, 538.9947747450975, 577.4485366475006, 632.9525285603598, 651.4527796650837, 657.7993711917816, 4081.4898137732785, 4251.744895306291, 4257.74453532789, 4279.315309550457, 4368.541303163186, 79165.0, 81112.59443702862, 90706.02233754282, 104095.0, 130951.0, 71432.2856771436, 104164.0, 104476.0, 106881.46559267309, 109339.45330273297, 62185.37814621886, 64606.38484037843, 66965.66517167383, 80124.59937700274, 98145.0, 8106.83786324282, 8107.1892810718455, 8179.304759095544, 8199.631016604386, 8244.57515300842, 47481.05037899292, 51057.74471127621, 54591.0, 56919.430805691416, 57237.999999998334, 56482.0, 75934.62032689802, 77381.0, 77540.0, 80766.0, 12060.0, 12209.450574723982, 12215.511379545074, 12242.326672033123, 12755.02297143944, 13029.348532573524, 13325.267110309027, 13341.866581334063, 13435.380066098296, 13801.89584833231, 12330.136890417944, 12354.25874447536, 12393.504259829506, 12439.813402799145, 12668.113232073667, 10849.728756781144, 12199.817002744789, 12234.938825305817, 12595.370231488572, 13095.869041309465, 12421.19262247951, 12443.813342799684, 12772.87227127754, 12795.488180472677, 13006.674833129247, 10446.791064178826, 10546.894531054591, 10833.349999000086, 10911.618093366695, 11066.004059634672, 11102.111831053659, 11128.109751220045, 11364.715882103013, 11437.0, 11446.771064578828], "value": 7147.764962915654, "unit": "score"}], "name": "dromaeo_css", "value": 7232.1547419773615, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 115.0, 112.0, 118.0, 114.0, 114.0, 113.0, 119.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 114.0, 146.0, 118.0, 140.0, 135.0, 115.0, 143.0, 112.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 183.0, 182.0, 182.0, 182.0, 183.0, 184.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 83.0, 114.0, 78.0, 59.0, 59.0, 59.0, 82.0, 59.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 94.0, 70.0, 70.0, 70.0, 90.0, 68.0, 69.0, 94.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 87.0, 85.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0, 98.0, 92.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 90.0, 80.0, 99.0, 122.0, 129.0, 86.0, 81.0, 82.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 53.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 69.0, 76.0, 76.0, 73.0, 68.0, 70.0, 93.0, 103.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 84.0, 85.0, 85.0, 80.0, 82.0, 86.0, 86.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 146.0, 149.0, 150.0, 149.0, 151.0, 220.0, 184.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 171.0, 168.0, 166.0, 168.0, 169.0, 172.0, 165.0, 166.0, 167.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 77.0, 84.0, 87.0, 73.0, 66.0, 66.0, 65.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1473.3999999999999, "alertThreshold": 2.0}]} 10:38:48 INFO - Return code: 0 10:38:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:38:49 INFO - # TBPL SUCCESS # 10:38:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:38:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:38:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:38:49 INFO - 2016-11-25 10:38:49.060 screenresolution[1655:9806] starting screenresolution argv=screenresolution get 10:38:49 INFO - 2016-11-25 10:38:49.062 screenresolution[1655:9806] Display 0: 1600x1200x32@60 10:38:49 INFO - 2016-11-25 10:38:49.069 screenresolution[1656:9809] starting screenresolution argv=screenresolution list 10:38:49 INFO - Available Modes on Display 0 10:38:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:38:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:38:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:38:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:38:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:38:49 INFO - Intel Iris: 10:38:49 INFO - Chipset Model: Intel Iris 10:38:49 INFO - Type: GPU 10:38:49 INFO - Bus: Built-In 10:38:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:38:49 INFO - Vendor: Intel (0x8086) 10:38:49 INFO - Device ID: 0x0a2e 10:38:49 INFO - Revision ID: 0x0009 10:38:49 INFO - Displays: 10:38:49 INFO - Display: 10:38:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:38:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:38:49 INFO - Display Serial Number: 1600x1200 60 10:38:49 INFO - Main Display: Yes 10:38:49 INFO - Mirror: Off 10:38:49 INFO - Online: Yes 10:38:49 INFO - Rotation: Supported 10:38:49 INFO - Return code: 0 10:38:49 INFO - Running post-action listener: _resource_record_post_action 10:38:49 INFO - [mozharness: 2016-11-25 18:38:49.314203Z] Finished run-tests step (success) 10:38:49 INFO - Running post-run listener: _resource_record_post_run 10:38:49 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38859776; Write bytes: 655549440; Read time: 380; Write time: 1878 10:38:49 INFO - TinderboxPrint: CPU usage
53.4% 10:38:49 INFO - TinderboxPrint: I/O read bytes / time
38,859,776 / 380 10:38:49 INFO - TinderboxPrint: I/O write bytes / time
655,549,440 / 1,878 10:38:49 INFO - TinderboxPrint: CPU idle
2,259.8 (46.6%) 10:38:49 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 10:38:49 INFO - TinderboxPrint: CPU user
1,618.3 (33.4%) 10:38:49 INFO - TinderboxPrint: Swap in / out
386,871,296 / 0 10:38:49 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 333892096; Write bytes: 332388352; Read time: 25779; Write time: 603 10:38:49 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 37938176; Write bytes: 305429504; Read time: 366; Write time: 1227 10:38:49 INFO - Running post-run listener: _upload_blobber_files 10:38:49 INFO - Blob upload gear active. 10:38:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:38:49 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 10:38:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:38:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:51 INFO - (blobuploader) - INFO - Done attempting. 10:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:52 INFO - (blobuploader) - INFO - Done attempting. 10:38:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:38:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:38:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:54 INFO - (blobuploader) - INFO - Done attempting. 10:38:54 INFO - (blobuploader) - INFO - Iteration through files over. 10:38:54 INFO - Return code: 0 10:38:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:38:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:38:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad9323a2d1984c57b2d540b2db29fc3d20f52e2004ab95cb790cfb2035393de5ea307f230fd794f8d2880edaaf365c165cb4f05ef3a85b241f5f621e6cbed35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56329354ba3f168334bc2e45bc58db9e492ce9cdac0c4a1c8f2e999d1b6637014864e8b1b2f002992a75bb09878e4a49b6309a38e1501465ab71f7d4aa0b4c92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c60c49749cebb18ec09f0a29b72ff8389e6532f71f33b75a6e5cccce0767a95a26d2612fee08838d25088a5cf99a9c23286878a3764a48aeb842a3f7ba0446a5"} 10:38:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:38:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:38:54 INFO - Contents: 10:38:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad9323a2d1984c57b2d540b2db29fc3d20f52e2004ab95cb790cfb2035393de5ea307f230fd794f8d2880edaaf365c165cb4f05ef3a85b241f5f621e6cbed35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56329354ba3f168334bc2e45bc58db9e492ce9cdac0c4a1c8f2e999d1b6637014864e8b1b2f002992a75bb09878e4a49b6309a38e1501465ab71f7d4aa0b4c92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c60c49749cebb18ec09f0a29b72ff8389e6532f71f33b75a6e5cccce0767a95a26d2612fee08838d25088a5cf99a9c23286878a3764a48aeb842a3f7ba0446a5"} 10:38:54 INFO - Running post-run listener: copy_logs_to_upload_dir 10:38:54 INFO - Copying logs to upload dir... 10:38:54 INFO - mkdir: /builds/slave/test/build/upload/logs 10:38:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.689056 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 10:38:54.516806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 10:38:54.520027) ========= 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.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad9323a2d1984c57b2d540b2db29fc3d20f52e2004ab95cb790cfb2035393de5ea307f230fd794f8d2880edaaf365c165cb4f05ef3a85b241f5f621e6cbed35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56329354ba3f168334bc2e45bc58db9e492ce9cdac0c4a1c8f2e999d1b6637014864e8b1b2f002992a75bb09878e4a49b6309a38e1501465ab71f7d4aa0b4c92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c60c49749cebb18ec09f0a29b72ff8389e6532f71f33b75a6e5cccce0767a95a26d2612fee08838d25088a5cf99a9c23286878a3764a48aeb842a3f7ba0446a5"} build_url:https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011048 build_url: 'https://queue.taskcluster.net/v1/task/fRDTOFOSR_a8y5i9PTiTHQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ad9323a2d1984c57b2d540b2db29fc3d20f52e2004ab95cb790cfb2035393de5ea307f230fd794f8d2880edaaf365c165cb4f05ef3a85b241f5f621e6cbed35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/56329354ba3f168334bc2e45bc58db9e492ce9cdac0c4a1c8f2e999d1b6637014864e8b1b2f002992a75bb09878e4a49b6309a38e1501465ab71f7d4aa0b4c92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c60c49749cebb18ec09f0a29b72ff8389e6532f71f33b75a6e5cccce0767a95a26d2612fee08838d25088a5cf99a9c23286878a3764a48aeb842a3f7ba0446a5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 10:38:54.554039) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:38:54.554333) ========= 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.1kIUtgEapi/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.nc5nvvWFyV/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.005252 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 10:38:54.602269) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 10:38:54.602567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 10:38:54.602872) ========= ========= Total master_lag: 0.17 =========