builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0270 starttime: 1492370984.32 results: success (0) buildid: 20170416110007 builduid: cfc5a7c00a7f4a29a3d6f5bfe8304549 revision: d3551474de3d6fdb8b58e770c658efe39d24f15f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:44.322219) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:44.322749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:44.323088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:44.534393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:44.534802) ========= 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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 --2017-04-16 12:29:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.86M=0.001s 2017-04-16 12:29:45 (7.86 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.527851 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:45.086294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:45.086617) ========= 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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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.020002 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:45.135987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 12:29:45.136368) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d3551474de3d6fdb8b58e770c658efe39d24f15f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d3551474de3d6fdb8b58e770c658efe39d24f15f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 2017-04-16 12:29:45,229 truncating revision to first 12 chars 2017-04-16 12:29:45,229 Setting DEBUG logging. 2017-04-16 12:29:45,229 attempt 1/10 2017-04-16 12:29:45,229 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d3551474de3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-16 12:29:46,237 unpacking tar archive at: mozilla-central-d3551474de3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.264496 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 12:29:46.424848) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:46.425312) ========= script_repo_revision: d3551474de3d6fdb8b58e770c658efe39d24f15f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:46.425859) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:46.426224) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 12:29:46.444288) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-16 12:29:46.444680) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:29:46 INFO - MultiFileLogger online at 20170416 12:29:46 in /builds/slave/test 12:29:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:29:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:29:46 INFO - {'append_to_log': False, 12:29:46 INFO - 'base_work_dir': '/builds/slave/test', 12:29:46 INFO - 'blob_upload_branch': 'Firefox', 12:29:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:29:46 INFO - 'branch': 'Firefox', 12:29:46 INFO - 'buildbot_json_path': 'buildprops.json', 12:29:46 INFO - 'config_files': ('talos/mac_config.py',), 12:29:46 INFO - 'default_actions': ('clobber', 12:29:46 INFO - 'read-buildbot-config', 12:29:46 INFO - 'download-and-extract', 12:29:46 INFO - 'populate-webroot', 12:29:46 INFO - 'create-virtualenv', 12:29:46 INFO - 'install', 12:29:46 INFO - 'run-tests'), 12:29:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:29:46 INFO - 'download_minidump_stackwalk': True, 12:29:46 INFO - 'download_symbols': 'ondemand', 12:29:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:29:46 INFO - 'tooltool.py': '/tools/tooltool.py', 12:29:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:29:46 INFO - '/tools/misc-python/virtualenv.py')}, 12:29:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:29:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:29:46 INFO - 'gecko_profile': False, 12:29:46 INFO - 'gecko_profile_interval': 0, 12:29:46 INFO - 'installer_path': 'installer.exe', 12:29:46 INFO - 'log_level': 'info', 12:29:46 INFO - 'log_name': 'talos', 12:29:46 INFO - 'log_to_console': True, 12:29:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:29:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:29:46 INFO - 'opt_config_files': (), 12:29:46 INFO - 'pip_index': False, 12:29:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:29:46 INFO - 'cmd': ('bash', 12:29:46 INFO - '-c', 12:29:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:29:46 INFO - 'enabled': True, 12:29:46 INFO - 'halt_on_failure': False, 12:29:46 INFO - 'name': 'check_screen_resolution'},), 12:29:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:29:46 INFO - 'cmd': ('bash', 12:29:46 INFO - '-c', 12:29:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:29:46 INFO - 'enabled': True, 12:29:46 INFO - 'halt_on_failure': False, 12:29:46 INFO - 'name': 'check_screen_resolution'},), 12:29:46 INFO - 'run_cmd_checks_enabled': True, 12:29:46 INFO - 'suite': 'dromaeojs-e10s', 12:29:46 INFO - 'system_bits': '32', 12:29:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:29:46 INFO - 'title': 't-yosemite-r7-0270', 12:29:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:29:46 INFO - 'use_talos_json': True, 12:29:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:29:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:29:46 INFO - 'work_dir': 'build'} 12:29:46 INFO - [mozharness: 2017-04-16 19:29:46.657849Z] Running clobber step. 12:29:46 INFO - Running pre-action listener: _resource_record_pre_action 12:29:46 INFO - Running main action method: clobber 12:29:46 INFO - rmtree: /builds/slave/test/build 12:29:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:29:46 INFO - Running post-action listener: _resource_record_post_action 12:29:46 INFO - [mozharness: 2017-04-16 19:29:46.864174Z] Finished clobber step (success) 12:29:46 INFO - [mozharness: 2017-04-16 19:29:46.864295Z] Running read-buildbot-config step. 12:29:46 INFO - Running pre-action listener: _resource_record_pre_action 12:29:46 INFO - Running main action method: read_buildbot_config 12:29:46 INFO - Using buildbot properties: 12:29:46 INFO - { 12:29:46 INFO - "project": "", 12:29:46 INFO - "product": "firefox", 12:29:46 INFO - "script_repo_revision": "production", 12:29:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:29:46 INFO - "repository": "", 12:29:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:29:46 INFO - "buildid": "20170416110007", 12:29:46 INFO - "pgo_build": "False", 12:29:46 INFO - "basedir": "/builds/slave/test", 12:29:46 INFO - "buildnumber": 24, 12:29:46 INFO - "slavename": "t-yosemite-r7-0270", 12:29:46 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:29:46 INFO - "platform": "yosemite_r7", 12:29:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:29:46 INFO - "branch": "mozilla-central", 12:29:46 INFO - "repo_path": "mozilla-central", 12:29:46 INFO - "revision": "d3551474de3d6fdb8b58e770c658efe39d24f15f", 12:29:46 INFO - "stage_platform": "macosx64", 12:29:46 INFO - "builduid": "cfc5a7c00a7f4a29a3d6f5bfe8304549", 12:29:46 INFO - "slavebuilddir": "test" 12:29:46 INFO - } 12:29:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:29:46 INFO - Running post-action listener: _resource_record_post_action 12:29:46 INFO - [mozharness: 2017-04-16 19:29:46.867226Z] Finished read-buildbot-config step (success) 12:29:46 INFO - [mozharness: 2017-04-16 19:29:46.867371Z] Running download-and-extract step. 12:29:46 INFO - Running pre-action listener: _resource_record_pre_action 12:29:46 INFO - Running main action method: download_and_extract 12:29:46 INFO - mkdir: /builds/slave/test/build/tests 12:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:46 INFO - trying https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:29:46 INFO - Downloading https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:29:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:29:48 INFO - Downloaded 1325 bytes. 12:29:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:29:48 INFO - Using the following test package requirements: 12:29:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:29:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:29:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:29:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:29:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'jsshell-mac.zip'], 12:29:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:29:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:29:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:29:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:29:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:29:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:29:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:29:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:29:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:29:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:29:48 INFO - Fetch https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:29:49 INFO - Expected file size: 52665611 12:29:49 INFO - Obtained file size: 52665611 12:30:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:30:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:30:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:30:06 INFO - Fetch https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:30:08 INFO - Expected file size: 52665611 12:30:08 INFO - Obtained file size: 52665611 12:30:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:30:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:30:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:30:24 INFO - Expected file size: 14408802 12:30:24 INFO - Obtained file size: 14408802 12:30: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')]} 12:30:25 INFO - trying https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:30:25 INFO - Downloading https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:30:27 INFO - Downloaded 71442104 bytes. 12:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:30:27 INFO - mkdir: /builds/slave/test/properties 12:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:30:27 INFO - Contents: 12:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:30:27 INFO - Running post-action listener: _resource_record_post_action 12:30:27 INFO - Running post-action listener: set_extra_try_arguments 12:30:27 INFO - [mozharness: 2017-04-16 19:30:27.918459Z] Finished download-and-extract step (success) 12:30:27 INFO - [mozharness: 2017-04-16 19:30:27.918588Z] Running populate-webroot step. 12:30:27 INFO - Running pre-action listener: _resource_record_pre_action 12:30:27 INFO - Running main action method: populate_webroot 12:30:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:30:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:30:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:30:27 INFO - '--tppagecycles', 12:30:27 INFO - '7'], 12:30:27 INFO - 'tests': ['tsvgm']}}, 12:30:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:30:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:30:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:30:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:30:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:30:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['damp', 'tps']}, 12:30:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'tests': ['damp', 'tps']}, 12:30:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['dromaeo_dom']}, 12:30:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:30:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:30:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:30:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'other-e10s': {'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['a11yr', 12:30:27 INFO - 'ts_paint', 12:30:27 INFO - 'tpaint', 12:30:27 INFO - 'sessionrestore', 12:30:27 INFO - 'sessionrestore_no_auto_restore', 12:30:27 INFO - 'tabpaint']}, 12:30:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:30:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:30:27 INFO - 'bloom_basic_ref']}, 12:30:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['tsvgx', 12:30:27 INFO - 'tsvgr_opacity', 12:30:27 INFO - 'tart', 12:30:27 INFO - 'tscrollx', 12:30:27 INFO - 'cart', 12:30:27 INFO - 'tsvg_static']}, 12:30:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:30:27 INFO - 'tsvgr_opacity', 12:30:27 INFO - 'tart', 12:30:27 INFO - 'tscrollx', 12:30:27 INFO - 'cart', 12:30:27 INFO - 'tsvg_static']}, 12:30:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'talos_options': ['--disable-e10s'], 12:30:27 INFO - 'tests': ['tp5o']}, 12:30:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:30:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'talos_options': ['--disable-e10s', 12:30:27 INFO - '--xperf_path', 12:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:27 INFO - 'tests': ['tp5n']}, 12:30:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:30:27 INFO - 'talos_options': ['--xperf_path', 12:30:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:30:27 INFO - 'tests': ['tp5n']}}, 12:30:27 INFO - 'talos.zip': {'path': '', 12:30:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:30:27 INFO - Running post-action listener: _resource_record_post_action 12:30:27 INFO - [mozharness: 2017-04-16 19:30:27.938948Z] Finished populate-webroot step (success) 12:30:27 INFO - [mozharness: 2017-04-16 19:30:27.939068Z] Running create-virtualenv step. 12:30:27 INFO - Running pre-action listener: _resource_record_pre_action 12:30:27 INFO - Running main action method: create_virtualenv 12:30:27 INFO - Creating virtualenv /builds/slave/test/build/venv 12:30:27 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 12:30:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:30:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:30:28 INFO - Using real prefix '/tools/python27' 12:30:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:30:28 INFO - Installing distribute.............................................................................................................................................................................................done. 12:30:31 INFO - Installing pip.................done. 12:30:31 INFO - Return code: 0 12:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:30:32 INFO - Reading from file tmpfile_stdout 12:30:32 INFO - Output received: 12:30:32 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) 12:30:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:30:32 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')]} 12:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:32 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')]} 12:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:32 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:32 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 12:30:32 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 12:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:32 INFO - 'HOME': '/Users/cltbld', 12:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:32 INFO - 'LOGNAME': 'cltbld', 12:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:32 INFO - 'MOZ_NO_REMOTE': '1', 12:30:32 INFO - 'NO_EM_RESTART': '1', 12:30:32 INFO - 'PAGER': '/bin/cat', 12:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:32 INFO - 'PWD': '/builds/slave/test', 12:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:32 INFO - 'SHELL': '/bin/bash', 12:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:32 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:32 INFO - 'USER': 'cltbld', 12:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:32 INFO - 'XPC_FLAGS': '0x0', 12:30:32 INFO - 'XPC_SERVICE_NAME': '0', 12:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:32 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 12:30:32 INFO - Cleaning up... 12:30:32 INFO - Return code: 0 12:30:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:30:32 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')]} 12:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:32 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')]} 12:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:32 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:32 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 12:30:32 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 12:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:32 INFO - 'HOME': '/Users/cltbld', 12:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:32 INFO - 'LOGNAME': 'cltbld', 12:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:32 INFO - 'MOZ_NO_REMOTE': '1', 12:30:32 INFO - 'NO_EM_RESTART': '1', 12:30:32 INFO - 'PAGER': '/bin/cat', 12:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:32 INFO - 'PWD': '/builds/slave/test', 12:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:32 INFO - 'SHELL': '/bin/bash', 12:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:32 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:32 INFO - 'USER': 'cltbld', 12:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:32 INFO - 'XPC_FLAGS': '0x0', 12:30:32 INFO - 'XPC_SERVICE_NAME': '0', 12:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:32 INFO - Downloading/unpacking psutil>=3.1.1 12:30: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 12:30: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 12:30: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 12:30: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 12:30: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 12:30: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 12:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:36 INFO - Installing collected packages: psutil 12:30:36 INFO - Running setup.py install for psutil 12:30:36 INFO - building 'psutil._psutil_osx' extension 12:30:36 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 12:30:37 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 12:30:37 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 12:30:37 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 12:30:37 INFO - building 'psutil._psutil_posix' extension 12:30:37 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 12:30:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:30:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:30:37 INFO - ^ 12:30:37 INFO - 1 warning generated. 12:30:37 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 12:30:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:30:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:30:37 INFO - Successfully installed psutil 12:30:37 INFO - Cleaning up... 12:30:37 INFO - Return code: 0 12:30:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:30: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 mozsystemmonitor==0.3 12:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:37 INFO - 'HOME': '/Users/cltbld', 12:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:37 INFO - 'LOGNAME': 'cltbld', 12:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:37 INFO - 'MOZ_NO_REMOTE': '1', 12:30:37 INFO - 'NO_EM_RESTART': '1', 12:30:37 INFO - 'PAGER': '/bin/cat', 12:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:37 INFO - 'PWD': '/builds/slave/test', 12:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:37 INFO - 'SHELL': '/bin/bash', 12:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:37 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:37 INFO - 'USER': 'cltbld', 12:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:37 INFO - 'XPC_FLAGS': '0x0', 12:30:37 INFO - 'XPC_SERVICE_NAME': '0', 12:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:30:37 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 12:30:37 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 12:30:37 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 12:30:37 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 12:30:37 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 12:30:37 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 12:30:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:30:40 INFO - Installing collected packages: mozsystemmonitor 12:30:40 INFO - Running setup.py install for mozsystemmonitor 12:30:40 INFO - Successfully installed mozsystemmonitor 12:30:40 INFO - Cleaning up... 12:30:40 INFO - Return code: 0 12:30:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:30:40 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')]} 12:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:40 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')]} 12:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:40 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:40 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 12:30:40 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 12:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:40 INFO - 'HOME': '/Users/cltbld', 12:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:40 INFO - 'LOGNAME': 'cltbld', 12:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:40 INFO - 'MOZ_NO_REMOTE': '1', 12:30:40 INFO - 'NO_EM_RESTART': '1', 12:30:40 INFO - 'PAGER': '/bin/cat', 12:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:40 INFO - 'PWD': '/builds/slave/test', 12:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:40 INFO - 'SHELL': '/bin/bash', 12:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:40 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:40 INFO - 'USER': 'cltbld', 12:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:40 INFO - 'XPC_FLAGS': '0x0', 12:30:40 INFO - 'XPC_SERVICE_NAME': '0', 12:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:40 INFO - Downloading/unpacking jsonschema==2.5.1 12:30:40 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 12:30:40 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 12:30:40 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 12:30:40 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 12:30:40 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 12:30:40 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 12:30:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:30:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:43 INFO - Downloading functools32-3.2.3-2.tar.gz 12:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:30:44 INFO - warning: no files found matching '*.txt' 12:30:44 INFO - no previously-included directories found matching 'build' 12:30:44 INFO - no previously-included directories found matching 'dist' 12:30:44 INFO - no previously-included directories found matching '.git*' 12:30:44 INFO - Installing collected packages: jsonschema, functools32 12:30:44 INFO - Running setup.py install for functools32 12:30:44 INFO - warning: no files found matching '*.txt' 12:30:44 INFO - no previously-included directories found matching 'build' 12:30:44 INFO - no previously-included directories found matching 'dist' 12:30:44 INFO - no previously-included directories found matching '.git*' 12:30:44 INFO - Successfully installed jsonschema functools32 12:30:44 INFO - Cleaning up... 12:30:44 INFO - Return code: 0 12:30:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:44 INFO - 'HOME': '/Users/cltbld', 12:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:44 INFO - 'LOGNAME': 'cltbld', 12:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:44 INFO - 'MOZ_NO_REMOTE': '1', 12:30:44 INFO - 'NO_EM_RESTART': '1', 12:30:44 INFO - 'PAGER': '/bin/cat', 12:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:44 INFO - 'PWD': '/builds/slave/test', 12:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:44 INFO - 'SHELL': '/bin/bash', 12:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:44 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:44 INFO - 'USER': 'cltbld', 12:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:44 INFO - 'XPC_FLAGS': '0x0', 12:30:44 INFO - 'XPC_SERVICE_NAME': '0', 12:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:30:44 INFO - Cleaning up... 12:30:44 INFO - Return code: 0 12:30:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:44 INFO - 'HOME': '/Users/cltbld', 12:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:44 INFO - 'LOGNAME': 'cltbld', 12:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:44 INFO - 'MOZ_NO_REMOTE': '1', 12:30:44 INFO - 'NO_EM_RESTART': '1', 12:30:44 INFO - 'PAGER': '/bin/cat', 12:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:44 INFO - 'PWD': '/builds/slave/test', 12:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:44 INFO - 'SHELL': '/bin/bash', 12:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:44 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:44 INFO - 'USER': 'cltbld', 12:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:44 INFO - 'XPC_FLAGS': '0x0', 12:30:44 INFO - 'XPC_SERVICE_NAME': '0', 12:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:44 INFO - Downloading/unpacking blobuploader==1.2.4 12:30:44 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 12:30:44 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 12:30:44 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 12:30:44 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 12:30:44 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 12:30:44 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 12:30:47 INFO - Downloading blobuploader-1.2.4.tar.gz 12:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:30:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:30:47 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 12:30:47 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 12:30:47 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 12:30:47 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 12:30:47 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 12:30:47 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 12:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:30:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:30:48 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 12:30:48 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 12:30:48 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 12:30:48 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 12:30:48 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 12:30:48 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 12:30:48 INFO - Downloading docopt-0.6.1.tar.gz 12:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:30:48 INFO - Installing collected packages: blobuploader, requests, docopt 12:30:48 INFO - Running setup.py install for blobuploader 12:30:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:30:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:30:48 INFO - Running setup.py install for requests 12:30:49 INFO - Running setup.py install for docopt 12:30:49 INFO - Successfully installed blobuploader requests docopt 12:30:49 INFO - Cleaning up... 12:30:49 INFO - Return code: 0 12:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:49 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')]} 12:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:49 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')]} 12:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:49 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:49 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 12:30:49 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 12:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:49 INFO - 'HOME': '/Users/cltbld', 12:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:49 INFO - 'LOGNAME': 'cltbld', 12:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:49 INFO - 'MOZ_NO_REMOTE': '1', 12:30:49 INFO - 'NO_EM_RESTART': '1', 12:30:49 INFO - 'PAGER': '/bin/cat', 12:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:49 INFO - 'PWD': '/builds/slave/test', 12:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:49 INFO - 'SHELL': '/bin/bash', 12:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:49 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:49 INFO - 'USER': 'cltbld', 12:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:49 INFO - 'XPC_FLAGS': '0x0', 12:30:49 INFO - 'XPC_SERVICE_NAME': '0', 12:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:49 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-ZQnQTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:49 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-AmhBcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-2u3OSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-0tEpN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-jdc8D1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-71rJd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-dgbp6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-YiK03Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-m8_AGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-KT5I3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-b7J4iE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:50 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-KAskn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:51 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-X7r5AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:51 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-ZC6LE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:51 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-wQ2OvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:51 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-_fBapj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:51 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-xpDOxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:30:51 INFO - Running setup.py install for manifestparser 12:30:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:30:51 INFO - Running setup.py install for mozcrash 12:30:51 INFO - Running setup.py install for mozdebug 12:30:52 INFO - Running setup.py install for mozdevice 12:30:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Running setup.py install for mozfile 12:30:52 INFO - Running setup.py install for mozhttpd 12:30:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Running setup.py install for mozinfo 12:30:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Running setup.py install for mozInstall 12:30:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:30:52 INFO - Running setup.py install for mozleak 12:30:52 INFO - Running setup.py install for mozlog 12:30:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Running setup.py install for moznetwork 12:30:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Running setup.py install for mozprocess 12:30:53 INFO - Running setup.py install for mozprofile 12:30:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Running setup.py install for mozrunner 12:30:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:30:53 INFO - Running setup.py install for mozscreenshot 12:30:53 INFO - Running setup.py install for moztest 12:30:54 INFO - Running setup.py install for mozversion 12:30:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:30:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:30:54 INFO - Cleaning up... 12:30:54 INFO - Return code: 0 12:30:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:54 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')]} 12:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:54 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')]} 12:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:54 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:54 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 12:30:54 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 12:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:54 INFO - 'HOME': '/Users/cltbld', 12:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:54 INFO - 'LOGNAME': 'cltbld', 12:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:54 INFO - 'MOZ_NO_REMOTE': '1', 12:30:54 INFO - 'NO_EM_RESTART': '1', 12:30:54 INFO - 'PAGER': '/bin/cat', 12:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:54 INFO - 'PWD': '/builds/slave/test', 12:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:54 INFO - 'SHELL': '/bin/bash', 12:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:54 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:54 INFO - 'USER': 'cltbld', 12:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:54 INFO - 'XPC_FLAGS': '0x0', 12:30:54 INFO - 'XPC_SERVICE_NAME': '0', 12:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-CQSqAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:30:54 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)) 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Sx16T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:30:54 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)) 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Q6F2rQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:30:54 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)) 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-nZyYtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-JDaS_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:30:54 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)) 12:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:30:54 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-YvJjpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-YdGd5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-0YHrNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-sjeq_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-4exKGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-aIWRxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-W8F4Zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-OUObem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-0BOJXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:30:55 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)) 12:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-eR1TwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:30:56 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)) 12:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:30:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-DVd72I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:30:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:30:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-QToyU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:30:56 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)) 12:30:56 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)) 12:30:56 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)) 12:30:56 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)) 12:30:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:30:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:30:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:30:58 INFO - Downloading blessings-1.6.tar.gz 12:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:30:58 INFO - Installing collected packages: blessings 12:30:58 INFO - Running setup.py install for blessings 12:30:59 INFO - Successfully installed blessings 12:30:59 INFO - Cleaning up... 12:30:59 INFO - Return code: 0 12:30:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:30:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:30:59 INFO - Reading from file tmpfile_stdout 12:30:59 INFO - Current package versions: 12:30:59 INFO - blessings == 1.6 12:30:59 INFO - blobuploader == 1.2.4 12:30:59 INFO - docopt == 0.6.1 12:30:59 INFO - functools32 == 3.2.3-2 12:30:59 INFO - jsonschema == 2.5.1 12:30:59 INFO - manifestparser == 1.1 12:30:59 INFO - mozInstall == 1.12 12:30:59 INFO - mozcrash == 1.0 12:30:59 INFO - mozdebug == 0.1 12:30:59 INFO - mozdevice == 0.50 12:30:59 INFO - mozfile == 1.2 12:30:59 INFO - mozhttpd == 0.7 12:30:59 INFO - mozinfo == 0.9 12:30:59 INFO - mozleak == 0.1 12:30:59 INFO - mozlog == 3.4 12:30:59 INFO - moznetwork == 0.27 12:30:59 INFO - mozprocess == 0.25 12:30:59 INFO - mozprofile == 0.28 12:30:59 INFO - mozrunner == 6.13 12:30:59 INFO - mozscreenshot == 0.1 12:30:59 INFO - mozsystemmonitor == 0.3 12:30:59 INFO - moztest == 0.8 12:30:59 INFO - mozversion == 1.4 12:30:59 INFO - psutil == 3.1.1 12:30:59 INFO - requests == 1.2.3 12:30:59 INFO - wsgiref == 0.1.2 12:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:59 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:59 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 12:30:59 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 12:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:59 INFO - 'HOME': '/Users/cltbld', 12:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:59 INFO - 'LOGNAME': 'cltbld', 12:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:59 INFO - 'MOZ_NO_REMOTE': '1', 12:30:59 INFO - 'NO_EM_RESTART': '1', 12:30:59 INFO - 'PAGER': '/bin/cat', 12:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:59 INFO - 'PWD': '/builds/slave/test', 12:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:59 INFO - 'SHELL': '/bin/bash', 12:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:59 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:59 INFO - 'USER': 'cltbld', 12:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:59 INFO - 'XPC_FLAGS': '0x0', 12:30:59 INFO - 'XPC_SERVICE_NAME': '0', 12:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 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)) 12:30:59 INFO - Cleaning up... 12:30:59 INFO - Return code: 0 12:30:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:30:59 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 0x106004b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106007768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f833259e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, '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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 12:30:59 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 12:30:59 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 12:30:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:30:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:59 INFO - 'HOME': '/Users/cltbld', 12:30:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:59 INFO - 'LOGNAME': 'cltbld', 12:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:59 INFO - 'MOZ_NO_REMOTE': '1', 12:30:59 INFO - 'NO_EM_RESTART': '1', 12:30:59 INFO - 'PAGER': '/bin/cat', 12:30:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:59 INFO - 'PWD': '/builds/slave/test', 12:30:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:59 INFO - 'SHELL': '/bin/bash', 12:30:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:30:59 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:30:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:59 INFO - 'USER': 'cltbld', 12:30:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:59 INFO - 'XPC_FLAGS': '0x0', 12:30:59 INFO - 'XPC_SERVICE_NAME': '0', 12:30:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:31:00 INFO - Cleaning up... 12:31:00 INFO - Return code: 0 12:31:00 INFO - Running post-action listener: _resource_record_post_action 12:31:00 INFO - Running post-action listener: _start_resource_monitoring 12:31:00 INFO - Starting resource monitoring. 12:31:00 INFO - [mozharness: 2017-04-16 19:31:00.073850Z] Finished create-virtualenv step (success) 12:31:00 INFO - [mozharness: 2017-04-16 19:31:00.074660Z] Running install step. 12:31:00 INFO - Running pre-action listener: _resource_record_pre_action 12:31:00 INFO - Running main action method: install 12:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:31:00 INFO - Reading from file tmpfile_stdout 12:31:00 INFO - Detecting whether we're running mozinstall >=1.0... 12:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:31:00 INFO - Reading from file tmpfile_stdout 12:31:00 INFO - Output received: 12:31:00 INFO - Usage: mozinstall [options] installer 12:31:00 INFO - Options: 12:31:00 INFO - -h, --help show this help message and exit 12:31:00 INFO - -d DEST, --destination=DEST 12:31:00 INFO - Directory to install application into. [default: 12:31:00 INFO - "/builds/slave/test"] 12:31:00 INFO - --app=APP Application being installed. [default: firefox] 12:31:00 INFO - mkdir: /builds/slave/test/build/application 12:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:31:20 INFO - Reading from file tmpfile_stdout 12:31:20 INFO - Output received: 12:31:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:31:20 INFO - Running post-action listener: _resource_record_post_action 12:31:20 INFO - [mozharness: 2017-04-16 19:31:20.005435Z] Finished install step (success) 12:31:20 INFO - [mozharness: 2017-04-16 19:31:20.005580Z] Running run-tests step. 12:31:20 INFO - Running pre-action listener: _resource_record_pre_action 12:31:20 INFO - Running main action method: run_tests 12:31:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:20 INFO - 2017-04-16 12:31:20.019 screenresolution[1909:14958] starting screenresolution argv=screenresolution get 12:31:20 INFO - 2017-04-16 12:31:20.022 screenresolution[1909:14958] Display 0: 1600x1200x32@60 12:31:20 INFO - 2017-04-16 12:31:20.027 screenresolution[1910:14961] starting screenresolution argv=screenresolution list 12:31:20 INFO - Available Modes on Display 0 12:31:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:20 INFO - Intel Iris: 12:31:20 INFO - Chipset Model: Intel Iris 12:31:20 INFO - Type: GPU 12:31:20 INFO - Bus: Built-In 12:31:20 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:20 INFO - Vendor: Intel (0x8086) 12:31:20 INFO - Device ID: 0x0a2e 12:31:20 INFO - Revision ID: 0x0009 12:31:20 INFO - Displays: 12:31:20 INFO - Display: 12:31:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:20 INFO - Display Serial Number: 1600x1200 60 12:31:20 INFO - Main Display: Yes 12:31:20 INFO - Mirror: Off 12:31:20 INFO - Online: Yes 12:31:20 INFO - Rotation: Supported 12:31:20 INFO - Return code: 0 12:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:31:20 INFO - Python 2.7.3 12:31:20 INFO - Return code: 0 12:31:20 INFO - grabbing minidump binary from tooltool 12:31: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')]} 12:31:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2d30>, '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 0x105d08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:31:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:31:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:31:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:31:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:31:20 INFO - Return code: 0 12:31:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:31:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:31:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:31:20 INFO - ENV: RUST_BACKTRACE is now 1 12:31:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:31:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0270', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0270 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mvdg9tAsKI/Render', 12:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:20 INFO - 'HOME': '/Users/cltbld', 12:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:20 INFO - 'LOGNAME': 'cltbld', 12:31:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:20 INFO - 'MOZ_NO_REMOTE': '1', 12:31:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:20 INFO - 'NO_EM_RESTART': '1', 12:31:20 INFO - 'PAGER': '/bin/cat', 12:31:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:20 INFO - 'PWD': '/builds/slave/test', 12:31:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:20 INFO - 'RUST_BACKTRACE': '1', 12:31:20 INFO - 'SHELL': '/bin/bash', 12:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dbDUF23JPE/Listeners', 12:31:20 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 12:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:20 INFO - 'USER': 'cltbld', 12:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:20 INFO - 'XPC_FLAGS': '0x0', 12:31:20 INFO - 'XPC_SERVICE_NAME': '0', 12:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0270', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:31:20 INFO - mozversion application_buildid: 20170416110007 12:31:20 INFO - mozversion application_changeset: d3551474de3d6fdb8b58e770c658efe39d24f15f 12:31:20 INFO - mozversion application_display_name: Nightly 12:31:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:31:20 INFO - mozversion application_name: Firefox 12:31:20 INFO - mozversion application_remotingname: firefox 12:31:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:31:20 INFO - mozversion application_vendor: Mozilla 12:31:20 INFO - mozversion application_version: 55.0a1 12:31:20 INFO - mozversion platform_buildid: 20170416110007 12:31:20 INFO - mozversion platform_changeset: d3551474de3d6fdb8b58e770c658efe39d24f15f 12:31:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:31:20 INFO - mozversion platform_version: 55.0a1 12:31:20 INFO - using testdate: 1492371080 12:31:20 INFO - actual date: 1492371080 12:31:20 INFO - starting webserver on 'localhost:49384' 12:31:20 INFO - SUITE-START | Running 2 tests 12:31:20 INFO - TEST-START | dromaeo_css 12:31:20 INFO - operating with platform_type : mac_ 12:31:20 INFO - Initialising browser for dromaeo_css test... 12:31:20 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmp5x6FM1/profile http://localhost:49384/getInfo.html) 12:31:23 INFO - PID 1920 | 2017-04-16 12:31:23.326 plugin-container[1922:15131] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 12:31:23 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:23 INFO - PID 1920 | 2017-04-16 12:31:23.330 plugin-container[1922:15131] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 12:31:23 INFO - PID 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:23 INFO - PID 1920 | 2017-04-16 12:31:23.330 plugin-container[1922:15131] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:31:23 INFO - PID 1920 | __metrics Screen width/height:1600/1200 12:31:23 INFO - PID 1920 | colorDepth:24 12:31:23 INFO - PID 1920 | Browser inner width/height: 1024/689 12:31:23 INFO - PID 1920 | __metrics 12:31:23 ERROR - PID 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:31:24 INFO - TEST-INFO | 1920: exit 0 12:31:24 INFO - Browser initialized. 12:31:24 INFO - Running cycle 1/1 for dromaeo_css test... 12:31:24 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmp5x6FM1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:27 INFO - PID 1923 | 2017-04-16 12:31:27.026 plugin-container[1924:15259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 12:31:27 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:27 INFO - PID 1923 | 2017-04-16 12:31:27.027 plugin-container[1924:15259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 12:31:27 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:31:27 INFO - PID 1923 | 2017-04-16 12:31:27.027 plugin-container[1924:15259] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:31:36 INFO - PID 1923 | RSS: Main: 237330432 12:31:36 INFO - PID 1923 | 12:34:40 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49384/tests/dromaeo/cssquery-ext.html) 12:34:42 INFO - PID 1923 | RSS: Main: 252751872 12:34:42 INFO - PID 1923 | 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | A coding exception was thrown and uncaught in a Task. 12:36:24 INFO - PID 1923 | 12:36:24 ERROR - PID 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:24 INFO - PID 1923 | Full stack: 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | A coding exception was thrown and uncaught in a Task. 12:36:24 INFO - PID 1923 | 12:36:24 ERROR - PID 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:24 INFO - PID 1923 | Full stack: 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | A coding exception was thrown and uncaught in a Task. 12:36:24 INFO - PID 1923 | 12:36:24 ERROR - PID 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:24 INFO - PID 1923 | Full stack: 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | ************************* 12:36:24 INFO - PID 1923 | A coding exception was thrown and uncaught in a Task. 12:36:24 INFO - PID 1923 | 12:36:24 ERROR - PID 1923 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:36:24 INFO - PID 1923 | Full stack: 12:36:24 INFO - PID 1923 | ************************* 12:37:44 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-ext.html (next: http://localhost:49384/tests/dromaeo/cssquery-jquery.html) 12:37:44 INFO - PID 1923 | RSS: Main: 256073728 12:37:44 INFO - PID 1923 | 12:40:46 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49384/tests/dromaeo/cssquery-mootools.html) 12:40:47 INFO - PID 1923 | RSS: Main: 265846784 12:40:47 INFO - PID 1923 | 12:43:49 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49384/tests/dromaeo/cssquery-prototype.html) 12:43:49 INFO - PID 1923 | RSS: Main: 258330624 12:43:49 INFO - PID 1923 | 12:46:52 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49384/tests/dromaeo/cssquery-yui.html) 12:46:53 INFO - PID 1923 | RSS: Main: 267784192 12:46:53 INFO - PID 1923 | 12:49:54 INFO - PID 1923 | Cycle 1(1): loaded http://localhost:49384/tests/dromaeo/cssquery-yui.html (next: http://localhost:49384/tests/dromaeo/cssquery-dojo.html) 12:49:55 INFO - PID 1923 | RSS: Main: 263569408 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | __start_tp_report 12:49:55 INFO - PID 1923 | _x_x_mozilla_page_load 12:49:55 INFO - PID 1923 | _x_x_mozilla_page_load_details 12:49:55 INFO - PID 1923 | |i|pagename|runs| 12:49:55 INFO - PID 1923 | |0;dojo.html;3078.8152710837353;3633.3096711624808;3684.9631503684955;3689.8893033209006;3690.6309369063097;6123.4656464211685;6259.280182778982;6322.778702745405;6332.113504109423;6384;5146.279520867081;6229.688515574215;6255.374462553742;6262.9979203327475;6329.398707122828;5530.281063461744;5567.777288908449;5587.8603034924245;5599.664020158783;5669.971650141743;5495.554478257177;5576.609637325389;5626.240457538242;5627.971860140694;5649.350324712649;5492.642978206443;5537.723113844332;5587.497125258706;5618.550515958714;5636.323641163066;5539.612227144102;5554.694491802949;5582.302212223472;5597.468240517144;5653.1520271959125;5486.094794358926;5506.587005974568;5518.613697041209;5529.751161197715;5537.335519737617;6085.908711369333;6164.660943648098;6215.68921553925;6221.844453888643;6310.6213627182515;6688.9665551672415;6767.323267673242;6768.458523318172;6775.525713200078;6787.864242715167;6863.485238607124;6884.552504087218;6892.276310987324;6896.413804826596;6898.482613803984;6247.656378899113;6256.99888017915;6312.116303717529;6332.208473940757;6333.778317758856;6977.686004129826;7018.649067546602;7027.402670772939;7056.576605403692;7096;6352.904706429407;6438.420542151229;6488.383603557654;6576.638284894281;6685.565438246498;6006.759729610854;6710.295418981034;6745.763898263635;6787.2534021257625;6834.316568343126;6407.423331900151;6746.561473504206;6756.729730810811;6771.796846094625;6772.5936443813525;6526.543141980016;6557.8360540985905;6651.135352404156;6734.427573656196;6759.898601520981;5441;5447.210154527571;5460.208269800856;5467.863303417367;5471.316085489314;4105.302098643178;4788.592969597554;4804.303376010459;4804.8798780030775;4809.422869255712;6185;6277.96861015692;6298.811035668938;6322.4942004639515;6443.097966284724;6841.965790171017;6879.415249703731;6907.930920690829;6913.688884000232;6922.861542769117;6381.266154392211;6438.6136831790245;6439.871202576016;6449.5485316027425;6554.082428460019;5902.822915312548;5951.4941229995065;5954.791582294599;6111.51107911366;6121.020636698196;6851.828704282532;6958.652067396712;7038.472114591528;7057.752978645723;7094.645267736696;5664.405237450009;6862.279460656559;6875.106236189264;6879.621620810904;6910.101686780888;5578.856334451347;6923.653817309215;6937.132858392701;7039.718411263492;7047.612381319077;6482.481401487964;6593.3736295051895;6696.062551242732;6727.091842601186;6727.360900714424;6885.965570172117;6891.931080689329;6894.827629309308;6907.89638155418;6912.101426814482;6290.591111577733;6349.49204063683;6429.196350456193;6435.967820160869;6601.997828879904;6552.08270842092;6633.104530888461;6634.170728658918;6634.237062737654;6641.667916604247;5719.170720245625;6148.50811935053;6550.410463058348;6555.951047832324;6559.245686746085;6425.10048593188;6472.126262954441;6539.313372095861;6543.836404089936;6606.6036037837885;5328.49379308965;6396.520260980352;6439.130717353098;6461.935380646133;6469.7412103515335 12:49:55 INFO - PID 1923 | |1;ext.html;569.2912324156426;802.8875957365968;832.5129799067544;1288.5876519513754;1308.136629824315;37856.81071594641;39037.60962390375;39102.80448597753;39123.60876391235;39163.216735665344;39225.607743922556;39502.20995580087;39563.813085607406;39612.20775584494;39704.20591588166;8003.319717823977;8124.02511698594;8562.529060901648;8851.468911865277;8889.95555022224;8771.517566533837;8878.42290251135;8886.688965886195;8895.154960278764;8908;8829.984551776532;8853.468791872509;8888.422252553595;8911.554422278892;8912.73261802147;8804.163604457566;8826.47041177525;8841.38110332277;8846.20384165425;8872.290216782674;8551.444156129832;8551.914480855236;8571.100034496412;8603.268722158624;8603.440776349516;38550.22899542021;38744.225115497815;38756;38786.836394907914;38792;3558.1460449492192;3567.8037707926055;3603.6756691897854;3613.2231570212266;3614.3855544557227;31449.37101257962;31485.79599428041;31526.211844703837;31567.36865262705;31567.842160789278;3164.0507847645845;3178.9205269868207;3189.8245596492184;3190.122716253026;3197.9200519986953;233363;236912.78523288487;263800.6809965957;291638;291779.5411022953;48921;49086.26370604444;49349.0130197394;49397.76505587389;49432;51720.70698232499;51927.221091683656;51980.48019519756;52002.69993250199;52068.43794686165;14619;14637.560873173821;14679.633009174857;14690.55928322152;14697.55907322782;12119.818202726967;12122.696932576757;12134;12146.392680365947;12153.93923030397;13904.374303156383;13954.372053257628;13957.58127256184;14013.929930350487;14058.648533786532;4988.476209997971;5150.510701483353;5932.644041357532;5967.16459695643;5990.430909063632;942.3544871762728;1046.2989796836139;1050.56926660069;1052.8683914510686;1065.717584840018;24644.50710985806;24645.876770616032;24737.381565460648;24927.875360623082;25073.49853002966;1012.6607586458618;1149.6665966869514;1180.1267062373781;1185.6324539392815;1192.1833544022372;10911.563537458413;12729.333180236557;12798.104132710621;12858.421371038326;12873.485060597659;293843;296403.0359696419;296782;297173;297683;44667.77666111714;55638.72180639152;55681;55732.72133639306;55841.441585584435;1326.7943468762357;1558.6648870492897;1565.3816742386693;1573.8504842039988;1580.6917651057934;23270.418239544146;24546;24689.62965555482;24708.752912470645;24748;39754.80122599369;39853.80073099616;40171;40272;40429.78710638686;279573;282339;283010;283436;284258;27397.45205095847;35165.82417087898;35561.82219088888;35771.64228357683;35824.82087589546;44765.77617112024;45498.31752523649;45544.8613784658;45576.316355254035;45627.771861140485;30249.697503024687;30295.651306105312;30643;31384.48924086022;31394.372112558078;31274.374512509166;31371.686283136878;31394.529082064244;31409.528857066703;31417.915670963543;37448.6255137456;37470.250594988494;37506.62493375031;37524.249515010095;37586.68446604356;30588.694113058584;31115.68884311128;31549.36901261916;31841.567129478764;31892.84053579717;24083.397915052265;24134.275971720876;24326.756732433158;24485.51028979446;24602.50794984126 12:49:55 INFO - PID 1923 | |2;jquery.html;430.67914403769197;438.6249756458227;442.0297447103609;455.79033644523525;458.9013362127144;19064.809351906493;20212.292569760062;20892.58214835706;21025.789742102574;21100.57798844022;21347;21387.57224855502;21598.920053997277;21764;23201.767982320212;6754.290799466059;6804.693788779505;7027.0513480679965;7029.964850175742;7053.541519801196;6842.281560436158;7036.190838053638;7047.260037696019;7061.576305421666;7069.505134640577;6726.394624483795;6885.758998435056;6963.582185068887;7042.436605071582;7050.788476345692;6643.734250629969;6826.897596536056;6876.828079298008;6894.172699276069;6900.413464855494;6761.323867613249;6801.251862295144;6842.521023528355;6844.691988860513;6849.623270720109;22954;29218.269543261376;29717.85141074281;29852;30076;2286.0512887151813;2327.359976006595;2343.238447504568;2344.284993077111;2347.0611755297846;17197.484075477754;18065.7290140648;19119.426417207505;19134.330298439487;19249.422517324503;2303.6314189729565;2335.6963594732747;2399.4481269308158;2399.6520504526748;2401.2556107606697;134776.30447391095;159866;160454;163385;163800.18099909544;18609.162587683586;24413;24439.877800610884;24599.754002460104;24836.37909052288;32692.673073269438;41266;41633.583664163576;41681.79159104185;41802.58197417987;11181.888181118247;11359.772804544029;11439.542418303341;11605.419729013516;11746.29522228669;7321.450891183183;8997.955010224907;9051.185393314634;9194.862077068849;9453.76365590852;9208.309376796768;11274.436278186222;11395.487203075882;11441.885581144248;11543.595974140864;3791.2227993261317;4521.344405061248;4554.544545545486;4568.984470249619;4589.33454649074;957.8036502516967;970.5583959298643;976.7460460280379;999.240577161348;1021.1167340250778;16702.58243543921;19861.404157875288;20532.794672053387;21248.893755531433;21541.78458215429;936.3352020065694;1005.261133067195;1028.8096702110133;1034.8395998620226;1038.3199004651965;10599.84100238497;10956.835647465294;10969.506372213269;10995.175361847894;11023.338599684044;496635;498090;498393;513388;514708;122797;127193.72806271819;134998.3250083763;139398;141117;1100.350793032115;1239.7334573066835;1259.4647274907945;1261.4323554400557;1266.120046567645;21390;22411.615216012706;22759.31722048341;22788.772112278664;22798.430039249153;40694.79652601836;48854.75572622114;58696;59978.700106499185;60808.695956519936;58399;84409;85824.14175858161;86351.56824215839;86498;8687.087855775306;8688.087750786077;8833.558322083998;9082.04638512973;9112.225460835904;13777.104488208235;14106.929465352607;14140.575782726535;14334.068285561822;14601.488947886774;11647.670465906684;11661.416929153338;11941.044716422839;11959.76080478403;12024.819627705416;11099.668039835271;11312;11405.258658187191;11626.709332266422;11634.89468500491;11801.232919860182;12038.638840834788;12242.816357754818;12262;12308.384580771104;8574.442661227;8795.516246606498;8822.911770882209;8873.201411872962;8953.418027828171;11588.94205528967;11870.169088163746;12028.398580070787;12029.7970202979;12038.638840834788 12:49:55 INFO - PID 1923 | |3;mootools.html;1219.6036288206333;1460.5545308680848;1471.2937789860866;1481.9851801481982;1483.5623491070137;9056.45661260324;11779.823302650457;11810;11919.523219071227;12195;11602.477888495017;11669.474873630686;11757.235779674336;11817.995470385005;11833.994110500618;2303.8387312888103;2407.217654262363;2470.678811754474;2524.3436706456364;2526.3305224115625;2453.7064721242177;2479.1570865905587;2515.559777039011;2520.5715028445206;2523.634073059405;2104.200403846536;2430.650988701279;2476.2447453526665;2507.18516482144;2517.6349429332736;2453.2149712092137;2457.9262622121278;2477.950440991179;2486.428121532049;2502.099244272061;2425.2360506440414;2469.1481438903547;2475.900963961439;2483.180550418376;2525.5075260324243;11889.94055029728;11920.701982450422;11961.581344652983;11967;11994.580189693319;6039.849003774897;6413.460769415354;6504.479641628658;6761.357671021246;6761.830954226134;3916.6083391660604;4158.9376159357635;4223.113146239293;4240.448741663595;4242.342436922282;1026.5483187397522;1063.2504084620325;1229.0781913564826;1233.5374234662002;1272.8854403103765;16048.197590120448;16451.25969331383;16528.25622846975;16533.91733041327;16765;5549.972250138804;5669.262995810518;5790.88418231633;5818.912716309259;5845.210896528999;11927.224730392496;12480.188787728768;12481.438335274934;12497.063190150307;12521.37393130358;6556.409923106943;6601.075849381091;6603.504737144734;6604.636744978976;6619.5035372347265;5179.870503237449;6120;6157.076438534255;6192.628442293477;6206.534509911775;4373.650107991353;4469.173202957463;4500.302453119706;4528.592426681615;4536.455625324982;1308.4700696218047;1332.3205165365732;1333.6532501549473;1340.557615986722;1347.8517363090052;782.7221336425487;801.8213226557068;808.6563210635456;817.264461984219;846.3905987688854;6062.514998800086;6081.422264884829;6268.4671802897265;6324.2410910691015;6397.232332120082;569.1462805791313;579.4408395897894;586.7711592478937;593.8812237552506;596.8597379615787;4214.831406743696;4337.677008512371;4355.760433176142;4373.890652733643;4382.474103107647;14425.278736063156;14830.70338593222;14962.476313328982;14968.476103336548;15007.249637518298;14769.704605907822;15092.698146037017;15103.018303810217;15116.017458865139;15180;7567.31894129531;8531.829363412697;8649.394542382099;8694.565271736388;8701.17338852808;6459.677016149268;7086.480945129463;7166.355028047501;7190.245024272376;7208.026916366433;12696.555620553236;12830.16603920742;13379.665508362368;13515.121517101357;13707.794383084462;11360.488778005174;13674.384652690454;13792.310384480936;13839.792403113759;13855.515056972958;7372.557646541225;8556.18716221958;8731.388802783998;8732.738017859474;8757.824843502967;10644.520996555018;10697.518611662339;10748.785024299314;10763.784724305626;10809.432632268434;8986.19124278792;9022.774430639287;9058.230050445522;9072.591733372146;9121.543922803701;7380.5940673263485;8832.779180520538;8869.778255543662;8880.467171969702;8947.463152210888;7988.920110798817;8190;8195.67217311301;8205.58972051407;8207.220314070153;5716.28546431696;5963.642181468952;6039.15451836752;6174.104754810617;6183;5672.035753921926;5999.4900433463645;6145.0782382642965;6228.408301211378;6380.808575742735 12:49:55 INFO - PID 1923 | |4;prototype.html;355.7758612074393;385.66639856524114;407.1612478294711;407.61073175117764;408.0675656125752;6186.731719997401;6253.781117660883;6282.685865706713;6452.225732912046;6475.9314713072345;5326.414094449608;5349.22436246744;5732.283464566929;6094.421030002154;6219.595726277788;4225.366195070748;4378.365137055141;4582.427196600425;4600.769961501928;4614.5385461453925;4180.47744031996;4486.506484286726;4516.954830451703;4552.567506086933;4595.862124136282;4510.030343476152;4520.615747661453;4525;4526.894993827869;4530.388397566319;4540.614047805908;4549.181147393468;4552.8861778455475;4559.74921379324;4560.361549383089;3158.3367492826533;3754.6808521275725;3913.7651740895635;3915.314819906505;3917.7649341039346;5558.49920521461;6117.816465506042;6126.601770884923;6146.815595532096;6184.443400094013;4549.590536851666;4817.542333478314;5007.69953802773;5432.592555558349;5485.945140548583;5792.594518383715;6260.373962603749;6511.925532287214;6535.738570457176;6751.223609284946;4439.600435960779;4442.2004039272915;4448.4439445069365;4462.977685111554;4481.282994720829;6887.345702158287;7087.1495420549345;7091.219965803706;7178.066851309349;7225.674844632004;4499.87503124219;4503;4512.616427603625;4521.570450807168;4580.106879158532;4574.153781550423;4599.287110497811;4603.171429142786;4615.8846028848875;4645.767711614406;4020.4974378202724;4091.1204091120553;4673.289492094577;4722.456917454468;5238.92141617876;5877.94122058774;6221.844453888599;6729.05793188954;6743.966280168568;6749.08887300218;1448.2686243447201;1576.684663067391;1577.148339896443;1579.9526014219591;1581.1619841484032;946.7633091727068;947.7868328539342;987.3582171588525;1001.7245257554231;1041.7551875309296;5693.373728889819;6058.303295121029;6384.457321127663;6397.698267206798;6430;6536.215654121441;6722.260551339349;6968.372846443743;6982.93017069833;7000.719971201095;5119.360079990001;5966.880662386815;6422.8715425691225;6518.511111666644;6539.869202615921;3569.125564236727;3641.253543023674;3657.835397407123;3688.704903607705;3689.4834723138783;6682.899756503656;6815.693293801791;7156.463265255126;7240.443304689517;7254.383377412979;6035.305939816977;6583.539152259392;7132.607706576188;7187.382838861215;7227.710891564384;5841.123831425235;6059.424354686386;6291.622502649856;6293.961496353142;6607.240167380813;7021.508494405547;7025.402840758388;7029.156501219886;7042.57744535309;7055.400290975324;5269.762860671356;5630.5777066720975;6496.740130394731;6707.396334329934;6930.341617546325;5940.79207227745;6641.33586641332;6836.829079273058;7087.929120708727;7199.460040496879;3768.792716400624;4503.211937910918;4522.5929666330185;4529.23003089469;4548.019918189577;5790.536757059509;5888.941110588839;6740.393364597014;6773.882309418891;7012.1935977363255;4836.177849765478;5317.494837990384;5814.447627475383;6722.311075569811;6740.460763139035;5830.271216097988;6455.709493072729;6654.837177917293;6702.329767023259;6726.990951357336;6578.177727784027;6580.967095164494;6591.439727623206;6593.901091483535;6635.303293154149;5906.818636272848;6467.902981455188;6757.526973112032;6762.830929226809;6767.95096760003;6215.595986260879;6545.967270163619;6549.4760419167305;6550.344965503412;6579.703913324008 12:49:55 INFO - PID 1923 | |5;yui.html;771.5756334016289;843.7173546861804;848.6350869126276;880.7842078690718;892.8169725206332;16470.67058658826;16499.67000659986;16976.660466790658;18056.097195140257;18810.905945470255;17476;17775.64448711025;17851.018193999324;18125.728114078265;18269.543261418472;988.0267936082953;1018.6129270877057;1019.0726438940566;1019.6889948565722;1020.2501161646184;986.1469828598283;989.7773001074737;994.2692121290845;995.4624502768496;996.2627655534889;1072.05123465733;1075.7525769073118;1077.1221454514584;1078.2991055813704;1081.405227125082;933.4119504712056;1005.4419797012655;1065.664315740543;1066.3548553125363;1069.4225118436036;739.6819367671899;763.2672634271106;777.7705576854842;780.6057940739947;795.7970717467067;13063.150895191877;15632.687346253126;15774.211289435598;15802.367905283774;15834.604134896605;16485.83514164855;17159.05625190614;17209;17495;17643.823561764475;13901.443942242298;14068.788968165485;14079.85920140806;14195.716085678228;14224.786628200585;3498.2478767065204;3537.451694987281;3539.929201415983;3567.126054116732;3568.107973006748;107358.46320768424;124525;124700.12949805669;125622;125678;11867.525298988117;13638.454461821615;13849.030567860354;13931.512397066257;13965.882729381625;20502.487437813874;24907;25795.613065804027;26204.60693089605;26239.081632142785;7931.167227441151;8038.67845286182;8082.797930051678;8093.716719914774;8102.108768035511;3974.7615143091507;4050;4054.4526488924207;4054.5945405459806;4054.7769872656695;4682.672212945061;4840.975795121002;4870.245112007645;4884.169691152512;4904.239842824368;868.7610907000626;897.6858099665066;911.4713466189594;912.7535565397305;912.8402529557434;559.8376470823415;643.8358218654213;667.5127157175192;679.592244653214;699.8600279944031;9625.71122866315;11655.475503602189;11853.46659400329;11960;13017.958563314913;574.3136951343189;575.4533193466224;654.1561385812344;665.4576520136073;668.5453891353828;4533.911861153366;5101.311322971352;5420.4850539198715;5502.477264659851;5560.193771903051;77025;98866.00969997846;106139.40790888143;106333.4683326594;106689.4665526683;90927;93444.59833102509;94658.52670736602;95269.61825954406;95505.5674164888;76175.61912190514;76402.47195056068;77213.22786772173;77225.99999999888;78677;12044.51821927113;12516;12531.18547294423;12541.686457838627;12570.874291257336;39152.608473914894;39649.24209094927;39665;40702.79648601738;40848.18303633851;51712.482875172274;51790.48209518007;51920.480795191565;51927.44217673476;52111.218331724296;15443.768343475082;15816.209189540707;15855;15910.60223494422;15990.120543370227;15452;17783.555411114827;17929.37247196342;18109.818901810813;18210.635787283914;15827;15838.12890291045;15868.44460443879;16063.437779677655;16207.189640518163;15881.047137171807;16019.919900400424;16058.197090145679;16172.353105875633;16256.268467918735;15296;15484.303206355966;15632.609184770472;15736.370545178064;15830.9208453957;11486.82769758471;11590.768184636092;12039.578614748441;12094.637160885188;12124.75750485003;11919.225250359046;12437.564685235973;12454.065945053975;12556.246625202517;12917.09580329368 12:49:55 INFO - PID 1923 | __end_tp_report 12:49:55 INFO - PID 1923 | __start_cc_report 12:49:55 INFO - PID 1923 | _x_x_mozilla_cycle_collect,414 12:49:55 INFO - PID 1923 | __end_cc_report 12:49:55 INFO - PID 1923 | __startTimestamp1492372195072__endTimestamp 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | ------- Summary: start ------- 12:49:55 INFO - PID 1923 | Number of tests: 6 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#0] dojo.html Cycles:165 Average:6216.25 Median:6429.20 stddev:745.48 (11.6%) stddev-sans-first:705.97 12:49:55 INFO - PID 1923 | Values: 3078.8 3633.3 3685.0 3689.9 3690.6 6123.5 6259.3 6322.8 6332.1 6384.0 5146.3 6229.7 6255.4 6263.0 6329.4 5530.3 5567.8 5587.9 5599.7 5670.0 5495.6 5576.6 5626.2 5628.0 5649.4 5492.6 5537.7 5587.5 5618.6 5636.3 5539.6 5554.7 5582.3 5597.5 5653.2 5486.1 5506.6 5518.6 5529.8 5537.3 6085.9 6164.7 6215.7 6221.8 6310.6 6689.0 6767.3 6768.5 6775.5 6787.9 6863.5 6884.6 6892.3 6896.4 6898.5 6247.7 6257.0 6312.1 6332.2 6333.8 6977.7 7018.6 7027.4 7056.6 7096.0 6352.9 6438.4 6488.4 6576.6 6685.6 6006.8 6710.3 6745.8 6787.3 6834.3 6407.4 6746.6 6756.7 6771.8 6772.6 6526.5 6557.8 6651.1 6734.4 6759.9 5441.0 5447.2 5460.2 5467.9 5471.3 4105.3 4788.6 4804.3 4804.9 4809.4 6185.0 6278.0 6298.8 6322.5 6443.1 6842.0 6879.4 6907.9 6913.7 6922.9 6381.3 6438.6 6439.9 6449.5 6554.1 5902.8 5951.5 5954.8 6111.5 6121.0 6851.8 6958.7 7038.5 7057.8 7094.6 5664.4 6862.3 6875.1 6879.6 6910.1 5578.9 6923.7 6937.1 7039.7 7047.6 6482.5 6593.4 6696.1 6727.1 6727.4 6886.0 6891.9 6894.8 6907.9 6912.1 6290.6 6349.5 6429.2 6436.0 6602.0 6552.1 6633.1 6634.2 6634.2 6641.7 5719.2 6148.5 6550.4 6556.0 6559.2 6425.1 6472.1 6539.3 6543.8 6606.6 5328.5 6396.5 6439.1 6461.9 6469.7 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#1] ext.html Cycles:180 Average:43990.29 Median:3194.02 stddev:73567.53 (2303.3%) stddev-sans-first:73701.67 12:49:55 INFO - PID 1923 | Values: 569.3 802.9 832.5 1288.6 1308.1 37856.8 39037.6 39102.8 39123.6 39163.2 39225.6 39502.2 39563.8 39612.2 39704.2 8003.3 8124.0 8562.5 8851.5 8890.0 8771.5 8878.4 8886.7 8895.2 8908.0 8830.0 8853.5 8888.4 8911.6 8912.7 8804.2 8826.5 8841.4 8846.2 8872.3 8551.4 8551.9 8571.1 8603.3 8603.4 38550.2 38744.2 38756.0 38786.8 38792.0 3558.1 3567.8 3603.7 3613.2 3614.4 31449.4 31485.8 31526.2 31567.4 31567.8 3164.1 3178.9 3189.8 3190.1 3197.9 233363.0 236912.8 263800.7 291638.0 291779.5 48921.0 49086.3 49349.0 49397.8 49432.0 51720.7 51927.2 51980.5 52002.7 52068.4 14619.0 14637.6 14679.6 14690.6 14697.6 12119.8 12122.7 12134.0 12146.4 12153.9 13904.4 13954.4 13957.6 14013.9 14058.6 4988.5 5150.5 5932.6 5967.2 5990.4 942.4 1046.3 1050.6 1052.9 1065.7 24644.5 24645.9 24737.4 24927.9 25073.5 1012.7 1149.7 1180.1 1185.6 1192.2 10911.6 12729.3 12798.1 12858.4 12873.5 293843.0 296403.0 296782.0 297173.0 297683.0 44667.8 55638.7 55681.0 55732.7 55841.4 1326.8 1558.7 1565.4 1573.9 1580.7 23270.4 24546.0 24689.6 24708.8 24748.0 39754.8 39853.8 40171.0 40272.0 40429.8 279573.0 282339.0 283010.0 283436.0 284258.0 27397.5 35165.8 35561.8 35771.6 35824.8 44765.8 45498.3 45544.9 45576.3 45627.8 30249.7 30295.7 30643.0 31384.5 31394.4 31274.4 31371.7 31394.5 31409.5 31417.9 37448.6 37470.3 37506.6 37524.2 37586.7 30588.7 31115.7 31549.4 31841.6 31892.8 24083.4 24134.3 24326.8 24485.5 24602.5 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#2] jquery.html Cycles:180 Average:35871.34 Median:2343.76 stddev:86302.25 (3682.2%) stddev-sans-first:86503.33 12:49:55 INFO - PID 1923 | Values: 430.7 438.6 442.0 455.8 458.9 19064.8 20212.3 20892.6 21025.8 21100.6 21347.0 21387.6 21598.9 21764.0 23201.8 6754.3 6804.7 7027.1 7030.0 7053.5 6842.3 7036.2 7047.3 7061.6 7069.5 6726.4 6885.8 6963.6 7042.4 7050.8 6643.7 6826.9 6876.8 6894.2 6900.4 6761.3 6801.3 6842.5 6844.7 6849.6 22954.0 29218.3 29717.9 29852.0 30076.0 2286.1 2327.4 2343.2 2344.3 2347.1 17197.5 18065.7 19119.4 19134.3 19249.4 2303.6 2335.7 2399.4 2399.7 2401.3 134776.3 159866.0 160454.0 163385.0 163800.2 18609.2 24413.0 24439.9 24599.8 24836.4 32692.7 41266.0 41633.6 41681.8 41802.6 11181.9 11359.8 11439.5 11605.4 11746.3 7321.5 8998.0 9051.2 9194.9 9453.8 9208.3 11274.4 11395.5 11441.9 11543.6 3791.2 4521.3 4554.5 4569.0 4589.3 957.8 970.6 976.7 999.2 1021.1 16702.6 19861.4 20532.8 21248.9 21541.8 936.3 1005.3 1028.8 1034.8 1038.3 10599.8 10956.8 10969.5 10995.2 11023.3 496635.0 498090.0 498393.0 513388.0 514708.0 122797.0 127193.7 134998.3 139398.0 141117.0 1100.4 1239.7 1259.5 1261.4 1266.1 21390.0 22411.6 22759.3 22788.8 22798.4 40694.8 48854.8 58696.0 59978.7 60808.7 58399.0 84409.0 85824.1 86351.6 86498.0 8687.1 8688.1 8833.6 9082.0 9112.2 13777.1 14106.9 14140.6 14334.1 14601.5 11647.7 11661.4 11941.0 11959.8 12024.8 11099.7 11312.0 11405.3 11626.7 11634.9 11801.2 12038.6 12242.8 12262.0 12308.4 8574.4 8795.5 8822.9 8873.2 8953.4 11588.9 11870.2 12028.4 12029.8 12038.6 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#3] mootools.html Cycles:180 Average:7067.44 Median:4186.88 stddev:4502.79 (107.5%) stddev-sans-first:4493.97 12:49:55 INFO - PID 1923 | Values: 1219.6 1460.6 1471.3 1482.0 1483.6 9056.5 11779.8 11810.0 11919.5 12195.0 11602.5 11669.5 11757.2 11818.0 11834.0 2303.8 2407.2 2470.7 2524.3 2526.3 2453.7 2479.2 2515.6 2520.6 2523.6 2104.2 2430.7 2476.2 2507.2 2517.6 2453.2 2457.9 2478.0 2486.4 2502.1 2425.2 2469.1 2475.9 2483.2 2525.5 11889.9 11920.7 11961.6 11967.0 11994.6 6039.8 6413.5 6504.5 6761.4 6761.8 3916.6 4158.9 4223.1 4240.4 4242.3 1026.5 1063.3 1229.1 1233.5 1272.9 16048.2 16451.3 16528.3 16533.9 16765.0 5550.0 5669.3 5790.9 5818.9 5845.2 11927.2 12480.2 12481.4 12497.1 12521.4 6556.4 6601.1 6603.5 6604.6 6619.5 5179.9 6120.0 6157.1 6192.6 6206.5 4373.7 4469.2 4500.3 4528.6 4536.5 1308.5 1332.3 1333.7 1340.6 1347.9 782.7 801.8 808.7 817.3 846.4 6062.5 6081.4 6268.5 6324.2 6397.2 569.1 579.4 586.8 593.9 596.9 4214.8 4337.7 4355.8 4373.9 4382.5 14425.3 14830.7 14962.5 14968.5 15007.2 14769.7 15092.7 15103.0 15116.0 15180.0 7567.3 8531.8 8649.4 8694.6 8701.2 6459.7 7086.5 7166.4 7190.2 7208.0 12696.6 12830.2 13379.7 13515.1 13707.8 11360.5 13674.4 13792.3 13839.8 13855.5 7372.6 8556.2 8731.4 8732.7 8757.8 10644.5 10697.5 10748.8 10763.8 10809.4 8986.2 9022.8 9058.2 9072.6 9121.5 7380.6 8832.8 8869.8 8880.5 8947.5 7988.9 8190.0 8195.7 8205.6 8207.2 5716.3 5963.6 6039.2 6174.1 6183.0 5672.0 5999.5 6145.1 6228.4 6380.8 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#4] prototype.html Cycles:180 Average:5321.21 Median:5953.84 stddev:1689.77 (28.4%) stddev-sans-first:1652.90 12:49:55 INFO - PID 1923 | Values: 355.8 385.7 407.2 407.6 408.1 6186.7 6253.8 6282.7 6452.2 6475.9 5326.4 5349.2 5732.3 6094.4 6219.6 4225.4 4378.4 4582.4 4600.8 4614.5 4180.5 4486.5 4517.0 4552.6 4595.9 4510.0 4520.6 4525.0 4526.9 4530.4 4540.6 4549.2 4552.9 4559.7 4560.4 3158.3 3754.7 3913.8 3915.3 3917.8 5558.5 6117.8 6126.6 6146.8 6184.4 4549.6 4817.5 5007.7 5432.6 5485.9 5792.6 6260.4 6511.9 6535.7 6751.2 4439.6 4442.2 4448.4 4463.0 4481.3 6887.3 7087.1 7091.2 7178.1 7225.7 4499.9 4503.0 4512.6 4521.6 4580.1 4574.2 4599.3 4603.2 4615.9 4645.8 4020.5 4091.1 4673.3 4722.5 5238.9 5877.9 6221.8 6729.1 6744.0 6749.1 1448.3 1576.7 1577.1 1580.0 1581.2 946.8 947.8 987.4 1001.7 1041.8 5693.4 6058.3 6384.5 6397.7 6430.0 6536.2 6722.3 6968.4 6982.9 7000.7 5119.4 5966.9 6422.9 6518.5 6539.9 3569.1 3641.3 3657.8 3688.7 3689.5 6682.9 6815.7 7156.5 7240.4 7254.4 6035.3 6583.5 7132.6 7187.4 7227.7 5841.1 6059.4 6291.6 6294.0 6607.2 7021.5 7025.4 7029.2 7042.6 7055.4 5269.8 5630.6 6496.7 6707.4 6930.3 5940.8 6641.3 6836.8 7087.9 7199.5 3768.8 4503.2 4522.6 4529.2 4548.0 5790.5 5888.9 6740.4 6773.9 7012.2 4836.2 5317.5 5814.4 6722.3 6740.5 5830.3 6455.7 6654.8 6702.3 6727.0 6578.2 6581.0 6591.4 6593.9 6635.3 5906.8 6467.9 6757.5 6762.8 6768.0 6215.6 6546.0 6549.5 6550.3 6579.7 12:49:55 INFO - PID 1923 | 12:49:55 INFO - PID 1923 | [#5] yui.html Cycles:180 Average:21290.55 Median:18117.77 stddev:29767.53 (164.3%) stddev-sans-first:29811.16 12:49:55 INFO - PID 1923 | Values: 771.6 843.7 848.6 880.8 892.8 16470.7 16499.7 16976.7 18056.1 18810.9 17476.0 17775.6 17851.0 18125.7 18269.5 988.0 1018.6 1019.1 1019.7 1020.3 986.1 989.8 994.3 995.5 996.3 1072.1 1075.8 1077.1 1078.3 1081.4 933.4 1005.4 1065.7 1066.4 1069.4 739.7 763.3 777.8 780.6 795.8 13063.2 15632.7 15774.2 15802.4 15834.6 16485.8 17159.1 17209.0 17495.0 17643.8 13901.4 14068.8 14079.9 14195.7 14224.8 3498.2 3537.5 3539.9 3567.1 3568.1 107358.5 124525.0 124700.1 125622.0 125678.0 11867.5 13638.5 13849.0 13931.5 13965.9 20502.5 24907.0 25795.6 26204.6 26239.1 7931.2 8038.7 8082.8 8093.7 8102.1 3974.8 4050.0 4054.5 4054.6 4054.8 4682.7 4841.0 4870.2 4884.2 4904.2 868.8 897.7 911.5 912.8 912.8 559.8 643.8 667.5 679.6 699.9 9625.7 11655.5 11853.5 11960.0 13018.0 574.3 575.5 654.2 665.5 668.5 4533.9 5101.3 5420.5 5502.5 5560.2 77025.0 98866.0 106139.4 106333.5 106689.5 90927.0 93444.6 94658.5 95269.6 95505.6 76175.6 76402.5 77213.2 77226.0 78677.0 12044.5 12516.0 12531.2 12541.7 12570.9 39152.6 39649.2 39665.0 40702.8 40848.2 51712.5 51790.5 51920.5 51927.4 52111.2 15443.8 15816.2 15855.0 15910.6 15990.1 15452.0 17783.6 17929.4 18109.8 18210.6 15827.0 15838.1 15868.4 16063.4 16207.2 15881.0 16019.9 16058.2 16172.4 16256.3 15296.0 15484.3 15632.6 15736.4 15830.9 11486.8 11590.8 12039.6 12094.6 12124.8 11919.2 12437.6 12454.1 12556.2 12917.1 12:49:55 INFO - PID 1923 | -------- Summary: end -------- 12:49:55 INFO - PID 1923 | 12:49:55 INFO - TEST-INFO | 1923: exit 0 12:49:55 INFO - TEST-OK | dromaeo_css | took 1115160ms 12:49:55 INFO - TEST-START | kraken 12:49:55 INFO - operating with platform_type : mac_ 12:49:55 INFO - Initialising browser for kraken test... 12:49:55 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmpiHWM7X/profile http://localhost:49384/getInfo.html) 12:49:58 INFO - PID 1930 | 2017-04-16 12:49:58.700 plugin-container[1931:17380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:49:58 INFO - PID 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:58 INFO - PID 1930 | 2017-04-16 12:49:58.703 plugin-container[1931:17380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 12:49:58 INFO - PID 1930 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:49:58 INFO - PID 1930 | 2017-04-16 12:49:58.703 plugin-container[1931:17380] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:49:58 INFO - PID 1930 | __metrics Screen width/height:1600/1200 12:49:58 INFO - PID 1930 | colorDepth:24 12:49:58 INFO - PID 1930 | Browser inner width/height: 1024/689 12:49:58 INFO - PID 1930 | __metrics 12:49:58 ERROR - PID 1930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 12:49:59 INFO - TEST-INFO | 1930: exit 0 12:49:59 INFO - Browser initialized. 12:49:59 INFO - Running cycle 1/1 for kraken test... 12:49:59 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmpiHWM7X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:50:02 INFO - PID 1932 | 2017-04-16 12:50:02.135 plugin-container[1933:17511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 12:50:02 INFO - PID 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:02 INFO - PID 1932 | 2017-04-16 12:50:02.136 plugin-container[1933:17511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 12:50:02 INFO - PID 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:50:02 INFO - PID 1932 | 2017-04-16 12:50:02.136 plugin-container[1933:17511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:50:12 INFO - PID 1932 | RSS: Main: 238850048 12:50:12 INFO - PID 1932 | 12:50:14 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49384/tests/kraken/driver.html?testName=audio-beat-detection) 12:50:15 INFO - PID 1932 | RSS: Main: 250626048 12:50:15 INFO - PID 1932 | 12:50:18 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49384/tests/kraken/driver.html?testName=audio-dft) 12:50:18 INFO - PID 1932 | RSS: Main: 258850816 12:50:18 INFO - PID 1932 | 12:50:22 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49384/tests/kraken/driver.html?testName=audio-fft) 12:50:23 INFO - PID 1932 | RSS: Main: 260153344 12:50:23 INFO - PID 1932 | 12:50:24 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49384/tests/kraken/driver.html?testName=audio-oscillator) 12:50:25 INFO - PID 1932 | RSS: Main: 262168576 12:50:25 INFO - PID 1932 | 12:50:27 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49384/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:50:28 INFO - PID 1932 | RSS: Main: 262582272 12:50:28 INFO - PID 1932 | 12:50:31 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49384/tests/kraken/driver.html?testName=imaging-darkroom) 12:50:32 INFO - PID 1932 | RSS: Main: 267665408 12:50:32 INFO - PID 1932 | 12:50:35 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49384/tests/kraken/driver.html?testName=imaging-desaturate) 12:50:35 INFO - PID 1932 | RSS: Main: 268845056 12:50:35 INFO - PID 1932 | 12:50:39 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49384/tests/kraken/driver.html?testName=json-parse-financial) 12:50:39 INFO - PID 1932 | RSS: Main: 268890112 12:50:39 INFO - PID 1932 | 12:50:41 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49384/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:50:41 INFO - PID 1932 | RSS: Main: 268849152 12:50:41 INFO - PID 1932 | 12:50:43 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:50:44 INFO - PID 1932 | RSS: Main: 268926976 12:50:44 INFO - PID 1932 | 12:50:46 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:50:47 INFO - PID 1932 | RSS: Main: 268591104 12:50:47 INFO - PID 1932 | 12:50:50 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:50:51 INFO - PID 1932 | RSS: Main: 268836864 12:50:51 INFO - PID 1932 | 12:50:54 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:50:54 INFO - PID 1932 | RSS: Main: 268812288 12:50:54 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | Cycle 1(1): loaded http://localhost:49384/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49384/tests/kraken/driver.html?testName=ai-astar) 12:50:56 INFO - PID 1932 | RSS: Main: 270528512 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | __start_tp_report 12:50:56 INFO - PID 1932 | _x_x_mozilla_page_load 12:50:56 INFO - PID 1932 | _x_x_mozilla_page_load_details 12:50:56 INFO - PID 1932 | |i|pagename|runs| 12:50:56 INFO - PID 1932 | |0;ai-astar;104;116;112;113;113;112;114;113;116;116 12:50:56 INFO - PID 1932 | |1;audio-beat-detection;152;136;95;185;142;149;120;112;139;110 12:50:56 INFO - PID 1932 | |2;audio-dft;196;220;210;267;236;281;277;199;194;196 12:50:56 INFO - PID 1932 | |3;audio-fft;83;60;60;86;56;60;58;60;75;75 12:50:56 INFO - PID 1932 | |4;audio-oscillator;90;72;116;101;103;102;89;68;65;86 12:50:56 INFO - PID 1932 | |5;imaging-gaussian-blur;77;135;135;96;88;87;89;87;88;87 12:50:56 INFO - PID 1932 | |6;imaging-darkroom;95;97;96;124;104;154;96;122;112;149 12:50:56 INFO - PID 1932 | |7;imaging-desaturate;148;74;128;83;149;147;92;93;93;89 12:50:56 INFO - PID 1932 | |8;json-parse-financial;51;52;52;72;80;63;51;46;45;70 12:50:56 INFO - PID 1932 | |9;json-stringify-tinderbox;59;60;59;60;64;58;57;58;57;56 12:50:56 INFO - PID 1932 | |10;stanford-crypto-aes;99;107;112;106;83;85;86;85;85;77 12:50:56 INFO - PID 1932 | |11;stanford-crypto-ccm;159;157;154;166;168;148;142;163;199;147 12:50:56 INFO - PID 1932 | |12;stanford-crypto-pbkdf2;186;186;182;183;185;183;238;185;181;186 12:50:56 INFO - PID 1932 | |13;stanford-crypto-sha256-iterative;67;69;68;68;68;67;69;67;68;73 12:50:56 INFO - PID 1932 | __end_tp_report 12:50:56 INFO - PID 1932 | __start_cc_report 12:50:56 INFO - PID 1932 | _x_x_mozilla_cycle_collect,992 12:50:56 INFO - PID 1932 | __end_cc_report 12:50:56 INFO - PID 1932 | __startTimestamp1492372256642__endTimestamp 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | ------- Summary: start ------- 12:50:56 INFO - PID 1932 | Number of tests: 14 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.50 stddev:3.51 (3.1%) stddev-sans-first:1.69 12:50:56 INFO - PID 1932 | Values: 104.0 116.0 112.0 113.0 113.0 112.0 114.0 113.0 116.0 116.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:145.50 stddev:25.86 (17.8%) stddev-sans-first:26.60 12:50:56 INFO - PID 1932 | Values: 152.0 136.0 95.0 185.0 142.0 149.0 120.0 112.0 139.0 110.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#2] audio-dft Cycles:10 Average:227.60 Median:228.00 stddev:35.32 (15.5%) stddev-sans-first:35.56 12:50:56 INFO - PID 1932 | Values: 196.0 220.0 210.0 267.0 236.0 281.0 277.0 199.0 194.0 196.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.50 stddev:11.26 (16.7%) stddev-sans-first:10.42 12:50:56 INFO - PID 1932 | Values: 83.0 60.0 60.0 86.0 56.0 60.0 58.0 60.0 75.0 75.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:70.00 stddev:16.84 (24.1%) stddev-sans-first:17.86 12:50:56 INFO - PID 1932 | Values: 90.0 72.0 116.0 101.0 103.0 102.0 89.0 68.0 65.0 86.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:87.50 stddev:20.59 (23.5%) stddev-sans-first:20.54 12:50:56 INFO - PID 1932 | Values: 77.0 135.0 135.0 96.0 88.0 87.0 89.0 87.0 88.0 87.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:124.50 stddev:22.07 (17.7%) stddev-sans-first:22.20 12:50:56 INFO - PID 1932 | Values: 95.0 97.0 96.0 124.0 104.0 154.0 96.0 122.0 112.0 149.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#7] imaging-desaturate Cycles:10 Average:109.60 Median:86.00 stddev:29.86 (34.7%) stddev-sans-first:28.25 12:50:56 INFO - PID 1932 | Values: 148.0 74.0 128.0 83.0 149.0 147.0 92.0 93.0 93.0 89.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:57.50 stddev:12.16 (21.2%) stddev-sans-first:12.62 12:50:56 INFO - PID 1932 | Values: 51.0 52.0 52.0 72.0 80.0 63.0 51.0 46.0 45.0 70.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:2.25 (3.8%) stddev-sans-first:2.39 12:50:56 INFO - PID 1932 | Values: 59.0 60.0 59.0 60.0 64.0 58.0 57.0 58.0 57.0 56.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:85.00 stddev:12.28 (14.4%) stddev-sans-first:12.79 12:50:56 INFO - PID 1932 | Values: 99.0 107.0 112.0 106.0 83.0 85.0 86.0 85.0 85.0 77.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:161.00 stddev:16.03 (10.0%) stddev-sans-first:16.99 12:50:56 INFO - PID 1932 | Values: 159.0 157.0 154.0 166.0 168.0 148.0 142.0 163.0 199.0 147.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.50 Median:185.50 stddev:17.14 (9.2%) stddev-sans-first:18.13 12:50:56 INFO - PID 1932 | Values: 186.0 186.0 182.0 183.0 185.0 183.0 238.0 185.0 181.0 186.0 12:50:56 INFO - PID 1932 | 12:50:56 INFO - PID 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.78 (2.6%) stddev-sans-first:1.81 12:50:56 INFO - PID 1932 | Values: 67.0 69.0 68.0 68.0 68.0 67.0 69.0 67.0 68.0 73.0 12:50:56 INFO - PID 1932 | -------- Summary: end -------- 12:50:56 INFO - PID 1932 | 12:50:57 INFO - TEST-INFO | 1932: exit 0 12:50:57 INFO - TEST-OK | kraken | took 62025ms 12:50:57 INFO - SUITE-END | took 1177s 12:50:57 INFO - Completed test suite (00:19:37) 12:50:57 INFO - javascript benchmark 12:50:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8036.092461335428, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.8152710837353, 3633.3096711624808, 3684.9631503684955, 3689.8893033209006, 3690.6309369063097, 6123.4656464211685, 6259.280182778982, 6322.778702745405, 6332.113504109423, 6384.0, 5146.279520867081, 6229.688515574215, 6255.374462553742, 6262.9979203327475, 6329.398707122828, 5530.281063461744, 5567.777288908449, 5587.8603034924245, 5599.664020158783, 5669.971650141743, 5495.554478257177, 5576.609637325389, 5626.240457538242, 5627.971860140694, 5649.350324712649, 5492.642978206443, 5537.723113844332, 5587.497125258706, 5618.550515958714, 5636.323641163066, 5539.612227144102, 5554.694491802949, 5582.302212223472, 5597.468240517144, 5653.1520271959125, 5486.094794358926, 5506.587005974568, 5518.613697041209, 5529.751161197715, 5537.335519737617, 6085.908711369333, 6164.660943648098, 6215.68921553925, 6221.844453888643, 6310.6213627182515, 6688.9665551672415, 6767.323267673242, 6768.458523318172, 6775.525713200078, 6787.864242715167, 6863.485238607124, 6884.552504087218, 6892.276310987324, 6896.413804826596, 6898.482613803984, 6247.656378899113, 6256.99888017915, 6312.116303717529, 6332.208473940757, 6333.778317758856, 6977.686004129826, 7018.649067546602, 7027.402670772939, 7056.576605403692, 7096.0, 6352.904706429407, 6438.420542151229, 6488.383603557654, 6576.638284894281, 6685.565438246498, 6006.759729610854, 6710.295418981034, 6745.763898263635, 6787.2534021257625, 6834.316568343126, 6407.423331900151, 6746.561473504206, 6756.729730810811, 6771.796846094625, 6772.5936443813525, 6526.543141980016, 6557.8360540985905, 6651.135352404156, 6734.427573656196, 6759.898601520981, 5441.0, 5447.210154527571, 5460.208269800856, 5467.863303417367, 5471.316085489314, 4105.302098643178, 4788.592969597554, 4804.303376010459, 4804.8798780030775, 4809.422869255712, 6185.0, 6277.96861015692, 6298.811035668938, 6322.4942004639515, 6443.097966284724, 6841.965790171017, 6879.415249703731, 6907.930920690829, 6913.688884000232, 6922.861542769117, 6381.266154392211, 6438.6136831790245, 6439.871202576016, 6449.5485316027425, 6554.082428460019, 5902.822915312548, 5951.4941229995065, 5954.791582294599, 6111.51107911366, 6121.020636698196, 6851.828704282532, 6958.652067396712, 7038.472114591528, 7057.752978645723, 7094.645267736696, 5664.405237450009, 6862.279460656559, 6875.106236189264, 6879.621620810904, 6910.101686780888, 5578.856334451347, 6923.653817309215, 6937.132858392701, 7039.718411263492, 7047.612381319077, 6482.481401487964, 6593.3736295051895, 6696.062551242732, 6727.091842601186, 6727.360900714424, 6885.965570172117, 6891.931080689329, 6894.827629309308, 6907.89638155418, 6912.101426814482, 6290.591111577733, 6349.49204063683, 6429.196350456193, 6435.967820160869, 6601.997828879904, 6552.08270842092, 6633.104530888461, 6634.170728658918, 6634.237062737654, 6641.667916604247, 5719.170720245625, 6148.50811935053, 6550.410463058348, 6555.951047832324, 6559.245686746085, 6425.10048593188, 6472.126262954441, 6539.313372095861, 6543.836404089936, 6606.6036037837885, 5328.49379308965, 6396.520260980352, 6439.130717353098, 6461.935380646133, 6469.7412103515335], "value": 6167.088133268297, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.2912324156426, 802.8875957365968, 832.5129799067544, 1288.5876519513754, 1308.136629824315, 37856.81071594641, 39037.60962390375, 39102.80448597753, 39123.60876391235, 39163.216735665344, 39225.607743922556, 39502.20995580087, 39563.813085607406, 39612.20775584494, 39704.20591588166, 8003.319717823977, 8124.02511698594, 8562.529060901648, 8851.468911865277, 8889.95555022224, 8771.517566533837, 8878.42290251135, 8886.688965886195, 8895.154960278764, 8908.0, 8829.984551776532, 8853.468791872509, 8888.422252553595, 8911.554422278892, 8912.73261802147, 8804.163604457566, 8826.47041177525, 8841.38110332277, 8846.20384165425, 8872.290216782674, 8551.444156129832, 8551.914480855236, 8571.100034496412, 8603.268722158624, 8603.440776349516, 38550.22899542021, 38744.225115497815, 38756.0, 38786.836394907914, 38792.0, 3558.1460449492192, 3567.8037707926055, 3603.6756691897854, 3613.2231570212266, 3614.3855544557227, 31449.37101257962, 31485.79599428041, 31526.211844703837, 31567.36865262705, 31567.842160789278, 3164.0507847645845, 3178.9205269868207, 3189.8245596492184, 3190.122716253026, 3197.9200519986953, 233363.0, 236912.78523288487, 263800.6809965957, 291638.0, 291779.5411022953, 48921.0, 49086.26370604444, 49349.0130197394, 49397.76505587389, 49432.0, 51720.70698232499, 51927.221091683656, 51980.48019519756, 52002.69993250199, 52068.43794686165, 14619.0, 14637.560873173821, 14679.633009174857, 14690.55928322152, 14697.55907322782, 12119.818202726967, 12122.696932576757, 12134.0, 12146.392680365947, 12153.93923030397, 13904.374303156383, 13954.372053257628, 13957.58127256184, 14013.929930350487, 14058.648533786532, 4988.476209997971, 5150.510701483353, 5932.644041357532, 5967.16459695643, 5990.430909063632, 942.3544871762728, 1046.2989796836139, 1050.56926660069, 1052.8683914510686, 1065.717584840018, 24644.50710985806, 24645.876770616032, 24737.381565460648, 24927.875360623082, 25073.49853002966, 1012.6607586458618, 1149.6665966869514, 1180.1267062373781, 1185.6324539392815, 1192.1833544022372, 10911.563537458413, 12729.333180236557, 12798.104132710621, 12858.421371038326, 12873.485060597659, 293843.0, 296403.0359696419, 296782.0, 297173.0, 297683.0, 44667.77666111714, 55638.72180639152, 55681.0, 55732.72133639306, 55841.441585584435, 1326.7943468762357, 1558.6648870492897, 1565.3816742386693, 1573.8504842039988, 1580.6917651057934, 23270.418239544146, 24546.0, 24689.62965555482, 24708.752912470645, 24748.0, 39754.80122599369, 39853.80073099616, 40171.0, 40272.0, 40429.78710638686, 279573.0, 282339.0, 283010.0, 283436.0, 284258.0, 27397.45205095847, 35165.82417087898, 35561.82219088888, 35771.64228357683, 35824.82087589546, 44765.77617112024, 45498.31752523649, 45544.8613784658, 45576.316355254035, 45627.771861140485, 30249.697503024687, 30295.651306105312, 30643.0, 31384.48924086022, 31394.372112558078, 31274.374512509166, 31371.686283136878, 31394.529082064244, 31409.528857066703, 31417.915670963543, 37448.6255137456, 37470.250594988494, 37506.62493375031, 37524.249515010095, 37586.68446604356, 30588.694113058584, 31115.68884311128, 31549.36901261916, 31841.567129478764, 31892.84053579717, 24083.397915052265, 24134.275971720876, 24326.756732433158, 24485.51028979446, 24602.50794984126], "value": 17791.474010635004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.67914403769197, 438.6249756458227, 442.0297447103609, 455.79033644523525, 458.9013362127144, 19064.809351906493, 20212.292569760062, 20892.58214835706, 21025.789742102574, 21100.57798844022, 21347.0, 21387.57224855502, 21598.920053997277, 21764.0, 23201.767982320212, 6754.290799466059, 6804.693788779505, 7027.0513480679965, 7029.964850175742, 7053.541519801196, 6842.281560436158, 7036.190838053638, 7047.260037696019, 7061.576305421666, 7069.505134640577, 6726.394624483795, 6885.758998435056, 6963.582185068887, 7042.436605071582, 7050.788476345692, 6643.734250629969, 6826.897596536056, 6876.828079298008, 6894.172699276069, 6900.413464855494, 6761.323867613249, 6801.251862295144, 6842.521023528355, 6844.691988860513, 6849.623270720109, 22954.0, 29218.269543261376, 29717.85141074281, 29852.0, 30076.0, 2286.0512887151813, 2327.359976006595, 2343.238447504568, 2344.284993077111, 2347.0611755297846, 17197.484075477754, 18065.7290140648, 19119.426417207505, 19134.330298439487, 19249.422517324503, 2303.6314189729565, 2335.6963594732747, 2399.4481269308158, 2399.6520504526748, 2401.2556107606697, 134776.30447391095, 159866.0, 160454.0, 163385.0, 163800.18099909544, 18609.162587683586, 24413.0, 24439.877800610884, 24599.754002460104, 24836.37909052288, 32692.673073269438, 41266.0, 41633.583664163576, 41681.79159104185, 41802.58197417987, 11181.888181118247, 11359.772804544029, 11439.542418303341, 11605.419729013516, 11746.29522228669, 7321.450891183183, 8997.955010224907, 9051.185393314634, 9194.862077068849, 9453.76365590852, 9208.309376796768, 11274.436278186222, 11395.487203075882, 11441.885581144248, 11543.595974140864, 3791.2227993261317, 4521.344405061248, 4554.544545545486, 4568.984470249619, 4589.33454649074, 957.8036502516967, 970.5583959298643, 976.7460460280379, 999.240577161348, 1021.1167340250778, 16702.58243543921, 19861.404157875288, 20532.794672053387, 21248.893755531433, 21541.78458215429, 936.3352020065694, 1005.261133067195, 1028.8096702110133, 1034.8395998620226, 1038.3199004651965, 10599.84100238497, 10956.835647465294, 10969.506372213269, 10995.175361847894, 11023.338599684044, 496635.0, 498090.0, 498393.0, 513388.0, 514708.0, 122797.0, 127193.72806271819, 134998.3250083763, 139398.0, 141117.0, 1100.350793032115, 1239.7334573066835, 1259.4647274907945, 1261.4323554400557, 1266.120046567645, 21390.0, 22411.615216012706, 22759.31722048341, 22788.772112278664, 22798.430039249153, 40694.79652601836, 48854.75572622114, 58696.0, 59978.700106499185, 60808.695956519936, 58399.0, 84409.0, 85824.14175858161, 86351.56824215839, 86498.0, 8687.087855775306, 8688.087750786077, 8833.558322083998, 9082.04638512973, 9112.225460835904, 13777.104488208235, 14106.929465352607, 14140.575782726535, 14334.068285561822, 14601.488947886774, 11647.670465906684, 11661.416929153338, 11941.044716422839, 11959.76080478403, 12024.819627705416, 11099.668039835271, 11312.0, 11405.258658187191, 11626.709332266422, 11634.89468500491, 11801.232919860182, 12038.638840834788, 12242.816357754818, 12262.0, 12308.384580771104, 8574.442661227, 8795.516246606498, 8822.911770882209, 8873.201411872962, 8953.418027828171, 11588.94205528967, 11870.169088163746, 12028.398580070787, 12029.7970202979, 12038.638840834788], "value": 11647.832507011724, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.6036288206333, 1460.5545308680848, 1471.2937789860866, 1481.9851801481982, 1483.5623491070137, 9056.45661260324, 11779.823302650457, 11810.0, 11919.523219071227, 12195.0, 11602.477888495017, 11669.474873630686, 11757.235779674336, 11817.995470385005, 11833.994110500618, 2303.8387312888103, 2407.217654262363, 2470.678811754474, 2524.3436706456364, 2526.3305224115625, 2453.7064721242177, 2479.1570865905587, 2515.559777039011, 2520.5715028445206, 2523.634073059405, 2104.200403846536, 2430.650988701279, 2476.2447453526665, 2507.18516482144, 2517.6349429332736, 2453.2149712092137, 2457.9262622121278, 2477.950440991179, 2486.428121532049, 2502.099244272061, 2425.2360506440414, 2469.1481438903547, 2475.900963961439, 2483.180550418376, 2525.5075260324243, 11889.94055029728, 11920.701982450422, 11961.581344652983, 11967.0, 11994.580189693319, 6039.849003774897, 6413.460769415354, 6504.479641628658, 6761.357671021246, 6761.830954226134, 3916.6083391660604, 4158.9376159357635, 4223.113146239293, 4240.448741663595, 4242.342436922282, 1026.5483187397522, 1063.2504084620325, 1229.0781913564826, 1233.5374234662002, 1272.8854403103765, 16048.197590120448, 16451.25969331383, 16528.25622846975, 16533.91733041327, 16765.0, 5549.972250138804, 5669.262995810518, 5790.88418231633, 5818.912716309259, 5845.210896528999, 11927.224730392496, 12480.188787728768, 12481.438335274934, 12497.063190150307, 12521.37393130358, 6556.409923106943, 6601.075849381091, 6603.504737144734, 6604.636744978976, 6619.5035372347265, 5179.870503237449, 6120.0, 6157.076438534255, 6192.628442293477, 6206.534509911775, 4373.650107991353, 4469.173202957463, 4500.302453119706, 4528.592426681615, 4536.455625324982, 1308.4700696218047, 1332.3205165365732, 1333.6532501549473, 1340.557615986722, 1347.8517363090052, 782.7221336425487, 801.8213226557068, 808.6563210635456, 817.264461984219, 846.3905987688854, 6062.514998800086, 6081.422264884829, 6268.4671802897265, 6324.2410910691015, 6397.232332120082, 569.1462805791313, 579.4408395897894, 586.7711592478937, 593.8812237552506, 596.8597379615787, 4214.831406743696, 4337.677008512371, 4355.760433176142, 4373.890652733643, 4382.474103107647, 14425.278736063156, 14830.70338593222, 14962.476313328982, 14968.476103336548, 15007.249637518298, 14769.704605907822, 15092.698146037017, 15103.018303810217, 15116.017458865139, 15180.0, 7567.31894129531, 8531.829363412697, 8649.394542382099, 8694.565271736388, 8701.17338852808, 6459.677016149268, 7086.480945129463, 7166.355028047501, 7190.245024272376, 7208.026916366433, 12696.555620553236, 12830.16603920742, 13379.665508362368, 13515.121517101357, 13707.794383084462, 11360.488778005174, 13674.384652690454, 13792.310384480936, 13839.792403113759, 13855.515056972958, 7372.557646541225, 8556.18716221958, 8731.388802783998, 8732.738017859474, 8757.824843502967, 10644.520996555018, 10697.518611662339, 10748.785024299314, 10763.784724305626, 10809.432632268434, 8986.19124278792, 9022.774430639287, 9058.230050445522, 9072.591733372146, 9121.543922803701, 7380.5940673263485, 8832.779180520538, 8869.778255543662, 8880.467171969702, 8947.463152210888, 7988.920110798817, 8190.0, 8195.67217311301, 8205.58972051407, 8207.220314070153, 5716.28546431696, 5963.642181468952, 6039.15451836752, 6174.104754810617, 6183.0, 5672.035753921926, 5999.4900433463645, 6145.0782382642965, 6228.408301211378, 6380.808575742735], "value": 5290.305358387074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.7758612074393, 385.66639856524114, 407.1612478294711, 407.61073175117764, 408.0675656125752, 6186.731719997401, 6253.781117660883, 6282.685865706713, 6452.225732912046, 6475.9314713072345, 5326.414094449608, 5349.22436246744, 5732.283464566929, 6094.421030002154, 6219.595726277788, 4225.366195070748, 4378.365137055141, 4582.427196600425, 4600.769961501928, 4614.5385461453925, 4180.47744031996, 4486.506484286726, 4516.954830451703, 4552.567506086933, 4595.862124136282, 4510.030343476152, 4520.615747661453, 4525.0, 4526.894993827869, 4530.388397566319, 4540.614047805908, 4549.181147393468, 4552.8861778455475, 4559.74921379324, 4560.361549383089, 3158.3367492826533, 3754.6808521275725, 3913.7651740895635, 3915.314819906505, 3917.7649341039346, 5558.49920521461, 6117.816465506042, 6126.601770884923, 6146.815595532096, 6184.443400094013, 4549.590536851666, 4817.542333478314, 5007.69953802773, 5432.592555558349, 5485.945140548583, 5792.594518383715, 6260.373962603749, 6511.925532287214, 6535.738570457176, 6751.223609284946, 4439.600435960779, 4442.2004039272915, 4448.4439445069365, 4462.977685111554, 4481.282994720829, 6887.345702158287, 7087.1495420549345, 7091.219965803706, 7178.066851309349, 7225.674844632004, 4499.87503124219, 4503.0, 4512.616427603625, 4521.570450807168, 4580.106879158532, 4574.153781550423, 4599.287110497811, 4603.171429142786, 4615.8846028848875, 4645.767711614406, 4020.4974378202724, 4091.1204091120553, 4673.289492094577, 4722.456917454468, 5238.92141617876, 5877.94122058774, 6221.844453888599, 6729.05793188954, 6743.966280168568, 6749.08887300218, 1448.2686243447201, 1576.684663067391, 1577.148339896443, 1579.9526014219591, 1581.1619841484032, 946.7633091727068, 947.7868328539342, 987.3582171588525, 1001.7245257554231, 1041.7551875309296, 5693.373728889819, 6058.303295121029, 6384.457321127663, 6397.698267206798, 6430.0, 6536.215654121441, 6722.260551339349, 6968.372846443743, 6982.93017069833, 7000.719971201095, 5119.360079990001, 5966.880662386815, 6422.8715425691225, 6518.511111666644, 6539.869202615921, 3569.125564236727, 3641.253543023674, 3657.835397407123, 3688.704903607705, 3689.4834723138783, 6682.899756503656, 6815.693293801791, 7156.463265255126, 7240.443304689517, 7254.383377412979, 6035.305939816977, 6583.539152259392, 7132.607706576188, 7187.382838861215, 7227.710891564384, 5841.123831425235, 6059.424354686386, 6291.622502649856, 6293.961496353142, 6607.240167380813, 7021.508494405547, 7025.402840758388, 7029.156501219886, 7042.57744535309, 7055.400290975324, 5269.762860671356, 5630.5777066720975, 6496.740130394731, 6707.396334329934, 6930.341617546325, 5940.79207227745, 6641.33586641332, 6836.829079273058, 7087.929120708727, 7199.460040496879, 3768.792716400624, 4503.211937910918, 4522.5929666330185, 4529.23003089469, 4548.019918189577, 5790.536757059509, 5888.941110588839, 6740.393364597014, 6773.882309418891, 7012.1935977363255, 4836.177849765478, 5317.494837990384, 5814.447627475383, 6722.311075569811, 6740.460763139035, 5830.271216097988, 6455.709493072729, 6654.837177917293, 6702.329767023259, 6726.990951357336, 6578.177727784027, 6580.967095164494, 6591.439727623206, 6593.901091483535, 6635.303293154149, 5906.818636272848, 6467.902981455188, 6757.526973112032, 6762.830929226809, 6767.95096760003, 6215.595986260879, 6545.967270163619, 6549.4760419167305, 6550.344965503412, 6579.703913324008], "value": 4792.426583067899, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.5756334016289, 843.7173546861804, 848.6350869126276, 880.7842078690718, 892.8169725206332, 16470.67058658826, 16499.67000659986, 16976.660466790658, 18056.097195140257, 18810.905945470255, 17476.0, 17775.64448711025, 17851.018193999324, 18125.728114078265, 18269.543261418472, 988.0267936082953, 1018.6129270877057, 1019.0726438940566, 1019.6889948565722, 1020.2501161646184, 986.1469828598283, 989.7773001074737, 994.2692121290845, 995.4624502768496, 996.2627655534889, 1072.05123465733, 1075.7525769073118, 1077.1221454514584, 1078.2991055813704, 1081.405227125082, 933.4119504712056, 1005.4419797012655, 1065.664315740543, 1066.3548553125363, 1069.4225118436036, 739.6819367671899, 763.2672634271106, 777.7705576854842, 780.6057940739947, 795.7970717467067, 13063.150895191877, 15632.687346253126, 15774.211289435598, 15802.367905283774, 15834.604134896605, 16485.83514164855, 17159.05625190614, 17209.0, 17495.0, 17643.823561764475, 13901.443942242298, 14068.788968165485, 14079.85920140806, 14195.716085678228, 14224.786628200585, 3498.2478767065204, 3537.451694987281, 3539.929201415983, 3567.126054116732, 3568.107973006748, 107358.46320768424, 124525.0, 124700.12949805669, 125622.0, 125678.0, 11867.525298988117, 13638.454461821615, 13849.030567860354, 13931.512397066257, 13965.882729381625, 20502.487437813874, 24907.0, 25795.613065804027, 26204.60693089605, 26239.081632142785, 7931.167227441151, 8038.67845286182, 8082.797930051678, 8093.716719914774, 8102.108768035511, 3974.7615143091507, 4050.0, 4054.4526488924207, 4054.5945405459806, 4054.7769872656695, 4682.672212945061, 4840.975795121002, 4870.245112007645, 4884.169691152512, 4904.239842824368, 868.7610907000626, 897.6858099665066, 911.4713466189594, 912.7535565397305, 912.8402529557434, 559.8376470823415, 643.8358218654213, 667.5127157175192, 679.592244653214, 699.8600279944031, 9625.71122866315, 11655.475503602189, 11853.46659400329, 11960.0, 13017.958563314913, 574.3136951343189, 575.4533193466224, 654.1561385812344, 665.4576520136073, 668.5453891353828, 4533.911861153366, 5101.311322971352, 5420.4850539198715, 5502.477264659851, 5560.193771903051, 77025.0, 98866.00969997846, 106139.40790888143, 106333.4683326594, 106689.4665526683, 90927.0, 93444.59833102509, 94658.52670736602, 95269.61825954406, 95505.5674164888, 76175.61912190514, 76402.47195056068, 77213.22786772173, 77225.99999999888, 78677.0, 12044.51821927113, 12516.0, 12531.18547294423, 12541.686457838627, 12570.874291257336, 39152.608473914894, 39649.24209094927, 39665.0, 40702.79648601738, 40848.18303633851, 51712.482875172274, 51790.48209518007, 51920.480795191565, 51927.44217673476, 52111.218331724296, 15443.768343475082, 15816.209189540707, 15855.0, 15910.60223494422, 15990.120543370227, 15452.0, 17783.555411114827, 17929.37247196342, 18109.818901810813, 18210.635787283914, 15827.0, 15838.12890291045, 15868.44460443879, 16063.437779677655, 16207.189640518163, 15881.047137171807, 16019.919900400424, 16058.197090145679, 16172.353105875633, 16256.268467918735, 15296.0, 15484.303206355966, 15632.609184770472, 15736.370545178064, 15830.9208453957, 11486.82769758471, 11590.768184636092, 12039.578614748441, 12094.637160885188, 12124.75750485003, 11919.225250359046, 12437.564685235973, 12454.065945053975, 12556.246625202517, 12917.09580329368], "value": 8311.192092217529, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 112.0, 113.0, 113.0, 112.0, 114.0, 113.0, 116.0, 116.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 136.0, 95.0, 185.0, 142.0, 149.0, 120.0, 112.0, 139.0, 110.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 220.0, 210.0, 267.0, 236.0, 281.0, 277.0, 199.0, 194.0, 196.0], "value": 227.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 60.0, 86.0, 56.0, 60.0, 58.0, 60.0, 75.0, 75.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 116.0, 101.0, 103.0, 102.0, 89.0, 68.0, 65.0, 86.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 135.0, 135.0, 96.0, 88.0, 87.0, 89.0, 87.0, 88.0, 87.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 124.0, 104.0, 154.0, 96.0, 122.0, 112.0, 149.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 74.0, 128.0, 83.0, 149.0, 147.0, 92.0, 93.0, 93.0, 89.0], "value": 109.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 72.0, 80.0, 63.0, 51.0, 46.0, 45.0, 70.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 64.0, 58.0, 57.0, 58.0, 57.0, 56.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 112.0, 106.0, 83.0, 85.0, 86.0, 85.0, 85.0, 77.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 154.0, 166.0, 168.0, 148.0, 142.0, 163.0, 199.0, 147.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 182.0, 183.0, 185.0, 183.0, 238.0, 185.0, 181.0, 186.0], "value": 189.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0, 68.0, 73.0], "value": 68.4, "unit": "score"}]}]} 12:50:57 INFO - Return code: 0 12:50:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:50:57 ERROR - # TBPL SUCCESS # 12:50:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:50:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:50:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:50:57 INFO - 2017-04-16 12:50:57.996 screenresolution[1938:17914] starting screenresolution argv=screenresolution get 12:50:57 INFO - 2017-04-16 12:50:57.999 screenresolution[1938:17914] Display 0: 1600x1200x32@60 12:50:58 INFO - 2017-04-16 12:50:58.005 screenresolution[1939:17917] starting screenresolution argv=screenresolution list 12:50:58 INFO - Available Modes on Display 0 12:50:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:50:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:50:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:50:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:50:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:50:58 INFO - Intel Iris: 12:50:58 INFO - Chipset Model: Intel Iris 12:50:58 INFO - Type: GPU 12:50:58 INFO - Bus: Built-In 12:50:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:50:58 INFO - Vendor: Intel (0x8086) 12:50:58 INFO - Device ID: 0x0a2e 12:50:58 INFO - Revision ID: 0x0009 12:50:58 INFO - Displays: 12:50:58 INFO - Display: 12:50:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:50:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:50:58 INFO - Display Serial Number: 1600x1200 60 12:50:58 INFO - Main Display: Yes 12:50:58 INFO - Mirror: Off 12:50:58 INFO - Online: Yes 12:50:58 INFO - Rotation: Supported 12:50:58 INFO - Return code: 0 12:50:58 INFO - Running post-action listener: _resource_record_post_action 12:50:58 INFO - [mozharness: 2017-04-16 19:50:58.259500Z] Finished run-tests step (success) 12:50:58 INFO - Running post-run listener: _resource_record_post_run 12:50:58 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 2947072; Write bytes: 514984448; Read time: 23; Write time: 1610 12:50:58 INFO - TinderboxPrint: CPU usage
54.0% 12:50:58 INFO - TinderboxPrint: I/O read bytes / time
2,947,072 / 23 12:50:58 INFO - TinderboxPrint: I/O write bytes / time
514,984,448 / 1,610 12:50:58 INFO - TinderboxPrint: CPU idle
2,201.5 (46.0%) 12:50:58 INFO - TinderboxPrint: CPU system
958.7 (20.0%) 12:50:58 INFO - TinderboxPrint: CPU user
1,629.6 (34.0%) 12:50:58 INFO - TinderboxPrint: Swap in / out
211,968,000 / 0 12:50:58 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194683392; Write bytes: 192462848; Read time: 15094; Write time: 396 12:50:58 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 2947072; Write bytes: 304231424; Read time: 23; Write time: 1154 12:50:58 INFO - Running post-run listener: _upload_blobber_files 12:50:58 INFO - Blob upload gear active. 12:50:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:50:58 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 12:50:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:50:59 INFO - (blobuploader) - INFO - Open directory for files ... 12:50:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:50:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:50:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:00 INFO - (blobuploader) - INFO - Done attempting. 12:51:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:51:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:51:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:01 INFO - (blobuploader) - INFO - Done attempting. 12:51:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:51:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:51:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:02 INFO - (blobuploader) - INFO - Done attempting. 12:51:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:51:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:51:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:51:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:51:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:51:05 INFO - (blobuploader) - INFO - Done attempting. 12:51:05 INFO - (blobuploader) - INFO - Iteration through files over. 12:51:05 INFO - Return code: 0 12:51:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:51:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:51:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3c3d77df50b6f298549db45e2a209d5550fbfa3e43f010f4fff5a0aa146148454909a47f193636fc6f6cb8440f0e45fc6b135d3116b3e2065d4af34e9274ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edaa61a97cd1a838aaa67ee66e2d8a000cfeb1b4ba361cffb55e8578019ee78a942608809f14fad0644c9268a0250ea824a60e48aac27a712e134167b0f00242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bc891b675b8892463f717dcea274ae4b6b5ca97655ef0a0484d04687d942b3733f849757628e5a2e9fa6a13c506dcbd46f2ee0fe14a9cb8e79fc793a02ece6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f2c3d8f24f80ccd9979d1e8a9dca69669bec8d060d526769ae649b83dc2e338e52902e712e7b8aecec1ba161fd3c620d13599ad38df3d1bcae71a5f286ef1fd"} 12:51:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:51:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:51:05 INFO - Contents: 12:51:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3c3d77df50b6f298549db45e2a209d5550fbfa3e43f010f4fff5a0aa146148454909a47f193636fc6f6cb8440f0e45fc6b135d3116b3e2065d4af34e9274ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edaa61a97cd1a838aaa67ee66e2d8a000cfeb1b4ba361cffb55e8578019ee78a942608809f14fad0644c9268a0250ea824a60e48aac27a712e134167b0f00242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bc891b675b8892463f717dcea274ae4b6b5ca97655ef0a0484d04687d942b3733f849757628e5a2e9fa6a13c506dcbd46f2ee0fe14a9cb8e79fc793a02ece6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f2c3d8f24f80ccd9979d1e8a9dca69669bec8d060d526769ae649b83dc2e338e52902e712e7b8aecec1ba161fd3c620d13599ad38df3d1bcae71a5f286ef1fd"} 12:51:05 INFO - Running post-run listener: copy_logs_to_upload_dir 12:51:05 INFO - Copying logs to upload dir... 12:51:05 INFO - mkdir: /builds/slave/test/build/upload/logs 12:51:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.875614 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-16 12:51:05.377608) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 12:51:05.381601) ========= 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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3c3d77df50b6f298549db45e2a209d5550fbfa3e43f010f4fff5a0aa146148454909a47f193636fc6f6cb8440f0e45fc6b135d3116b3e2065d4af34e9274ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edaa61a97cd1a838aaa67ee66e2d8a000cfeb1b4ba361cffb55e8578019ee78a942608809f14fad0644c9268a0250ea824a60e48aac27a712e134167b0f00242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bc891b675b8892463f717dcea274ae4b6b5ca97655ef0a0484d04687d942b3733f849757628e5a2e9fa6a13c506dcbd46f2ee0fe14a9cb8e79fc793a02ece6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f2c3d8f24f80ccd9979d1e8a9dca69669bec8d060d526769ae649b83dc2e338e52902e712e7b8aecec1ba161fd3c620d13599ad38df3d1bcae71a5f286ef1fd"} build_url:https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/DN-nlgDDQyyWTUD6CPZ3mA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef3c3d77df50b6f298549db45e2a209d5550fbfa3e43f010f4fff5a0aa146148454909a47f193636fc6f6cb8440f0e45fc6b135d3116b3e2065d4af34e9274ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edaa61a97cd1a838aaa67ee66e2d8a000cfeb1b4ba361cffb55e8578019ee78a942608809f14fad0644c9268a0250ea824a60e48aac27a712e134167b0f00242", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bc891b675b8892463f717dcea274ae4b6b5ca97655ef0a0484d04687d942b3733f849757628e5a2e9fa6a13c506dcbd46f2ee0fe14a9cb8e79fc793a02ece6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f2c3d8f24f80ccd9979d1e8a9dca69669bec8d060d526769ae649b83dc2e338e52902e712e7b8aecec1ba161fd3c620d13599ad38df3d1bcae71a5f286ef1fd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 12:51:05.423460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:51:05.424076) ========= 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.Mvdg9tAsKI/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.dbDUF23JPE/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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.005394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:51:05.474226) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 12:51:05.474549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 12:51:05.474908) ========= ========= Total master_lag: 0.21 =========