builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1484920171.68 results: success (0) buildid: 20170120021724 builduid: f49a2f583c344a2d840e6ddbe0d0d339 revision: 585ed02acd43399decd146f7b83d10e93c765c13 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.676675) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.677195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.677527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.699794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.700307) ========= 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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-01-20 05:49:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.94M=0.001s 2017-01-20 05:49:31 (7.94 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107997 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.834571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.834898) ========= 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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.019502 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:31.884526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-20 05:49:31.884861) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 585ed02acd43399decd146f7b83d10e93c765c13 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 585ed02acd43399decd146f7b83d10e93c765c13 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-01-20 05:49:31,932 truncating revision to first 12 chars 2017-01-20 05:49:31,932 Setting DEBUG logging. 2017-01-20 05:49:31,932 attempt 1/10 2017-01-20 05:49:31,933 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/585ed02acd43?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 05:49:32,719 unpacking tar archive at: graphics-585ed02acd43/testing/mozharness/ program finished with exit code 0 elapsedTime=1.038915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-20 05:49:32.947675) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:32.948059) ========= script_repo_revision: 585ed02acd43399decd146f7b83d10e93c765c13 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:32.948540) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:32.948858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 05:49:32.969506) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-20 05:49:32.970209) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:49:33 INFO - MultiFileLogger online at 20170120 05:49:33 in /builds/slave/test 05:49:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 05:49:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:49:33 INFO - {'append_to_log': False, 05:49:33 INFO - 'base_work_dir': '/builds/slave/test', 05:49:33 INFO - 'blob_upload_branch': 'Graphics', 05:49:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:49:33 INFO - 'branch': 'Graphics', 05:49:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:49:33 INFO - 'config_files': ('talos/mac_config.py',), 05:49:33 INFO - 'default_actions': ('clobber', 05:49:33 INFO - 'read-buildbot-config', 05:49:33 INFO - 'download-and-extract', 05:49:33 INFO - 'populate-webroot', 05:49:33 INFO - 'create-virtualenv', 05:49:33 INFO - 'install', 05:49:33 INFO - 'run-tests'), 05:49:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:49:33 INFO - 'download_minidump_stackwalk': True, 05:49:33 INFO - 'download_symbols': 'ondemand', 05:49:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:49:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:49:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:49:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:49:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:49:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:49:33 INFO - 'installer_path': 'installer.exe', 05:49:33 INFO - 'log_level': 'info', 05:49:33 INFO - 'log_name': 'talos', 05:49:33 INFO - 'log_to_console': True, 05:49:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:49:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:49:33 INFO - 'opt_config_files': (), 05:49:33 INFO - 'pip_index': False, 05:49:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:49:33 INFO - 'cmd': ('bash', 05:49:33 INFO - '-c', 05:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:49:33 INFO - 'enabled': True, 05:49:33 INFO - 'halt_on_failure': False, 05:49:33 INFO - 'name': 'check_screen_resolution'},), 05:49:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:49:33 INFO - 'cmd': ('bash', 05:49:33 INFO - '-c', 05:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:49:33 INFO - 'enabled': True, 05:49:33 INFO - 'halt_on_failure': False, 05:49:33 INFO - 'name': 'check_screen_resolution'},), 05:49:33 INFO - 'run_cmd_checks_enabled': True, 05:49:33 INFO - 'sps_profile': False, 05:49:33 INFO - 'sps_profile_interval': 0, 05:49:33 INFO - 'suite': 'dromaeojs-e10s', 05:49:33 INFO - 'system_bits': '32', 05:49:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:49:33 INFO - 'title': 't-yosemite-r7-0098', 05:49:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:49:33 INFO - 'use_talos_json': True, 05:49:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:49:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:49:33 INFO - 'work_dir': 'build'} 05:49:33 INFO - [mozharness: 2017-01-20 13:49:33.144012Z] Running clobber step. 05:49:33 INFO - Running pre-action listener: _resource_record_pre_action 05:49:33 INFO - Running main action method: clobber 05:49:33 INFO - rmtree: /builds/slave/test/build 05:49:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:49:34 INFO - Running post-action listener: _resource_record_post_action 05:49:34 INFO - [mozharness: 2017-01-20 13:49:34.798327Z] Finished clobber step (success) 05:49:34 INFO - [mozharness: 2017-01-20 13:49:34.798461Z] Running read-buildbot-config step. 05:49:34 INFO - Running pre-action listener: _resource_record_pre_action 05:49:34 INFO - Running main action method: read_buildbot_config 05:49:34 INFO - Using buildbot properties: 05:49:34 INFO - { 05:49:34 INFO - "project": "", 05:49:34 INFO - "product": "firefox", 05:49:34 INFO - "script_repo_revision": "default", 05:49:34 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 05:49:34 INFO - "repository": "", 05:49:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 05:49:34 INFO - "buildid": "20170120021724", 05:49:34 INFO - "pgo_build": "False", 05:49:34 INFO - "basedir": "/builds/slave/test", 05:49:34 INFO - "buildnumber": 10, 05:49:34 INFO - "slavename": "t-yosemite-r7-0098", 05:49:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:49:34 INFO - "platform": "yosemite_r7", 05:49:34 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 05:49:34 INFO - "branch": "graphics", 05:49:34 INFO - "repo_path": "projects/graphics", 05:49:34 INFO - "revision": "585ed02acd43399decd146f7b83d10e93c765c13", 05:49:34 INFO - "stage_platform": "macosx64", 05:49:34 INFO - "builduid": "f49a2f583c344a2d840e6ddbe0d0d339", 05:49:34 INFO - "slavebuilddir": "test" 05:49:34 INFO - } 05:49:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:49:34 INFO - Running post-action listener: _resource_record_post_action 05:49:34 INFO - [mozharness: 2017-01-20 13:49:34.801709Z] Finished read-buildbot-config step (success) 05:49:34 INFO - [mozharness: 2017-01-20 13:49:34.801843Z] Running download-and-extract step. 05:49:34 INFO - Running pre-action listener: _resource_record_pre_action 05:49:34 INFO - Running main action method: download_and_extract 05:49:34 INFO - mkdir: /builds/slave/test/build/tests 05:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:34 INFO - trying https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:49:34 INFO - Downloading https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:49:36 INFO - Downloaded 1199 bytes. 05:49:36 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:49:36 INFO - Using the following test package requirements: 05:49:36 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:49:36 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:49:36 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:49:36 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'jsshell-mac.zip'], 05:49:36 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:49:36 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:49:36 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:49:36 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:49:36 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:49:36 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:49:36 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:49:36 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:49:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:49:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:49:36 INFO - Fetch https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:49:37 INFO - Expected file size: 16340635 05:49:37 INFO - Obtained file size: 16340635 05:49:43 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:49:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:49:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:49:43 INFO - Fetch https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:49:44 INFO - Expected file size: 16340635 05:49:44 INFO - Obtained file size: 16340635 05:49:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:49:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:49:51 INFO - Fetch https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:49:51 INFO - Expected file size: 14403463 05:49:51 INFO - Obtained file size: 14403463 05:49:52 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')]} 05:49:52 INFO - trying https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:49:52 INFO - Downloading https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:49:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:49:54 INFO - Downloaded 69937799 bytes. 05:49:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:49:54 INFO - mkdir: /builds/slave/test/properties 05:49:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:49:54 INFO - Writing to file /builds/slave/test/properties/build_url 05:49:54 INFO - Contents: 05:49:54 INFO - build_url:https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:49:54 INFO - Running post-action listener: _resource_record_post_action 05:49:54 INFO - Running post-action listener: set_extra_try_arguments 05:49:54 INFO - [mozharness: 2017-01-20 13:49:54.994651Z] Finished download-and-extract step (success) 05:49:54 INFO - [mozharness: 2017-01-20 13:49:54.994776Z] Running populate-webroot step. 05:49:54 INFO - Running pre-action listener: _resource_record_pre_action 05:49:54 INFO - Running main action method: populate_webroot 05:49:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:49:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:49:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:49:55 INFO - '--tppagecycles', 05:49:55 INFO - '7'], 05:49:55 INFO - 'tests': ['tsvgm']}}, 05:49:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:49:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:49:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:49:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:49:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['damp', 'tps']}, 05:49:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'tests': ['damp', 'tps']}, 05:49:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['dromaeo_dom']}, 05:49:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:49:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['basic_compositor_video']}, 05:49:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:49:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'other-e10s': {'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['a11yr', 05:49:55 INFO - 'ts_paint', 05:49:55 INFO - 'tpaint', 05:49:55 INFO - 'sessionrestore', 05:49:55 INFO - 'sessionrestore_no_auto_restore', 05:49:55 INFO - 'tabpaint']}, 05:49:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['tsvgx', 05:49:55 INFO - 'tsvgr_opacity', 05:49:55 INFO - 'tart', 05:49:55 INFO - 'tscrollx', 05:49:55 INFO - 'cart', 05:49:55 INFO - 'tsvg_static']}, 05:49:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:49:55 INFO - 'tsvgr_opacity', 05:49:55 INFO - 'tart', 05:49:55 INFO - 'tscrollx', 05:49:55 INFO - 'cart', 05:49:55 INFO - 'tsvg_static']}, 05:49:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'talos_options': ['--disable-e10s'], 05:49:55 INFO - 'tests': ['tp5o']}, 05:49:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:49:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'talos_options': ['--disable-e10s', 05:49:55 INFO - '--xperf_path', 05:49:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:55 INFO - 'tests': ['tp5n']}, 05:49:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:49:55 INFO - 'talos_options': ['--xperf_path', 05:49:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:55 INFO - 'tests': ['tp5n']}}, 05:49:55 INFO - 'talos.zip': {'path': '', 05:49:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:49:55 INFO - Running post-action listener: _resource_record_post_action 05:49:55 INFO - [mozharness: 2017-01-20 13:49:55.012932Z] Finished populate-webroot step (success) 05:49:55 INFO - [mozharness: 2017-01-20 13:49:55.013042Z] Running create-virtualenv step. 05:49:55 INFO - Running pre-action listener: _resource_record_pre_action 05:49:55 INFO - Running main action method: create_virtualenv 05:49:55 INFO - Creating virtualenv /builds/slave/test/build/venv 05:49:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:49:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:49:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:49:55 INFO - Using real prefix '/tools/python27' 05:49:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:49:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:49:58 INFO - Installing pip.................done. 05:49:58 INFO - Return code: 0 05:49:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:49:58 INFO - Reading from file tmpfile_stdout 05:49:58 INFO - Output received: 05:49:58 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) 05:49:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:49:58 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')]} 05:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:58 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')]} 05:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:58 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:49:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:49:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:49:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:58 INFO - 'HOME': '/Users/cltbld', 05:49:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:58 INFO - 'LOGNAME': 'cltbld', 05:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:58 INFO - 'MOZ_NO_REMOTE': '1', 05:49:58 INFO - 'NO_EM_RESTART': '1', 05:49:58 INFO - 'PAGER': '/bin/cat', 05:49:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:58 INFO - 'PWD': '/builds/slave/test', 05:49:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:58 INFO - 'SHELL': '/bin/bash', 05:49:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:49:58 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:49:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:58 INFO - 'USER': 'cltbld', 05:49:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:59 INFO - 'XPC_FLAGS': '0x0', 05:49:59 INFO - 'XPC_SERVICE_NAME': '0', 05:49:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:49:59 INFO - Cleaning up... 05:49:59 INFO - Return code: 0 05:49:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:49: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')]} 05:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49: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')]} 05:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49: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', '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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:49: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:49: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 psutil>=3.1.1 05:49:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:49:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:59 INFO - 'HOME': '/Users/cltbld', 05:49:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:59 INFO - 'LOGNAME': 'cltbld', 05:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:59 INFO - 'MOZ_NO_REMOTE': '1', 05:49:59 INFO - 'NO_EM_RESTART': '1', 05:49:59 INFO - 'PAGER': '/bin/cat', 05:49:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:59 INFO - 'PWD': '/builds/slave/test', 05:49:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:59 INFO - 'SHELL': '/bin/bash', 05:49:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:49:59 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:49:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:59 INFO - 'USER': 'cltbld', 05:49:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:59 INFO - 'XPC_FLAGS': '0x0', 05:49:59 INFO - 'XPC_SERVICE_NAME': '0', 05:49:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:59 INFO - Downloading/unpacking psutil>=3.1.1 05:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:50:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:50:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:50:02 INFO - Installing collected packages: psutil 05:50:02 INFO - Running setup.py install for psutil 05:50:03 INFO - building 'psutil._psutil_osx' extension 05:50:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:50:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:50:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:50:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:50:03 INFO - building 'psutil._psutil_posix' extension 05:50:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:50:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:50:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:50:03 INFO - ^ 05:50:03 INFO - 1 warning generated. 05:50:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:50:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:50:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:50:03 INFO - Successfully installed psutil 05:50:03 INFO - Cleaning up... 05:50:03 INFO - Return code: 0 05:50:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:50:03 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')]} 05:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:03 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')]} 05:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:03 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:50:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:03 INFO - 'HOME': '/Users/cltbld', 05:50:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:03 INFO - 'LOGNAME': 'cltbld', 05:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:03 INFO - 'MOZ_NO_REMOTE': '1', 05:50:03 INFO - 'NO_EM_RESTART': '1', 05:50:03 INFO - 'PAGER': '/bin/cat', 05:50:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:03 INFO - 'PWD': '/builds/slave/test', 05:50:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:03 INFO - 'SHELL': '/bin/bash', 05:50:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:03 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:03 INFO - 'USER': 'cltbld', 05:50:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:03 INFO - 'XPC_FLAGS': '0x0', 05:50:03 INFO - 'XPC_SERVICE_NAME': '0', 05:50:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:50:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:50:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:50:06 INFO - Installing collected packages: mozsystemmonitor 05:50:06 INFO - Running setup.py install for mozsystemmonitor 05:50:06 INFO - Successfully installed mozsystemmonitor 05:50:06 INFO - Cleaning up... 05:50:07 INFO - Return code: 0 05:50:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:07 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:07 INFO - 'HOME': '/Users/cltbld', 05:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:07 INFO - 'LOGNAME': 'cltbld', 05:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:07 INFO - 'MOZ_NO_REMOTE': '1', 05:50:07 INFO - 'NO_EM_RESTART': '1', 05:50:07 INFO - 'PAGER': '/bin/cat', 05:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:07 INFO - 'PWD': '/builds/slave/test', 05:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:07 INFO - 'SHELL': '/bin/bash', 05:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:07 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:07 INFO - 'USER': 'cltbld', 05:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:07 INFO - 'XPC_FLAGS': '0x0', 05:50:07 INFO - 'XPC_SERVICE_NAME': '0', 05:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:07 INFO - Downloading/unpacking jsonschema==2.5.1 05:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:50:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:10 INFO - Downloading functools32-3.2.3-2.tar.gz 05:50:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:50:10 INFO - warning: no files found matching '*.txt' 05:50:10 INFO - no previously-included directories found matching 'build' 05:50:10 INFO - no previously-included directories found matching 'dist' 05:50:10 INFO - no previously-included directories found matching '.git*' 05:50:10 INFO - Installing collected packages: jsonschema, functools32 05:50:10 INFO - Running setup.py install for functools32 05:50:10 INFO - warning: no files found matching '*.txt' 05:50:10 INFO - no previously-included directories found matching 'build' 05:50:10 INFO - no previously-included directories found matching 'dist' 05:50:10 INFO - no previously-included directories found matching '.git*' 05:50:10 INFO - Successfully installed jsonschema functools32 05:50:10 INFO - Cleaning up... 05:50:10 INFO - Return code: 0 05:50:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:50:10 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')]} 05:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 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')]} 05:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:10 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 05:50:10 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 05:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:10 INFO - 'HOME': '/Users/cltbld', 05:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:10 INFO - 'LOGNAME': 'cltbld', 05:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:10 INFO - 'MOZ_NO_REMOTE': '1', 05:50:10 INFO - 'NO_EM_RESTART': '1', 05:50:10 INFO - 'PAGER': '/bin/cat', 05:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:10 INFO - 'PWD': '/builds/slave/test', 05:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:10 INFO - 'SHELL': '/bin/bash', 05:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:10 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:10 INFO - 'USER': 'cltbld', 05:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:10 INFO - 'XPC_FLAGS': '0x0', 05:50:10 INFO - 'XPC_SERVICE_NAME': '0', 05:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:50:10 INFO - Cleaning up... 05:50:10 INFO - Return code: 0 05:50:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:50:10 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')]} 05:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 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')]} 05:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:10 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:10 INFO - 'HOME': '/Users/cltbld', 05:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:10 INFO - 'LOGNAME': 'cltbld', 05:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:10 INFO - 'MOZ_NO_REMOTE': '1', 05:50:10 INFO - 'NO_EM_RESTART': '1', 05:50:10 INFO - 'PAGER': '/bin/cat', 05:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:10 INFO - 'PWD': '/builds/slave/test', 05:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:10 INFO - 'SHELL': '/bin/bash', 05:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:10 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:10 INFO - 'USER': 'cltbld', 05:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:10 INFO - 'XPC_FLAGS': '0x0', 05:50:10 INFO - 'XPC_SERVICE_NAME': '0', 05:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:11 INFO - Downloading/unpacking blobuploader==1.2.4 05:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:50:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:50:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:50:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:15 INFO - Downloading docopt-0.6.1.tar.gz 05:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:50:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:50:15 INFO - Running setup.py install for blobuploader 05:50:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:50:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:50:15 INFO - Running setup.py install for requests 05:50:15 INFO - Running setup.py install for docopt 05:50:15 INFO - Successfully installed blobuploader requests docopt 05:50:15 INFO - Cleaning up... 05:50:15 INFO - Return code: 0 05:50:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:15 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:16 INFO - 'HOME': '/Users/cltbld', 05:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:16 INFO - 'LOGNAME': 'cltbld', 05:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:16 INFO - 'MOZ_NO_REMOTE': '1', 05:50:16 INFO - 'NO_EM_RESTART': '1', 05:50:16 INFO - 'PAGER': '/bin/cat', 05:50:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:16 INFO - 'PWD': '/builds/slave/test', 05:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:16 INFO - 'SHELL': '/bin/bash', 05:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:16 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:16 INFO - 'USER': 'cltbld', 05:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:16 INFO - 'XPC_FLAGS': '0x0', 05:50:16 INFO - 'XPC_SERVICE_NAME': '0', 05:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pSINgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-XeROxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-j2zFk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9MF_5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-iPcuCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-IsQDJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-QZXZQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:50:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KG4thm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KhEKbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-cl7tih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-dngOE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-u2kdiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-RKKLYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-otYfnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-7h4kQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-94FhT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:50:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JmV501-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:50:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:50:18 INFO - Running setup.py install for manifestparser 05:50:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:50:18 INFO - Running setup.py install for mozcrash 05:50:18 INFO - Running setup.py install for mozdebug 05:50:18 INFO - Running setup.py install for mozdevice 05:50:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:50:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:50:18 INFO - Running setup.py install for mozfile 05:50:18 INFO - Running setup.py install for mozhttpd 05:50:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:50:18 INFO - Running setup.py install for mozinfo 05:50:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Running setup.py install for mozInstall 05:50:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Running setup.py install for mozleak 05:50:19 INFO - Running setup.py install for mozlog 05:50:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Running setup.py install for moznetwork 05:50:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Running setup.py install for mozprocess 05:50:19 INFO - Running setup.py install for mozprofile 05:50:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:50:19 INFO - Running setup.py install for mozrunner 05:50:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:50:20 INFO - Running setup.py install for mozscreenshot 05:50:20 INFO - Running setup.py install for moztest 05:50:20 INFO - Running setup.py install for mozversion 05:50:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:50:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:50:20 INFO - Cleaning up... 05:50:20 INFO - Return code: 0 05:50:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50: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')]} 05:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50: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')]} 05:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:20 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:20 INFO - 'HOME': '/Users/cltbld', 05:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:20 INFO - 'LOGNAME': 'cltbld', 05:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:20 INFO - 'MOZ_NO_REMOTE': '1', 05:50:20 INFO - 'NO_EM_RESTART': '1', 05:50:20 INFO - 'PAGER': '/bin/cat', 05:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:20 INFO - 'PWD': '/builds/slave/test', 05:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:20 INFO - 'SHELL': '/bin/bash', 05:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:20 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:20 INFO - 'USER': 'cltbld', 05:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:20 INFO - 'XPC_FLAGS': '0x0', 05:50:20 INFO - 'XPC_SERVICE_NAME': '0', 05:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:50:20 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-FOPCYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:50:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-LAEPvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:50:21 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)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-lDUF1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-sKwAIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-PC1Sqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Z3cFbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JwipQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-aRf8yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CCUZJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-DB4n1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:50:21 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)) 05:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:50:21 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-6mFm2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-F6cBKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-I4PW5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-04Celb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:50:22 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)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Qw3kW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-MrKZ0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:50:22 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)) 05:50:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:50:22 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-tNP56X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:50:22 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)) 05:50:22 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)) 05:50:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:50:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:50:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:50:25 INFO - Downloading blessings-1.6.tar.gz 05:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:50:25 INFO - Installing collected packages: blessings 05:50:25 INFO - Running setup.py install for blessings 05:50:25 INFO - Successfully installed blessings 05:50:25 INFO - Cleaning up... 05:50:25 INFO - Return code: 0 05:50:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:50:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:50:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:50:25 INFO - Reading from file tmpfile_stdout 05:50:25 INFO - Current package versions: 05:50:25 INFO - blessings == 1.6 05:50:25 INFO - blobuploader == 1.2.4 05:50:25 INFO - docopt == 0.6.1 05:50:25 INFO - functools32 == 3.2.3-2 05:50:25 INFO - jsonschema == 2.5.1 05:50:25 INFO - manifestparser == 1.1 05:50:25 INFO - mozInstall == 1.12 05:50:25 INFO - mozcrash == 1.0 05:50:26 INFO - mozdebug == 0.1 05:50:26 INFO - mozdevice == 0.49 05:50:26 INFO - mozfile == 1.2 05:50:26 INFO - mozhttpd == 0.7 05:50:26 INFO - mozinfo == 0.9 05:50:26 INFO - mozleak == 0.1 05:50:26 INFO - mozlog == 3.4 05:50:26 INFO - moznetwork == 0.27 05:50:26 INFO - mozprocess == 0.24 05:50:26 INFO - mozprofile == 0.28 05:50:26 INFO - mozrunner == 6.13 05:50:26 INFO - mozscreenshot == 0.1 05:50:26 INFO - mozsystemmonitor == 0.3 05:50:26 INFO - moztest == 0.8 05:50:26 INFO - mozversion == 1.4 05:50:26 INFO - psutil == 3.1.1 05:50:26 INFO - requests == 1.2.3 05:50:26 INFO - wsgiref == 0.1.2 05:50:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:26 INFO - 'HOME': '/Users/cltbld', 05:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:26 INFO - 'LOGNAME': 'cltbld', 05:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:26 INFO - 'MOZ_NO_REMOTE': '1', 05:50:26 INFO - 'NO_EM_RESTART': '1', 05:50:26 INFO - 'PAGER': '/bin/cat', 05:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:26 INFO - 'PWD': '/builds/slave/test', 05:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:26 INFO - 'SHELL': '/bin/bash', 05:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:26 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:26 INFO - 'USER': 'cltbld', 05:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:26 INFO - 'XPC_FLAGS': '0x0', 05:50:26 INFO - 'XPC_SERVICE_NAME': '0', 05:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:50:26 INFO - Cleaning up... 05:50:26 INFO - Return code: 0 05:50:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:50:26 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 0x10b5c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc383c9a020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, '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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:50:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:26 INFO - 'HOME': '/Users/cltbld', 05:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:26 INFO - 'LOGNAME': 'cltbld', 05:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:26 INFO - 'MOZ_NO_REMOTE': '1', 05:50:26 INFO - 'NO_EM_RESTART': '1', 05:50:26 INFO - 'PAGER': '/bin/cat', 05:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:26 INFO - 'PWD': '/builds/slave/test', 05:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:26 INFO - 'SHELL': '/bin/bash', 05:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:26 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:26 INFO - 'USER': 'cltbld', 05:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:26 INFO - 'XPC_FLAGS': '0x0', 05:50:26 INFO - 'XPC_SERVICE_NAME': '0', 05:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:50:26 INFO - Cleaning up... 05:50:26 INFO - Return code: 0 05:50:26 INFO - Running post-action listener: _resource_record_post_action 05:50:26 INFO - Running post-action listener: _start_resource_monitoring 05:50:26 INFO - Starting resource monitoring. 05:50:26 INFO - [mozharness: 2017-01-20 13:50:26.575178Z] Finished create-virtualenv step (success) 05:50:26 INFO - [mozharness: 2017-01-20 13:50:26.576118Z] Running install step. 05:50:26 INFO - Running pre-action listener: _resource_record_pre_action 05:50:26 INFO - Running main action method: install 05:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:50:26 INFO - Reading from file tmpfile_stdout 05:50:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:50:26 INFO - Reading from file tmpfile_stdout 05:50:26 INFO - Output received: 05:50:26 INFO - Usage: mozinstall [options] installer 05:50:26 INFO - Options: 05:50:26 INFO - -h, --help show this help message and exit 05:50:26 INFO - -d DEST, --destination=DEST 05:50:26 INFO - Directory to install application into. [default: 05:50:26 INFO - "/builds/slave/test"] 05:50:26 INFO - --app=APP Application being installed. [default: firefox] 05:50:26 INFO - mkdir: /builds/slave/test/build/application 05:50:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:50:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:50:48 INFO - Reading from file tmpfile_stdout 05:50:48 INFO - Output received: 05:50:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:50:48 INFO - Running post-action listener: _resource_record_post_action 05:50:48 INFO - [mozharness: 2017-01-20 13:50:48.843129Z] Finished install step (success) 05:50:48 INFO - [mozharness: 2017-01-20 13:50:48.843278Z] Running run-tests step. 05:50:48 INFO - Running pre-action listener: _resource_record_pre_action 05:50:48 INFO - Running main action method: run_tests 05:50:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:48 INFO - 2017-01-20 05:50:48.857 screenresolution[1901:15562] starting screenresolution argv=screenresolution get 05:50:48 INFO - 2017-01-20 05:50:48.860 screenresolution[1901:15562] Display 0: 1600x1200x32@60 05:50:48 INFO - 2017-01-20 05:50:48.866 screenresolution[1902:15565] starting screenresolution argv=screenresolution list 05:50:48 INFO - Available Modes on Display 0 05:50:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:49 INFO - Intel Iris: 05:50:49 INFO - Chipset Model: Intel Iris 05:50:49 INFO - Type: GPU 05:50:49 INFO - Bus: Built-In 05:50:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:49 INFO - Vendor: Intel (0x8086) 05:50:49 INFO - Device ID: 0x0a2e 05:50:49 INFO - Revision ID: 0x0009 05:50:49 INFO - Displays: 05:50:49 INFO - Display: 05:50:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:49 INFO - Display Serial Number: 1600x1200 60 05:50:49 INFO - Main Display: Yes 05:50:49 INFO - Mirror: Off 05:50:49 INFO - Online: Yes 05:50:49 INFO - Rotation: Supported 05:50:49 INFO - Return code: 0 05:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:50:49 INFO - Python 2.7.3 05:50:49 INFO - Return code: 0 05:50:49 INFO - grabbing minidump binary from tooltool 05:50: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')]} 05:50:49 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 0x10b39fd30>, '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 0x10b2c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b394be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:50:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:50:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:50:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:50:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:50:49 INFO - Return code: 0 05:50:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:50:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:50:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:50:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:50:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 05:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --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 05:50:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VlEGnuYLo8/Render', 05:50:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:49 INFO - 'HOME': '/Users/cltbld', 05:50:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:49 INFO - 'LOGNAME': 'cltbld', 05:50:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:50:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:49 INFO - 'MOZ_NO_REMOTE': '1', 05:50:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:50:49 INFO - 'NO_EM_RESTART': '1', 05:50:49 INFO - 'PAGER': '/bin/cat', 05:50:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:49 INFO - 'PWD': '/builds/slave/test', 05:50:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:50:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:49 INFO - 'SHELL': '/bin/bash', 05:50:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WEWLcm4DtK/Listeners', 05:50:49 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:50:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:49 INFO - 'USER': 'cltbld', 05:50:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:49 INFO - 'XPC_FLAGS': '0x0', 05:50:49 INFO - 'XPC_SERVICE_NAME': '0', 05:50:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 05:50:49 INFO - mozversion application_buildid: 20170120021724 05:50:49 INFO - mozversion application_changeset: 585ed02acd43399decd146f7b83d10e93c765c13 05:50:49 INFO - mozversion application_display_name: Nightly 05:50:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:50:49 INFO - mozversion application_name: Firefox 05:50:49 INFO - mozversion application_remotingname: firefox 05:50:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 05:50:49 INFO - mozversion application_vendor: Mozilla 05:50:49 INFO - mozversion application_version: 53.0a1 05:50:49 INFO - mozversion platform_buildid: 20170120021724 05:50:49 INFO - mozversion platform_changeset: 585ed02acd43399decd146f7b83d10e93c765c13 05:50:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 05:50:49 INFO - mozversion platform_version: 53.0a1 05:50:49 INFO - using testdate: 1484920249 05:50:49 INFO - actual date: 1484920249 05:50:49 INFO - starting webserver on 'localhost:52572' 05:50:49 INFO - SUITE-START | Running 2 tests 05:50:49 INFO - TEST-START | dromaeo_css 05:50:49 INFO - operating with platform_type : mac_ 05:50:49 INFO - Initialising browser for dromaeo_css test... 05:50:49 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpaN5ohh/profile http://localhost:52572/getInfo.html) 05:50:52 INFO - PROCESS | 1912 | 2017-01-20 05:50:52.326 plugin-container[1914:15725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 05:50:52 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:52 INFO - PROCESS | 1912 | 2017-01-20 05:50:52.329 plugin-container[1914:15725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 05:50:52 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:52 INFO - PROCESS | 1912 | 2017-01-20 05:50:52.329 plugin-container[1914:15725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:50:52 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 05:50:52 INFO - PROCESS | 1912 | colorDepth:24 05:50:52 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 05:50:52 INFO - PROCESS | 1912 | __metrics 05:50:52 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 05:50:53 INFO - TEST-INFO | 1912: exit 0 05:50:53 INFO - Browser initialized. 05:50:53 INFO - Running cycle 1/1 for dromaeo_css test... 05:50:53 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpaN5ohh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:55 INFO - PROCESS | 1915 | 2017-01-20 05:50:55.648 plugin-container[1916:15843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:50:55 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:55 INFO - PROCESS | 1915 | 2017-01-20 05:50:55.648 plugin-container[1916:15843] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 05:50:55 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:55 INFO - PROCESS | 1915 | 2017-01-20 05:50:55.648 plugin-container[1916:15843] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:51:05 INFO - PROCESS | 1915 | RSS: Main: 232009728 05:51:05 INFO - PROCESS | 1915 | 05:54:07 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52572/tests/dromaeo/cssquery-ext.html) 05:54:09 INFO - PROCESS | 1915 | RSS: Main: 250773504 05:54:09 INFO - PROCESS | 1915 | 05:55:53 INFO - PROCESS | 1915 | ************************* 05:55:53 INFO - PROCESS | 1915 | A coding exception was thrown and uncaught in a Task. 05:55:53 INFO - PROCESS | 1915 | 05:55:53 ERROR - PROCESS | 1915 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:55:53 INFO - PROCESS | 1915 | Full stack: 05:55:53 INFO - PROCESS | 1915 | ************************* 05:57:12 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-ext.html (next: http://localhost:52572/tests/dromaeo/cssquery-jquery.html) 05:57:13 INFO - PROCESS | 1915 | RSS: Main: 262356992 05:57:13 INFO - PROCESS | 1915 | 06:00:14 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52572/tests/dromaeo/cssquery-mootools.html) 06:00:15 INFO - PROCESS | 1915 | RSS: Main: 270024704 06:00:15 INFO - PROCESS | 1915 | 06:03:17 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52572/tests/dromaeo/cssquery-prototype.html) 06:03:18 INFO - PROCESS | 1915 | RSS: Main: 266084352 06:03:18 INFO - PROCESS | 1915 | 06:06:20 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52572/tests/dromaeo/cssquery-yui.html) 06:06:21 INFO - PROCESS | 1915 | RSS: Main: 271020032 06:06:21 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:52572/tests/dromaeo/cssquery-yui.html (next: http://localhost:52572/tests/dromaeo/cssquery-dojo.html) 06:09:24 INFO - PROCESS | 1915 | RSS: Main: 273481728 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | __start_tp_report 06:09:24 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 06:09:24 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 06:09:24 INFO - PROCESS | 1915 | |i|pagename|runs| 06:09:24 INFO - PROCESS | 1915 | |0;dojo.html;3358.2443950111224;3361.915952101199;3370.3427831572853;3402.4215883299835;3415.8633654653813;5661.716914154296;5681.176229446733;5708.0867061270255;5749.108888122342;5758.769649214037;5668.603197776158;5725.14122881568;5808.709564521768;5837.124431335302;5847.883042339151;5224.39919409269;5244.632875698702;5248.658837175591;5280.9207861882105;5305.177697456881;5238.580913526908;5349.117395629716;5355.116405793059;5380.677159370451;5395.919061214066;5148.742562871841;5316.255724198615;5324.946750532484;5339.199120131992;5352.705601191933;5201.583873290166;5209.0363282792805;5248.923970586021;5258.763355649005;5264.657797243167;5179.637425380225;5198.92201616976;5266.552343050846;5275.366955965271;5293.814716484943;4661.95338046622;5684.573656975744;5723.313202415695;5763.221965034751;5850.0932355484965;6120.755169793203;6127.647695153045;6142.805741014692;6204.689765511706;6235;6169.7840575580085;6203.689815509251;6225.688715564249;6250.4374606285655;6282.560220784547;4777.689450185727;5854;5854.5023672987845;5900.527957763368;5901.763929442817;6164.753409863645;6207.286162091327;6265.498760099181;6282.780102696383;6304.873902521924;5064.822731204464;6098.664573448415;6108.205933228652;6112.108359148532;6179.876402471926;6242;6243;6260.68696565179;6283.937160628427;6302.621842689362;6056.3338032816355;6100.298465676416;6122.855605810045;6135.53983451234;6139.631622102688;5208.583313334924;6203.100550420254;6230.7819226326865;6234;6266.373362663697;4922.064807686528;4980.77586508608;4985.52637499437;5036.093503169392;5047.192449208109;4271.295236286041;4398.384226208269;4436.733795972252;4493.236149854532;4496.055828276033;5359.14253719403;5956.225690660273;5985.521158307325;5995.850103747442;6026.578139530278;6210.596311239755;6222.595531290452;6240.31356550779;6241.407066328691;6251.28110267316;4680.297955306732;5791.044477661138;5821.941780582225;5825.854353641193;6007.699615019232;5338.706371149624;5361.839144825662;5382.434844341366;5433.347998240158;5466.726663666801;5225.764840582183;6205.131281620486;6263.78076767311;6267.4986001119805;6272.4982001440685;6184.721687524162;6187.969060154851;6216.2540495140875;6234.781782637586;6242.001279795211;6145.539084568586;6176.35148309421;6217.626942383471;6226.533010024357;6242.65665388408;5164.690118592896;5268.4994925482015;6216.658083805434;6247.312795592572;6255.96872015637;6200.3179650237535;6213.285472170758;6223.439890409885;6226.439620434183;6241.656708881055;5885.823425297248;6042.697865106639;6058.4244496772735;6142.590862071594;6188.44304012641;6057.545684073801;6096.695165241809;6129.570930035033;6176.289726681489;6212.316645168938;5707.229524014205;5871.1486834407915;5893.764249429974;6021.055775405028;6022;5980.760769569169;6086.482648974887;6091.329953705177;6115.5719099664375;6140.416660417253;5982.581219314607;5984.1323008164445;6002.2197114376595;6003.909941350796;6021.879562408815 06:09:24 INFO - PROCESS | 1915 | |1;ext.html;462.7182703910169;469.37807405188136;476.47111706006336;510.2957918073059;533.6851257757922;38446;41484.96287592812;41598.79200603996;41661.58338416615;41730.791346043225;42215;42769.572304276946;42896.356554651626;43069;43197.78401107998;8153.9184608153755;8927.77680557988;9279.582418791137;9447.952760236189;11177.72055698606;9457.385269957465;11074.723131921686;11204.271722338022;11226.607068752595;11227.877212278789;9519.714408567754;11215.831762523529;11228.15788815838;11243.381614011227;11293.491792869341;11146;11152.776944461106;11215.766265710807;11221.38282394468;11226.101911847032;10941.617043403523;11146.777064458747;11174.720631984184;11202.607908723237;11210.439478026066;37159.62840371616;37170.81414592937;37309.81345093257;37579.624203757885;37658.05854853623;3367.7810942288675;3589.228315912065;3998.8800335989677;4019.8191081401114;4029.8388064477385;28689;28860.278493037633;29399.706002940125;29475;29477.904819993808;2709.403931135147;3197;3298.2908674634828;3339.9499007514905;3353.7149342305934;479030;481537;485305;485429;486056.99999999645;57240.713796430755;57584.71207643935;57586.7120664394;57738.55653608694;57763;59898.10152847623;59953.70023149944;60189.796204075676;60313.39686603078;60637.09044364338;12920.935395322964;14037.947153963494;15702.293396797171;15737.763933541006;15746.527604171893;10894.237403381845;11944;12223.449944752507;12256.632301030982;12426.440810163564;18279;18319.54201144982;18424.35514756977;18434.354797582022;18472.630547388977;7503.473367517258;7745.148033716287;7811.515811995816;7839.176886426957;7913.683452661944;878.9428687135315;886.082904194156;891.1934699097327;912.3567884641312;928.4661319741176;28354.149375518766;29383.55924661132;29545.556816647768;30557.69442305593;30832.53751193734;1069.224812011301;1074.1406874500524;1107.2360071550604;1110.2505808579176;1225.2158618484177;15153.696926061417;15860.841391586167;16145.838541614434;16155.596110097107;16200.270987805578;234104;236901.6309836914;237229;237743;238792;61674;62249.68875155595;62252.688736556935;62449.68775156095;62529.68735156295;1402.3128666953119;1434.211183848879;1437.1951707044088;1447.8045463862454;1452.7385070687378;28173;29154.271143221587;29854.70145298519;30371.81064243458;30451.54322685206;44877;53040;53056.469435306695;53309.200361993826;53434.732826337175;529713;530959;531626;532064;532822;24872;26865.194044178705;27230.72769272282;27263.72736272612;27369.86315068412;31882.043538693877;37298;41734.791326043174;43082.353764692925;44123.558764411944;33939.69002527503;35976.82011589925;36858.63141368659;36938.44592331063;38777.806110969264;36246.818765906;36402.27195456129;37112;37224.88325350244;37421.81289093537;35226;35671.64328356787;36061.73783917655;36181.457278140326;36234;30773.076807695805;31986;32079.67920320767;32120.67879321177;32158.19604509906;17106.91446542759;19217.32739354116;23600.937957791597;23988;24035.399115022265 06:09:24 INFO - PROCESS | 1915 | |2;jquery.html;336.56078817143634;339.2181022742579;386.6230425335298;389.43337444018954;405.839693321138;19018.619627607455;19523.902380488096;20534.89732551339;20707.689384659203;20853.791462085373;17438.38965636203;20922.476938076517;21394.35816925491;21817;21957;5558.749856256478;5618.887622247573;5643.435656434344;5727.398623144572;5887.116932460122;4850.757462126897;5662.17898404731;5728.083506638938;5748.511376533;5767.192593036978;5444;5549.223108764756;5705.4579814917515;5711.571632127586;5714.971425142869;5636.04187288162;5722.055860782966;5723.742431590588;5726.255586773735;5728.42715728428;4807.158747219258;4954.777035033432;5018.64869459138;5042.142835717937;5053.36832895888;24963.62554561683;25218.495630087295;25275.24174274775;25282.367940801443;25428.491430171478;2687.2475706801933;2689.663792025997;2707.512647723409;2720.5375086235385;2744.2316151477617;15124;15543.844561554466;15555.30001149951;15832;16156.515304540882;1820.4447643468736;1827.9451616451527;1836.5775871549617;1839.1447976690904;1857.4149143019906;129380;129833.35083324618;131286;131363.99999999904;135307;11344.773104538028;11455.59905403307;11648.6120345329;11705.297682139098;11956.521739130338;14247.43010279598;14375.712485750435;14422.206778627276;14426.134431934117;14432.855671443362;8366.58167091643;8472;8505.65977360911;8559.486430814171;8633.481991080555;7045.471589630798;8401.537915414588;8405.243528082501;8420.705275315335;8598.613062412081;6935.965320173367;7014;7014.649267536603;7046.6828992695455;7125.25184855593;1717.896926184433;1880.7743070831589;1923.6441258367247;1925.836303914155;1937.9612407752047;991.504247876062;1043.7599352148895;1069.5989004123453;1085.0939465546198;1135.1089394825024;16340.75488867668;21240.681389779165;22023.66964495534;22101.778982209973;22147.78187199687;985.7486340983004;1066.3228849680354;1069.2675517270695;1125.6285425809463;1133.5352505472717;9657.806843863083;10883.510242039127;11528.481218345194;11562.07503399726;11576.768464630828;600887;659768;719758;730862;736891;78570.82143767847;115978;129374;129444;137200;1022.3814592171777;1073.2272763610188;1079.983800242997;1088.5210507376728;1092.6503518873883;16015.55859972608;20350.08424620866;20412.387628371172;20417.285395011102;21146.471338216666;58691;58984;59113.40886591079;60557.39442605517;60677;89107;92496.53751731198;93245;94123;94378.52810736177;8250.504969701837;8345.707900223462;8430.70492532783;8528.488290702579;8605.139486051345;11961.820572691591;13176.80234796498;13217.405216765077;13526.86473135256;13544;11091.445427728742;11387.601433949772;11435.313881167156;11490.712732181428;11582.131340149692;10479.738006549896;10817.296875703054;11285.548578057114;11597.82603260935;11814.881851181723;10838.729031774268;11376.260543064674;11640.76718465609;11651.41742912868;11722.824157637471;7668.463207575639;7730.961345193238;8153.673853046049;8469.026062002702;8553.187447192508;10616.416097114732;11478.139139564733;11611.76776464449;11643.825342620037;11724.296542207494 06:09:24 INFO - PROCESS | 1915 | |3;mootools.html;1455.4178328668538;1459.6277949122973;1463.5609317204837;1471.807835653121;1477.8226612806461;7544.622768861563;7784.455088143832;7991.440599158054;8455.450395724274;8473.788155296113;6933.272006439327;7928.445008849383;8183.9590802045905;8205.753827385173;8285.254327110559;2266.5240299537077;2315.8263130265213;2316.177756896303;2320.628699408095;2324.8140148788057;2283.5432913417385;2299.3446867642724;2313.5141620259765;2315.2475445480204;2315.3979965209;2283.977160228393;2289.3246492284816;2294.598445272079;2306.423394151462;2308.8730119843403;1831.6519861226325;2224.243757122586;2238.1718764057405;2242.8654280743126;2245.708057952464;2249.4376405898524;2316.0388438798045;2316.976830231693;2322;2322.3613506285737;8348.540830254333;8393.748187554322;8401.117882622359;8431.70489032887;8439.409241353107;5010.0981823271795;5138.229265610151;5168.22476628505;5170.3278573785165;5179.741012949337;2767.6955534891144;3267.3955318265957;3521.1373213562583;3527.4532447470683;3538.1862171700654;1189.2091758980268;1210.2677879882633;1231.4150778380288;1235.0696337413906;1258.628704532165;10530.47347632623;10633;10684.626038088629;10688.625898093607;10692.305000174963;4363.803628836647;4589.380433641482;4666.510016448292;4673.976630116827;4690.249560070372;9342.205912497513;9388.8591671125;9407.717768466957;9408.059194080673;9442;5175.120229560984;5202.7918883245;5207.140821764367;5226.92159617606;5232.73836308183;4271.466066741657;4583.656225783079;4686.992296656167;4709.905801883943;4794.089123066607;3391.406503861814;3610.494530765695;3799.715021373408;3843.577206507282;3844;1087.4888802262924;1282.224254326138;1289.7162624222656;1294.3851670456459;1304.0024381348276;791.920807919215;852.6996330595767;902.4630344944748;923.316745608245;961.0869673809908;5143.019937324082;5218.347706536683;5560.471755183251;5569.136783798518;5683.460071293221;565.7595521903174;578.6788332475433;584.4885724990634;591.9171316015761;592.0911400999531;3854.9807250964127;3893.396523538856;3906.4335671328067;3979.4428779970826;4009.1981603678796;9778.951105244429;9982.850257246148;10008.39949603026;10018.094752521833;10023.298369114238;8030.39772017101;9802.9019709802;9924.900750992541;9935.701928942144;9962.153216976638;7030.824229394306;7662.118856331482;7890.763277101696;7897.842043159105;7920.801979950547;5607.046802043581;5686.8578285541535;5720.313562372542;5814.389489103753;5834.27071616048;8807.779805504913;9008.369414140947;9072.410293330913;9138.17756401927;9147.542622868697;8976.41653292534;9204.355695101278;9205.953970230106;9323.254139668947;9338.206252468617;6549.869002619826;6731.124953755981;6988.126484189476;7009.088818453569;7214.531055481377;7855.7250496232355;7910.129885712682;7972.282494575284;7972.641231144728;7992.240737129963;7005.264447233171;7096.112985876765;7109.573425594481;7136.464765142531;7213.9278607213255;6786.864262714619;6807.115075040213;6961.37347638715;6986.441084713109;7002.3697867192195;6182.227221597301;6320.241571011508;6376.840578985562;6391.4247717703765;6400.871982560416;4988.326575912351;5040.571551418024;5058.140116180185;5097;5113.642045056959;4823.2282834746275;4945.59249276239;4974.32846565709;5178;5208.895822083461 06:09:24 INFO - PROCESS | 1915 | |4;prototype.html;316.2220936496219;362.60838294641775;367.1134210880723;369.8372716004959;371.30009931279545;4636.797521177447;4745.667803253774;5354.6613346663335;5611.719414029298;5620.8313750587495;4393.428854248943;4776.880577985544;4942.18453955097;5481.177823326503;5483.57427068962;3530.5939816921127;3671.301593328901;3945.132070944402;4226.344916537942;4230.630846725843;3330.650281720421;3529.3470707918987;4075.9388609170887;4233.174530966463;4293.313069908815;3302.6532214117533;4243.384709217162;4259.659227261812;4268.7225330353585;4291.914161716763;4090.897727556805;4180.435641188431;4264.509581398148;4266.7439953602725;4331.242032644291;3136.52952057191;3389.915252118692;3709.4605738618443;3736.3648179809497;3736.906577335561;4474.507804141542;5273.367195936474;5329.307190065306;5362.946370536284;5386.380566234894;4131.938020929659;4418.3372494125815;4434;4530.799338175386;4978.6763860348965;5685.573581981368;5729.598928075036;5741.33974592923;5748.48263656273;5786.826395208109;3170.4768713162302;3887.5140607424073;3914.8042597869994;3924.6648244294597;3933.095388060762;5086.516780905808;5845.766169353221;6006.399360063959;6017.5185985121525;6057.575969682124;3283.3925723741186;3862.5558060822805;3924.3706716500014;3954.54522729884;3986.6611338036014;3640.9635903641156;3718.442233664972;4023.5574086850424;4035.2736507428945;4061.5126184858004;4128.8348466061025;4693.20215563355;4699;4705.011947491065;4719.905601887943;5003.146318806932;5465.180222966586;5769.336526299529;5772.884542309214;5848.619839710405;1240.3798100949525;1298.2470167302943;1306.5557710378516;1306.6667999660026;1318.45284207053;748.124694107895;783.5925318834175;834.3116928533984;860.2859626509982;894.7315805258396;4796.712197268175;5272.339213147846;5314.229436731653;5476.479734425223;5489.313835770528;5478.822053258568;5509.449055094458;5531.695756733418;5663.292088488939;5838.562107841929;4879;5264.183856569554;5478.698671573102;5484.436935473451;5485.436650554573;2865.6131422257727;2931.9413611728073;2932.618759561286;2963.6739958604535;2979.4041191761303;4972.925406118911;5126.794928202905;5129.640925135204;5186.896262074737;5910.25647434008;5038.924416133761;5435.206381894044;5475.370332411744;5514.09017512106;5820.82537523875;5160.7935682573025;5505.532029777513;5671.092625180034;5904.439078287642;5917.3490915998345;5311.654742441729;5431.701256430934;5451.48210919962;5684.77260909559;5690.943090569207;4835.105505481497;4957.769003948166;5043.949560504348;5598.972005139948;5641;4843.515648435128;5192.987367463308;5550.888982220252;5671.716414179192;5737.942620573741;3526.6473352665553;3579.516765236764;3729.645683660048;3738.775673459601;3759.4548790425783;5279.841604751864;5348.705821179872;5562.136684210673;5701.657900525982;5745.500707192385;4575.359449677115;5138.948610513997;5200.621835213596;5524.66851988882;5536;4915.336429581961;5156.7421628919155;5306.381553626181;5443.319585051869;5474.09677403224;4599;4942.827001054946;5026.958689992648;5082.898342033065;5296.947030529645;4861.076395484846;4923.138450771029;5047.419546752024;5302.681839089667;5593.524550413261;4878.121938051185;4965.602751779777;5172.818951336685;5438.619296649196;5623.710129574245 06:09:24 INFO - PROCESS | 1915 | |5;yui.html;724.652166959859;737.6164394514852;778.1401551285829;812.3605455975704;841.5497708725829;15116.31976561056;15841;15962.521124366258;15973.760393594106;16004.959677620962;14332.140071595712;14568.781468277984;14606.269686515685;14730.705385892275;14737;676.4824908944661;695.9060526828864;836.5399030533213;837.7277384849933;862.6721845698657;664.5713514782963;797.4457751862458;857.9442336248155;858.1161403754144;860.3375400941271;895.6954635423955;906.4651855405309;908.995455022724;909.2816674826879;910.5219759626216;754.5736658787795;896.4083704754896;902.7066203483861;904.6607522179182;908.0420156734642;663.920329560451;668.0713807807152;669.8928171492587;671.4058058618109;671.7447369999418;14954.177520236382;15310.770338444932;15315.540533783893;15477.613059673486;15864.841351586567;14502.854971450255;14641.267936603128;14835.777463338058;14868.925655371762;15046.247687615576;11575.189736718434;11658.591949281734;11740.354280514568;11845.23006004607;12408.56570020054;3132.6240851097787;3312.96687033129;3603.207294395255;3717.070732316921;3742.5883152853166;80237.59881200557;103198;106975;108508;111289.44355278171;8543.700970466003;10457.529411176516;10632.149428045737;10812.891871081345;11016.394098324508;20222.595548088957;20824.791752082587;20941.162353505995;20962.161513539286;20985;7597.772066838003;7617.733379331808;7622.8094297643;7788.883166752503;7874.094479134858;4372.409724687126;4447.710898791568;4448.911021779546;4452.710573812692;4551.613112885376;3524.8896597572198;3597.0467826026124;3600.459931010317;3663.4687970244167;3684.894531640497;618.4897459595851;676.9898451523231;683.723092147681;694.3646563394437;699.632692836265;660.7588230296003;670.1321788284183;684.5926673629183;705.6330708031898;719.4388377065897;9407.529623518796;9870.667459892822;10419.218558608134;10432.321899076687;10836;704.6089420371641;705.752986454747;724.1635910523354;724.2865777209445;737.9704811807468;4642.257238841769;4997.825076122318;5011.498850115032;5022.974885125551;5029;97872.51063744635;98883.50558247307;100015;100523;102408.97591024144;94598;95202.52398737962;95764.52117739366;96383;96839;70251.64874175597;71434.64282678554;78631.21368786354;78645.60677196577;79536.40927181309;9329.626814927598;9724.367916085432;9744.220462363135;9769;9788.923218445823;39742.60257397389;43858.56141438545;54004.91990160096;54036.91926161376;54344.72827635994;68026.47629331799;68959.65520172367;70278;70843;72147;9671;11575.826362604399;11681.941590291994;11689.298642081503;11889.405529723652;10177.949110254402;10754.516046777757;12739.872601273868;13373.53192638253;13442.529511467052;11755.177137600285;11945.82081268799;12110.939445302716;12396.876031239934;12496.562620308245;9707.660231892132;12179.330136842558;12287.877121228674;12368.628941131781;12433.75132497327;9634.807303854024;11802.074544642277;12035.518579257083;12390.752184956431;12457.937710311391;9968.850467242852;10127.139193168368;10199.694009179737;10307.381557106597;10770.515326810155;9234.445933244027;9954.950225248827;9956;9974.850377244202;10025.548850301608 06:09:24 INFO - PROCESS | 1915 | __end_tp_report 06:09:24 INFO - PROCESS | 1915 | __start_cc_report 06:09:24 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,377 06:09:24 INFO - PROCESS | 1915 | __end_cc_report 06:09:24 INFO - PROCESS | 1915 | __startTimestamp1484921364652__endTimestamp 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | ------- Summary: start ------- 06:09:24 INFO - PROCESS | 1915 | Number of tests: 6 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#0] dojo.html Cycles:165 Average:5716.12 Median:5984.13 stddev:637.59 (10.7%) stddev-sans-first:612.13 06:09:24 INFO - PROCESS | 1915 | Values: 3358.2 3361.9 3370.3 3402.4 3415.9 5661.7 5681.2 5708.1 5749.1 5758.8 5668.6 5725.1 5808.7 5837.1 5847.9 5224.4 5244.6 5248.7 5280.9 5305.2 5238.6 5349.1 5355.1 5380.7 5395.9 5148.7 5316.3 5324.9 5339.2 5352.7 5201.6 5209.0 5248.9 5258.8 5264.7 5179.6 5198.9 5266.6 5275.4 5293.8 4662.0 5684.6 5723.3 5763.2 5850.1 6120.8 6127.6 6142.8 6204.7 6235.0 6169.8 6203.7 6225.7 6250.4 6282.6 4777.7 5854.0 5854.5 5900.5 5901.8 6164.8 6207.3 6265.5 6282.8 6304.9 5064.8 6098.7 6108.2 6112.1 6179.9 6242.0 6243.0 6260.7 6283.9 6302.6 6056.3 6100.3 6122.9 6135.5 6139.6 5208.6 6203.1 6230.8 6234.0 6266.4 4922.1 4980.8 4985.5 5036.1 5047.2 4271.3 4398.4 4436.7 4493.2 4496.1 5359.1 5956.2 5985.5 5995.9 6026.6 6210.6 6222.6 6240.3 6241.4 6251.3 4680.3 5791.0 5821.9 5825.9 6007.7 5338.7 5361.8 5382.4 5433.3 5466.7 5225.8 6205.1 6263.8 6267.5 6272.5 6184.7 6188.0 6216.3 6234.8 6242.0 6145.5 6176.4 6217.6 6226.5 6242.7 5164.7 5268.5 6216.7 6247.3 6256.0 6200.3 6213.3 6223.4 6226.4 6241.7 5885.8 6042.7 6058.4 6142.6 6188.4 6057.5 6096.7 6129.6 6176.3 6212.3 5707.2 5871.1 5893.8 6021.1 6022.0 5980.8 6086.5 6091.3 6115.6 6140.4 5982.6 5984.1 6002.2 6003.9 6021.9 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#1] ext.html Cycles:180 Average:57093.12 Median:18653.74 stddev:116439.72 (624.2%) stddev-sans-first:116688.73 06:09:24 INFO - PROCESS | 1915 | Values: 462.7 469.4 476.5 510.3 533.7 38446.0 41485.0 41598.8 41661.6 41730.8 42215.0 42769.6 42896.4 43069.0 43197.8 8153.9 8927.8 9279.6 9448.0 11177.7 9457.4 11074.7 11204.3 11226.6 11227.9 9519.7 11215.8 11228.2 11243.4 11293.5 11146.0 11152.8 11215.8 11221.4 11226.1 10941.6 11146.8 11174.7 11202.6 11210.4 37159.6 37170.8 37309.8 37579.6 37658.1 3367.8 3589.2 3998.9 4019.8 4029.8 28689.0 28860.3 29399.7 29475.0 29477.9 2709.4 3197.0 3298.3 3339.9 3353.7 479030.0 481537.0 485305.0 485429.0 486057.0 57240.7 57584.7 57586.7 57738.6 57763.0 59898.1 59953.7 60189.8 60313.4 60637.1 12920.9 14037.9 15702.3 15737.8 15746.5 10894.2 11944.0 12223.4 12256.6 12426.4 18279.0 18319.5 18424.4 18434.4 18472.6 7503.5 7745.1 7811.5 7839.2 7913.7 878.9 886.1 891.2 912.4 928.5 28354.1 29383.6 29545.6 30557.7 30832.5 1069.2 1074.1 1107.2 1110.3 1225.2 15153.7 15860.8 16145.8 16155.6 16200.3 234104.0 236901.6 237229.0 237743.0 238792.0 61674.0 62249.7 62252.7 62449.7 62529.7 1402.3 1434.2 1437.2 1447.8 1452.7 28173.0 29154.3 29854.7 30371.8 30451.5 44877.0 53040.0 53056.5 53309.2 53434.7 529713.0 530959.0 531626.0 532064.0 532822.0 24872.0 26865.2 27230.7 27263.7 27369.9 31882.0 37298.0 41734.8 43082.4 44123.6 33939.7 35976.8 36858.6 36938.4 38777.8 36246.8 36402.3 37112.0 37224.9 37421.8 35226.0 35671.6 36061.7 36181.5 36234.0 30773.1 31986.0 32079.7 32120.7 32158.2 17106.9 19217.3 23600.9 23988.0 24035.4 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#2] jquery.html Cycles:180 Average:38527.81 Median:21605.68 stddev:114930.70 (531.9%) stddev-sans-first:115217.34 06:09:24 INFO - PROCESS | 1915 | Values: 336.6 339.2 386.6 389.4 405.8 19018.6 19523.9 20534.9 20707.7 20853.8 17438.4 20922.5 21394.4 21817.0 21957.0 5558.7 5618.9 5643.4 5727.4 5887.1 4850.8 5662.2 5728.1 5748.5 5767.2 5444.0 5549.2 5705.5 5711.6 5715.0 5636.0 5722.1 5723.7 5726.3 5728.4 4807.2 4954.8 5018.6 5042.1 5053.4 24963.6 25218.5 25275.2 25282.4 25428.5 2687.2 2689.7 2707.5 2720.5 2744.2 15124.0 15543.8 15555.3 15832.0 16156.5 1820.4 1827.9 1836.6 1839.1 1857.4 129380.0 129833.4 131286.0 131364.0 135307.0 11344.8 11455.6 11648.6 11705.3 11956.5 14247.4 14375.7 14422.2 14426.1 14432.9 8366.6 8472.0 8505.7 8559.5 8633.5 7045.5 8401.5 8405.2 8420.7 8598.6 6936.0 7014.0 7014.6 7046.7 7125.3 1717.9 1880.8 1923.6 1925.8 1938.0 991.5 1043.8 1069.6 1085.1 1135.1 16340.8 21240.7 22023.7 22101.8 22147.8 985.7 1066.3 1069.3 1125.6 1133.5 9657.8 10883.5 11528.5 11562.1 11576.8 600887.0 659768.0 719758.0 730862.0 736891.0 78570.8 115978.0 129374.0 129444.0 137200.0 1022.4 1073.2 1080.0 1088.5 1092.7 16015.6 20350.1 20412.4 20417.3 21146.5 58691.0 58984.0 59113.4 60557.4 60677.0 89107.0 92496.5 93245.0 94123.0 94378.5 8250.5 8345.7 8430.7 8528.5 8605.1 11961.8 13176.8 13217.4 13526.9 13544.0 11091.4 11387.6 11435.3 11490.7 11582.1 10479.7 10817.3 11285.5 11597.8 11814.9 10838.7 11376.3 11640.8 11651.4 11722.8 7668.5 7731.0 8153.7 8469.0 8553.2 10616.4 11478.1 11611.8 11643.8 11724.3 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#3] mootools.html Cycles:180 Average:5376.47 Median:5208.02 stddev:2926.24 (56.2%) stddev-sans-first:2919.61 06:09:24 INFO - PROCESS | 1915 | Values: 1455.4 1459.6 1463.6 1471.8 1477.8 7544.6 7784.5 7991.4 8455.5 8473.8 6933.3 7928.4 8184.0 8205.8 8285.3 2266.5 2315.8 2316.2 2320.6 2324.8 2283.5 2299.3 2313.5 2315.2 2315.4 2284.0 2289.3 2294.6 2306.4 2308.9 1831.7 2224.2 2238.2 2242.9 2245.7 2249.4 2316.0 2317.0 2322.0 2322.4 8348.5 8393.7 8401.1 8431.7 8439.4 5010.1 5138.2 5168.2 5170.3 5179.7 2767.7 3267.4 3521.1 3527.5 3538.2 1189.2 1210.3 1231.4 1235.1 1258.6 10530.5 10633.0 10684.6 10688.6 10692.3 4363.8 4589.4 4666.5 4674.0 4690.2 9342.2 9388.9 9407.7 9408.1 9442.0 5175.1 5202.8 5207.1 5226.9 5232.7 4271.5 4583.7 4687.0 4709.9 4794.1 3391.4 3610.5 3799.7 3843.6 3844.0 1087.5 1282.2 1289.7 1294.4 1304.0 791.9 852.7 902.5 923.3 961.1 5143.0 5218.3 5560.5 5569.1 5683.5 565.8 578.7 584.5 591.9 592.1 3855.0 3893.4 3906.4 3979.4 4009.2 9779.0 9982.9 10008.4 10018.1 10023.3 8030.4 9802.9 9924.9 9935.7 9962.2 7030.8 7662.1 7890.8 7897.8 7920.8 5607.0 5686.9 5720.3 5814.4 5834.3 8807.8 9008.4 9072.4 9138.2 9147.5 8976.4 9204.4 9206.0 9323.3 9338.2 6549.9 6731.1 6988.1 7009.1 7214.5 7855.7 7910.1 7972.3 7972.6 7992.2 7005.3 7096.1 7109.6 7136.5 7213.9 6786.9 6807.1 6961.4 6986.4 7002.4 6182.2 6320.2 6376.8 6391.4 6400.9 4988.3 5040.6 5058.1 5097.0 5113.6 4823.2 4945.6 4974.3 5178.0 5208.9 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#4] prototype.html Cycles:180 Average:4493.91 Median:5015.05 stddev:1361.54 (27.1%) stddev-sans-first:1328.76 06:09:24 INFO - PROCESS | 1915 | Values: 316.2 362.6 367.1 369.8 371.3 4636.8 4745.7 5354.7 5611.7 5620.8 4393.4 4776.9 4942.2 5481.2 5483.6 3530.6 3671.3 3945.1 4226.3 4230.6 3330.7 3529.3 4075.9 4233.2 4293.3 3302.7 4243.4 4259.7 4268.7 4291.9 4090.9 4180.4 4264.5 4266.7 4331.2 3136.5 3389.9 3709.5 3736.4 3736.9 4474.5 5273.4 5329.3 5362.9 5386.4 4131.9 4418.3 4434.0 4530.8 4978.7 5685.6 5729.6 5741.3 5748.5 5786.8 3170.5 3887.5 3914.8 3924.7 3933.1 5086.5 5845.8 6006.4 6017.5 6057.6 3283.4 3862.6 3924.4 3954.5 3986.7 3641.0 3718.4 4023.6 4035.3 4061.5 4128.8 4693.2 4699.0 4705.0 4719.9 5003.1 5465.2 5769.3 5772.9 5848.6 1240.4 1298.2 1306.6 1306.7 1318.5 748.1 783.6 834.3 860.3 894.7 4796.7 5272.3 5314.2 5476.5 5489.3 5478.8 5509.4 5531.7 5663.3 5838.6 4879.0 5264.2 5478.7 5484.4 5485.4 2865.6 2931.9 2932.6 2963.7 2979.4 4972.9 5126.8 5129.6 5186.9 5910.3 5038.9 5435.2 5475.4 5514.1 5820.8 5160.8 5505.5 5671.1 5904.4 5917.3 5311.7 5431.7 5451.5 5684.8 5690.9 4835.1 4957.8 5043.9 5599.0 5641.0 4843.5 5193.0 5550.9 5671.7 5737.9 3526.6 3579.5 3729.6 3738.8 3759.5 5279.8 5348.7 5562.1 5701.7 5745.5 4575.4 5138.9 5200.6 5524.7 5536.0 4915.3 5156.7 5306.4 5443.3 5474.1 4599.0 4942.8 5027.0 5082.9 5296.9 4861.1 4923.1 5047.4 5302.7 5593.5 4878.1 4965.6 5172.8 5438.6 5623.7 06:09:24 INFO - PROCESS | 1915 | 06:09:24 INFO - PROCESS | 1915 | [#5] yui.html Cycles:180 Average:20173.06 Median:5017.24 stddev:29424.32 (586.5%) stddev-sans-first:29470.62 06:09:24 INFO - PROCESS | 1915 | Values: 724.7 737.6 778.1 812.4 841.5 15116.3 15841.0 15962.5 15973.8 16005.0 14332.1 14568.8 14606.3 14730.7 14737.0 676.5 695.9 836.5 837.7 862.7 664.6 797.4 857.9 858.1 860.3 895.7 906.5 909.0 909.3 910.5 754.6 896.4 902.7 904.7 908.0 663.9 668.1 669.9 671.4 671.7 14954.2 15310.8 15315.5 15477.6 15864.8 14502.9 14641.3 14835.8 14868.9 15046.2 11575.2 11658.6 11740.4 11845.2 12408.6 3132.6 3313.0 3603.2 3717.1 3742.6 80237.6 103198.0 106975.0 108508.0 111289.4 8543.7 10457.5 10632.1 10812.9 11016.4 20222.6 20824.8 20941.2 20962.2 20985.0 7597.8 7617.7 7622.8 7788.9 7874.1 4372.4 4447.7 4448.9 4452.7 4551.6 3524.9 3597.0 3600.5 3663.5 3684.9 618.5 677.0 683.7 694.4 699.6 660.8 670.1 684.6 705.6 719.4 9407.5 9870.7 10419.2 10432.3 10836.0 704.6 705.8 724.2 724.3 738.0 4642.3 4997.8 5011.5 5023.0 5029.0 97872.5 98883.5 100015.0 100523.0 102409.0 94598.0 95202.5 95764.5 96383.0 96839.0 70251.6 71434.6 78631.2 78645.6 79536.4 9329.6 9724.4 9744.2 9769.0 9788.9 39742.6 43858.6 54004.9 54036.9 54344.7 68026.5 68959.7 70278.0 70843.0 72147.0 9671.0 11575.8 11681.9 11689.3 11889.4 10177.9 10754.5 12739.9 13373.5 13442.5 11755.2 11945.8 12110.9 12396.9 12496.6 9707.7 12179.3 12287.9 12368.6 12433.8 9634.8 11802.1 12035.5 12390.8 12457.9 9968.9 10127.1 10199.7 10307.4 10770.5 9234.4 9955.0 9956.0 9974.9 10025.5 06:09:24 INFO - PROCESS | 1915 | -------- Summary: end -------- 06:09:24 INFO - PROCESS | 1915 | 06:09:25 INFO - TEST-INFO | 1915: exit 0 06:09:25 INFO - TEST-OK | dromaeo_css | took 1115849ms 06:09:25 INFO - TEST-START | kraken 06:09:25 INFO - operating with platform_type : mac_ 06:09:25 INFO - Initialising browser for kraken test... 06:09:25 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpsg66UE/profile http://localhost:52572/getInfo.html) 06:09:28 INFO - PROCESS | 1921 | 2017-01-20 06:09:28.127 plugin-container[1922:17809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 06:09:28 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:28 INFO - PROCESS | 1921 | 2017-01-20 06:09:28.130 plugin-container[1922:17809] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.CFPasteboardClient' 06:09:28 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:28 INFO - PROCESS | 1921 | 2017-01-20 06:09:28.130 plugin-container[1922:17809] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:28 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 06:09:28 INFO - PROCESS | 1921 | colorDepth:24 06:09:28 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 06:09:28 INFO - PROCESS | 1921 | __metrics 06:09:28 ERROR - PROCESS | 1921 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 06:09:28 INFO - TEST-INFO | 1921: exit 0 06:09:28 INFO - Browser initialized. 06:09:28 INFO - Running cycle 1/1 for kraken test... 06:09:28 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpsg66UE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:09:31 INFO - PROCESS | 1923 | 2017-01-20 06:09:31.266 plugin-container[1924:17922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 06:09:31 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:31 INFO - PROCESS | 1923 | 2017-01-20 06:09:31.267 plugin-container[1924:17922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:09:31 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:09:31 INFO - PROCESS | 1923 | 2017-01-20 06:09:31.267 plugin-container[1924:17922] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:09:40 INFO - PROCESS | 1923 | RSS: Main: 232071168 06:09:40 INFO - PROCESS | 1923 | 06:09:44 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52572/tests/kraken/driver.html?testName=audio-beat-detection) 06:09:44 INFO - PROCESS | 1923 | RSS: Main: 245501952 06:09:44 INFO - PROCESS | 1923 | 06:09:47 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52572/tests/kraken/driver.html?testName=audio-dft) 06:09:48 INFO - PROCESS | 1923 | RSS: Main: 253460480 06:09:48 INFO - PROCESS | 1923 | 06:09:51 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52572/tests/kraken/driver.html?testName=audio-fft) 06:09:52 INFO - PROCESS | 1923 | RSS: Main: 256135168 06:09:52 INFO - PROCESS | 1923 | 06:09:54 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52572/tests/kraken/driver.html?testName=audio-oscillator) 06:09:54 INFO - PROCESS | 1923 | RSS: Main: 258334720 06:09:54 INFO - PROCESS | 1923 | 06:09:56 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52572/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:09:57 INFO - PROCESS | 1923 | RSS: Main: 259502080 06:09:57 INFO - PROCESS | 1923 | 06:10:00 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52572/tests/kraken/driver.html?testName=imaging-darkroom) 06:10:01 INFO - PROCESS | 1923 | RSS: Main: 264519680 06:10:01 INFO - PROCESS | 1923 | 06:10:04 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52572/tests/kraken/driver.html?testName=imaging-desaturate) 06:10:04 INFO - PROCESS | 1923 | RSS: Main: 263856128 06:10:04 INFO - PROCESS | 1923 | 06:10:08 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52572/tests/kraken/driver.html?testName=json-parse-financial) 06:10:08 INFO - PROCESS | 1923 | RSS: Main: 263802880 06:10:08 INFO - PROCESS | 1923 | 06:10:10 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52572/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:10:10 INFO - PROCESS | 1923 | RSS: Main: 262995968 06:10:10 INFO - PROCESS | 1923 | 06:10:12 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:10:13 INFO - PROCESS | 1923 | RSS: Main: 263012352 06:10:13 INFO - PROCESS | 1923 | 06:10:15 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:10:16 INFO - PROCESS | 1923 | RSS: Main: 263106560 06:10:16 INFO - PROCESS | 1923 | 06:10:19 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:10:20 INFO - PROCESS | 1923 | RSS: Main: 263299072 06:10:20 INFO - PROCESS | 1923 | 06:10:23 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:10:24 INFO - PROCESS | 1923 | RSS: Main: 263241728 06:10:24 INFO - PROCESS | 1923 | 06:10:25 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52572/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52572/tests/kraken/driver.html?testName=ai-astar) 06:10:26 INFO - PROCESS | 1923 | RSS: Main: 265285632 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | __start_tp_report 06:10:26 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 06:10:26 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 06:10:26 INFO - PROCESS | 1923 | |i|pagename|runs| 06:10:26 INFO - PROCESS | 1923 | |0;ai-astar;106;117;119;117;118;118;119;118;116;118 06:10:26 INFO - PROCESS | 1923 | |1;audio-beat-detection;146;112;115;152;113;115;115;131;171;146 06:10:26 INFO - PROCESS | 1923 | |2;audio-dft;205;202;226;257;242;201;196;200;195;196 06:10:26 INFO - PROCESS | 1923 | |3;audio-fft;83;50;57;107;84;87;84;61;73;61 06:10:26 INFO - PROCESS | 1923 | |4;audio-oscillator;90;74;77;60;59;71;114;105;59;98 06:10:26 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;89;86;88;87;111;126;110;78;137;132 06:10:26 INFO - PROCESS | 1923 | |6;imaging-darkroom;108;100;99;100;101;167;101;111;100;149 06:10:26 INFO - PROCESS | 1923 | |7;imaging-desaturate;145;147;94;94;79;144;147;90;95;92 06:10:26 INFO - PROCESS | 1923 | |8;json-parse-financial;62;63;61;62;62;62;62;63;62;61 06:10:26 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;69;75;70;78;80;71;90;108;71;71 06:10:26 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;91;104;80;76;97;114;94;103;78;91 06:10:26 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;141;151;144;152;160;158;141;143;216;184 06:10:26 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;199;243;247;180;177;172;197;166;231;246 06:10:26 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;68;68;68;68;70;67;82;89;92;94 06:10:26 INFO - PROCESS | 1923 | __end_tp_report 06:10:26 INFO - PROCESS | 1923 | __start_cc_report 06:10:26 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,920 06:10:26 INFO - PROCESS | 1923 | __end_cc_report 06:10:26 INFO - PROCESS | 1923 | __startTimestamp1484921426236__endTimestamp 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | ------- Summary: start ------- 06:10:26 INFO - PROCESS | 1923 | Number of tests: 14 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.00 stddev:3.84 (3.3%) stddev-sans-first:0.97 06:10:26 INFO - PROCESS | 1923 | Values: 106.0 117.0 119.0 117.0 118.0 118.0 119.0 118.0 116.0 118.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:138.50 stddev:20.92 (15.1%) stddev-sans-first:21.53 06:10:26 INFO - PROCESS | 1923 | Values: 146.0 112.0 115.0 152.0 113.0 115.0 115.0 131.0 171.0 146.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:212.00 Median:203.50 stddev:21.95 (10.8%) stddev-sans-first:23.13 06:10:26 INFO - PROCESS | 1923 | Values: 205.0 202.0 226.0 257.0 242.0 201.0 196.0 200.0 195.0 196.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:74.70 Median:78.00 stddev:17.44 (22.4%) stddev-sans-first:18.24 06:10:26 INFO - PROCESS | 1923 | Values: 83.0 50.0 57.0 107.0 84.0 87.0 84.0 61.0 73.0 61.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:72.50 stddev:20.02 (27.6%) stddev-sans-first:20.95 06:10:26 INFO - PROCESS | 1923 | Values: 90.0 74.0 77.0 60.0 59.0 71.0 114.0 105.0 59.0 98.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:104.40 Median:82.00 stddev:21.63 (26.4%) stddev-sans-first:22.21 06:10:26 INFO - PROCESS | 1923 | Values: 89.0 86.0 88.0 87.0 111.0 126.0 110.0 78.0 137.0 132.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:113.60 Median:109.50 stddev:24.10 (22.0%) stddev-sans-first:25.48 06:10:26 INFO - PROCESS | 1923 | Values: 108.0 100.0 99.0 100.0 101.0 167.0 101.0 111.0 100.0 149.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:112.70 Median:91.00 stddev:28.81 (31.7%) stddev-sans-first:28.08 06:10:26 INFO - PROCESS | 1923 | Values: 145.0 147.0 94.0 94.0 79.0 144.0 147.0 90.0 95.0 92.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 06:10:26 INFO - PROCESS | 1923 | Values: 62.0 63.0 61.0 62.0 62.0 62.0 62.0 63.0 62.0 61.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:73.00 stddev:12.24 (16.8%) stddev-sans-first:12.51 06:10:26 INFO - PROCESS | 1923 | Values: 69.0 75.0 70.0 78.0 80.0 71.0 90.0 108.0 71.0 71.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:85.50 stddev:12.34 (14.4%) stddev-sans-first:13.07 06:10:26 INFO - PROCESS | 1923 | Values: 91.0 104.0 80.0 76.0 97.0 114.0 94.0 103.0 78.0 91.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:155.00 stddev:23.85 (15.4%) stddev-sans-first:24.39 06:10:26 INFO - PROCESS | 1923 | Values: 141.0 151.0 144.0 152.0 160.0 158.0 141.0 143.0 216.0 184.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.80 Median:215.00 stddev:32.79 (15.3%) stddev-sans-first:34.69 06:10:26 INFO - PROCESS | 1923 | Values: 199.0 243.0 247.0 180.0 177.0 172.0 197.0 166.0 231.0 246.0 06:10:26 INFO - PROCESS | 1923 | 06:10:26 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:76.00 stddev:11.33 (14.9%) stddev-sans-first:11.58 06:10:26 INFO - PROCESS | 1923 | Values: 68.0 68.0 68.0 68.0 70.0 67.0 82.0 89.0 92.0 94.0 06:10:26 INFO - PROCESS | 1923 | -------- Summary: end -------- 06:10:26 INFO - PROCESS | 1923 | 06:10:27 INFO - TEST-INFO | 1923: exit 0 06:10:27 INFO - TEST-OK | kraken | took 61854ms 06:10:27 INFO - SUITE-END | took 1177s 06:10:27 INFO - Completed test suite (00:19:37) 06:10:27 INFO - javascript benchmark 06:10:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7226.854749992068, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3358.2443950111224, 3361.915952101199, 3370.3427831572853, 3402.4215883299835, 3415.8633654653813, 5661.716914154296, 5681.176229446733, 5708.0867061270255, 5749.108888122342, 5758.769649214037, 5668.603197776158, 5725.14122881568, 5808.709564521768, 5837.124431335302, 5847.883042339151, 5224.39919409269, 5244.632875698702, 5248.658837175591, 5280.9207861882105, 5305.177697456881, 5238.580913526908, 5349.117395629716, 5355.116405793059, 5380.677159370451, 5395.919061214066, 5148.742562871841, 5316.255724198615, 5324.946750532484, 5339.199120131992, 5352.705601191933, 5201.583873290166, 5209.0363282792805, 5248.923970586021, 5258.763355649005, 5264.657797243167, 5179.637425380225, 5198.92201616976, 5266.552343050846, 5275.366955965271, 5293.814716484943, 4661.95338046622, 5684.573656975744, 5723.313202415695, 5763.221965034751, 5850.0932355484965, 6120.755169793203, 6127.647695153045, 6142.805741014692, 6204.689765511706, 6235.0, 6169.7840575580085, 6203.689815509251, 6225.688715564249, 6250.4374606285655, 6282.560220784547, 4777.689450185727, 5854.0, 5854.5023672987845, 5900.527957763368, 5901.763929442817, 6164.753409863645, 6207.286162091327, 6265.498760099181, 6282.780102696383, 6304.873902521924, 5064.822731204464, 6098.664573448415, 6108.205933228652, 6112.108359148532, 6179.876402471926, 6242.0, 6243.0, 6260.68696565179, 6283.937160628427, 6302.621842689362, 6056.3338032816355, 6100.298465676416, 6122.855605810045, 6135.53983451234, 6139.631622102688, 5208.583313334924, 6203.100550420254, 6230.7819226326865, 6234.0, 6266.373362663697, 4922.064807686528, 4980.77586508608, 4985.52637499437, 5036.093503169392, 5047.192449208109, 4271.295236286041, 4398.384226208269, 4436.733795972252, 4493.236149854532, 4496.055828276033, 5359.14253719403, 5956.225690660273, 5985.521158307325, 5995.850103747442, 6026.578139530278, 6210.596311239755, 6222.595531290452, 6240.31356550779, 6241.407066328691, 6251.28110267316, 4680.297955306732, 5791.044477661138, 5821.941780582225, 5825.854353641193, 6007.699615019232, 5338.706371149624, 5361.839144825662, 5382.434844341366, 5433.347998240158, 5466.726663666801, 5225.764840582183, 6205.131281620486, 6263.78076767311, 6267.4986001119805, 6272.4982001440685, 6184.721687524162, 6187.969060154851, 6216.2540495140875, 6234.781782637586, 6242.001279795211, 6145.539084568586, 6176.35148309421, 6217.626942383471, 6226.533010024357, 6242.65665388408, 5164.690118592896, 5268.4994925482015, 6216.658083805434, 6247.312795592572, 6255.96872015637, 6200.3179650237535, 6213.285472170758, 6223.439890409885, 6226.439620434183, 6241.656708881055, 5885.823425297248, 6042.697865106639, 6058.4244496772735, 6142.590862071594, 6188.44304012641, 6057.545684073801, 6096.695165241809, 6129.570930035033, 6176.289726681489, 6212.316645168938, 5707.229524014205, 5871.1486834407915, 5893.764249429974, 6021.055775405028, 6022.0, 5980.760769569169, 6086.482648974887, 6091.329953705177, 6115.5719099664375, 6140.416660417253, 5982.581219314607, 5984.1323008164445, 6002.2197114376595, 6003.909941350796, 6021.879562408815], "value": 5678.509335424603, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.7182703910169, 469.37807405188136, 476.47111706006336, 510.2957918073059, 533.6851257757922, 38446.0, 41484.96287592812, 41598.79200603996, 41661.58338416615, 41730.791346043225, 42215.0, 42769.572304276946, 42896.356554651626, 43069.0, 43197.78401107998, 8153.9184608153755, 8927.77680557988, 9279.582418791137, 9447.952760236189, 11177.72055698606, 9457.385269957465, 11074.723131921686, 11204.271722338022, 11226.607068752595, 11227.877212278789, 9519.714408567754, 11215.831762523529, 11228.15788815838, 11243.381614011227, 11293.491792869341, 11146.0, 11152.776944461106, 11215.766265710807, 11221.38282394468, 11226.101911847032, 10941.617043403523, 11146.777064458747, 11174.720631984184, 11202.607908723237, 11210.439478026066, 37159.62840371616, 37170.81414592937, 37309.81345093257, 37579.624203757885, 37658.05854853623, 3367.7810942288675, 3589.228315912065, 3998.8800335989677, 4019.8191081401114, 4029.8388064477385, 28689.0, 28860.278493037633, 29399.706002940125, 29475.0, 29477.904819993808, 2709.403931135147, 3197.0, 3298.2908674634828, 3339.9499007514905, 3353.7149342305934, 479030.0, 481537.0, 485305.0, 485429.0, 486056.99999999645, 57240.713796430755, 57584.71207643935, 57586.7120664394, 57738.55653608694, 57763.0, 59898.10152847623, 59953.70023149944, 60189.796204075676, 60313.39686603078, 60637.09044364338, 12920.935395322964, 14037.947153963494, 15702.293396797171, 15737.763933541006, 15746.527604171893, 10894.237403381845, 11944.0, 12223.449944752507, 12256.632301030982, 12426.440810163564, 18279.0, 18319.54201144982, 18424.35514756977, 18434.354797582022, 18472.630547388977, 7503.473367517258, 7745.148033716287, 7811.515811995816, 7839.176886426957, 7913.683452661944, 878.9428687135315, 886.082904194156, 891.1934699097327, 912.3567884641312, 928.4661319741176, 28354.149375518766, 29383.55924661132, 29545.556816647768, 30557.69442305593, 30832.53751193734, 1069.224812011301, 1074.1406874500524, 1107.2360071550604, 1110.2505808579176, 1225.2158618484177, 15153.696926061417, 15860.841391586167, 16145.838541614434, 16155.596110097107, 16200.270987805578, 234104.0, 236901.6309836914, 237229.0, 237743.0, 238792.0, 61674.0, 62249.68875155595, 62252.688736556935, 62449.68775156095, 62529.68735156295, 1402.3128666953119, 1434.211183848879, 1437.1951707044088, 1447.8045463862454, 1452.7385070687378, 28173.0, 29154.271143221587, 29854.70145298519, 30371.81064243458, 30451.54322685206, 44877.0, 53040.0, 53056.469435306695, 53309.200361993826, 53434.732826337175, 529713.0, 530959.0, 531626.0, 532064.0, 532822.0, 24872.0, 26865.194044178705, 27230.72769272282, 27263.72736272612, 27369.86315068412, 31882.043538693877, 37298.0, 41734.791326043174, 43082.353764692925, 44123.558764411944, 33939.69002527503, 35976.82011589925, 36858.63141368659, 36938.44592331063, 38777.806110969264, 36246.818765906, 36402.27195456129, 37112.0, 37224.88325350244, 37421.81289093537, 35226.0, 35671.64328356787, 36061.73783917655, 36181.457278140326, 36234.0, 30773.076807695805, 31986.0, 32079.67920320767, 32120.67879321177, 32158.19604509906, 17106.91446542759, 19217.32739354116, 23600.937957791597, 23988.0, 24035.399115022265], "value": 19302.627857202606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.56078817143634, 339.2181022742579, 386.6230425335298, 389.43337444018954, 405.839693321138, 19018.619627607455, 19523.902380488096, 20534.89732551339, 20707.689384659203, 20853.791462085373, 17438.38965636203, 20922.476938076517, 21394.35816925491, 21817.0, 21957.0, 5558.749856256478, 5618.887622247573, 5643.435656434344, 5727.398623144572, 5887.116932460122, 4850.757462126897, 5662.17898404731, 5728.083506638938, 5748.511376533, 5767.192593036978, 5444.0, 5549.223108764756, 5705.4579814917515, 5711.571632127586, 5714.971425142869, 5636.04187288162, 5722.055860782966, 5723.742431590588, 5726.255586773735, 5728.42715728428, 4807.158747219258, 4954.777035033432, 5018.64869459138, 5042.142835717937, 5053.36832895888, 24963.62554561683, 25218.495630087295, 25275.24174274775, 25282.367940801443, 25428.491430171478, 2687.2475706801933, 2689.663792025997, 2707.512647723409, 2720.5375086235385, 2744.2316151477617, 15124.0, 15543.844561554466, 15555.30001149951, 15832.0, 16156.515304540882, 1820.4447643468736, 1827.9451616451527, 1836.5775871549617, 1839.1447976690904, 1857.4149143019906, 129380.0, 129833.35083324618, 131286.0, 131363.99999999904, 135307.0, 11344.773104538028, 11455.59905403307, 11648.6120345329, 11705.297682139098, 11956.521739130338, 14247.43010279598, 14375.712485750435, 14422.206778627276, 14426.134431934117, 14432.855671443362, 8366.58167091643, 8472.0, 8505.65977360911, 8559.486430814171, 8633.481991080555, 7045.471589630798, 8401.537915414588, 8405.243528082501, 8420.705275315335, 8598.613062412081, 6935.965320173367, 7014.0, 7014.649267536603, 7046.6828992695455, 7125.25184855593, 1717.896926184433, 1880.7743070831589, 1923.6441258367247, 1925.836303914155, 1937.9612407752047, 991.504247876062, 1043.7599352148895, 1069.5989004123453, 1085.0939465546198, 1135.1089394825024, 16340.75488867668, 21240.681389779165, 22023.66964495534, 22101.778982209973, 22147.78187199687, 985.7486340983004, 1066.3228849680354, 1069.2675517270695, 1125.6285425809463, 1133.5352505472717, 9657.806843863083, 10883.510242039127, 11528.481218345194, 11562.07503399726, 11576.768464630828, 600887.0, 659768.0, 719758.0, 730862.0, 736891.0, 78570.82143767847, 115978.0, 129374.0, 129444.0, 137200.0, 1022.3814592171777, 1073.2272763610188, 1079.983800242997, 1088.5210507376728, 1092.6503518873883, 16015.55859972608, 20350.08424620866, 20412.387628371172, 20417.285395011102, 21146.471338216666, 58691.0, 58984.0, 59113.40886591079, 60557.39442605517, 60677.0, 89107.0, 92496.53751731198, 93245.0, 94123.0, 94378.52810736177, 8250.504969701837, 8345.707900223462, 8430.70492532783, 8528.488290702579, 8605.139486051345, 11961.820572691591, 13176.80234796498, 13217.405216765077, 13526.86473135256, 13544.0, 11091.445427728742, 11387.601433949772, 11435.313881167156, 11490.712732181428, 11582.131340149692, 10479.738006549896, 10817.296875703054, 11285.548578057114, 11597.82603260935, 11814.881851181723, 10838.729031774268, 11376.260543064674, 11640.76718465609, 11651.41742912868, 11722.824157637471, 7668.463207575639, 7730.961345193238, 8153.673853046049, 8469.026062002702, 8553.187447192508, 10616.416097114732, 11478.139139564733, 11611.76776464449, 11643.825342620037, 11724.296542207494], "value": 10040.558198422108, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.4178328668538, 1459.6277949122973, 1463.5609317204837, 1471.807835653121, 1477.8226612806461, 7544.622768861563, 7784.455088143832, 7991.440599158054, 8455.450395724274, 8473.788155296113, 6933.272006439327, 7928.445008849383, 8183.9590802045905, 8205.753827385173, 8285.254327110559, 2266.5240299537077, 2315.8263130265213, 2316.177756896303, 2320.628699408095, 2324.8140148788057, 2283.5432913417385, 2299.3446867642724, 2313.5141620259765, 2315.2475445480204, 2315.3979965209, 2283.977160228393, 2289.3246492284816, 2294.598445272079, 2306.423394151462, 2308.8730119843403, 1831.6519861226325, 2224.243757122586, 2238.1718764057405, 2242.8654280743126, 2245.708057952464, 2249.4376405898524, 2316.0388438798045, 2316.976830231693, 2322.0, 2322.3613506285737, 8348.540830254333, 8393.748187554322, 8401.117882622359, 8431.70489032887, 8439.409241353107, 5010.0981823271795, 5138.229265610151, 5168.22476628505, 5170.3278573785165, 5179.741012949337, 2767.6955534891144, 3267.3955318265957, 3521.1373213562583, 3527.4532447470683, 3538.1862171700654, 1189.2091758980268, 1210.2677879882633, 1231.4150778380288, 1235.0696337413906, 1258.628704532165, 10530.47347632623, 10633.0, 10684.626038088629, 10688.625898093607, 10692.305000174963, 4363.803628836647, 4589.380433641482, 4666.510016448292, 4673.976630116827, 4690.249560070372, 9342.205912497513, 9388.8591671125, 9407.717768466957, 9408.059194080673, 9442.0, 5175.120229560984, 5202.7918883245, 5207.140821764367, 5226.92159617606, 5232.73836308183, 4271.466066741657, 4583.656225783079, 4686.992296656167, 4709.905801883943, 4794.089123066607, 3391.406503861814, 3610.494530765695, 3799.715021373408, 3843.577206507282, 3844.0, 1087.4888802262924, 1282.224254326138, 1289.7162624222656, 1294.3851670456459, 1304.0024381348276, 791.920807919215, 852.6996330595767, 902.4630344944748, 923.316745608245, 961.0869673809908, 5143.019937324082, 5218.347706536683, 5560.471755183251, 5569.136783798518, 5683.460071293221, 565.7595521903174, 578.6788332475433, 584.4885724990634, 591.9171316015761, 592.0911400999531, 3854.9807250964127, 3893.396523538856, 3906.4335671328067, 3979.4428779970826, 4009.1981603678796, 9778.951105244429, 9982.850257246148, 10008.39949603026, 10018.094752521833, 10023.298369114238, 8030.39772017101, 9802.9019709802, 9924.900750992541, 9935.701928942144, 9962.153216976638, 7030.824229394306, 7662.118856331482, 7890.763277101696, 7897.842043159105, 7920.801979950547, 5607.046802043581, 5686.8578285541535, 5720.313562372542, 5814.389489103753, 5834.27071616048, 8807.779805504913, 9008.369414140947, 9072.410293330913, 9138.17756401927, 9147.542622868697, 8976.41653292534, 9204.355695101278, 9205.953970230106, 9323.254139668947, 9338.206252468617, 6549.869002619826, 6731.124953755981, 6988.126484189476, 7009.088818453569, 7214.531055481377, 7855.7250496232355, 7910.129885712682, 7972.282494575284, 7972.641231144728, 7992.240737129963, 7005.264447233171, 7096.112985876765, 7109.573425594481, 7136.464765142531, 7213.9278607213255, 6786.864262714619, 6807.115075040213, 6961.37347638715, 6986.441084713109, 7002.3697867192195, 6182.227221597301, 6320.241571011508, 6376.840578985562, 6391.4247717703765, 6400.871982560416, 4988.326575912351, 5040.571551418024, 5058.140116180185, 5097.0, 5113.642045056959, 4823.2282834746275, 4945.59249276239, 4974.32846565709, 5178.0, 5208.895822083461], "value": 4334.255583304197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.2220936496219, 362.60838294641775, 367.1134210880723, 369.8372716004959, 371.30009931279545, 4636.797521177447, 4745.667803253774, 5354.6613346663335, 5611.719414029298, 5620.8313750587495, 4393.428854248943, 4776.880577985544, 4942.18453955097, 5481.177823326503, 5483.57427068962, 3530.5939816921127, 3671.301593328901, 3945.132070944402, 4226.344916537942, 4230.630846725843, 3330.650281720421, 3529.3470707918987, 4075.9388609170887, 4233.174530966463, 4293.313069908815, 3302.6532214117533, 4243.384709217162, 4259.659227261812, 4268.7225330353585, 4291.914161716763, 4090.897727556805, 4180.435641188431, 4264.509581398148, 4266.7439953602725, 4331.242032644291, 3136.52952057191, 3389.915252118692, 3709.4605738618443, 3736.3648179809497, 3736.906577335561, 4474.507804141542, 5273.367195936474, 5329.307190065306, 5362.946370536284, 5386.380566234894, 4131.938020929659, 4418.3372494125815, 4434.0, 4530.799338175386, 4978.6763860348965, 5685.573581981368, 5729.598928075036, 5741.33974592923, 5748.48263656273, 5786.826395208109, 3170.4768713162302, 3887.5140607424073, 3914.8042597869994, 3924.6648244294597, 3933.095388060762, 5086.516780905808, 5845.766169353221, 6006.399360063959, 6017.5185985121525, 6057.575969682124, 3283.3925723741186, 3862.5558060822805, 3924.3706716500014, 3954.54522729884, 3986.6611338036014, 3640.9635903641156, 3718.442233664972, 4023.5574086850424, 4035.2736507428945, 4061.5126184858004, 4128.8348466061025, 4693.20215563355, 4699.0, 4705.011947491065, 4719.905601887943, 5003.146318806932, 5465.180222966586, 5769.336526299529, 5772.884542309214, 5848.619839710405, 1240.3798100949525, 1298.2470167302943, 1306.5557710378516, 1306.6667999660026, 1318.45284207053, 748.124694107895, 783.5925318834175, 834.3116928533984, 860.2859626509982, 894.7315805258396, 4796.712197268175, 5272.339213147846, 5314.229436731653, 5476.479734425223, 5489.313835770528, 5478.822053258568, 5509.449055094458, 5531.695756733418, 5663.292088488939, 5838.562107841929, 4879.0, 5264.183856569554, 5478.698671573102, 5484.436935473451, 5485.436650554573, 2865.6131422257727, 2931.9413611728073, 2932.618759561286, 2963.6739958604535, 2979.4041191761303, 4972.925406118911, 5126.794928202905, 5129.640925135204, 5186.896262074737, 5910.25647434008, 5038.924416133761, 5435.206381894044, 5475.370332411744, 5514.09017512106, 5820.82537523875, 5160.7935682573025, 5505.532029777513, 5671.092625180034, 5904.439078287642, 5917.3490915998345, 5311.654742441729, 5431.701256430934, 5451.48210919962, 5684.77260909559, 5690.943090569207, 4835.105505481497, 4957.769003948166, 5043.949560504348, 5598.972005139948, 5641.0, 4843.515648435128, 5192.987367463308, 5550.888982220252, 5671.716414179192, 5737.942620573741, 3526.6473352665553, 3579.516765236764, 3729.645683660048, 3738.775673459601, 3759.4548790425783, 5279.841604751864, 5348.705821179872, 5562.136684210673, 5701.657900525982, 5745.500707192385, 4575.359449677115, 5138.948610513997, 5200.621835213596, 5524.66851988882, 5536.0, 4915.336429581961, 5156.7421628919155, 5306.381553626181, 5443.319585051869, 5474.09677403224, 4599.0, 4942.827001054946, 5026.958689992648, 5082.898342033065, 5296.947030529645, 4861.076395484846, 4923.138450771029, 5047.419546752024, 5302.681839089667, 5593.524550413261, 4878.121938051185, 4965.602751779777, 5172.818951336685, 5438.619296649196, 5623.710129574245], "value": 4069.606642705655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.652166959859, 737.6164394514852, 778.1401551285829, 812.3605455975704, 841.5497708725829, 15116.31976561056, 15841.0, 15962.521124366258, 15973.760393594106, 16004.959677620962, 14332.140071595712, 14568.781468277984, 14606.269686515685, 14730.705385892275, 14737.0, 676.4824908944661, 695.9060526828864, 836.5399030533213, 837.7277384849933, 862.6721845698657, 664.5713514782963, 797.4457751862458, 857.9442336248155, 858.1161403754144, 860.3375400941271, 895.6954635423955, 906.4651855405309, 908.995455022724, 909.2816674826879, 910.5219759626216, 754.5736658787795, 896.4083704754896, 902.7066203483861, 904.6607522179182, 908.0420156734642, 663.920329560451, 668.0713807807152, 669.8928171492587, 671.4058058618109, 671.7447369999418, 14954.177520236382, 15310.770338444932, 15315.540533783893, 15477.613059673486, 15864.841351586567, 14502.854971450255, 14641.267936603128, 14835.777463338058, 14868.925655371762, 15046.247687615576, 11575.189736718434, 11658.591949281734, 11740.354280514568, 11845.23006004607, 12408.56570020054, 3132.6240851097787, 3312.96687033129, 3603.207294395255, 3717.070732316921, 3742.5883152853166, 80237.59881200557, 103198.0, 106975.0, 108508.0, 111289.44355278171, 8543.700970466003, 10457.529411176516, 10632.149428045737, 10812.891871081345, 11016.394098324508, 20222.595548088957, 20824.791752082587, 20941.162353505995, 20962.161513539286, 20985.0, 7597.772066838003, 7617.733379331808, 7622.8094297643, 7788.883166752503, 7874.094479134858, 4372.409724687126, 4447.710898791568, 4448.911021779546, 4452.710573812692, 4551.613112885376, 3524.8896597572198, 3597.0467826026124, 3600.459931010317, 3663.4687970244167, 3684.894531640497, 618.4897459595851, 676.9898451523231, 683.723092147681, 694.3646563394437, 699.632692836265, 660.7588230296003, 670.1321788284183, 684.5926673629183, 705.6330708031898, 719.4388377065897, 9407.529623518796, 9870.667459892822, 10419.218558608134, 10432.321899076687, 10836.0, 704.6089420371641, 705.752986454747, 724.1635910523354, 724.2865777209445, 737.9704811807468, 4642.257238841769, 4997.825076122318, 5011.498850115032, 5022.974885125551, 5029.0, 97872.51063744635, 98883.50558247307, 100015.0, 100523.0, 102408.97591024144, 94598.0, 95202.52398737962, 95764.52117739366, 96383.0, 96839.0, 70251.64874175597, 71434.64282678554, 78631.21368786354, 78645.60677196577, 79536.40927181309, 9329.626814927598, 9724.367916085432, 9744.220462363135, 9769.0, 9788.923218445823, 39742.60257397389, 43858.56141438545, 54004.91990160096, 54036.91926161376, 54344.72827635994, 68026.47629331799, 68959.65520172367, 70278.0, 70843.0, 72147.0, 9671.0, 11575.826362604399, 11681.941590291994, 11689.298642081503, 11889.405529723652, 10177.949110254402, 10754.516046777757, 12739.872601273868, 13373.53192638253, 13442.529511467052, 11755.177137600285, 11945.82081268799, 12110.939445302716, 12396.876031239934, 12496.562620308245, 9707.660231892132, 12179.330136842558, 12287.877121228674, 12368.628941131781, 12433.75132497327, 9634.807303854024, 11802.074544642277, 12035.518579257083, 12390.752184956431, 12457.937710311391, 9968.850467242852, 10127.139193168368, 10199.694009179737, 10307.381557106597, 10770.515326810155, 9234.445933244027, 9954.950225248827, 9956.0, 9974.850377244202, 10025.548850301608], "value": 7337.913883603048, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1620.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 119.0, 117.0, 118.0, 118.0, 119.0, 118.0, 116.0, 118.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 115.0, 152.0, 113.0, 115.0, 115.0, 131.0, 171.0, 146.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 202.0, 226.0, 257.0, 242.0, 201.0, 196.0, 200.0, 195.0, 196.0], "value": 212.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 50.0, 57.0, 107.0, 84.0, 87.0, 84.0, 61.0, 73.0, 61.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 74.0, 77.0, 60.0, 59.0, 71.0, 114.0, 105.0, 59.0, 98.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 111.0, 126.0, 110.0, 78.0, 137.0, 132.0], "value": 104.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 100.0, 99.0, 100.0, 101.0, 167.0, 101.0, 111.0, 100.0, 149.0], "value": 113.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 94.0, 94.0, 79.0, 144.0, 147.0, 90.0, 95.0, 92.0], "value": 112.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 70.0, 78.0, 80.0, 71.0, 90.0, 108.0, 71.0, 71.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 104.0, 80.0, 76.0, 97.0, 114.0, 94.0, 103.0, 78.0, 91.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 151.0, 144.0, 152.0, 160.0, 158.0, 141.0, 143.0, 216.0, 184.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 243.0, 247.0, 180.0, 177.0, 172.0, 197.0, 166.0, 231.0, 246.0], "value": 205.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 70.0, 67.0, 82.0, 89.0, 92.0, 94.0], "value": 76.6, "unit": "score"}]}]} 06:10:27 INFO - Return code: 0 06:10:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:10:27 ERROR - # TBPL SUCCESS # 06:10:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:10:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:10:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:10:27 INFO - 2017-01-20 06:10:27.545 screenresolution[1928:18310] starting screenresolution argv=screenresolution get 06:10:27 INFO - 2017-01-20 06:10:27.547 screenresolution[1928:18310] Display 0: 1600x1200x32@60 06:10:27 INFO - 2017-01-20 06:10:27.553 screenresolution[1929:18313] starting screenresolution argv=screenresolution list 06:10:27 INFO - Available Modes on Display 0 06:10:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:10:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:10:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:10:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:10:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:10:27 INFO - Intel Iris: 06:10:27 INFO - Chipset Model: Intel Iris 06:10:27 INFO - Type: GPU 06:10:27 INFO - Bus: Built-In 06:10:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:10:27 INFO - Vendor: Intel (0x8086) 06:10:27 INFO - Device ID: 0x0a2e 06:10:27 INFO - Revision ID: 0x0009 06:10:27 INFO - Displays: 06:10:27 INFO - Display: 06:10:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:10:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:10:27 INFO - Display Serial Number: 1600x1200 60 06:10:27 INFO - Main Display: Yes 06:10:27 INFO - Mirror: Off 06:10:27 INFO - Online: Yes 06:10:27 INFO - Rotation: Supported 06:10:27 INFO - Return code: 0 06:10:27 INFO - Running post-action listener: _resource_record_post_action 06:10:27 INFO - [mozharness: 2017-01-20 14:10:27.790692Z] Finished run-tests step (success) 06:10:27 INFO - Running post-run listener: _resource_record_post_run 06:10:27 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 659456; Write bytes: 552852992; Read time: 10; Write time: 1729 06:10:27 INFO - TinderboxPrint: CPU usage
54.5% 06:10:27 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 10 06:10:27 INFO - TinderboxPrint: I/O write bytes / time
552,852,992 / 1,729 06:10:27 INFO - TinderboxPrint: CPU idle
2,184.7 (45.5%) 06:10:27 INFO - TinderboxPrint: CPU system
967.4 (20.1%) 06:10:27 INFO - TinderboxPrint: CPU user
1,649.6 (34.4%) 06:10:27 INFO - TinderboxPrint: Swap in / out
252,231,680 / 0 06:10:27 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247265792; Write bytes: 239849472; Read time: 17758; Write time: 449 06:10:27 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 602112; Write bytes: 299392512; Read time: 9; Write time: 1242 06:10:28 INFO - Running post-run listener: _upload_blobber_files 06:10:28 INFO - Blob upload gear active. 06:10:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:10:28 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 06:10:28 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:10:28 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:10:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:10:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:10:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:10:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:29 INFO - (blobuploader) - INFO - Done attempting. 06:10:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:10:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:10:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:30 INFO - (blobuploader) - INFO - Done attempting. 06:10:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:10:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:10:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:31 INFO - (blobuploader) - INFO - Done attempting. 06:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:10:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:33 INFO - (blobuploader) - INFO - Done attempting. 06:10:33 INFO - (blobuploader) - INFO - Iteration through files over. 06:10:33 INFO - Return code: 0 06:10:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:10:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:10:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d48782a0ee901ca0af99760bc3769ae7c34d72bf4c07579b3fe9b17cb21def26d08302d0a3f9a272a660324d3527fb4ed8f1cfb26f081e607fa4080278520c17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42869057bb0e295432a973ff8e12ffc091cc8593c80b8621a12f55873f6e925c380a4f5bf6e96a9afe584d7e5c632f57f8c706290e6b645ae7b9bfce6b30e2b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f8b5ab0b12930ca8551fd0f7ffea11a504033e1b70cabe73ed3db82d9230435df4b2f12fce122c217cdfed61698455edccc3350dad4ee13bb09d359d6c8b81bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02aeaaf4d73f1bd98f80938fc74600a05eb0c337afbd05a559a8dfb737c4087cac6b9ccd08522132ee0089eda347db6e8978328c278525474c9506189d6c9aa5"} 06:10:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:10:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:10:33 INFO - Contents: 06:10:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d48782a0ee901ca0af99760bc3769ae7c34d72bf4c07579b3fe9b17cb21def26d08302d0a3f9a272a660324d3527fb4ed8f1cfb26f081e607fa4080278520c17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42869057bb0e295432a973ff8e12ffc091cc8593c80b8621a12f55873f6e925c380a4f5bf6e96a9afe584d7e5c632f57f8c706290e6b645ae7b9bfce6b30e2b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f8b5ab0b12930ca8551fd0f7ffea11a504033e1b70cabe73ed3db82d9230435df4b2f12fce122c217cdfed61698455edccc3350dad4ee13bb09d359d6c8b81bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02aeaaf4d73f1bd98f80938fc74600a05eb0c337afbd05a559a8dfb737c4087cac6b9ccd08522132ee0089eda347db6e8978328c278525474c9506189d6c9aa5"} 06:10:33 INFO - Running post-run listener: copy_logs_to_upload_dir 06:10:33 INFO - Copying logs to upload dir... 06:10:33 INFO - mkdir: /builds/slave/test/build/upload/logs 06:10:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.043866 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-20 06:10:34.051614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 06:10:34.056792) ========= 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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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/Graphics/sha512/d48782a0ee901ca0af99760bc3769ae7c34d72bf4c07579b3fe9b17cb21def26d08302d0a3f9a272a660324d3527fb4ed8f1cfb26f081e607fa4080278520c17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42869057bb0e295432a973ff8e12ffc091cc8593c80b8621a12f55873f6e925c380a4f5bf6e96a9afe584d7e5c632f57f8c706290e6b645ae7b9bfce6b30e2b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f8b5ab0b12930ca8551fd0f7ffea11a504033e1b70cabe73ed3db82d9230435df4b2f12fce122c217cdfed61698455edccc3350dad4ee13bb09d359d6c8b81bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02aeaaf4d73f1bd98f80938fc74600a05eb0c337afbd05a559a8dfb737c4087cac6b9ccd08522132ee0089eda347db6e8978328c278525474c9506189d6c9aa5"} build_url:https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/a-uzGB-BQcWObAMwOKfCjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d48782a0ee901ca0af99760bc3769ae7c34d72bf4c07579b3fe9b17cb21def26d08302d0a3f9a272a660324d3527fb4ed8f1cfb26f081e607fa4080278520c17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42869057bb0e295432a973ff8e12ffc091cc8593c80b8621a12f55873f6e925c380a4f5bf6e96a9afe584d7e5c632f57f8c706290e6b645ae7b9bfce6b30e2b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f8b5ab0b12930ca8551fd0f7ffea11a504033e1b70cabe73ed3db82d9230435df4b2f12fce122c217cdfed61698455edccc3350dad4ee13bb09d359d6c8b81bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02aeaaf4d73f1bd98f80938fc74600a05eb0c337afbd05a559a8dfb737c4087cac6b9ccd08522132ee0089eda347db6e8978328c278525474c9506189d6c9aa5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 06:10:34.091364) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 06:10:34.091683) ========= 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.VlEGnuYLo8/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.WEWLcm4DtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.004695 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 06:10:34.139112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 06:10:34.139439) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 06:10:34.139727) ========= ========= Total master_lag: 0.18 =========