builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0257 starttime: 1490024615.66 results: success (0) buildid: 20170320065841 builduid: 0f6c18e28c764c3f815874a8b92ac039 revision: ab5725326239e69217d16e48df1e2661b5820833 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.657489) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.658418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.659058) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.686760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.687436) ========= 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.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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-03-20 08:43:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-03-20 08:43:35 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185121 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.897275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.897613) ========= 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.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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.026375 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:35.945768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 08:43:35.946083) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ab5725326239e69217d16e48df1e2661b5820833 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ab5725326239e69217d16e48df1e2661b5820833 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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-03-20 08:43:36,053 truncating revision to first 12 chars 2017-03-20 08:43:36,053 Setting DEBUG logging. 2017-03-20 08:43:36,053 attempt 1/10 2017-03-20 08:43:36,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ab5725326239?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 08:43:37,539 unpacking tar archive at: mozilla-beta-ab5725326239/testing/mozharness/ program finished with exit code 0 elapsedTime=1.763539 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 08:43:37.732927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:37.733242) ========= script_repo_revision: ab5725326239e69217d16e48df1e2661b5820833 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:37.733691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:37.733954) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 08:43:37.753523) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-20 08:43:37.753829) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:43:37 INFO - MultiFileLogger online at 20170320 08:43:37 in /builds/slave/test 08:43:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:43:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:37 INFO - {'append_to_log': False, 08:43:37 INFO - 'base_work_dir': '/builds/slave/test', 08:43:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:43:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:37 INFO - 'branch': 'Mozilla-Beta', 08:43:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:37 INFO - 'config_files': ('talos/mac_config.py',), 08:43:37 INFO - 'default_actions': ('clobber', 08:43:37 INFO - 'read-buildbot-config', 08:43:37 INFO - 'download-and-extract', 08:43:37 INFO - 'populate-webroot', 08:43:37 INFO - 'create-virtualenv', 08:43:37 INFO - 'install', 08:43:37 INFO - 'run-tests'), 08:43:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:37 INFO - 'download_minidump_stackwalk': True, 08:43:37 INFO - 'download_symbols': 'ondemand', 08:43:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:37 INFO - 'installer_path': 'installer.exe', 08:43:37 INFO - 'log_level': 'info', 08:43:37 INFO - 'log_name': 'talos', 08:43:37 INFO - 'log_to_console': True, 08:43:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:37 INFO - 'opt_config_files': (), 08:43:37 INFO - 'pip_index': False, 08:43:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:37 INFO - 'cmd': ('bash', 08:43:37 INFO - '-c', 08:43:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:37 INFO - 'enabled': True, 08:43:37 INFO - 'halt_on_failure': False, 08:43:37 INFO - 'name': 'check_screen_resolution'},), 08:43:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:37 INFO - 'cmd': ('bash', 08:43:37 INFO - '-c', 08:43:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:37 INFO - 'enabled': True, 08:43:37 INFO - 'halt_on_failure': False, 08:43:37 INFO - 'name': 'check_screen_resolution'},), 08:43:37 INFO - 'run_cmd_checks_enabled': True, 08:43:37 INFO - 'sps_profile': False, 08:43:37 INFO - 'sps_profile_interval': 0, 08:43:37 INFO - 'suite': 'dromaeojs-e10s', 08:43:37 INFO - 'system_bits': '32', 08:43:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:37 INFO - 'title': 't-yosemite-r7-0257', 08:43:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:37 INFO - 'use_talos_json': True, 08:43:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:37 INFO - 'work_dir': 'build'} 08:43:37 INFO - [mozharness: 2017-03-20 15:43:37.968705Z] Running clobber step. 08:43:37 INFO - Running pre-action listener: _resource_record_pre_action 08:43:37 INFO - Running main action method: clobber 08:43:37 INFO - rmtree: /builds/slave/test/build 08:43:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:40 INFO - Running post-action listener: _resource_record_post_action 08:43:40 INFO - [mozharness: 2017-03-20 15:43:40.353225Z] Finished clobber step (success) 08:43:40 INFO - [mozharness: 2017-03-20 15:43:40.353360Z] Running read-buildbot-config step. 08:43:40 INFO - Running pre-action listener: _resource_record_pre_action 08:43:40 INFO - Running main action method: read_buildbot_config 08:43:40 INFO - Using buildbot properties: 08:43:40 INFO - { 08:43:40 INFO - "project": "", 08:43:40 INFO - "product": "firefox", 08:43:40 INFO - "script_repo_revision": "production", 08:43:40 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:43:40 INFO - "repository": "", 08:43:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:43:40 INFO - "buildid": "20170320065841", 08:43:40 INFO - "pgo_build": "False", 08:43:40 INFO - "basedir": "/builds/slave/test", 08:43:40 INFO - "buildnumber": 8, 08:43:40 INFO - "slavename": "t-yosemite-r7-0257", 08:43:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:43:40 INFO - "platform": "yosemite_r7", 08:43:40 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:43:40 INFO - "branch": "mozilla-beta", 08:43:40 INFO - "repo_path": "releases/mozilla-beta", 08:43:40 INFO - "revision": "ab5725326239e69217d16e48df1e2661b5820833", 08:43:40 INFO - "stage_platform": "macosx64", 08:43:40 INFO - "builduid": "0f6c18e28c764c3f815874a8b92ac039", 08:43:40 INFO - "slavebuilddir": "test" 08:43:40 INFO - } 08:43:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 08:43:40 INFO - Running post-action listener: _resource_record_post_action 08:43:40 INFO - [mozharness: 2017-03-20 15:43:40.356450Z] Finished read-buildbot-config step (success) 08:43:40 INFO - [mozharness: 2017-03-20 15:43:40.356548Z] Running download-and-extract step. 08:43:40 INFO - Running pre-action listener: _resource_record_pre_action 08:43:40 INFO - Running main action method: download_and_extract 08:43:40 INFO - mkdir: /builds/slave/test/build/tests 08:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:40 INFO - trying https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 08:43:40 INFO - Downloading https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 08:43:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 08:43:41 INFO - Downloaded 1165 bytes. 08:43:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 08:43:41 INFO - Using the following test package requirements: 08:43:41 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:43:41 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 08:43:41 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 08:43:41 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:43:41 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 08:43:41 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 08:43:41 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 08:43:41 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 08:43:41 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 08:43:41 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 08:43:41 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 08:43:41 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 08:43:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:43:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:43:42 INFO - Expected file size: 16108263 08:43:42 INFO - Obtained file size: 16108263 08:43:49 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:43:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 08:43:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:49 INFO - Fetch https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 08:43:50 INFO - Expected file size: 16108263 08:43:50 INFO - Obtained file size: 16108263 08:43:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 08:43:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 08:43:56 INFO - Fetch https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 08:43:57 INFO - Expected file size: 14403663 08:43:57 INFO - Obtained file size: 14403663 08:43:57 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')]} 08:43:57 INFO - trying https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 08:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 08:43:59 INFO - Downloaded 58726595 bytes. 08:43:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:43:59 INFO - mkdir: /builds/slave/test/properties 08:43:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:59 INFO - Contents: 08:43:59 INFO - build_url:https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 08:43:59 INFO - Running post-action listener: _resource_record_post_action 08:43:59 INFO - Running post-action listener: set_extra_try_arguments 08:43:59 INFO - [mozharness: 2017-03-20 15:43:59.993025Z] Finished download-and-extract step (success) 08:43:59 INFO - [mozharness: 2017-03-20 15:43:59.993171Z] Running populate-webroot step. 08:43:59 INFO - Running pre-action listener: _resource_record_pre_action 08:43:59 INFO - Running main action method: populate_webroot 08:44:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:00 INFO - '--tppagecycles', 08:44:00 INFO - '7'], 08:44:00 INFO - 'tests': ['tsvgm']}}, 08:44:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['damp', 'tps']}, 08:44:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'tests': ['damp', 'tps']}, 08:44:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['dromaeo_dom']}, 08:44:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['basic_compositor_video']}, 08:44:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['a11yr', 08:44:00 INFO - 'ts_paint', 08:44:00 INFO - 'tpaint', 08:44:00 INFO - 'sessionrestore', 08:44:00 INFO - 'sessionrestore_no_auto_restore', 08:44:00 INFO - 'tabpaint']}, 08:44:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['tsvgx', 08:44:00 INFO - 'tsvgr_opacity', 08:44:00 INFO - 'tart', 08:44:00 INFO - 'tscrollx', 08:44:00 INFO - 'cart', 08:44:00 INFO - 'tsvg_static']}, 08:44:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:00 INFO - 'tsvgr_opacity', 08:44:00 INFO - 'tart', 08:44:00 INFO - 'tscrollx', 08:44:00 INFO - 'cart', 08:44:00 INFO - 'tsvg_static']}, 08:44:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'talos_options': ['--disable-e10s'], 08:44:00 INFO - 'tests': ['tp5o']}, 08:44:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'talos_options': ['--disable-e10s', 08:44:00 INFO - '--xperf_path', 08:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:00 INFO - 'tests': ['tp5n']}, 08:44:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:00 INFO - 'talos_options': ['--xperf_path', 08:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:00 INFO - 'tests': ['tp5n']}}, 08:44:00 INFO - 'talos.zip': {'path': '', 08:44:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:00 INFO - Running post-action listener: _resource_record_post_action 08:44:00 INFO - [mozharness: 2017-03-20 15:44:00.012177Z] Finished populate-webroot step (success) 08:44:00 INFO - [mozharness: 2017-03-20 15:44:00.012297Z] Running create-virtualenv step. 08:44:00 INFO - Running pre-action listener: _resource_record_pre_action 08:44:00 INFO - Running main action method: create_virtualenv 08:44:00 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:00 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 08:44:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:00 INFO - Using real prefix '/tools/python27' 08:44:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:00 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:03 INFO - Installing pip.................done. 08:44:03 INFO - Return code: 0 08:44:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:03 INFO - Reading from file tmpfile_stdout 08:44:03 INFO - Output received: 08:44:03 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) 08:44:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:04 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:04 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 08:44:04 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 08:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:04 INFO - 'HOME': '/Users/cltbld', 08:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:04 INFO - 'LOGNAME': 'cltbld', 08:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:04 INFO - 'MOZ_NO_REMOTE': '1', 08:44:04 INFO - 'NO_EM_RESTART': '1', 08:44:04 INFO - 'PAGER': '/bin/cat', 08:44:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:04 INFO - 'PWD': '/builds/slave/test', 08:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:04 INFO - 'SHELL': '/bin/bash', 08:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:04 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:04 INFO - 'USER': 'cltbld', 08:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:04 INFO - 'XPC_FLAGS': '0x0', 08:44:04 INFO - 'XPC_SERVICE_NAME': '0', 08:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:04 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 08:44:04 INFO - Cleaning up... 08:44:04 INFO - Return code: 0 08:44:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:04 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:04 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 08:44:04 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 08:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:04 INFO - 'HOME': '/Users/cltbld', 08:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:04 INFO - 'LOGNAME': 'cltbld', 08:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:04 INFO - 'MOZ_NO_REMOTE': '1', 08:44:04 INFO - 'NO_EM_RESTART': '1', 08:44:04 INFO - 'PAGER': '/bin/cat', 08:44:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:04 INFO - 'PWD': '/builds/slave/test', 08:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:04 INFO - 'SHELL': '/bin/bash', 08:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:04 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:04 INFO - 'USER': 'cltbld', 08:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:04 INFO - 'XPC_FLAGS': '0x0', 08:44:04 INFO - 'XPC_SERVICE_NAME': '0', 08:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:04 INFO - Downloading/unpacking psutil>=3.1.1 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:08 INFO - Installing collected packages: psutil 08:44:08 INFO - Running setup.py install for psutil 08:44:08 INFO - building 'psutil._psutil_osx' extension 08:44:08 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 08:44:08 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 08:44:08 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 08:44:08 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 08:44:08 INFO - building 'psutil._psutil_posix' extension 08:44:08 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 08:44:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:08 INFO - ^ 08:44:08 INFO - 1 warning generated. 08:44:08 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 08:44:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:09 INFO - Successfully installed psutil 08:44:09 INFO - Cleaning up... 08:44:09 INFO - Return code: 0 08:44:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:09 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')]} 08:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:09 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')]} 08:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:09 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:09 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 08:44:09 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 08:44:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:09 INFO - 'HOME': '/Users/cltbld', 08:44:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:09 INFO - 'LOGNAME': 'cltbld', 08:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:09 INFO - 'MOZ_NO_REMOTE': '1', 08:44:09 INFO - 'NO_EM_RESTART': '1', 08:44:09 INFO - 'PAGER': '/bin/cat', 08:44:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:09 INFO - 'PWD': '/builds/slave/test', 08:44:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:09 INFO - 'SHELL': '/bin/bash', 08:44:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:09 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:09 INFO - 'USER': 'cltbld', 08:44:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:09 INFO - 'XPC_FLAGS': '0x0', 08:44:09 INFO - 'XPC_SERVICE_NAME': '0', 08:44:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:12 INFO - Installing collected packages: mozsystemmonitor 08:44:12 INFO - Running setup.py install for mozsystemmonitor 08:44:12 INFO - Successfully installed mozsystemmonitor 08:44:12 INFO - Cleaning up... 08:44:12 INFO - Return code: 0 08:44:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:12 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')]} 08:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:12 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')]} 08:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:12 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:12 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 08:44:12 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 08:44:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:12 INFO - 'HOME': '/Users/cltbld', 08:44:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:12 INFO - 'LOGNAME': 'cltbld', 08:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:12 INFO - 'MOZ_NO_REMOTE': '1', 08:44:12 INFO - 'NO_EM_RESTART': '1', 08:44:12 INFO - 'PAGER': '/bin/cat', 08:44:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:12 INFO - 'PWD': '/builds/slave/test', 08:44:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:12 INFO - 'SHELL': '/bin/bash', 08:44:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:12 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:12 INFO - 'USER': 'cltbld', 08:44:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:12 INFO - 'XPC_FLAGS': '0x0', 08:44:12 INFO - 'XPC_SERVICE_NAME': '0', 08:44:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:12 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:12 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 08:44:12 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 08:44:12 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 08:44:12 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 08:44:12 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 08:44:12 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 08:44:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:15 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 08:44:15 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 08:44:15 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 08:44:15 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 08:44:15 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 08:44:15 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 08:44:15 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:15 INFO - warning: no files found matching '*.txt' 08:44:15 INFO - no previously-included directories found matching 'build' 08:44:15 INFO - no previously-included directories found matching 'dist' 08:44:15 INFO - no previously-included directories found matching '.git*' 08:44:15 INFO - Installing collected packages: jsonschema, functools32 08:44:15 INFO - Running setup.py install for functools32 08:44:15 INFO - warning: no files found matching '*.txt' 08:44:15 INFO - no previously-included directories found matching 'build' 08:44:15 INFO - no previously-included directories found matching 'dist' 08:44:15 INFO - no previously-included directories found matching '.git*' 08:44:15 INFO - Successfully installed jsonschema functools32 08:44:15 INFO - Cleaning up... 08:44:16 INFO - Return code: 0 08:44:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:44:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:16 INFO - 'HOME': '/Users/cltbld', 08:44:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:16 INFO - 'LOGNAME': 'cltbld', 08:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:16 INFO - 'MOZ_NO_REMOTE': '1', 08:44:16 INFO - 'NO_EM_RESTART': '1', 08:44:16 INFO - 'PAGER': '/bin/cat', 08:44:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:16 INFO - 'PWD': '/builds/slave/test', 08:44:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:16 INFO - 'SHELL': '/bin/bash', 08:44:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:16 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:16 INFO - 'USER': 'cltbld', 08:44:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:16 INFO - 'XPC_FLAGS': '0x0', 08:44:16 INFO - 'XPC_SERVICE_NAME': '0', 08:44:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:16 INFO - Cleaning up... 08:44:16 INFO - Return code: 0 08:44:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:44:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:16 INFO - 'HOME': '/Users/cltbld', 08:44:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:16 INFO - 'LOGNAME': 'cltbld', 08:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:16 INFO - 'MOZ_NO_REMOTE': '1', 08:44:16 INFO - 'NO_EM_RESTART': '1', 08:44:16 INFO - 'PAGER': '/bin/cat', 08:44:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:16 INFO - 'PWD': '/builds/slave/test', 08:44:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:16 INFO - 'SHELL': '/bin/bash', 08:44:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:16 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:16 INFO - 'USER': 'cltbld', 08:44:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:16 INFO - 'XPC_FLAGS': '0x0', 08:44:16 INFO - 'XPC_SERVICE_NAME': '0', 08:44:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:16 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:16 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 08:44:16 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 08:44:16 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 08:44:16 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 08:44:16 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 08:44:16 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 08:44:19 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:20 INFO - Downloading docopt-0.6.1.tar.gz 08:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:20 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:20 INFO - Running setup.py install for blobuploader 08:44:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:20 INFO - Running setup.py install for requests 08:44:21 INFO - Running setup.py install for docopt 08:44:21 INFO - Successfully installed blobuploader requests docopt 08:44:21 INFO - Cleaning up... 08:44:21 INFO - Return code: 0 08:44:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:21 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')]} 08:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:21 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')]} 08:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:21 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:21 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 08:44:21 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 08:44:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:21 INFO - 'HOME': '/Users/cltbld', 08:44:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:21 INFO - 'LOGNAME': 'cltbld', 08:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:21 INFO - 'MOZ_NO_REMOTE': '1', 08:44:21 INFO - 'NO_EM_RESTART': '1', 08:44:21 INFO - 'PAGER': '/bin/cat', 08:44:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:21 INFO - 'PWD': '/builds/slave/test', 08:44:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:21 INFO - 'SHELL': '/bin/bash', 08:44:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:21 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:21 INFO - 'USER': 'cltbld', 08:44:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:21 INFO - 'XPC_FLAGS': '0x0', 08:44:21 INFO - 'XPC_SERVICE_NAME': '0', 08:44:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:21 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-xMQwUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:21 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-_clT73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:21 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-XPgr19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:21 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-pLPPYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-GwSLkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-aYAea7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-v0tW2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-Llm6Dl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-5E_QCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-9eXARl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-5TAc0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-0sombg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-r3Pkrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:22 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-6y0hiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:23 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-w2eBWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:23 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-7KJkmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:23 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-jyyJUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:23 INFO - Running setup.py install for manifestparser 08:44:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:23 INFO - Running setup.py install for mozcrash 08:44:23 INFO - Running setup.py install for mozdebug 08:44:23 INFO - Running setup.py install for mozdevice 08:44:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Running setup.py install for mozfile 08:44:24 INFO - Running setup.py install for mozhttpd 08:44:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Running setup.py install for mozinfo 08:44:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Running setup.py install for mozInstall 08:44:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Running setup.py install for mozleak 08:44:24 INFO - Running setup.py install for mozlog 08:44:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:24 INFO - Running setup.py install for moznetwork 08:44:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Running setup.py install for mozprocess 08:44:25 INFO - Running setup.py install for mozprofile 08:44:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Running setup.py install for mozrunner 08:44:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Running setup.py install for mozscreenshot 08:44:25 INFO - Running setup.py install for moztest 08:44:25 INFO - Running setup.py install for mozversion 08:44:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:25 INFO - Cleaning up... 08:44:25 INFO - Return code: 0 08:44:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:25 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:25 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 08:44:25 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 08:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:25 INFO - 'HOME': '/Users/cltbld', 08:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:25 INFO - 'LOGNAME': 'cltbld', 08:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:25 INFO - 'MOZ_NO_REMOTE': '1', 08:44:25 INFO - 'NO_EM_RESTART': '1', 08:44:25 INFO - 'PAGER': '/bin/cat', 08:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:25 INFO - 'PWD': '/builds/slave/test', 08:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:25 INFO - 'SHELL': '/bin/bash', 08:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:25 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:25 INFO - 'USER': 'cltbld', 08:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:25 INFO - 'XPC_FLAGS': '0x0', 08:44:25 INFO - 'XPC_SERVICE_NAME': '0', 08:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-N80hl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-dMkzUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-07Wbex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-xYayj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-k0cWzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-lLMioB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-ubluMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:26 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)) 08:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:26 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-q9yMgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-AA08Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-KRMfC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-idMx0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-nYR8Yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-ihgFYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-D1AqnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-zlWxGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-IjzTHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:27 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)) 08:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:27 INFO - Running setup.py (path:/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/pip-QCIt8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:28 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)) 08:44:28 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)) 08:44:28 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)) 08:44:28 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)) 08:44:28 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)) 08:44:28 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)) 08:44:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:30 INFO - Downloading blessings-1.6.tar.gz 08:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:30 INFO - Installing collected packages: blessings 08:44:30 INFO - Running setup.py install for blessings 08:44:30 INFO - Successfully installed blessings 08:44:30 INFO - Cleaning up... 08:44:31 INFO - Return code: 0 08:44:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:31 INFO - Reading from file tmpfile_stdout 08:44:31 INFO - Current package versions: 08:44:31 INFO - blessings == 1.6 08:44:31 INFO - blobuploader == 1.2.4 08:44:31 INFO - docopt == 0.6.1 08:44:31 INFO - functools32 == 3.2.3-2 08:44:31 INFO - jsonschema == 2.5.1 08:44:31 INFO - manifestparser == 1.1 08:44:31 INFO - mozInstall == 1.12 08:44:31 INFO - mozcrash == 1.0 08:44:31 INFO - mozdebug == 0.1 08:44:31 INFO - mozdevice == 0.49 08:44:31 INFO - mozfile == 1.2 08:44:31 INFO - mozhttpd == 0.7 08:44:31 INFO - mozinfo == 0.9 08:44:31 INFO - mozleak == 0.1 08:44:31 INFO - mozlog == 3.4 08:44:31 INFO - moznetwork == 0.27 08:44:31 INFO - mozprocess == 0.24 08:44:31 INFO - mozprofile == 0.28 08:44:31 INFO - mozrunner == 6.13 08:44:31 INFO - mozscreenshot == 0.1 08:44:31 INFO - mozsystemmonitor == 0.3 08:44:31 INFO - moztest == 0.8 08:44:31 INFO - mozversion == 1.4 08:44:31 INFO - psutil == 3.1.1 08:44:31 INFO - requests == 1.2.3 08:44:31 INFO - wsgiref == 0.1.2 08:44:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:31 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')]} 08:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:32 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:32 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 08:44:32 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 08:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:32 INFO - 'HOME': '/Users/cltbld', 08:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:32 INFO - 'LOGNAME': 'cltbld', 08:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:32 INFO - 'MOZ_NO_REMOTE': '1', 08:44:32 INFO - 'NO_EM_RESTART': '1', 08:44:32 INFO - 'PAGER': '/bin/cat', 08:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:32 INFO - 'PWD': '/builds/slave/test', 08:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:32 INFO - 'SHELL': '/bin/bash', 08:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:32 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:32 INFO - 'USER': 'cltbld', 08:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:32 INFO - 'XPC_FLAGS': '0x0', 08:44:32 INFO - 'XPC_SERVICE_NAME': '0', 08:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 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)) 08:44:32 INFO - Cleaning up... 08:44:32 INFO - Return code: 0 08:44:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:33 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 0x103382b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103385768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8f2d7c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, '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.rv68AKl3nK/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.bLnFpEFarr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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 08:44:33 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 08:44:33 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 08:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:33 INFO - 'HOME': '/Users/cltbld', 08:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:33 INFO - 'LOGNAME': 'cltbld', 08:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:33 INFO - 'MOZ_NO_REMOTE': '1', 08:44:33 INFO - 'NO_EM_RESTART': '1', 08:44:33 INFO - 'PAGER': '/bin/cat', 08:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:33 INFO - 'PWD': '/builds/slave/test', 08:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:33 INFO - 'SHELL': '/bin/bash', 08:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:33 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:33 INFO - 'USER': 'cltbld', 08:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:33 INFO - 'XPC_FLAGS': '0x0', 08:44:33 INFO - 'XPC_SERVICE_NAME': '0', 08:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:33 INFO - Cleaning up... 08:44:33 INFO - Return code: 0 08:44:33 INFO - Running post-action listener: _resource_record_post_action 08:44:33 INFO - Running post-action listener: _start_resource_monitoring 08:44:33 INFO - Starting resource monitoring. 08:44:33 INFO - [mozharness: 2017-03-20 15:44:33.495383Z] Finished create-virtualenv step (success) 08:44:33 INFO - [mozharness: 2017-03-20 15:44:33.496319Z] Running install step. 08:44:33 INFO - Running pre-action listener: _resource_record_pre_action 08:44:33 INFO - Running main action method: install 08:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:33 INFO - Reading from file tmpfile_stdout 08:44:33 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:33 INFO - Reading from file tmpfile_stdout 08:44:33 INFO - Output received: 08:44:33 INFO - Usage: mozinstall [options] installer 08:44:33 INFO - Options: 08:44:33 INFO - -h, --help show this help message and exit 08:44:33 INFO - -d DEST, --destination=DEST 08:44:33 INFO - Directory to install application into. [default: 08:44:33 INFO - "/builds/slave/test"] 08:44:33 INFO - --app=APP Application being installed. [default: firefox] 08:44:33 INFO - mkdir: /builds/slave/test/build/application 08:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:44:48 INFO - Reading from file tmpfile_stdout 08:44:48 INFO - Output received: 08:44:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:44:48 INFO - Running post-action listener: _resource_record_post_action 08:44:48 INFO - [mozharness: 2017-03-20 15:44:48.787266Z] Finished install step (success) 08:44:48 INFO - [mozharness: 2017-03-20 15:44:48.787370Z] Running run-tests step. 08:44:48 INFO - Running pre-action listener: _resource_record_pre_action 08:44:48 INFO - Running main action method: run_tests 08:44:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:48 INFO - 2017-03-20 08:44:48.801 screenresolution[1623:7280] starting screenresolution argv=screenresolution get 08:44:48 INFO - 2017-03-20 08:44:48.804 screenresolution[1623:7280] Display 0: 1600x1200x32@60 08:44:48 INFO - 2017-03-20 08:44:48.810 screenresolution[1624:7282] starting screenresolution argv=screenresolution list 08:44:48 INFO - Available Modes on Display 0 08:44:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:49 INFO - Intel Iris: 08:44:49 INFO - Chipset Model: Intel Iris 08:44:49 INFO - Type: GPU 08:44:49 INFO - Bus: Built-In 08:44:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:49 INFO - Vendor: Intel (0x8086) 08:44:49 INFO - Device ID: 0x0a2e 08:44:49 INFO - Revision ID: 0x0009 08:44:49 INFO - Displays: 08:44:49 INFO - Display: 08:44:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:49 INFO - Display Serial Number: 1600x1200 60 08:44:49 INFO - Main Display: Yes 08:44:49 INFO - Mirror: Off 08:44:49 INFO - Online: Yes 08:44:49 INFO - Rotation: Supported 08:44:49 INFO - Return code: 0 08:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:49 INFO - Python 2.7.3 08:44:49 INFO - Return code: 0 08:44:49 INFO - grabbing minidump binary from tooltool 08:44: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')]} 08:44: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 0x103160d30>, '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 0x103087db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103155be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44: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 08:44: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 08:44: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 08:44:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:49 INFO - Return code: 0 08:44:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 08:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --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 08:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rv68AKl3nK/Render', 08:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:49 INFO - 'HOME': '/Users/cltbld', 08:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PAGER': '/bin/cat', 08:44:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bLnFpEFarr/Listeners', 08:44:49 INFO - 'TMPDIR': '/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/', 08:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - 'XPC_FLAGS': '0x0', 08:44:49 INFO - 'XPC_SERVICE_NAME': '0', 08:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 08:44:49 INFO - mozversion application_buildid: 20170320065841 08:44:49 INFO - mozversion application_changeset: ab5725326239e69217d16e48df1e2661b5820833 08:44:49 INFO - mozversion application_display_name: Firefox 08:44:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:49 INFO - mozversion application_name: Firefox 08:44:49 INFO - mozversion application_remotingname: firefox 08:44:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:49 INFO - mozversion application_vendor: Mozilla 08:44:49 INFO - mozversion application_version: 53.0 08:44:49 INFO - mozversion platform_buildid: 20170320065841 08:44:49 INFO - mozversion platform_changeset: ab5725326239e69217d16e48df1e2661b5820833 08:44:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:44:49 INFO - mozversion platform_version: 53.0 08:44:49 INFO - using testdate: 1490024689 08:44:49 INFO - actual date: 1490024689 08:44:49 INFO - starting webserver on 'localhost:49240' 08:44:49 INFO - SUITE-START | Running 2 tests 08:44:49 INFO - TEST-START | dromaeo_css 08:44:49 INFO - operating with platform_type : mac_ 08:44:49 INFO - Initialising browser for dromaeo_css test... 08:44:49 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/tmp0jUjbg/profile http://localhost:49240/getInfo.html) 08:44:52 INFO - PROCESS | 1634 | 2017-03-20 08:44:52.206 plugin-container[1637:7457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c2f, name = 'com.apple.tsm.portname' 08:44:52 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:52 INFO - PROCESS | 1634 | 2017-03-20 08:44:52.206 plugin-container[1637:7457] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 08:44:52 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:52 INFO - PROCESS | 1634 | 2017-03-20 08:44:52.206 plugin-container[1637:7457] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:52 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:44:52 INFO - PROCESS | 1634 | colorDepth:24 08:44:52 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:44:52 INFO - PROCESS | 1634 | __metrics 08:44:52 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 08:44:52 INFO - TEST-INFO | 1634: exit 0 08:44:52 INFO - Browser initialized. 08:44:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:52 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/tmp0jUjbg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:55 INFO - PROCESS | 1638 | 2017-03-20 08:44:55.297 plugin-container[1639:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:44:55 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:55 INFO - PROCESS | 1638 | 2017-03-20 08:44:55.298 plugin-container[1639:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 08:44:55 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:55 INFO - PROCESS | 1638 | 2017-03-20 08:44:55.298 plugin-container[1639:7587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:04 INFO - PROCESS | 1638 | RSS: Main: 173137920 08:45:04 INFO - PROCESS | 1638 | 08:48:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:48:08 INFO - PROCESS | 1638 | RSS: Main: 189468672 08:48:08 INFO - PROCESS | 1638 | 08:51:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:51:11 INFO - PROCESS | 1638 | RSS: Main: 203448320 08:51:11 INFO - PROCESS | 1638 | 08:54:13 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:54:14 INFO - PROCESS | 1638 | RSS: Main: 205156352 08:54:14 INFO - PROCESS | 1638 | 08:57:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:57:16 INFO - PROCESS | 1638 | RSS: Main: 208203776 08:57:16 INFO - PROCESS | 1638 | 09:00:19 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:00:20 INFO - PROCESS | 1638 | RSS: Main: 209367040 09:00:20 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:03:23 INFO - PROCESS | 1638 | RSS: Main: 209518592 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | __start_tp_report 09:03:23 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:03:23 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:03:23 INFO - PROCESS | 1638 | |i|pagename|runs| 09:03:23 INFO - PROCESS | 1638 | |0;dojo.html;3573.4103872860974;3593.371160046991;3614.7108231341495;3623.6919861811743;3625.256822351418;6029.246344206974;6045.516358691305;6054.788082417116;6070.36261192574;6098.969505152468;6009.729562169702;6030.758769649209;6067.817965461032;6071.544634152434;6086.817395478131;5451.863703407407;5514.172874068901;5524;5539.639923404966;5587.38538760736;5565.332160140789;5573.554115670757;5576.804811831586;5591.496765291123;5619.550435965133;4354.412154359168;5392.595555333346;5569.693666848321;5577.163425486189;5577.944220557783;4373.256546387106;5523.558115350762;5528.806491772789;5534.4742249486635;5535.501804837584;5328.254044433782;5440.591955603306;5445.455454454563;5459.945400545984;5481.397046324901;6211.689415529206;6221.5333849961435;6232.220972378453;6252.562320637558;6254.593451425642;5905.995980683251;5969.8806023879715;6384.776532821375;6444.903326450107;6446.709898054599;6310;6350.841228969267;6434.517411194132;6557.606543607399;6611.504137189682;6139.798803208747;6268;6272.780452684134;6278.560500764994;6288.088227207029;6641.369069938348;6668.899966500507;6714.127163468718;6722.428593569503;6725.361090696376;5261.973690131601;6177.567570270038;6179.938200618026;6281.403266689657;6382.617042977344;6382.106505089291;6392.648404337806;6396.264429590564;6491.383318584727;6503.057056726748;6398.680065996681;6402.519811014193;6403.263624683128;6405;6426.582272152388;4535.160995215896;6185.670080932621;6381.4575761059905;6413.615183088937;6446.129772480749;5151.330327057533;5179.7928082877015;5188.63679542436;5215.634905556574;5271.446498117719;3655.6892664123316;3909.667678247381;4621.560951709582;4642.442906851132;4670.766461676971;6196.1635179251125;6199.907001394983;6209.006558950637;6214.098955651496;6216.813495595139;6267.749290028439;6280;6355.014972679242;6361.14124593174;6367.522435817332;5964.7315870785915;6125.601835880653;6125.84685382869;6141.508679305645;6143.385661433821;5608.9439105609235;5655.264815573949;5693.971530142406;5704.942950570524;5710.771569137188;5461.481159289781;6608.207015158212;6674.83312917181;6729.5625784323865;6739.05653208561;6482.416582507597;6501.772437964649;6513.511486638616;6516.6415847128865;6528.738850445929;6347.174867267226;6469.611823290617;6680.63256520896;6711.429528490133;6757.054012438361;6254.781082662085;6292.779752708633;6295.779647712491;6324.3991820776955;6423.325550817097;6444.323346048597;6497.18785151856;6504.056911747675;6533.607983521004;6542.214934207926;6299.96850015747;6308.432241098323;6323.620582765049;6336.271328797063;6342.270638876588;6172.9074063889975;6204.162438070803;6213.2233470816145;6220.191375121205;6305;5821.922944255326;5864.970675146597;6226.439620434183;6246.187995560548;6256.7810126645345;5170.689758614495;6254.655993920378;6270.874582508415;6285.43431091204;6335;5020.698758074527;5063.949360506348;6151.3541078186145;6168.444839964424;6174.25908890918 09:03:23 INFO - PROCESS | 1638 | |1;ext.html;439.40460675784294;468.6391478561507;531.6384858296357;538.074511839636;543.703681493586;37202.62797372026;43637.12725745487;43826;43857.78071109644;43859.78070109645;45131.32303015449;45608.54391456085;45648.543514564844;45674.31488527666;45784.54215457844;9185.448873067604;9370;9641.56612952417;9824.263180261472;9862.45756483393;11919.702007449796;12194.756104877897;12211.877881221208;12355.938220308932;12382.93808530956;12032.639020829389;12157.635270941842;12227.69430764229;12229.63311100664;12243.93878030613;11137.888621113767;11609.071274298036;12116.697082572962;12206.511739530408;12243.632691019284;12111.273323600612;12170.208936419105;12260.203086799418;12264.938675306566;12296.385180741017;42850.35724464135;43013;43014.78492607548;43282.56717432817;43483.78258108721;4092.6852335797366;4101.405296232059;4146;4165.58344165584;4175.123224122938;31055.689443105504;31509.369812603847;31970;33006.50490242648;33056.00831975044;3377.1219482934366;3422.828858557087;3435.553378060836;3564.803935783531;3577.3918433866047;466666.6666666679;469937.99999999657;498893;511780;512655;65437.69124617481;65800;66308;66348.6682566584;66413.33586664168;68811;69064;69369;69390;69529.65235173792;17192.570185745455;17825.46523604294;17847.46457606274;17871.73192402115;17875.553111172325;12427;12683.556075537497;13334.933325333312;13516.053876228569;13630.659233519242;17548.82451175472;17579.82420175782;17627.118644067745;17638;17639.73540396895;7792.181820908836;7834.960825195838;7870.331021863091;7883.763487095396;7910.446268761131;886.8182022685334;888.1340692824574;891.9598618062203;912.2656261709387;913.7487191022522;31285.27930963773;31914.042578722674;31967.20081997922;32440;32549.837250813594;998.1914649134225;1067.7330667333167;1071.464267866067;1124.1063354633086;1212.605903081502;13197.73604527904;16409.261583228785;16426.42507512231;16646.66706665884;16791.244394002057;580300;593029;599301;601229;603023;61420.69289653612;63188.368116319165;63237;63532.68233658894;63612.68193659002;1455.548779878241;1455.9708805823825;1486.8290146633276;1490.1804007795886;1500.444835410905;28409.147725568266;28769.85615071995;30065.248368790955;31130.533042003935;31356;52853.20720189124;53037.93924121419;53504.732476338926;53563.46436535741;53633.46366536285;519611;522396;523103;523485;525642;26149.215523534323;26783.330416739736;26834.060807871632;27620.447591047665;42718.78640606777;44917;45742.08515829731;46594.534054659016;46727;47240.7637961808;36786.63213367939;37408.43887341637;37433.94113323793;37454.438183426726;38348.424773628976;37520.81239593784;37633.435498467;37735.81132094322;38663.03342416462;39734.80132599318;36520.086997824204;37337.62662373341;37506.62493375031;37640.81179594194;37721.81139094287;28268.151955441368;31279;33400.33199335951;34005.48991765076;34250.48624270588;19308.227670893008;23216;24597.38506537351;25149;25432.634417697107 09:03:23 INFO - PROCESS | 1638 | |2;jquery.html;400.0379088292655;404.6418919256457;410.60581841432224;413.6318676378023;432.7857710433336;18436.26254949804;20322.491937701565;20506.179752809887;21674.133034678634;21774.89112554437;18243.72634410485;18427.539311517186;21837.454063648416;22187.889060554717;22380.664290035664;5624.521915637166;6116.204893363856;6243.469305109071;6249.687515624223;6258.280297765747;6007.249093863267;6045.516358691294;6100.827569663772;6107.419795119479;6228.937710622904;5980.491658209035;6064.878702425971;6079.665618390986;6082.817515474543;6085.847853803646;5728.598998070137;6035.698215089228;6082.7530356277075;6083.239595050619;6112.6638034908065;5393.460653934575;5396.298481197458;5400.891982160335;5402.837914862561;5404.567634589223;26023.479530409477;26874.5968810468;27068.052618158268;27081.45837083267;27349.042783502682;2741.232454912628;2746.8489233092373;2764.557670772687;2766.1701489553257;2780.33272014715;14150.858491415058;16122.919385403115;16271.674566508722;16276.43032493857;16920.661586768194;1927.9228830846748;1934.419674097765;1940.6312800567846;1960.5196726802026;1969.1138987455702;128057.35971320177;128291.35854320762;128820;131298.34350828186;132136.01795973163;11681.591144309908;11765;11957.880421195676;11971.281723096643;12100.939495302644;15190.240487975558;15209.847901520843;15225.695486090437;15237;15318;8755.299576033902;9100.81798364029;9102.635894564275;9158.40470369424;9210.401323913924;7497.400207983348;9015;9023.843894668751;9084.772880678036;9192.448453092837;6087.391260873966;7545.50954187976;7559.811004724816;7619.047619047619;7621.618919054025;2009.4775358406919;2033.4916270932267;2040.1533363653916;2048.218050296252;2053.1992522916075;905.9174286727298;917.8944421391492;1043.0351924469835;1060.8408738689104;1087.5323610847254;20250.69623955642;22712.205072822704;22812.543749125256;22814.543709125726;22910.969006394753;1102.5148934468807;1113.1929351220463;1129.7796929598649;1146.4095990564865;1153.5039932828963;11598.362090084958;11951.16341856079;11964.693364770383;12055.698607534881;12063.638090857288;595479;690466;764310;783973.080134607;784879;106446.46776766067;157264;159722.40277597075;160127;167269.16365418094;1121.8653761548667;1136.9602063927723;1171.5606647507184;1172.3962159487871;1178.7171078940992;19000.52498687544;19831.107600158317;20681.379558613266;20729.585408292052;20798.688019679415;55329.1700624499;60913.390866092544;61292.69353653203;61852.69073654603;62377;88631.11368886229;91831;92313.15373692354;92475.22574322781;92563.53718231365;8397.916020839713;8468.068512463748;8821.161989610977;8842.557872106498;8845.734627961172;11331.886681133414;12046.277223366626;13248.271345076113;13685.31573421345;13940.163590184216;11556.306621602731;11583.710407239887;11692.70768230801;11917.52329906794;11968.521259149884;11428.82856757166;11522.884771152516;11554.364509952033;11778.823317650413;11935.701607459883;11589.768204635691;11757.706057348634;11903.642890713292;11952.64142075739;12106;7549.1695913448375;7864.646090925808;8194.09864914847;8393.95803020981;8807.943046834422;11035.779284414428;11538.192326537397;11585.189036767346;11811.468483918416;11857.525698971944 09:03:23 INFO - PROCESS | 1638 | |3;mootools.html;1300.8113823495594;1339.7119619281855;1420.728448039005;1436.4541474239788;1451.2816156002773;7692.692292308301;7908;8178.100408955018;8614.870776938344;8620.439671421353;7147.535410198347;8136.145704700995;8275.875861862078;8439.873401898976;8492.150784921505;2390.259019703893;2418.5283869645427;2425.1875621666672;2430.4045508850268;2438.0979037755965;2159.676048592708;2247.6291411917014;2406.302172370011;2448.081969261527;2460.323411061954;2338.286822519131;2397.8081753459683;2421.624648179535;2432;2437.744036876129;1869.7662792150982;2401.2556107606697;2412.4210189554556;2432.0514999150346;2432.793212576933;2382;2394.31761947844;2433.7931275841397;2450.987745061281;2461.3477428481465;8541;8576;8576.571171441403;8578.742637720878;8584.184502472255;5254.842354729364;5386.380566234855;5397.649152805055;5399;5408.107662235727;3401.914952126192;3599.9280014399565;3672.8714494992546;3726.8695595654294;3729.366007778684;1096.7093720164162;1234.21010553246;1291.6189724031433;1323.1465704620562;1328.468612554976;8977.057408972028;10514.053735163872;10644.0420362167;10851.891481085167;10868.891311086867;3735.364987952055;4617.699849509771;4674.018456124252;4678;4678.602318802872;8805.515696636747;8814.074522175208;8828.911710882938;8829.249513791272;8838.602262898185;5310.150375939908;5310.522053015247;5317.574594032468;5331.280277162611;5334.733263336818;4602.723836569817;4996.850094497171;5025.020121076428;5026.07017701719;5048.722320272347;3725.2363265530503;3866.342721737255;3871.3031654302495;3902.35611124167;3938.724289299779;1014.457265363042;1143.0512674480162;1159.588346137126;1190.470240742861;1198.9400529973466;725.1298441869777;826.8304997475503;886.423824514071;897.4794619120894;917.2524392619994;4670.089332580114;5385.111456609616;5469.835904922858;5941.138534912414;6145.539084568675;958.520739630185;979.9510024498746;1026.2713473433946;1026.6406757634768;1085.1210519479248;3680.1535646801385;3898.181381909774;3905.628965248297;3984.7011474139554;4012.719109662354;9956.253281003954;10237.23220758446;10263.7434064149;10269.948650256702;10273.589056437659;10163.7459063524;10175.796484070277;10191.592336306465;10192.592296308212;10217.948910255402;7854.764357069297;7908.841823163504;7991.120976692559;8121.4721043132;8122.268995790408;5641.323041235078;5655.773769049192;5693.715314234355;5795.478406943395;5828.795992140254;9143.63425462974;9219.26245900313;9291.67479138227;9322.673706420243;9422.246220302497;9504.95247523758;9505.762355941157;9518.23854091657;9607.855882161914;9648.951755241178;6818.454523638196;6847.144106986627;6877.1747390313485;6940.861182776215;6973.442124629916;7940.444168908121;7948.205179482005;8018.759437216893;8046.436749427484;8055.919440805517;7101.147862256562;7204.9639751800905;7208.675609597685;7229.457790665616;7334.743283985035;6568.967155164385;7133.500654954311;7168.856622867409;7173.426125910019;7177.712891484491;6432.839179020562;6440.098386225838;6440.967795160994;6507.934920650733;6533.346665333619;5045.747712614281;5068.873278168076;5074.543291103819;5108.693478391308;5142.485751424828;4980;4994.1509943309;5003.224500202475;5087.847364579068;5250.264962905273 09:03:23 INFO - PROCESS | 1638 | |4;prototype.html;367.5515870637821;370.8794641741434;377.3528398796064;378.50037949906135;379.8708439130697;3943.447917291578;4507.504174540798;4605.838795642153;5149.279100925864;5439;4526.4794548626915;5293.417724050351;5298.311219541454;5492.203630473579;5502.780873189179;4072.7759973201464;4347.804348804296;4369.235383807837;4376.2997920332755;4392.494863090754;3324.6535153262967;3516;3716.3867961786273;4239.6893692937265;4274.636655884254;4128.770463949464;4196.958030419687;4202.327627579588;4222.234436444134;4233.555476674951;3627.132027006094;3725.4039353703547;4260.190563792885;4272.333789821972;4272.33778764292;3371.477420999749;3548.6451354864566;3604.945925811115;3650.945235821465;3651.6348365163535;5370.43610420904;5380.219868119101;5415.562996651483;5445.972770136124;5488.890222195534;4046.271671099201;4818.518148185189;4862.82980095695;4892.094962431962;4907.239377896396;4948.653594248404;5095.878906640533;5414.648047876875;5747.166660834156;5928.555358348098;3707.7589956652732;3784.1674831537016;3954.960450395488;3962.7820469874146;3963.5441924178513;6069.514438844882;6080.908786368208;6089.969550152266;6096.603720758137;6127.325994140686;3444.776089554171;3798.4112462568346;3900.31744444721;3951.0152733276736;4046.0491784430637;4079.755214687069;4102.528209255914;4108.794560271975;4121.567235440236;4144.52337981136;4412.536683648171;4816.638752093607;4822.927656085161;4833.275008748716;4858.562729354375;5377.381601115843;5426.484483974016;5856.707164641751;5910.8817823643285;5956.255468066492;1182.645206438065;1202.9578964736368;1284.2598279331557;1315.7960516120017;1321.8281623388898;765.9119201291811;804.9034115906129;865.9653613855376;901.4726385064679;909.5815924674723;4862.82980095695;5081.644284900095;5254.894902101936;5272.6309158358545;5281.989266118276;4881.169561414469;5553;5653.558342622605;5724.940885936116;5729.82266738645;4365.825366985348;4726.393026370982;4892.902141957141;5188.766505507186;5231.424543300234;2436.780689737932;2667.1065193160507;2690.842937536878;2793.52510073288;3001.2346851552716;5651.321841379061;5729.7367539821535;5988.221531200916;6058.394160583906;6173.96913015441;5083.7853950798035;5153.613478989091;5835.374081663269;5863.309352517968;5874.471297583153;5043.672161309503;5258.631895767259;5291.682499050069;5346.919796202982;5547.916781248285;4896.853094407174;5084;5475.397706252229;5557.138643510262;5811.2106189028655;4985.6510044298;5968.612040217372;6071.757129714939;6078.908816367846;6106.2061931948565;6305.896468118152;6344.3987964936005;6357.728454309065;6360.204974378203;6392.520560957853;3568.8572457101427;3839.040239940015;3840.4815349927403;4056.513218413809;4141.316682747313;5450.345958485007;5700.857478563069;5826.388229236039;5975.880482390415;6046.879062418639;4954.9752251238515;5878.353381128158;5888.4700376966275;5900.911486327795;5962.750301085832;4006.8780741392457;4473.194824931414;5542.695151766691;5852.61054209343;5957.314908785372;4897.608191344755;5422.674639521641;5484.601426636183;5687.146927960839;5807.448292412214;5219.6689844089515;5450.373207081237;5942;5955.868385006835;5982.756466325128;5545.584081194007;5802.332731735735;5870.326956817286;5873.236479257669;5895.852603684943 09:03:23 INFO - PROCESS | 1638 | |5;yui.html;702.6521871673523;712.957222566646;762.8512440074188;814;881.8941726992759;14639.828813694905;15412.15233162177;15624.2969066392;16115.758263626056;16155;13554.728905421885;15061.096334219954;15090.320935557898;15145.545633630982;15941.760873586905;861;870.3254977392523;899.6266549381999;902.1204325782376;904.6381447421052;716.3194964783451;732.8607564562743;756.3419824752445;889.0931250124869;889.9466032038066;934.5700977550335;947.7062110745657;949.6296444386695;949.7673070097836;949.8432758594823;782.7847341980944;938.7090002099337;939;943.2736792669674;944.1361154543584;690.5235387582552;692.3146085375492;695.5270416117038;696.0568429777679;696.989545156823;15412;16295;16328.510144695678;16485.423010194587;16929.83070169295;14697.55907322782;14749.262536873113;14793.556193314218;14794.186319752409;14819.851801481955;10025;11929.284242945365;11977.880221197851;12039.819402708967;12567.622971310875;3735.925281494382;3775;3979.641832235113;3989.4813674222164;3997.060690737703;96691;99900;122909;125413;129218.3539082308;10856.185786066077;10859.945700271448;11160.665180044612;11456.083513318914;11618;23545.529089418116;23740.64389034166;23771.643425348633;23801.880990595284;23820.880895595757;8077.717279895293;8120.269175774209;8147.877781833277;8289.41974061822;8365.205305495969;3808.352580061396;3861.806909654506;4235.478713367821;4308.418363520885;4388.1223755249075;3732;3828.272628200633;3838.7504812187117;3839.424086387064;3842.9615703842605;687.3641881259815;693.0885885061128;710.705057401183;717.2253965717013;721.6391804097951;580.6603137164806;630.0989584893555;636.0237036149581;683.4634811672813;686.5091459606406;7615.6953721850505;10236.48817559119;10433.686989390331;10784.40685762275;11066.944665276622;581.9185314056035;590.6338070396382;591.3997292747779;599.658194828952;720.0603212807232;4953.777080031407;5178;5212.4266330703595;5214.7914083436235;5226.634135610547;85797.57101214454;97809;99147;99647;100900.49549752205;69545.65227173832;80454;89244.6613300801;92778.53610731903;93371;64845.351546484875;72615;73103;75173.62413188009;76597.6170119157;7755.146933837161;8858.883583072504;9993.400395976265;10018.348807327791;10065.547050382604;34573;38462.6153738459;52541;52812;53424;56794;68286;68682;69388;72670;9896.703098907044;11877.524899003944;12042;12069.698257543632;12325.322107283826;10559.947200263949;13223.272720000108;13481.932590336986;13848.446062157804;13996.790048149489;10918.01737843598;12133.090018248844;12420.192687475284;12708.555200567936;12847.678808029874;11887.702807429538;12249.448774804978;12390.256584604953;12532.812007819708;12654.87345126537;11910.464029118537;12252.571160009356;12277.324747138993;12548.749025019632;12554.623361299176;9976.700698979042;10411;10497.580096796042;10644.8935510647;10651;8774.912250877409;9358.719238422858;10353.689389318333;10359.533820977924;10367.896321036693 09:03:23 INFO - PROCESS | 1638 | __end_tp_report 09:03:23 INFO - PROCESS | 1638 | __start_cc_report 09:03:23 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,380 09:03:23 INFO - PROCESS | 1638 | __end_cc_report 09:03:23 INFO - PROCESS | 1638 | __startTimestamp1490025803291__endTimestamp 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:03:23 INFO - PROCESS | 1638 | Number of tests: 6 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5945.09 Median:6211.69 stddev:698.50 (11.2%) stddev-sans-first:675.41 09:03:23 INFO - PROCESS | 1638 | Values: 3573.4 3593.4 3614.7 3623.7 3625.3 6029.2 6045.5 6054.8 6070.4 6099.0 6009.7 6030.8 6067.8 6071.5 6086.8 5451.9 5514.2 5524.0 5539.6 5587.4 5565.3 5573.6 5576.8 5591.5 5619.6 4354.4 5392.6 5569.7 5577.2 5577.9 4373.3 5523.6 5528.8 5534.5 5535.5 5328.3 5440.6 5445.5 5459.9 5481.4 6211.7 6221.5 6232.2 6252.6 6254.6 5906.0 5969.9 6384.8 6444.9 6446.7 6310.0 6350.8 6434.5 6557.6 6611.5 6139.8 6268.0 6272.8 6278.6 6288.1 6641.4 6668.9 6714.1 6722.4 6725.4 5262.0 6177.6 6179.9 6281.4 6382.6 6382.1 6392.6 6396.3 6491.4 6503.1 6398.7 6402.5 6403.3 6405.0 6426.6 4535.2 6185.7 6381.5 6413.6 6446.1 5151.3 5179.8 5188.6 5215.6 5271.4 3655.7 3909.7 4621.6 4642.4 4670.8 6196.2 6199.9 6209.0 6214.1 6216.8 6267.7 6280.0 6355.0 6361.1 6367.5 5964.7 6125.6 6125.8 6141.5 6143.4 5608.9 5655.3 5694.0 5704.9 5710.8 5461.5 6608.2 6674.8 6729.6 6739.1 6482.4 6501.8 6513.5 6516.6 6528.7 6347.2 6469.6 6680.6 6711.4 6757.1 6254.8 6292.8 6295.8 6324.4 6423.3 6444.3 6497.2 6504.1 6533.6 6542.2 6300.0 6308.4 6323.6 6336.3 6342.3 6172.9 6204.2 6213.2 6220.2 6305.0 5821.9 5865.0 6226.4 6246.2 6256.8 5170.7 6254.7 6270.9 6285.4 6335.0 5020.7 5063.9 6151.4 6168.4 6174.3 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:68724.12 Median:37513.72 stddev:143362.04 (382.2%) stddev-sans-first:143672.53 09:03:23 INFO - PROCESS | 1638 | Values: 439.4 468.6 531.6 538.1 543.7 37202.6 43637.1 43826.0 43857.8 43859.8 45131.3 45608.5 45648.5 45674.3 45784.5 9185.4 9370.0 9641.6 9824.3 9862.5 11919.7 12194.8 12211.9 12355.9 12382.9 12032.6 12157.6 12227.7 12229.6 12243.9 11137.9 11609.1 12116.7 12206.5 12243.6 12111.3 12170.2 12260.2 12264.9 12296.4 42850.4 43013.0 43014.8 43282.6 43483.8 4092.7 4101.4 4146.0 4165.6 4175.1 31055.7 31509.4 31970.0 33006.5 33056.0 3377.1 3422.8 3435.6 3564.8 3577.4 466666.7 469938.0 498893.0 511780.0 512655.0 65437.7 65800.0 66308.0 66348.7 66413.3 68811.0 69064.0 69369.0 69390.0 69529.7 17192.6 17825.5 17847.5 17871.7 17875.6 12427.0 12683.6 13334.9 13516.1 13630.7 17548.8 17579.8 17627.1 17638.0 17639.7 7792.2 7835.0 7870.3 7883.8 7910.4 886.8 888.1 892.0 912.3 913.7 31285.3 31914.0 31967.2 32440.0 32549.8 998.2 1067.7 1071.5 1124.1 1212.6 13197.7 16409.3 16426.4 16646.7 16791.2 580300.0 593029.0 599301.0 601229.0 603023.0 61420.7 63188.4 63237.0 63532.7 63612.7 1455.5 1456.0 1486.8 1490.2 1500.4 28409.1 28769.9 30065.2 31130.5 31356.0 52853.2 53037.9 53504.7 53563.5 53633.5 519611.0 522396.0 523103.0 523485.0 525642.0 26149.2 26783.3 26834.1 27620.4 42718.8 44917.0 45742.1 46594.5 46727.0 47240.8 36786.6 37408.4 37433.9 37454.4 38348.4 37520.8 37633.4 37735.8 38663.0 39734.8 36520.1 37337.6 37506.6 37640.8 37721.8 28268.2 31279.0 33400.3 34005.5 34250.5 19308.2 23216.0 24597.4 25149.0 25432.6 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:40636.15 Median:21806.17 stddev:121237.43 (556.0%) stddev-sans-first:121539.88 09:03:23 INFO - PROCESS | 1638 | Values: 400.0 404.6 410.6 413.6 432.8 18436.3 20322.5 20506.2 21674.1 21774.9 18243.7 18427.5 21837.5 22187.9 22380.7 5624.5 6116.2 6243.5 6249.7 6258.3 6007.2 6045.5 6100.8 6107.4 6228.9 5980.5 6064.9 6079.7 6082.8 6085.8 5728.6 6035.7 6082.8 6083.2 6112.7 5393.5 5396.3 5400.9 5402.8 5404.6 26023.5 26874.6 27068.1 27081.5 27349.0 2741.2 2746.8 2764.6 2766.2 2780.3 14150.9 16122.9 16271.7 16276.4 16920.7 1927.9 1934.4 1940.6 1960.5 1969.1 128057.4 128291.4 128820.0 131298.3 132136.0 11681.6 11765.0 11957.9 11971.3 12100.9 15190.2 15209.8 15225.7 15237.0 15318.0 8755.3 9100.8 9102.6 9158.4 9210.4 7497.4 9015.0 9023.8 9084.8 9192.4 6087.4 7545.5 7559.8 7619.0 7621.6 2009.5 2033.5 2040.2 2048.2 2053.2 905.9 917.9 1043.0 1060.8 1087.5 20250.7 22712.2 22812.5 22814.5 22911.0 1102.5 1113.2 1129.8 1146.4 1153.5 11598.4 11951.2 11964.7 12055.7 12063.6 595479.0 690466.0 764310.0 783973.1 784879.0 106446.5 157264.0 159722.4 160127.0 167269.2 1121.9 1137.0 1171.6 1172.4 1178.7 19000.5 19831.1 20681.4 20729.6 20798.7 55329.2 60913.4 61292.7 61852.7 62377.0 88631.1 91831.0 92313.2 92475.2 92563.5 8397.9 8468.1 8821.2 8842.6 8845.7 11331.9 12046.3 13248.3 13685.3 13940.2 11556.3 11583.7 11692.7 11917.5 11968.5 11428.8 11522.9 11554.4 11778.8 11935.7 11589.8 11757.7 11903.6 11952.6 12106.0 7549.2 7864.6 8194.1 8394.0 8807.9 11035.8 11538.2 11585.2 11811.5 11857.5 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5482.21 Median:5196.38 stddev:2943.79 (56.7%) stddev-sans-first:2935.27 09:03:23 INFO - PROCESS | 1638 | Values: 1300.8 1339.7 1420.7 1436.5 1451.3 7692.7 7908.0 8178.1 8614.9 8620.4 7147.5 8136.1 8275.9 8439.9 8492.2 2390.3 2418.5 2425.2 2430.4 2438.1 2159.7 2247.6 2406.3 2448.1 2460.3 2338.3 2397.8 2421.6 2432.0 2437.7 1869.8 2401.3 2412.4 2432.1 2432.8 2382.0 2394.3 2433.8 2451.0 2461.3 8541.0 8576.0 8576.6 8578.7 8584.2 5254.8 5386.4 5397.6 5399.0 5408.1 3401.9 3599.9 3672.9 3726.9 3729.4 1096.7 1234.2 1291.6 1323.1 1328.5 8977.1 10514.1 10644.0 10851.9 10868.9 3735.4 4617.7 4674.0 4678.0 4678.6 8805.5 8814.1 8828.9 8829.2 8838.6 5310.2 5310.5 5317.6 5331.3 5334.7 4602.7 4996.9 5025.0 5026.1 5048.7 3725.2 3866.3 3871.3 3902.4 3938.7 1014.5 1143.1 1159.6 1190.5 1198.9 725.1 826.8 886.4 897.5 917.3 4670.1 5385.1 5469.8 5941.1 6145.5 958.5 980.0 1026.3 1026.6 1085.1 3680.2 3898.2 3905.6 3984.7 4012.7 9956.3 10237.2 10263.7 10269.9 10273.6 10163.7 10175.8 10191.6 10192.6 10217.9 7854.8 7908.8 7991.1 8121.5 8122.3 5641.3 5655.8 5693.7 5795.5 5828.8 9143.6 9219.3 9291.7 9322.7 9422.2 9505.0 9505.8 9518.2 9607.9 9649.0 6818.5 6847.1 6877.2 6940.9 6973.4 7940.4 7948.2 8018.8 8046.4 8055.9 7101.1 7205.0 7208.7 7229.5 7334.7 6569.0 7133.5 7168.9 7173.4 7177.7 6432.8 6440.1 6441.0 6507.9 6533.3 5045.7 5068.9 5074.5 5108.7 5142.5 4980.0 4994.2 5003.2 5087.8 5250.3 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4622.49 Median:5122.58 stddev:1445.55 (28.2%) stddev-sans-first:1413.89 09:03:23 INFO - PROCESS | 1638 | Values: 367.6 370.9 377.4 378.5 379.9 3943.4 4507.5 4605.8 5149.3 5439.0 4526.5 5293.4 5298.3 5492.2 5502.8 4072.8 4347.8 4369.2 4376.3 4392.5 3324.7 3516.0 3716.4 4239.7 4274.6 4128.8 4197.0 4202.3 4222.2 4233.6 3627.1 3725.4 4260.2 4272.3 4272.3 3371.5 3548.6 3604.9 3650.9 3651.6 5370.4 5380.2 5415.6 5446.0 5488.9 4046.3 4818.5 4862.8 4892.1 4907.2 4948.7 5095.9 5414.6 5747.2 5928.6 3707.8 3784.2 3955.0 3962.8 3963.5 6069.5 6080.9 6090.0 6096.6 6127.3 3444.8 3798.4 3900.3 3951.0 4046.0 4079.8 4102.5 4108.8 4121.6 4144.5 4412.5 4816.6 4822.9 4833.3 4858.6 5377.4 5426.5 5856.7 5910.9 5956.3 1182.6 1203.0 1284.3 1315.8 1321.8 765.9 804.9 866.0 901.5 909.6 4862.8 5081.6 5254.9 5272.6 5282.0 4881.2 5553.0 5653.6 5724.9 5729.8 4365.8 4726.4 4892.9 5188.8 5231.4 2436.8 2667.1 2690.8 2793.5 3001.2 5651.3 5729.7 5988.2 6058.4 6174.0 5083.8 5153.6 5835.4 5863.3 5874.5 5043.7 5258.6 5291.7 5346.9 5547.9 4896.9 5084.0 5475.4 5557.1 5811.2 4985.7 5968.6 6071.8 6078.9 6106.2 6305.9 6344.4 6357.7 6360.2 6392.5 3568.9 3839.0 3840.5 4056.5 4141.3 5450.3 5700.9 5826.4 5975.9 6046.9 4955.0 5878.4 5888.5 5900.9 5962.8 4006.9 4473.2 5542.7 5852.6 5957.3 4897.6 5422.7 5484.6 5687.1 5807.4 5219.7 5450.4 5942.0 5955.9 5982.8 5545.6 5802.3 5870.3 5873.2 5895.9 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20114.82 Median:4271.95 stddev:29238.84 (684.4%) stddev-sans-first:29284.53 09:03:23 INFO - PROCESS | 1638 | Values: 702.7 713.0 762.9 814.0 881.9 14639.8 15412.2 15624.3 16115.8 16155.0 13554.7 15061.1 15090.3 15145.5 15941.8 861.0 870.3 899.6 902.1 904.6 716.3 732.9 756.3 889.1 889.9 934.6 947.7 949.6 949.8 949.8 782.8 938.7 939.0 943.3 944.1 690.5 692.3 695.5 696.1 697.0 15412.0 16295.0 16328.5 16485.4 16929.8 14697.6 14749.3 14793.6 14794.2 14819.9 10025.0 11929.3 11977.9 12039.8 12567.6 3735.9 3775.0 3979.6 3989.5 3997.1 96691.0 99900.0 122909.0 125413.0 129218.4 10856.2 10859.9 11160.7 11456.1 11618.0 23545.5 23740.6 23771.6 23801.9 23820.9 8077.7 8120.3 8147.9 8289.4 8365.2 3808.4 3861.8 4235.5 4308.4 4388.1 3732.0 3828.3 3838.8 3839.4 3843.0 687.4 693.1 710.7 717.2 721.6 580.7 630.1 636.0 683.5 686.5 7615.7 10236.5 10433.7 10784.4 11066.9 581.9 590.6 591.4 599.7 720.1 4953.8 5178.0 5212.4 5214.8 5226.6 85797.6 97809.0 99147.0 99647.0 100900.5 69545.7 80454.0 89244.7 92778.5 93371.0 64845.4 72615.0 73103.0 75173.6 76597.6 7755.1 8858.9 9993.4 10018.3 10065.5 34573.0 38462.6 52541.0 52812.0 53424.0 56794.0 68286.0 68682.0 69388.0 72670.0 9896.7 11877.5 12042.0 12069.7 12325.3 10559.9 13223.3 13481.9 13848.4 13996.8 10918.0 12133.1 12420.2 12708.6 12847.7 11887.7 12249.4 12390.3 12532.8 12654.9 11910.5 12252.6 12277.3 12548.7 12554.6 9976.7 10411.0 10497.6 10644.9 10651.0 8774.9 9358.7 10353.7 10359.5 10367.9 09:03:23 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:03:23 INFO - PROCESS | 1638 | 09:03:23 INFO - TEST-INFO | 1638: exit 0 09:03:23 INFO - TEST-OK | dromaeo_css | took 1114608ms 09:03:23 INFO - TEST-START | kraken 09:03:23 INFO - operating with platform_type : mac_ 09:03:23 INFO - Initialising browser for kraken test... 09:03:24 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/tmpDq66Wm/profile http://localhost:49240/getInfo.html) 09:03:26 INFO - PROCESS | 1649 | 2017-03-20 09:03:26.690 plugin-container[1651:9856] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 09:03:26 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:26 INFO - PROCESS | 1649 | 2017-03-20 09:03:26.691 plugin-container[1651:9856] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 09:03:26 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:26 INFO - PROCESS | 1649 | 2017-03-20 09:03:26.691 plugin-container[1651:9856] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:26 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:03:26 INFO - PROCESS | 1649 | colorDepth:24 09:03:26 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:03:26 INFO - PROCESS | 1649 | __metrics 09:03:26 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:03:27 INFO - TEST-INFO | 1649: exit 0 09:03:27 INFO - Browser initialized. 09:03:27 INFO - Running cycle 1/1 for kraken test... 09:03:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/T/tmpDq66Wm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:29 INFO - PROCESS | 1652 | 2017-03-20 09:03:29.697 plugin-container[1653:9983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 09:03:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:29 INFO - PROCESS | 1652 | 2017-03-20 09:03:29.699 plugin-container[1653:9983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860b, name = 'com.apple.CFPasteboardClient' 09:03:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:29 INFO - PROCESS | 1652 | 2017-03-20 09:03:29.699 plugin-container[1653:9983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:39 INFO - PROCESS | 1652 | RSS: Main: 172883968 09:03:39 INFO - PROCESS | 1652 | 09:03:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:03:43 INFO - PROCESS | 1652 | RSS: Main: 185569280 09:03:43 INFO - PROCESS | 1652 | 09:03:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:03:46 INFO - PROCESS | 1652 | RSS: Main: 193630208 09:03:46 INFO - PROCESS | 1652 | 09:03:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:03:50 INFO - PROCESS | 1652 | RSS: Main: 197378048 09:03:50 INFO - PROCESS | 1652 | 09:03:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:03:53 INFO - PROCESS | 1652 | RSS: Main: 198733824 09:03:53 INFO - PROCESS | 1652 | 09:03:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:03:55 INFO - PROCESS | 1652 | RSS: Main: 199110656 09:03:55 INFO - PROCESS | 1652 | 09:03:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:59 INFO - PROCESS | 1652 | RSS: Main: 203190272 09:03:59 INFO - PROCESS | 1652 | 09:04:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:02 INFO - PROCESS | 1652 | RSS: Main: 202956800 09:04:02 INFO - PROCESS | 1652 | 09:04:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:04:06 INFO - PROCESS | 1652 | RSS: Main: 202555392 09:04:06 INFO - PROCESS | 1652 | 09:04:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:08 INFO - PROCESS | 1652 | RSS: Main: 201560064 09:04:08 INFO - PROCESS | 1652 | 09:04:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:11 INFO - PROCESS | 1652 | RSS: Main: 201785344 09:04:11 INFO - PROCESS | 1652 | 09:04:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:14 INFO - PROCESS | 1652 | RSS: Main: 201715712 09:04:14 INFO - PROCESS | 1652 | 09:04:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:17 INFO - PROCESS | 1652 | RSS: Main: 201637888 09:04:17 INFO - PROCESS | 1652 | 09:04:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:21 INFO - PROCESS | 1652 | RSS: Main: 202481664 09:04:21 INFO - PROCESS | 1652 | 09:04:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:04:23 INFO - PROCESS | 1652 | RSS: Main: 203763712 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | __start_tp_report 09:04:23 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:04:23 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:04:23 INFO - PROCESS | 1652 | |i|pagename|runs| 09:04:23 INFO - PROCESS | 1652 | |0;ai-astar;107;118;118;118;117;117;121;116;143;175 09:04:23 INFO - PROCESS | 1652 | |1;audio-beat-detection;140;112;106;143;122;145;138;109;124;109 09:04:23 INFO - PROCESS | 1652 | |2;audio-dft;201;198;199;197;197;199;197;194;194;227 09:04:23 INFO - PROCESS | 1652 | |3;audio-fft;80;58;59;84;60;66;71;82;62;72 09:04:23 INFO - PROCESS | 1652 | |4;audio-oscillator;88;72;85;72;68;68;82;69;70;82 09:04:23 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;91;89;93;90;93;87;93;89;135;123 09:04:23 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;98;97;97;99;96;96;97;111;100 09:04:23 INFO - PROCESS | 1652 | |7;imaging-desaturate;94;94;95;143;151;94;97;111;145;114 09:04:23 INFO - PROCESS | 1652 | |8;json-parse-financial;58;57;60;61;61;61;63;62;62;61 09:04:23 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;67;73;70;79;75;68;100;102;88;71 09:04:23 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;82;82;85;83;84;85;85;96;86;114 09:04:23 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;145;154;140;139;142;143;147;159;141;137 09:04:23 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;170;166;167;164;169;165;166;168;164;162 09:04:23 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;66;66;73;85;93;84;85;87;89 09:04:23 INFO - PROCESS | 1652 | __end_tp_report 09:04:23 INFO - PROCESS | 1652 | __start_cc_report 09:04:23 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,924 09:04:23 INFO - PROCESS | 1652 | __end_cc_report 09:04:23 INFO - PROCESS | 1652 | __startTimestamp1490025863126__endTimestamp 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:04:23 INFO - PROCESS | 1652 | Number of tests: 14 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:125.00 Median:118.00 stddev:19.78 (16.8%) stddev-sans-first:19.87 09:04:23 INFO - PROCESS | 1652 | Values: 107.0 118.0 118.0 118.0 117.0 117.0 121.0 116.0 143.0 175.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:131.00 stddev:15.53 (11.9%) stddev-sans-first:15.46 09:04:23 INFO - PROCESS | 1652 | Values: 140.0 112.0 106.0 143.0 122.0 145.0 138.0 109.0 124.0 109.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:200.30 Median:198.50 stddev:9.63 (4.8%) stddev-sans-first:10.21 09:04:23 INFO - PROCESS | 1652 | Values: 201.0 198.0 199.0 197.0 197.0 199.0 197.0 194.0 194.0 227.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.40 Median:71.50 stddev:9.92 (13.9%) stddev-sans-first:9.76 09:04:23 INFO - PROCESS | 1652 | Values: 80.0 58.0 59.0 84.0 60.0 66.0 71.0 82.0 62.0 72.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:77.00 stddev:7.75 (10.1%) stddev-sans-first:6.80 09:04:23 INFO - PROCESS | 1652 | Values: 88.0 72.0 85.0 72.0 68.0 68.0 82.0 69.0 70.0 82.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:90.50 stddev:16.55 (18.3%) stddev-sans-first:17.34 09:04:23 INFO - PROCESS | 1652 | Values: 91.0 89.0 93.0 90.0 93.0 87.0 93.0 89.0 135.0 123.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:97.00 stddev:4.47 (4.6%) stddev-sans-first:4.69 09:04:23 INFO - PROCESS | 1652 | Values: 97.0 98.0 97.0 97.0 99.0 96.0 96.0 97.0 111.0 100.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:113.80 Median:94.00 stddev:23.64 (25.1%) stddev-sans-first:23.96 09:04:23 INFO - PROCESS | 1652 | Values: 94.0 94.0 95.0 143.0 151.0 94.0 97.0 111.0 145.0 114.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:61.00 stddev:1.84 (3.0%) stddev-sans-first:1.69 09:04:23 INFO - PROCESS | 1652 | Values: 58.0 57.0 60.0 61.0 61.0 61.0 63.0 62.0 62.0 61.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:72.00 stddev:12.96 (18.0%) stddev-sans-first:12.96 09:04:23 INFO - PROCESS | 1652 | Values: 67.0 73.0 70.0 79.0 75.0 68.0 100.0 102.0 88.0 71.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:85.00 stddev:9.91 (11.7%) stddev-sans-first:10.25 09:04:23 INFO - PROCESS | 1652 | Values: 82.0 82.0 85.0 83.0 84.0 85.0 85.0 96.0 86.0 114.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:144.00 stddev:6.95 (4.8%) stddev-sans-first:7.37 09:04:23 INFO - PROCESS | 1652 | Values: 145.0 154.0 140.0 139.0 142.0 143.0 147.0 159.0 141.0 137.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:2.47 (1.5%) stddev-sans-first:2.18 09:04:23 INFO - PROCESS | 1652 | Values: 170.0 166.0 167.0 164.0 169.0 165.0 166.0 168.0 164.0 162.0 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.30 Median:85.00 stddev:10.68 (12.6%) stddev-sans-first:9.99 09:04:23 INFO - PROCESS | 1652 | Values: 65.0 66.0 66.0 73.0 85.0 93.0 84.0 85.0 87.0 89.0 09:04:23 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:04:23 INFO - PROCESS | 1652 | 09:04:23 INFO - TEST-INFO | 1652: exit 0 09:04:23 INFO - TEST-OK | kraken | took 59948ms 09:04:23 INFO - SUITE-END | took 1174s 09:04:24 INFO - Completed test suite (00:19:34) 09:04:24 INFO - javascript benchmark 09:04:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7518.483916340332, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3573.4103872860974, 3593.371160046991, 3614.7108231341495, 3623.6919861811743, 3625.256822351418, 6029.246344206974, 6045.516358691305, 6054.788082417116, 6070.36261192574, 6098.969505152468, 6009.729562169702, 6030.758769649209, 6067.817965461032, 6071.544634152434, 6086.817395478131, 5451.863703407407, 5514.172874068901, 5524.0, 5539.639923404966, 5587.38538760736, 5565.332160140789, 5573.554115670757, 5576.804811831586, 5591.496765291123, 5619.550435965133, 4354.412154359168, 5392.595555333346, 5569.693666848321, 5577.163425486189, 5577.944220557783, 4373.256546387106, 5523.558115350762, 5528.806491772789, 5534.4742249486635, 5535.501804837584, 5328.254044433782, 5440.591955603306, 5445.455454454563, 5459.945400545984, 5481.397046324901, 6211.689415529206, 6221.5333849961435, 6232.220972378453, 6252.562320637558, 6254.593451425642, 5905.995980683251, 5969.8806023879715, 6384.776532821375, 6444.903326450107, 6446.709898054599, 6310.0, 6350.841228969267, 6434.517411194132, 6557.606543607399, 6611.504137189682, 6139.798803208747, 6268.0, 6272.780452684134, 6278.560500764994, 6288.088227207029, 6641.369069938348, 6668.899966500507, 6714.127163468718, 6722.428593569503, 6725.361090696376, 5261.973690131601, 6177.567570270038, 6179.938200618026, 6281.403266689657, 6382.617042977344, 6382.106505089291, 6392.648404337806, 6396.264429590564, 6491.383318584727, 6503.057056726748, 6398.680065996681, 6402.519811014193, 6403.263624683128, 6405.0, 6426.582272152388, 4535.160995215896, 6185.670080932621, 6381.4575761059905, 6413.615183088937, 6446.129772480749, 5151.330327057533, 5179.7928082877015, 5188.63679542436, 5215.634905556574, 5271.446498117719, 3655.6892664123316, 3909.667678247381, 4621.560951709582, 4642.442906851132, 4670.766461676971, 6196.1635179251125, 6199.907001394983, 6209.006558950637, 6214.098955651496, 6216.813495595139, 6267.749290028439, 6280.0, 6355.014972679242, 6361.14124593174, 6367.522435817332, 5964.7315870785915, 6125.601835880653, 6125.84685382869, 6141.508679305645, 6143.385661433821, 5608.9439105609235, 5655.264815573949, 5693.971530142406, 5704.942950570524, 5710.771569137188, 5461.481159289781, 6608.207015158212, 6674.83312917181, 6729.5625784323865, 6739.05653208561, 6482.416582507597, 6501.772437964649, 6513.511486638616, 6516.6415847128865, 6528.738850445929, 6347.174867267226, 6469.611823290617, 6680.63256520896, 6711.429528490133, 6757.054012438361, 6254.781082662085, 6292.779752708633, 6295.779647712491, 6324.3991820776955, 6423.325550817097, 6444.323346048597, 6497.18785151856, 6504.056911747675, 6533.607983521004, 6542.214934207926, 6299.96850015747, 6308.432241098323, 6323.620582765049, 6336.271328797063, 6342.270638876588, 6172.9074063889975, 6204.162438070803, 6213.2233470816145, 6220.191375121205, 6305.0, 5821.922944255326, 5864.970675146597, 6226.439620434183, 6246.187995560548, 6256.7810126645345, 5170.689758614495, 6254.655993920378, 6270.874582508415, 6285.43431091204, 6335.0, 5020.698758074527, 5063.949360506348, 6151.3541078186145, 6168.444839964424, 6174.25908890918], "value": 5903.406373260836, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.40460675784294, 468.6391478561507, 531.6384858296357, 538.074511839636, 543.703681493586, 37202.62797372026, 43637.12725745487, 43826.0, 43857.78071109644, 43859.78070109645, 45131.32303015449, 45608.54391456085, 45648.543514564844, 45674.31488527666, 45784.54215457844, 9185.448873067604, 9370.0, 9641.56612952417, 9824.263180261472, 9862.45756483393, 11919.702007449796, 12194.756104877897, 12211.877881221208, 12355.938220308932, 12382.93808530956, 12032.639020829389, 12157.635270941842, 12227.69430764229, 12229.63311100664, 12243.93878030613, 11137.888621113767, 11609.071274298036, 12116.697082572962, 12206.511739530408, 12243.632691019284, 12111.273323600612, 12170.208936419105, 12260.203086799418, 12264.938675306566, 12296.385180741017, 42850.35724464135, 43013.0, 43014.78492607548, 43282.56717432817, 43483.78258108721, 4092.6852335797366, 4101.405296232059, 4146.0, 4165.58344165584, 4175.123224122938, 31055.689443105504, 31509.369812603847, 31970.0, 33006.50490242648, 33056.00831975044, 3377.1219482934366, 3422.828858557087, 3435.553378060836, 3564.803935783531, 3577.3918433866047, 466666.6666666679, 469937.99999999657, 498893.0, 511780.0, 512655.0, 65437.69124617481, 65800.0, 66308.0, 66348.6682566584, 66413.33586664168, 68811.0, 69064.0, 69369.0, 69390.0, 69529.65235173792, 17192.570185745455, 17825.46523604294, 17847.46457606274, 17871.73192402115, 17875.553111172325, 12427.0, 12683.556075537497, 13334.933325333312, 13516.053876228569, 13630.659233519242, 17548.82451175472, 17579.82420175782, 17627.118644067745, 17638.0, 17639.73540396895, 7792.181820908836, 7834.960825195838, 7870.331021863091, 7883.763487095396, 7910.446268761131, 886.8182022685334, 888.1340692824574, 891.9598618062203, 912.2656261709387, 913.7487191022522, 31285.27930963773, 31914.042578722674, 31967.20081997922, 32440.0, 32549.837250813594, 998.1914649134225, 1067.7330667333167, 1071.464267866067, 1124.1063354633086, 1212.605903081502, 13197.73604527904, 16409.261583228785, 16426.42507512231, 16646.66706665884, 16791.244394002057, 580300.0, 593029.0, 599301.0, 601229.0, 603023.0, 61420.69289653612, 63188.368116319165, 63237.0, 63532.68233658894, 63612.68193659002, 1455.548779878241, 1455.9708805823825, 1486.8290146633276, 1490.1804007795886, 1500.444835410905, 28409.147725568266, 28769.85615071995, 30065.248368790955, 31130.533042003935, 31356.0, 52853.20720189124, 53037.93924121419, 53504.732476338926, 53563.46436535741, 53633.46366536285, 519611.0, 522396.0, 523103.0, 523485.0, 525642.0, 26149.215523534323, 26783.330416739736, 26834.060807871632, 27620.447591047665, 42718.78640606777, 44917.0, 45742.08515829731, 46594.534054659016, 46727.0, 47240.7637961808, 36786.63213367939, 37408.43887341637, 37433.94113323793, 37454.438183426726, 38348.424773628976, 37520.81239593784, 37633.435498467, 37735.81132094322, 38663.03342416462, 39734.80132599318, 36520.086997824204, 37337.62662373341, 37506.62493375031, 37640.81179594194, 37721.81139094287, 28268.151955441368, 31279.0, 33400.33199335951, 34005.48991765076, 34250.48624270588, 19308.227670893008, 23216.0, 24597.38506537351, 25149.0, 25432.634417697107], "value": 20975.77287083189, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.0379088292655, 404.6418919256457, 410.60581841432224, 413.6318676378023, 432.7857710433336, 18436.26254949804, 20322.491937701565, 20506.179752809887, 21674.133034678634, 21774.89112554437, 18243.72634410485, 18427.539311517186, 21837.454063648416, 22187.889060554717, 22380.664290035664, 5624.521915637166, 6116.204893363856, 6243.469305109071, 6249.687515624223, 6258.280297765747, 6007.249093863267, 6045.516358691294, 6100.827569663772, 6107.419795119479, 6228.937710622904, 5980.491658209035, 6064.878702425971, 6079.665618390986, 6082.817515474543, 6085.847853803646, 5728.598998070137, 6035.698215089228, 6082.7530356277075, 6083.239595050619, 6112.6638034908065, 5393.460653934575, 5396.298481197458, 5400.891982160335, 5402.837914862561, 5404.567634589223, 26023.479530409477, 26874.5968810468, 27068.052618158268, 27081.45837083267, 27349.042783502682, 2741.232454912628, 2746.8489233092373, 2764.557670772687, 2766.1701489553257, 2780.33272014715, 14150.858491415058, 16122.919385403115, 16271.674566508722, 16276.43032493857, 16920.661586768194, 1927.9228830846748, 1934.419674097765, 1940.6312800567846, 1960.5196726802026, 1969.1138987455702, 128057.35971320177, 128291.35854320762, 128820.0, 131298.34350828186, 132136.01795973163, 11681.591144309908, 11765.0, 11957.880421195676, 11971.281723096643, 12100.939495302644, 15190.240487975558, 15209.847901520843, 15225.695486090437, 15237.0, 15318.0, 8755.299576033902, 9100.81798364029, 9102.635894564275, 9158.40470369424, 9210.401323913924, 7497.400207983348, 9015.0, 9023.843894668751, 9084.772880678036, 9192.448453092837, 6087.391260873966, 7545.50954187976, 7559.811004724816, 7619.047619047619, 7621.618919054025, 2009.4775358406919, 2033.4916270932267, 2040.1533363653916, 2048.218050296252, 2053.1992522916075, 905.9174286727298, 917.8944421391492, 1043.0351924469835, 1060.8408738689104, 1087.5323610847254, 20250.69623955642, 22712.205072822704, 22812.543749125256, 22814.543709125726, 22910.969006394753, 1102.5148934468807, 1113.1929351220463, 1129.7796929598649, 1146.4095990564865, 1153.5039932828963, 11598.362090084958, 11951.16341856079, 11964.693364770383, 12055.698607534881, 12063.638090857288, 595479.0, 690466.0, 764310.0, 783973.080134607, 784879.0, 106446.46776766067, 157264.0, 159722.40277597075, 160127.0, 167269.16365418094, 1121.8653761548667, 1136.9602063927723, 1171.5606647507184, 1172.3962159487871, 1178.7171078940992, 19000.52498687544, 19831.107600158317, 20681.379558613266, 20729.585408292052, 20798.688019679415, 55329.1700624499, 60913.390866092544, 61292.69353653203, 61852.69073654603, 62377.0, 88631.11368886229, 91831.0, 92313.15373692354, 92475.22574322781, 92563.53718231365, 8397.916020839713, 8468.068512463748, 8821.161989610977, 8842.557872106498, 8845.734627961172, 11331.886681133414, 12046.277223366626, 13248.271345076113, 13685.31573421345, 13940.163590184216, 11556.306621602731, 11583.710407239887, 11692.70768230801, 11917.52329906794, 11968.521259149884, 11428.82856757166, 11522.884771152516, 11554.364509952033, 11778.823317650413, 11935.701607459883, 11589.768204635691, 11757.706057348634, 11903.642890713292, 11952.64142075739, 12106.0, 7549.1695913448375, 7864.646090925808, 8194.09864914847, 8393.95803020981, 8807.943046834422, 11035.779284414428, 11538.192326537397, 11585.189036767346, 11811.468483918416, 11857.525698971944], "value": 10506.053855423103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.8113823495594, 1339.7119619281855, 1420.728448039005, 1436.4541474239788, 1451.2816156002773, 7692.692292308301, 7908.0, 8178.100408955018, 8614.870776938344, 8620.439671421353, 7147.535410198347, 8136.145704700995, 8275.875861862078, 8439.873401898976, 8492.150784921505, 2390.259019703893, 2418.5283869645427, 2425.1875621666672, 2430.4045508850268, 2438.0979037755965, 2159.676048592708, 2247.6291411917014, 2406.302172370011, 2448.081969261527, 2460.323411061954, 2338.286822519131, 2397.8081753459683, 2421.624648179535, 2432.0, 2437.744036876129, 1869.7662792150982, 2401.2556107606697, 2412.4210189554556, 2432.0514999150346, 2432.793212576933, 2382.0, 2394.31761947844, 2433.7931275841397, 2450.987745061281, 2461.3477428481465, 8541.0, 8576.0, 8576.571171441403, 8578.742637720878, 8584.184502472255, 5254.842354729364, 5386.380566234855, 5397.649152805055, 5399.0, 5408.107662235727, 3401.914952126192, 3599.9280014399565, 3672.8714494992546, 3726.8695595654294, 3729.366007778684, 1096.7093720164162, 1234.21010553246, 1291.6189724031433, 1323.1465704620562, 1328.468612554976, 8977.057408972028, 10514.053735163872, 10644.0420362167, 10851.891481085167, 10868.891311086867, 3735.364987952055, 4617.699849509771, 4674.018456124252, 4678.0, 4678.602318802872, 8805.515696636747, 8814.074522175208, 8828.911710882938, 8829.249513791272, 8838.602262898185, 5310.150375939908, 5310.522053015247, 5317.574594032468, 5331.280277162611, 5334.733263336818, 4602.723836569817, 4996.850094497171, 5025.020121076428, 5026.07017701719, 5048.722320272347, 3725.2363265530503, 3866.342721737255, 3871.3031654302495, 3902.35611124167, 3938.724289299779, 1014.457265363042, 1143.0512674480162, 1159.588346137126, 1190.470240742861, 1198.9400529973466, 725.1298441869777, 826.8304997475503, 886.423824514071, 897.4794619120894, 917.2524392619994, 4670.089332580114, 5385.111456609616, 5469.835904922858, 5941.138534912414, 6145.539084568675, 958.520739630185, 979.9510024498746, 1026.2713473433946, 1026.6406757634768, 1085.1210519479248, 3680.1535646801385, 3898.181381909774, 3905.628965248297, 3984.7011474139554, 4012.719109662354, 9956.253281003954, 10237.23220758446, 10263.7434064149, 10269.948650256702, 10273.589056437659, 10163.7459063524, 10175.796484070277, 10191.592336306465, 10192.592296308212, 10217.948910255402, 7854.764357069297, 7908.841823163504, 7991.120976692559, 8121.4721043132, 8122.268995790408, 5641.323041235078, 5655.773769049192, 5693.715314234355, 5795.478406943395, 5828.795992140254, 9143.63425462974, 9219.26245900313, 9291.67479138227, 9322.673706420243, 9422.246220302497, 9504.95247523758, 9505.762355941157, 9518.23854091657, 9607.855882161914, 9648.951755241178, 6818.454523638196, 6847.144106986627, 6877.1747390313485, 6940.861182776215, 6973.442124629916, 7940.444168908121, 7948.205179482005, 8018.759437216893, 8046.436749427484, 8055.919440805517, 7101.147862256562, 7204.9639751800905, 7208.675609597685, 7229.457790665616, 7334.743283985035, 6568.967155164385, 7133.500654954311, 7168.856622867409, 7173.426125910019, 7177.712891484491, 6432.839179020562, 6440.098386225838, 6440.967795160994, 6507.934920650733, 6533.346665333619, 5045.747712614281, 5068.873278168076, 5074.543291103819, 5108.693478391308, 5142.485751424828, 4980.0, 4994.1509943309, 5003.224500202475, 5087.847364579068, 5250.264962905273], "value": 4469.969623816538, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.5515870637821, 370.8794641741434, 377.3528398796064, 378.50037949906135, 379.8708439130697, 3943.447917291578, 4507.504174540798, 4605.838795642153, 5149.279100925864, 5439.0, 4526.4794548626915, 5293.417724050351, 5298.311219541454, 5492.203630473579, 5502.780873189179, 4072.7759973201464, 4347.804348804296, 4369.235383807837, 4376.2997920332755, 4392.494863090754, 3324.6535153262967, 3516.0, 3716.3867961786273, 4239.6893692937265, 4274.636655884254, 4128.770463949464, 4196.958030419687, 4202.327627579588, 4222.234436444134, 4233.555476674951, 3627.132027006094, 3725.4039353703547, 4260.190563792885, 4272.333789821972, 4272.33778764292, 3371.477420999749, 3548.6451354864566, 3604.945925811115, 3650.945235821465, 3651.6348365163535, 5370.43610420904, 5380.219868119101, 5415.562996651483, 5445.972770136124, 5488.890222195534, 4046.271671099201, 4818.518148185189, 4862.82980095695, 4892.094962431962, 4907.239377896396, 4948.653594248404, 5095.878906640533, 5414.648047876875, 5747.166660834156, 5928.555358348098, 3707.7589956652732, 3784.1674831537016, 3954.960450395488, 3962.7820469874146, 3963.5441924178513, 6069.514438844882, 6080.908786368208, 6089.969550152266, 6096.603720758137, 6127.325994140686, 3444.776089554171, 3798.4112462568346, 3900.31744444721, 3951.0152733276736, 4046.0491784430637, 4079.755214687069, 4102.528209255914, 4108.794560271975, 4121.567235440236, 4144.52337981136, 4412.536683648171, 4816.638752093607, 4822.927656085161, 4833.275008748716, 4858.562729354375, 5377.381601115843, 5426.484483974016, 5856.707164641751, 5910.8817823643285, 5956.255468066492, 1182.645206438065, 1202.9578964736368, 1284.2598279331557, 1315.7960516120017, 1321.8281623388898, 765.9119201291811, 804.9034115906129, 865.9653613855376, 901.4726385064679, 909.5815924674723, 4862.82980095695, 5081.644284900095, 5254.894902101936, 5272.6309158358545, 5281.989266118276, 4881.169561414469, 5553.0, 5653.558342622605, 5724.940885936116, 5729.82266738645, 4365.825366985348, 4726.393026370982, 4892.902141957141, 5188.766505507186, 5231.424543300234, 2436.780689737932, 2667.1065193160507, 2690.842937536878, 2793.52510073288, 3001.2346851552716, 5651.321841379061, 5729.7367539821535, 5988.221531200916, 6058.394160583906, 6173.96913015441, 5083.7853950798035, 5153.613478989091, 5835.374081663269, 5863.309352517968, 5874.471297583153, 5043.672161309503, 5258.631895767259, 5291.682499050069, 5346.919796202982, 5547.916781248285, 4896.853094407174, 5084.0, 5475.397706252229, 5557.138643510262, 5811.2106189028655, 4985.6510044298, 5968.612040217372, 6071.757129714939, 6078.908816367846, 6106.2061931948565, 6305.896468118152, 6344.3987964936005, 6357.728454309065, 6360.204974378203, 6392.520560957853, 3568.8572457101427, 3839.040239940015, 3840.4815349927403, 4056.513218413809, 4141.316682747313, 5450.345958485007, 5700.857478563069, 5826.388229236039, 5975.880482390415, 6046.879062418639, 4954.9752251238515, 5878.353381128158, 5888.4700376966275, 5900.911486327795, 5962.750301085832, 4006.8780741392457, 4473.194824931414, 5542.695151766691, 5852.61054209343, 5957.314908785372, 4897.608191344755, 5422.674639521641, 5484.601426636183, 5687.146927960839, 5807.448292412214, 5219.6689844089515, 5450.373207081237, 5942.0, 5955.868385006835, 5982.756466325128, 5545.584081194007, 5802.332731735735, 5870.326956817286, 5873.236479257669, 5895.852603684943], "value": 4172.861307616557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6521871673523, 712.957222566646, 762.8512440074188, 814.0, 881.8941726992759, 14639.828813694905, 15412.15233162177, 15624.2969066392, 16115.758263626056, 16155.0, 13554.728905421885, 15061.096334219954, 15090.320935557898, 15145.545633630982, 15941.760873586905, 861.0, 870.3254977392523, 899.6266549381999, 902.1204325782376, 904.6381447421052, 716.3194964783451, 732.8607564562743, 756.3419824752445, 889.0931250124869, 889.9466032038066, 934.5700977550335, 947.7062110745657, 949.6296444386695, 949.7673070097836, 949.8432758594823, 782.7847341980944, 938.7090002099337, 939.0, 943.2736792669674, 944.1361154543584, 690.5235387582552, 692.3146085375492, 695.5270416117038, 696.0568429777679, 696.989545156823, 15412.0, 16295.0, 16328.510144695678, 16485.423010194587, 16929.83070169295, 14697.55907322782, 14749.262536873113, 14793.556193314218, 14794.186319752409, 14819.851801481955, 10025.0, 11929.284242945365, 11977.880221197851, 12039.819402708967, 12567.622971310875, 3735.925281494382, 3775.0, 3979.641832235113, 3989.4813674222164, 3997.060690737703, 96691.0, 99900.0, 122909.0, 125413.0, 129218.3539082308, 10856.185786066077, 10859.945700271448, 11160.665180044612, 11456.083513318914, 11618.0, 23545.529089418116, 23740.64389034166, 23771.643425348633, 23801.880990595284, 23820.880895595757, 8077.717279895293, 8120.269175774209, 8147.877781833277, 8289.41974061822, 8365.205305495969, 3808.352580061396, 3861.806909654506, 4235.478713367821, 4308.418363520885, 4388.1223755249075, 3732.0, 3828.272628200633, 3838.7504812187117, 3839.424086387064, 3842.9615703842605, 687.3641881259815, 693.0885885061128, 710.705057401183, 717.2253965717013, 721.6391804097951, 580.6603137164806, 630.0989584893555, 636.0237036149581, 683.4634811672813, 686.5091459606406, 7615.6953721850505, 10236.48817559119, 10433.686989390331, 10784.40685762275, 11066.944665276622, 581.9185314056035, 590.6338070396382, 591.3997292747779, 599.658194828952, 720.0603212807232, 4953.777080031407, 5178.0, 5212.4266330703595, 5214.7914083436235, 5226.634135610547, 85797.57101214454, 97809.0, 99147.0, 99647.0, 100900.49549752205, 69545.65227173832, 80454.0, 89244.6613300801, 92778.53610731903, 93371.0, 64845.351546484875, 72615.0, 73103.0, 75173.62413188009, 76597.6170119157, 7755.146933837161, 8858.883583072504, 9993.400395976265, 10018.348807327791, 10065.547050382604, 34573.0, 38462.6153738459, 52541.0, 52812.0, 53424.0, 56794.0, 68286.0, 68682.0, 69388.0, 72670.0, 9896.703098907044, 11877.524899003944, 12042.0, 12069.698257543632, 12325.322107283826, 10559.947200263949, 13223.272720000108, 13481.932590336986, 13848.446062157804, 13996.790048149489, 10918.01737843598, 12133.090018248844, 12420.192687475284, 12708.555200567936, 12847.678808029874, 11887.702807429538, 12249.448774804978, 12390.256584604953, 12532.812007819708, 12654.87345126537, 11910.464029118537, 12252.571160009356, 12277.324747138993, 12548.749025019632, 12554.623361299176, 9976.700698979042, 10411.0, 10497.580096796042, 10644.8935510647, 10651.0, 8774.912250877409, 9358.719238422858, 10353.689389318333, 10359.533820977924, 10367.896321036693], "value": 7442.764089321179, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 118.0, 117.0, 117.0, 121.0, 116.0, 143.0, 175.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 106.0, 143.0, 122.0, 145.0, 138.0, 109.0, 124.0, 109.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 199.0, 197.0, 197.0, 199.0, 197.0, 194.0, 194.0, 227.0], "value": 200.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 84.0, 60.0, 66.0, 71.0, 82.0, 62.0, 72.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 85.0, 72.0, 68.0, 68.0, 82.0, 69.0, 70.0, 82.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 93.0, 90.0, 93.0, 87.0, 93.0, 89.0, 135.0, 123.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 99.0, 96.0, 96.0, 97.0, 111.0, 100.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 143.0, 151.0, 94.0, 97.0, 111.0, 145.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 61.0, 61.0, 61.0, 63.0, 62.0, 62.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 70.0, 79.0, 75.0, 68.0, 100.0, 102.0, 88.0, 71.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 85.0, 83.0, 84.0, 85.0, 85.0, 96.0, 86.0, 114.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 154.0, 140.0, 139.0, 142.0, 143.0, 147.0, 159.0, 141.0, 137.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 167.0, 164.0, 169.0, 165.0, 166.0, 168.0, 164.0, 162.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 73.0, 85.0, 93.0, 84.0, 85.0, 87.0, 89.0], "value": 79.3, "unit": "score"}]}]} 09:04:24 INFO - Return code: 0 09:04:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:04:24 ERROR - # TBPL SUCCESS # 09:04:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:24 INFO - 2017-03-20 09:04:24.442 screenresolution[1657:10364] starting screenresolution argv=screenresolution get 09:04:24 INFO - 2017-03-20 09:04:24.445 screenresolution[1657:10364] Display 0: 1600x1200x32@60 09:04:24 INFO - 2017-03-20 09:04:24.451 screenresolution[1658:10367] starting screenresolution argv=screenresolution list 09:04:24 INFO - Available Modes on Display 0 09:04:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:24 INFO - Intel Iris: 09:04:24 INFO - Chipset Model: Intel Iris 09:04:24 INFO - Type: GPU 09:04:24 INFO - Bus: Built-In 09:04:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:24 INFO - Vendor: Intel (0x8086) 09:04:24 INFO - Device ID: 0x0a2e 09:04:24 INFO - Revision ID: 0x0009 09:04:24 INFO - Displays: 09:04:24 INFO - Display: 09:04:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:24 INFO - Display Serial Number: 1600x1200 60 09:04:24 INFO - Main Display: Yes 09:04:24 INFO - Mirror: Off 09:04:24 INFO - Online: Yes 09:04:24 INFO - Rotation: Supported 09:04:24 INFO - Return code: 0 09:04:24 INFO - Running post-action listener: _resource_record_post_action 09:04:24 INFO - [mozharness: 2017-03-20 16:04:24.686351Z] Finished run-tests step (success) 09:04:24 INFO - Running post-run listener: _resource_record_post_run 09:04:24 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 38078464; Write bytes: 425338368; Read time: 247; Write time: 1437 09:04:24 INFO - TinderboxPrint: CPU usage
54.3% 09:04:24 INFO - TinderboxPrint: I/O read bytes / time
38,078,464 / 247 09:04:24 INFO - TinderboxPrint: I/O write bytes / time
425,338,368 / 1,437 09:04:24 INFO - TinderboxPrint: CPU idle
2,177.8 (45.7%) 09:04:24 INFO - TinderboxPrint: CPU system
958.2 (20.1%) 09:04:24 INFO - TinderboxPrint: CPU user
1,625.7 (34.1%) 09:04:24 INFO - TinderboxPrint: Swap in / out
160,219,136 / 0 09:04:24 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119570944; Write bytes: 114587136; Read time: 10943; Write time: 253 09:04:24 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35993600; Write bytes: 299769856; Read time: 226; Write time: 1151 09:04:25 INFO - Running post-run listener: _upload_blobber_files 09:04:25 INFO - Blob upload gear active. 09:04:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:25 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 09:04:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:04:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:04:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:04:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:26 INFO - (blobuploader) - INFO - Done attempting. 09:04:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:04:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:04:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:27 INFO - (blobuploader) - INFO - Done attempting. 09:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:04:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:28 INFO - (blobuploader) - INFO - Done attempting. 09:04:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:04:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:30 INFO - (blobuploader) - INFO - Done attempting. 09:04:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:30 INFO - Return code: 0 09:04:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d9f0d564b6143b665d928f2d3a4c4114cafbc3c92c6e10c7d72c5b3b55c8750c282fa6ba41f0640c782d17651fcd2ba85d3c8f9c4d26ec4d034393a99ee03e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83660b176b9beec5da0a94ee935c4c98134dd0ddd16de703b6170f78221a60e2f0ddc75c22c12b1f3c5e3389ef247f10a495d5c3f0f922b5ac88c4461f8f1fbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/98ed3b1c3c2c604d96301c41cbcff7658e53e40d257461f08ff35228d49c2d986858db0036fdf1e6e01a09f47a92f9251c61c699645fcd9e732b409427cb4aea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee5068ab7dee5042f3f66a4e43547f00e41279a33cb6ce6a7847036d374a02cb86a071bd773191a76b1767f44be330d66d866de36d5128fe379c2d9f8173ca38"} 09:04:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:30 INFO - Contents: 09:04:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d9f0d564b6143b665d928f2d3a4c4114cafbc3c92c6e10c7d72c5b3b55c8750c282fa6ba41f0640c782d17651fcd2ba85d3c8f9c4d26ec4d034393a99ee03e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83660b176b9beec5da0a94ee935c4c98134dd0ddd16de703b6170f78221a60e2f0ddc75c22c12b1f3c5e3389ef247f10a495d5c3f0f922b5ac88c4461f8f1fbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/98ed3b1c3c2c604d96301c41cbcff7658e53e40d257461f08ff35228d49c2d986858db0036fdf1e6e01a09f47a92f9251c61c699645fcd9e732b409427cb4aea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee5068ab7dee5042f3f66a4e43547f00e41279a33cb6ce6a7847036d374a02cb86a071bd773191a76b1767f44be330d66d866de36d5128fe379c2d9f8173ca38"} 09:04:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:30 INFO - Copying logs to upload dir... 09:04:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.750196 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-20 09:04:30.541986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 09:04:30.545695) ========= 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.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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/Mozilla-Beta/sha512/94d9f0d564b6143b665d928f2d3a4c4114cafbc3c92c6e10c7d72c5b3b55c8750c282fa6ba41f0640c782d17651fcd2ba85d3c8f9c4d26ec4d034393a99ee03e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83660b176b9beec5da0a94ee935c4c98134dd0ddd16de703b6170f78221a60e2f0ddc75c22c12b1f3c5e3389ef247f10a495d5c3f0f922b5ac88c4461f8f1fbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/98ed3b1c3c2c604d96301c41cbcff7658e53e40d257461f08ff35228d49c2d986858db0036fdf1e6e01a09f47a92f9251c61c699645fcd9e732b409427cb4aea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee5068ab7dee5042f3f66a4e43547f00e41279a33cb6ce6a7847036d374a02cb86a071bd773191a76b1767f44be330d66d866de36d5128fe379c2d9f8173ca38"} build_url:https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/SI8IZzv8SUOmpx7wcznZjA/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94d9f0d564b6143b665d928f2d3a4c4114cafbc3c92c6e10c7d72c5b3b55c8750c282fa6ba41f0640c782d17651fcd2ba85d3c8f9c4d26ec4d034393a99ee03e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83660b176b9beec5da0a94ee935c4c98134dd0ddd16de703b6170f78221a60e2f0ddc75c22c12b1f3c5e3389ef247f10a495d5c3f0f922b5ac88c4461f8f1fbd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/98ed3b1c3c2c604d96301c41cbcff7658e53e40d257461f08ff35228d49c2d986858db0036fdf1e6e01a09f47a92f9251c61c699645fcd9e732b409427cb4aea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee5068ab7dee5042f3f66a4e43547f00e41279a33cb6ce6a7847036d374a02cb86a071bd773191a76b1767f44be330d66d866de36d5128fe379c2d9f8173ca38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 09:04:30.580437) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:04:30.580771) ========= 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.rv68AKl3nK/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.bLnFpEFarr/Listeners TMPDIR=/var/folders/w4/wtzn5y_127z_rwtpxmdvh92000000w/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.005037 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 09:04:30.629186) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 09:04:30.629549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 09:04:30.630010) ========= ========= Total master_lag: 0.17 =========